[2010/11/07 20:22:26,  0] smbd/server.c:992(main)
  smbd version 3.6.0-GIT-786a767-devel started.
  Copyright Andrew Tridgell and the Samba Team 1992-2010
[2010/11/07 20:22:26.659281,  5] lib/debug.c:406(debug_dump_status)
  INFO: Current debug levels:
    all: True/6
    tdb: False/0
    printdrivers: False/0
    lanman: False/0
    smb: False/0
    rpc_parse: False/0
    rpc_srv: False/0
    rpc_cli: False/0
    passdb: False/0
    sam: False/0
    auth: False/0
    winbind: False/0
    vfs: False/0
    idmap: False/0
    quota: False/0
    acls: False/0
    locking: False/0
    msdfs: False/0
    dmapi: False/0
    registry: False/0
  doing parameter syslog = 0
  doing parameter max log size = 50
  doing parameter socket options = TCP_NODELAY IPTOS_LOWDELAY SO_RCVBUF=32768 SO_SNDBUF=32768
  doing parameter ldap ssl = no
  doing parameter template shell = /bin/bash
  doing parameter winbind enum users = Yes
  doing parameter winbind enum groups = Yes
[2010/11/07 20:22:26.659893,  4] param/loadparm.c:9400(lp_load_ex)
  pm_process() returned Yes
[2010/11/07 20:22:26.660041,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UCS-2LE
[2010/11/07 20:22:26.660085,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UCS-2LE
[2010/11/07 20:22:26.660123,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF-16LE
[2010/11/07 20:22:26.660163,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF-16LE
[2010/11/07 20:22:26.660200,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UCS-2BE
[2010/11/07 20:22:26.660236,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UCS-2BE
[2010/11/07 20:22:26.660270,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF-16BE
[2010/11/07 20:22:26.660305,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF-16BE
[2010/11/07 20:22:26.660341,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF8
[2010/11/07 20:22:26.660376,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF8
[2010/11/07 20:22:26.660411,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UTF-8
[2010/11/07 20:22:26.660447,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UTF-8
[2010/11/07 20:22:26.660481,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset ASCII
[2010/11/07 20:22:26.660523,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset ASCII
[2010/11/07 20:22:26.660561,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset 646
[2010/11/07 20:22:26.660600,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset 646
[2010/11/07 20:22:26.660635,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset ISO-8859-1
[2010/11/07 20:22:26.660670,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset ISO-8859-1
[2010/11/07 20:22:26.660704,  5] lib/iconv.c:104(smb_register_charset)
  Attempting to register new charset UCS2-HEX
[2010/11/07 20:22:26.660743,  5] lib/iconv.c:112(smb_register_charset)
  Registered charset UCS2-HEX
[2010/11/07 20:22:26.660917,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661345,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661475,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661535,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661592,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661643,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661694,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661759,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661818,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.661913,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.662000,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.662132,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.662222,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.662300,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.662446,  3] smbd/sec_ctx.c:171(get_current_groups)
  get_current_groups: user is in 1 groups: 0
[2010/11/07 20:22:26.700340,  2] lib/tallocmsg.c:124(register_msg_pool_usage)
  Registered MSG_REQ_POOL_USAGE
[2010/11/07 20:22:26.700429,  2] lib/dmallocmsg.c:78(register_dmalloc_msgs)
  Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED
[2010/11/07 20:22:26.700526,  3] param/loadparm.c:9365(lp_load_ex)
  lp_load_ex: refreshing parameters
Initialising global parameters
rlimit_max: rlimit_max (1024) below minimum Windows limit (16384)
[2010/11/07 20:22:26.700708,  3] ../lib/util/params.c:550(pm_process)
  params.c:pm_process() - Processing configuration file "/usr/local/samba/lib/smb.conf"
[2010/11/07 20:22:26.700764,  3] param/loadparm.c:8104(do_section)
  Processing section "[global]"
  doing parameter workgroup = MAISON
  doing parameter server string = Serveur Linux
  doing parameter allow trusted domains = No
  doing parameter map to guest = Bad User
  doing parameter log level = 6
[2010/11/07 20:22:26.700939,  5] lib/debug.c:406(debug_dump_status)
  INFO: Current debug levels:
    all: True/6
    tdb: False/0
    printdrivers: False/0
    lanman: False/0
    smb: False/0
    rpc_parse: False/0
    rpc_srv: False/0
    rpc_cli: False/0
    passdb: False/0
    sam: False/0
    auth: False/0
    winbind: False/0
    vfs: False/0
    idmap: False/0
    quota: False/0
    acls: False/0
    locking: False/0
    msdfs: False/0
    dmapi: False/0
    registry: False/0
  doing parameter syslog = 0
  doing parameter max log size = 50
  doing parameter socket options = TCP_NODELAY IPTOS_LOWDELAY SO_RCVBUF=32768 SO_SNDBUF=32768
  doing parameter ldap ssl = no
  doing parameter template shell = /bin/bash
  doing parameter winbind enum users = Yes
  doing parameter winbind enum groups = Yes
[2010/11/07 20:22:26.701644,  2] param/loadparm.c:8121(do_section)
  Processing section "[printers]"
  doing parameter comment = All Printers
  doing parameter path = /var/spool/samba
  doing parameter create mask = 0700
  doing parameter guest ok = Yes
  doing parameter max print jobs = 5000
  doing parameter printable = Yes
  doing parameter browseable = No
[2010/11/07 20:22:26.702009,  2] param/loadparm.c:8121(do_section)
  Processing section "[print$]"
  doing parameter comment = Printer Drivers
  doing parameter path = /var/lib/samba/printers
  doing parameter create mask = 0664
  doing parameter directory mask = 0775
  doing parameter guest ok = Yes
[2010/11/07 20:22:26.702248,  4] param/loadparm.c:9400(lp_load_ex)
  pm_process() returned Yes
[2010/11/07 20:22:26.702360,  3] param/loadparm.c:6492(lp_add_ipc)
  adding IPC service
[2010/11/07 20:22:26.702435,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702506,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702575,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702639,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702703,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702766,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702828,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702892,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.702971,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.703036,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.703105,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.703176,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.703243,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.703310,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.703370,  3] printing/pcap.c:116(pcap_cache_reload)
  reloading printcap cache
[2010/11/07 20:22:26.703579,  1] printing/printer_list.c:198(printer_list_get_last_refresh)
  Failed to fetch record!
[2010/11/07 20:22:26.768060,  5] printing/print_cups.c:412(cups_pcap_load_async)
  cups_pcap_load_async: asynchronously loading cups printers
[2010/11/07 20:22:26.768465,  5] printing/print_cups.c:462(cups_async_callback)
  cups_async_callback: callback received for printer data. fd = 9
[2010/11/07 20:22:26.768987,  5] printing/print_cups.c:170(cups_cache_reload_async)
  reloading cups printcap cache
[2010/11/07 20:22:26.785806,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.785884,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.785937,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.785983,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786030,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786076,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786122,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786169,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786215,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786261,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786312,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786366,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786417,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.786467,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.787494,  3] printing/pcap.c:167(pcap_cache_reload)
  reload status: ok
[2010/11/07 20:22:26.787611,  0] printing/pcap.c:175(pcap_cache_reload)
  Failed to cleanup printer list!
[2010/11/07 20:22:26.787652,  3] smbd/server_reload.c:48(reload_printers)
  reload_printers: Could not create system server_info
[2010/11/07 20:22:26.787768,  3] param/loadparm.c:6531(lp_add_printer)
  adding printer service IMP-INFO2
[2010/11/07 20:22:26.787884,  3] param/loadparm.c:6531(lp_add_printer)
  adding printer service IMP-INFO
[2010/11/07 20:22:26.787991,  3] param/loadparm.c:6531(lp_add_printer)
  adding printer service PDF
[2010/11/07 20:22:26.788085,  3] param/loadparm.c:6531(lp_add_printer)
  adding printer service IMP-HOME1
[2010/11/07 20:22:26.788178,  3] param/loadparm.c:6531(lp_add_printer)
  adding printer service IMP-RES1
[2010/11/07 20:22:26.788230,  3] printing/pcap.c:213(pcap_printer_fn)
  Failed to run fn for all printers!
[2010/11/07 20:22:26.788296,  6] param/loadparm.c:7323(lp_file_list_changed)
  lp_file_list_changed()
  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Sun Nov  7 20:21:51 2010
  
[2010/11/07 20:22:26.788519,  2] lib/interface.c:340(add_interface)
  added interface wlan0 ip=2a01:e35:2f16:2350:213:2ff:fe38:2ce7 bcast=2a01:e35:2f16:2350:ffff:ffff:ffff:ffff netmask=ffff:ffff:ffff:ffff::
[2010/11/07 20:22:26.788598,  2] lib/interface.c:340(add_interface)
  added interface wlan0 ip=fe80::213:2ff:fe38:2ce7%wlan0 bcast=fe80::ffff:ffff:ffff:ffff%wlan0 netmask=ffff:ffff:ffff:ffff::
[2010/11/07 20:22:26.788704,  2] lib/interface.c:340(add_interface)
  added interface wlan0 ip=192.168.0.2 bcast=192.168.0.255 netmask=255.255.255.0
[2010/11/07 20:22:26.788761,  3] smbd/server.c:1027(main)
  loaded services
[2010/11/07 20:22:26.788805,  5] lib/util.c:238(init_names)
  Netbios name list:-
  my_netbios_names[0]="LP-INFO2"
[2010/11/07 20:22:26.788898,  3] smbd/server.c:1059(main)
  Becoming a daemon.
[2010/11/07 20:22:26.789771,  5] passdb/pdb_interface.c:68(smb_register_passdb)
  Attempting to register passdb backend smbpasswd
[2010/11/07 20:22:26.789852,  5] passdb/pdb_interface.c:81(smb_register_passdb)
  Successfully added passdb backend 'smbpasswd'
[2010/11/07 20:22:26.789892,  5] passdb/pdb_interface.c:68(smb_register_passdb)
  Attempting to register passdb backend tdbsam
[2010/11/07 20:22:26.789929,  5] passdb/pdb_interface.c:81(smb_register_passdb)
  Successfully added passdb backend 'tdbsam'
[2010/11/07 20:22:26.789965,  5] passdb/pdb_interface.c:68(smb_register_passdb)
  Attempting to register passdb backend wbc_sam
[2010/11/07 20:22:26.790003,  5] passdb/pdb_interface.c:81(smb_register_passdb)
  Successfully added passdb backend 'wbc_sam'
[2010/11/07 20:22:26.790038,  5] passdb/pdb_interface.c:138(make_pdb_method_name)
  Attempting to find a passdb backend to match tdbsam (tdbsam)
[2010/11/07 20:22:26.790073,  5] passdb/pdb_interface.c:159(make_pdb_method_name)
  Found pdb backend tdbsam
[2010/11/07 20:22:26.790117,  5] passdb/pdb_interface.c:170(make_pdb_method_name)
  pdb backend tdbsam has a valid init
[2010/11/07 20:22:26.792120,  5] lib/gencache.c:65(gencache_init)
  Opening cache file at /usr/local/samba/var/locks/gencache.tdb
[2010/11/07 20:22:26.792240,  5] lib/gencache.c:108(gencache_init)
  Opening cache file at /usr/local/samba/var/locks/gencache_notrans.tdb
[2010/11/07 20:22:26.792468,  5] passdb/lookup_sid.c:1332(uid_to_sid)
  uid_to_sid: winbind failed to find a sid for uid 0
[2010/11/07 20:22:26.792514,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.792555,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.792593,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.792630,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.792668,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.792764,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:26.792802,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:26.792838,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:26.792874,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.792908,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.793011,  4] passdb/pdb_tdb.c:520(tdbsam_open)
  tdbsam_open: successfully opened /usr/local/samba/private/passdb.tdb
[2010/11/07 20:22:26.793083,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:26.793168,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793225,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793276,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793323,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793370,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793426,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793473,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793520,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793566,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793613,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793663,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793717,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793767,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793816,  5] lib/charcnv.c:98(charset_name)
  Substituting charset 'UTF-8' for LOCALE
[2010/11/07 20:22:26.793869,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:26.793915,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
[2010/11/07 20:22:26.793951,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
[2010/11/07 20:22:26.793986,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
[2010/11/07 20:22:26.794022,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.794058,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.794171,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:26.794221,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.794266,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.794337,  5] passdb/lookup_sid.c:1385(gid_to_sid)
  gid_to_sid: winbind failed to find a sid for gid 0
[2010/11/07 20:22:26.794374,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.794411,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.794446,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.794480,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.794514,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.794632,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.794744,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.794782,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.794818,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.794853,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.794887,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.794956,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.794993,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795029,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795064,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795099,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.795133,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.795208,  3] auth/token_util.c:564(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:22:26.795248,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795312,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795349,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795385,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795420,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.795454,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.795520,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795557,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795593,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795627,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795663,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.795697,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.795756,  3] auth/token_util.c:595(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:22:26.795797,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795833,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795869,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:26.795904,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:26.795960,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:26.795994,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:26.796111,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:26.796159,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-1000]
[2010/11/07 20:22:26.796208,  5] lib/privileges.c:173(get_privileges_for_sids)
  get_privileges_for_sids: sid = S-1-5-32-544
  Privilege set: 0x1ffffff0
[2010/11/07 20:22:26.796266,  5] lib/privileges.c:173(get_privileges_for_sids)
  get_privileges_for_sids: sid = S-1-1-0
  Privilege set: 0x0
[2010/11/07 20:22:26.796322,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-2]
[2010/11/07 20:22:26.796365,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-11]
[2010/11/07 20:22:32.336135,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:22:32.336232,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:22:32.336369,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:22:32.336497,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:22:32.336562,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:22:32.336630,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:22:32.336713,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.336819,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.336933,  3] passdb/lookup_sid.c:1635(get_primary_group_sid)
  Forcing Primary Group to 'Domain Users' for root
[2010/11/07 20:22:32.337010,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:22:32.337074,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:22:32.337141,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:22:32.337459,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.337532,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.337598,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.337663,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.337726,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.337858,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.337943,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.338024,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.338090,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.338154,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.338218,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.338281,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.338395,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.338473,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.338540,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.338604,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.338667,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.338730,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.338955,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.339024,  2] passdb/pdb_interface.c:266(pdb_try_account_unlock)
  pdb_try_account_unlock: Account root administratively locked out with no bad password time. Leaving locked out.
[2010/11/07 20:22:32.339099,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.339165,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.339229,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.339293,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.339356,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.339469,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.339538,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:22:32.339602,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:22:32.339669,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:22:32.339749,  3] passdb/lookup_sid.c:1635(get_primary_group_sid)
  Forcing Primary Group to 'Domain Users' for root
[2010/11/07 20:22:32.339839,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.339904,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.339993,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.340059,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.340121,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.340234,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.340318,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.340397,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.340476,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.340542,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.340606,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.340671,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.340733,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.340846,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.340934,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.341011,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.341078,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.341143,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.341208,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.341270,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.341371,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.341438,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.341502,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.341566,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.341628,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.341741,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.341811,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.341877,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.341941,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.342006,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.342069,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.342180,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.342261,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.342339,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:22:32.342429,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.342496,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.342561,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.342625,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.342688,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.342802,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.342877,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.342944,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.343009,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.343074,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.343137,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.343249,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.343317,  2] passdb/pdb_interface.c:266(pdb_try_account_unlock)
  pdb_try_account_unlock: Account root administratively locked out with no bad password time. Leaving locked out.
[2010/11/07 20:22:32.343397,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.343465,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:22:32.343528,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:22:32.343595,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:22:32.343846,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.343950,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.344020,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.344085,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.344149,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.344275,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.344344,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.344411,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.344475,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.344540,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.344603,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.344716,  3] auth/token_util.c:564(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:22:32.344789,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.344909,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.344976,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.345041,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.345105,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.345188,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.345310,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.345378,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.345444,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.345507,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.345572,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.345634,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.345744,  3] auth/token_util.c:595(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:22:32.345817,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.345884,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.345949,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.346014,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.346078,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.346140,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.346348,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.346433,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-1000]
[2010/11/07 20:22:32.346519,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-22-2-0]
[2010/11/07 20:22:32.346600,  5] lib/privileges.c:173(get_privileges_for_sids)
  get_privileges_for_sids: sid = S-1-1-0
  Privilege set: 0x0
[2010/11/07 20:22:32.346702,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-2]
[2010/11/07 20:22:32.346782,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-11]
[2010/11/07 20:22:32.346924,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.346993,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.347057,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.347120,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.347182,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.347300,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.347414,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.347482,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.347546,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.347611,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.347674,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.347792,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.347906,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.347991,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.348076,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.348141,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.348204,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.348322,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.348418,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user nobody
[2010/11/07 20:22:32.348484,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2010/11/07 20:22:32.348611,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [nobody]!
[2010/11/07 20:22:32.348700,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user LP-INFO2\nobody
[2010/11/07 20:22:32.348765,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is lp-info2\nobody
[2010/11/07 20:22:32.348897,  5] lib/username.c:157(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as given is LP-INFO2\nobody
[2010/11/07 20:22:32.349029,  5] lib/username.c:167(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as uppercase is LP-INFO2\NOBODY
[2010/11/07 20:22:32.349161,  5] lib/username.c:176(Get_Pwnam_internals)
  Checking combinations of 0 uppercase letters in lp-info2\nobody
[2010/11/07 20:22:32.349228,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals didn't find user [LP-INFO2\nobody]!
[2010/11/07 20:22:32.349292,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user nobody
[2010/11/07 20:22:32.349355,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is nobody
[2010/11/07 20:22:32.349422,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [nobody]!
[2010/11/07 20:22:32.349530,  5] passdb/lookup_sid.c:1385(gid_to_sid)
  gid_to_sid: winbind failed to find a sid for gid 65534
[2010/11/07 20:22:32.349597,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.349664,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.349728,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.349792,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.349855,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.349972,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.350055,  3] passdb/lookup_sid.c:1635(get_primary_group_sid)
  Forcing Primary Group to 'Domain Users' for nobody
[2010/11/07 20:22:32.350184,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.350252,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.350317,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.350381,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.350444,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.350564,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.350633,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.350699,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.350763,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.350827,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.350909,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.351020,  3] auth/token_util.c:564(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:22:32.351092,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.351209,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.351276,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.351341,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.351405,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.351468,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.351587,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.351656,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.351722,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.351786,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.351850,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.351913,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.352040,  3] auth/token_util.c:595(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:22:32.352111,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.352178,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.352244,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.352308,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.352373,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.352436,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.352671,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.352755,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-501]
[2010/11/07 20:22:32.352843,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-513]
[2010/11/07 20:22:32.352930,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-546]
[2010/11/07 20:22:32.353015,  5] lib/privileges.c:173(get_privileges_for_sids)
  get_privileges_for_sids: sid = S-1-1-0
  Privilege set: 0x0
[2010/11/07 20:22:32.353116,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-2]
[2010/11/07 20:22:32.353196,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-32-546]
[2010/11/07 20:22:32.353326,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.353395,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.353459,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.353524,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.353603,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.353706,  5] passdb/pdb_interface.c:1596(lookup_global_sam_rid)
  lookup_global_sam_rid: looking up RID 513.
[2010/11/07 20:22:32.353774,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.353838,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.353902,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.353966,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.354028,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.354139,  5] passdb/pdb_tdb.c:611(tdbsam_getsampwrid)
  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
[2010/11/07 20:22:32.354236,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.354303,  5] passdb/pdb_interface.c:1658(lookup_global_sam_rid)
  Can't find a unix id for an unmapped group
[2010/11/07 20:22:32.354374,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.354502,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.354570,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.354635,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.354699,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.354761,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.354860,  5] passdb/pdb_interface.c:1596(lookup_global_sam_rid)
  lookup_global_sam_rid: looking up RID 546.
[2010/11/07 20:22:32.354929,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.354993,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:22:32.355057,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:22:32.355121,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.355183,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.355292,  5] passdb/pdb_tdb.c:611(tdbsam_getsampwrid)
  pdb_getsampwrid (TDB): error looking up RID 546 by key RID_00000222.
[2010/11/07 20:22:32.355386,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.355453,  5] passdb/pdb_interface.c:1658(lookup_global_sam_rid)
  Can't find a unix id for an unmapped group
[2010/11/07 20:22:32.355523,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.355636,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.355704,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.355768,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.355832,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.355894,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.356072,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.356189,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.356256,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.356334,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.356392,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.356427,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.356493,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.356558,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.356595,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:22:32.356630,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:22:32.356666,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:22:32.356700,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:22:32.356766,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:22:32.356939,  3] printing/pcap.c:116(pcap_cache_reload)
  reloading printcap cache
[2010/11/07 20:22:32.357016,  5] printing/print_cups.c:412(cups_pcap_load_async)
  cups_pcap_load_async: asynchronously loading cups printers
[2010/11/07 20:22:32.357397,  3] printing/pcap.c:167(pcap_cache_reload)
  reload status: ok
[2010/11/07 20:22:32.357507,  0] printing/pcap.c:175(pcap_cache_reload)
  Failed to cleanup printer list!
[2010/11/07 20:22:32.357506,  5] printing/print_cups.c:170(cups_cache_reload_async)
  reloading cups printcap cache
[2010/11/07 20:22:32.357725,  3] printing/pcap.c:213(pcap_printer_fn)
  Failed to run fn for all printers!
[2010/11/07 20:22:32.357773,  3] printing/printing.c:1636(start_background_queue)
  start_background_queue: Starting background LPQ thread
[2010/11/07 20:22:32.358184,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 1
  	SO_REUSEADDR = 1
  	SO_BROADCAST = 0
  	TCP_NODELAY = 0
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 0
  	IPTOS_THROUGHPUT = 0
  	SO_SNDBUF = 16384
  	SO_RCVBUF = 87380
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:22:32.358480,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 1
  	SO_REUSEADDR = 1
  	SO_BROADCAST = 0
  	TCP_NODELAY = 1
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 16
  	IPTOS_THROUGHPUT = 16
  	SO_SNDBUF = 65536
  	SO_RCVBUF = 65536
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:22:32.358809,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 1
  	SO_REUSEADDR = 1
  	SO_BROADCAST = 0
  	TCP_NODELAY = 0
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 0
  	IPTOS_THROUGHPUT = 0
  	SO_SNDBUF = 16384
  	SO_RCVBUF = 87380
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:22:32.359088,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 1
  	SO_REUSEADDR = 1
  	SO_BROADCAST = 0
  	TCP_NODELAY = 1
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 16
  	IPTOS_THROUGHPUT = 16
  	SO_SNDBUF = 65536
  	SO_RCVBUF = 65536
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:22:32.359407,  2] lib/util_sock.c:795(open_socket_in)
  bind failed on port 445 socket_addr = 0.0.0.0.
  Error = Adresse déjà utilisée
[2010/11/07 20:22:32.359686,  0] smbd/server.c:540(smbd_open_one_socket)
  smbd_open_once_socket: open_socket_in: Adresse déjà utilisée
[2010/11/07 20:22:32.359762,  2] lib/util_sock.c:795(open_socket_in)
  bind failed on port 139 socket_addr = 0.0.0.0.
  Error = Adresse déjà utilisée
[2010/11/07 20:22:32.359837,  0] smbd/server.c:540(smbd_open_one_socket)
  smbd_open_once_socket: open_socket_in: Adresse déjà utilisée
[2010/11/07 20:22:32.359943,  5] lib/messages.c:300(messaging_register)
  Overriding messaging pointer for type 1 - private_data=(nil)
[2010/11/07 20:22:32.360111,  2] smbd/server.c:765(smbd_parent_loop)
  waiting for connections
[2010/11/07 20:22:32.360240,  5] printing/printing.c:1659(start_background_queue)
  start_background_queue: background LPQ thread started
[2010/11/07 20:22:32.360496,  5] printing/printing.c:1695(start_background_queue)
  start_background_queue: background LPQ thread waiting for messages
[2010/11/07 20:22:32.373240,  5] printing/print_cups.c:462(cups_async_callback)
  cups_async_callback: callback received for printer data. fd = 24
[2010/11/07 20:22:32.373738,  1] lib/serverid.c:198(serverid_deregister)
  Deleting serverid.tdb record failed: NT_STATUS_NOT_FOUND
[2010/11/07 20:22:32.373796,  1] smbd/server.c:284(remove_child_pid)
  Could not remove pid 11440 from serverid.tdb
[2010/11/07 20:22:32.373833,  0] smbd/server.c:297(remove_child_pid)
  Could not find child 11440 -- ignoring
[2010/11/07 20:24:06.382121,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 1
  	SO_REUSEADDR = 1
  	SO_BROADCAST = 0
  	TCP_NODELAY = 1
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 16
  	IPTOS_THROUGHPUT = 16
  	SO_SNDBUF = 65536
  	SO_RCVBUF = 65536
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:24:06.382750,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 1
  	SO_REUSEADDR = 1
  	SO_BROADCAST = 0
  	TCP_NODELAY = 1
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 16
  	IPTOS_THROUGHPUT = 16
  	SO_SNDBUF = 65536
  	SO_RCVBUF = 65536
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:24:06.383379,  6] param/loadparm.c:7323(lp_file_list_changed)
  lp_file_list_changed()
  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Sun Nov  7 20:21:51 2010
  
[2010/11/07 20:24:06.383550,  3] lib/access.c:334(allow_access)
  Allowed connection from ::ffff:172.19.123.123 (::ffff:172.19.123.123)
[2010/11/07 20:24:06.383645,  3] smbd/oplock.c:910(init_oplocks)
  init_oplocks: initializing messages.
[2010/11/07 20:24:06.383867,  3] smbd/oplock_linux.c:224(linux_init_kernel_oplocks)
  Linux kernel oplocks enabled
[2010/11/07 20:24:06.383999,  5] lib/messages.c:332(messaging_deregister)
  Deregistering messaging pointer for type 1 - private_data=(nil)
[2010/11/07 20:24:06.384206,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x85
[2010/11/07 20:24:06.384282,  3] smbd/process.c:1663(process_smb)
  Transaction 0 of length 137 (0 toread)
[2010/11/07 20:24:06.384350,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.384387,  5] lib/util.c:589(show_msg)
  size=133
  smb_com=0x72
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51283
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=0
  smt_wct=0
  smb_bcc=98
[2010/11/07 20:24:06.384705,  3] smbd/process.c:1468(switch_message)
  switch message SMBnegprot (pid 11448) conn 0x0
[2010/11/07 20:24:06.384775,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.384845,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.384911,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.385030,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.385158,  3] smbd/negprot.c:593(reply_negprot)
  Requested protocol [PC NETWORK PROGRAM 1.0]
[2010/11/07 20:24:06.385231,  3] smbd/negprot.c:593(reply_negprot)
  Requested protocol [LANMAN1.0]
[2010/11/07 20:24:06.385332,  3] smbd/negprot.c:593(reply_negprot)
  Requested protocol [Windows for Workgroups 3.1a]
[2010/11/07 20:24:06.385406,  3] smbd/negprot.c:593(reply_negprot)
  Requested protocol [LM1.2X002]
[2010/11/07 20:24:06.385471,  3] smbd/negprot.c:593(reply_negprot)
  Requested protocol [LANMAN2.1]
[2010/11/07 20:24:06.385535,  3] smbd/negprot.c:593(reply_negprot)
  Requested protocol [NT LM 0.12]
[2010/11/07 20:24:06.385662,  6] param/loadparm.c:7323(lp_file_list_changed)
  lp_file_list_changed()
  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Sun Nov  7 20:21:51 2010
  
[2010/11/07 20:24:06.385858,  6] param/loadparm.c:7323(lp_file_list_changed)
  lp_file_list_changed()
  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Sun Nov  7 20:21:51 2010
  
[2010/11/07 20:24:06.386074,  3] smbd/negprot.c:411(reply_nt1)
  using SPNEGO
[2010/11/07 20:24:06.386137,  3] smbd/negprot.c:699(reply_negprot)
  Selected protocol NT LM 0.12
[2010/11/07 20:24:06.386199,  5] smbd/negprot.c:706(reply_negprot)
  negprot index=5
[2010/11/07 20:24:06.386261,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.386297,  5] lib/util.c:589(show_msg)
  size=127
  smb_com=0x72
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51283
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=0
  smt_wct=17
  smb_vwv[ 0]=    5 (0x5)
  smb_vwv[ 1]=12803 (0x3203)
  smb_vwv[ 2]=  256 (0x100)
  smb_vwv[ 3]= 1024 (0x400)
  smb_vwv[ 4]=   65 (0x41)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  256 (0x100)
  smb_vwv[ 7]=47104 (0xB800)
  smb_vwv[ 8]=   44 (0x2C)
  smb_vwv[ 9]=64768 (0xFD00)
  smb_vwv[10]=33011 (0x80F3)
  smb_vwv[11]=14464 (0x3880)
  smb_vwv[12]=11573 (0x2D35)
  smb_vwv[13]=45400 (0xB158)
  smb_vwv[14]=52094 (0xCB7E)
  smb_vwv[15]=50177 (0xC401)
  smb_vwv[16]=  255 (0xFF)
  smb_bcc=58
[2010/11/07 20:24:06.499282,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0xec
[2010/11/07 20:24:06.499426,  3] smbd/process.c:1663(process_smb)
  Transaction 1 of length 240 (0 toread)
[2010/11/07 20:24:06.499492,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.499530,  5] lib/util.c:589(show_msg)
  size=236
  smb_com=0x73
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=0
  smb_pid=65279
  smb_uid=0
  smb_mid=45184
  smt_wct=12
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=  236 (0xEC)
  smb_vwv[ 2]=16644 (0x4104)
  smb_vwv[ 3]=   50 (0x32)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=   74 (0x4A)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=  212 (0xD4)
  smb_vwv[11]=40960 (0xA000)
  smb_bcc=177
[2010/11/07 20:24:06.500174,  3] smbd/process.c:1468(switch_message)
  switch message SMBsesssetupX (pid 11448) conn 0x0
[2010/11/07 20:24:06.500241,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.500305,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.500367,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.500469,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.500542,  3] smbd/sesssetup.c:1304(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2010/11/07 20:24:06.500614,  2] smbd/sesssetup.c:1250(setup_new_vc_session)
  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
[2010/11/07 20:24:06.500680,  3] smbd/sesssetup.c:1040(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2010/11/07 20:24:06.500751,  3] smbd/sesssetup.c:1082(reply_sesssetup_and_X_spnego)
  NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2010/11/07 20:24:06.500883,  5] smbd/sesssetup.c:602(parse_spnego_mechanisms)
  parse_spnego_mechanisms: Got OID 1.3.6.1.4.1.311.2.2.10
[2010/11/07 20:24:06.500985,  3] smbd/sesssetup.c:655(reply_spnego_negotiate)
  reply_spnego_negotiate: Got secblob of size 40
[2010/11/07 20:24:06.501688,  5] auth/auth.c:494(make_auth_context_subsystem)
  Making default auth method list for standalone security=user, encrypt passwords = yes
[2010/11/07 20:24:06.501773,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend sam
[2010/11/07 20:24:06.501837,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'sam'
[2010/11/07 20:24:06.501899,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend sam_ignoredomain
[2010/11/07 20:24:06.501962,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'sam_ignoredomain'
[2010/11/07 20:24:06.502028,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend unix
[2010/11/07 20:24:06.502091,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'unix'
[2010/11/07 20:24:06.502153,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend winbind
[2010/11/07 20:24:06.502215,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'winbind'
[2010/11/07 20:24:06.502276,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend wbc
[2010/11/07 20:24:06.502338,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'wbc'
[2010/11/07 20:24:06.502399,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend smbserver
[2010/11/07 20:24:06.502462,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'smbserver'
[2010/11/07 20:24:06.502526,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend trustdomain
[2010/11/07 20:24:06.502589,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'trustdomain'
[2010/11/07 20:24:06.502650,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend ntdomain
[2010/11/07 20:24:06.502713,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'ntdomain'
[2010/11/07 20:24:06.502777,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend guest
[2010/11/07 20:24:06.502839,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'guest'
[2010/11/07 20:24:06.502903,  5] auth/auth.c:47(smb_register_auth)
  Attempting to register auth backend netlogond
[2010/11/07 20:24:06.502969,  5] auth/auth.c:59(smb_register_auth)
  Successfully added auth method 'netlogond'
[2010/11/07 20:24:06.503031,  5] auth/auth.c:384(load_auth_module)
  load_auth_module: Attempting to find an auth method to match guest
[2010/11/07 20:24:06.503095,  5] auth/auth.c:409(load_auth_module)
  load_auth_module: auth method guest has a valid init
[2010/11/07 20:24:06.503157,  5] auth/auth.c:384(load_auth_module)
  load_auth_module: Attempting to find an auth method to match sam
[2010/11/07 20:24:06.503221,  5] auth/auth.c:409(load_auth_module)
  load_auth_module: auth method sam has a valid init
[2010/11/07 20:24:06.503310,  3] ../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0xa2088207
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_NEGOTIATE_OEM
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_VERSION
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_56
[2010/11/07 20:24:06.503584,  5] auth/auth.c:98(get_ntlm_challenge)
  auth_get_challenge: module guest did not want to specify a challenge
[2010/11/07 20:24:06.503646,  5] auth/auth.c:98(get_ntlm_challenge)
  auth_get_challenge: module sam did not want to specify a challenge
[2010/11/07 20:24:06.503733,  5] auth/auth.c:133(get_ntlm_challenge)
  auth_context challenge created by random
[2010/11/07 20:24:06.503795,  5] auth/auth.c:134(get_ntlm_challenge)
  challenge is: 
[2010/11/07 20:24:06.503856,  5] ../lib/util/util.c:415(dump_data)
  [0000] 54 16 75 63 61 C9 A5 84                            T.uca... 
[2010/11/07 20:24:06.504065,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.504125,  5] lib/util.c:589(show_msg)
  size=390
  smb_com=0x73
  smb_rcls=22
  smb_reh=0
  smb_err=49152
  smb_flg=136
  smb_flg2=51203
  smb_tid=0
  smb_pid=65279
  smb_uid=100
  smb_mid=45184
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=  253 (0xFD)
  smb_bcc=347
[2010/11/07 20:24:06.663959,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x154
[2010/11/07 20:24:06.664037,  3] smbd/process.c:1663(process_smb)
  Transaction 2 of length 344 (0 toread)
[2010/11/07 20:24:06.664101,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.664138,  5] lib/util.c:589(show_msg)
  size=340
  smb_com=0x73
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=0
  smb_pid=65279
  smb_uid=100
  smb_mid=45248
  smt_wct=12
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=  340 (0x154)
  smb_vwv[ 2]=16644 (0x4104)
  smb_vwv[ 3]=   50 (0x32)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=  178 (0xB2)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=  212 (0xD4)
  smb_vwv[11]=40960 (0xA000)
  smb_bcc=281
[2010/11/07 20:24:06.664746,  3] smbd/process.c:1468(switch_message)
  switch message SMBsesssetupX (pid 11448) conn 0x0
[2010/11/07 20:24:06.664810,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.664874,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.664934,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.665030,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.665095,  3] smbd/sesssetup.c:1304(reply_sesssetup_and_X)
  wct=12 flg2=0xc807
[2010/11/07 20:24:06.665157,  2] smbd/sesssetup.c:1250(setup_new_vc_session)
  setup_new_vc_session: New VC == 0, if NT4.x compatible we would close all old resources.
[2010/11/07 20:24:06.665218,  3] smbd/sesssetup.c:1040(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2010/11/07 20:24:06.665292,  3] smbd/sesssetup.c:1082(reply_sesssetup_and_X_spnego)
  NativeOS=[Windows 2002 Service Pack 3 2600] NativeLanMan=[Windows 2002 5.1] PrimaryDomain=[]
[2010/11/07 20:24:06.665381,  3] ../libcli/auth/ntlmssp_server.c:339(ntlmssp_server_preauth)
  Got user=[root] domain=[MAISON] workstation=[PC-INFO1] len1=24 len2=24
[2010/11/07 20:24:06.665458,  5] auth/auth_ntlmssp.c:97(auth_ntlmssp_set_challenge)
  auth_context challenge set by NTLMSSP callback (NTLM2)
[2010/11/07 20:24:06.665522,  5] auth/auth_ntlmssp.c:98(auth_ntlmssp_set_challenge)
  challenge is: 
[2010/11/07 20:24:06.665582,  5] ../lib/util/util.c:415(dump_data)
  [0000] 3A 03 11 CB E1 3C DB B0                            :....<.. 
[2010/11/07 20:24:06.665748,  6] param/loadparm.c:7323(lp_file_list_changed)
  lp_file_list_changed()
  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Sun Nov  7 20:21:51 2010
  
[2010/11/07 20:24:06.665901,  5] auth/auth_util.c:104(make_user_info_map)
  Mapping user [MAISON]\[root] from workstation [PC-INFO1]
[2010/11/07 20:24:06.665972,  5] auth/auth_util.c:125(make_user_info_map)
  Mapped domain from [MAISON] to [LP-INFO2] for user [root] from workstation [PC-INFO1]
[2010/11/07 20:24:06.666035,  5] auth/user_info.c:58(make_user_info)
  attempting to make a user_info for root (root)
[2010/11/07 20:24:06.666098,  5] auth/user_info.c:69(make_user_info)
  making strings for root's user_info struct
[2010/11/07 20:24:06.666163,  5] auth/user_info.c:86(make_user_info)
  making blobs for root's user_info struct
[2010/11/07 20:24:06.666225,  3] auth/auth.c:218(check_ntlm_password)
  check_ntlm_password:  Checking password for unmapped user [MAISON]\[root]@[PC-INFO1] with the new password interface
[2010/11/07 20:24:06.666289,  3] auth/auth.c:221(check_ntlm_password)
  check_ntlm_password:  mapped user is: [LP-INFO2]\[root]@[PC-INFO1]
[2010/11/07 20:24:06.666373,  5] ../lib/util/util.c:415(dump_data)
  [0000] 3A 03 11 CB E1 3C DB B0                            :....<.. 
[2010/11/07 20:24:06.666491,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.666559,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.666621,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.666683,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.666744,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.666924,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:24:06.667005,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:24:06.667082,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.667147,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.667210,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.667272,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.667332,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.667470,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.667560,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.667624,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.667687,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.667749,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.667811,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.667951,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.668020,  2] passdb/pdb_interface.c:266(pdb_try_account_unlock)
  pdb_try_account_unlock: Account root administratively locked out with no bad password time. Leaving locked out.
[2010/11/07 20:24:06.668094,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.668159,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.668221,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.668284,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.668344,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.668454,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.668527,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:24:06.668589,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:24:06.668656,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:24:06.668761,  3] passdb/lookup_sid.c:1635(get_primary_group_sid)
  Forcing Primary Group to 'Domain Users' for root
[2010/11/07 20:24:06.668832,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.668897,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.668959,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.669040,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.669103,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.669213,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.669296,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:24:06.669374,  4] lib/substitute.c:520(automount_server)
  Home server: lp-info2
[2010/11/07 20:24:06.669451,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.669517,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.669580,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.669643,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.669704,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.669815,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.669907,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.669979,  4] ../libcli/auth/ntlm_check.c:399(ntlm_password_check)
  ntlm_password_check: Checking NT MD4 password
[2010/11/07 20:24:06.670214,  4] auth/check_samsec.c:181(sam_account_ok)
  sam_account_ok: Checking SMB password for user root
[2010/11/07 20:24:06.670294,  5] auth/check_samsec.c:163(logon_hours_ok)
  logon_hours_ok: user root allowed to logon at this time (Sun Nov  7 19:24:06 2010
  )
[2010/11/07 20:24:06.670379,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.670443,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.670504,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.670567,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.670627,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.670735,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.670804,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.670867,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.670929,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.670992,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.671053,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.671148,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:24:06.671211,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:24:06.671275,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:24:06.671345,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.671408,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.671471,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.671533,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.671594,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.671722,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.671790,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.671854,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.671936,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.672002,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.672063,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.672173,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.672244,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:24:06.672306,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:24:06.672371,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:24:06.672599,  5] auth/server_info_sam.c:120(make_server_info_sam)
  make_server_info_sam: made server info for user root -> root
[2010/11/07 20:24:06.672675,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.672747,  3] auth/auth.c:267(check_ntlm_password)
  check_ntlm_password: sam authentication for user [root] succeeded
[2010/11/07 20:24:06.672812,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.672876,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.672938,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.673000,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.673061,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.673162,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.673227,  5] auth/auth.c:295(check_ntlm_password)
  check_ntlm_password:  PAM Account for user [root] succeeded
[2010/11/07 20:24:06.673289,  2] auth/auth.c:308(check_ntlm_password)
  check_ntlm_password:  authentication for user [root] -> [root] -> [root] succeeded
[2010/11/07 20:24:06.673492,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.673560,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.673623,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.673687,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.673748,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.673872,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.673940,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.674005,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.674068,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.674131,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.674192,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.674312,  3] auth/token_util.c:564(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:24:06.674387,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.674522,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.674589,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.674652,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.674714,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.674776,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.674896,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.674963,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.675027,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.675089,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.675152,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.675214,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.675320,  3] auth/token_util.c:595(finalize_local_nt_token)
  Failed to fetch domain sid for MAISON
[2010/11/07 20:24:06.675391,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.675457,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.675520,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.675582,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.675645,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.675706,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.675977,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.676064,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-1000]
[2010/11/07 20:24:06.676155,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-21-3336553926-1907721212-1242502600-513]
[2010/11/07 20:24:06.676238,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-22-2-0]
[2010/11/07 20:24:06.676318,  5] lib/privileges.c:173(get_privileges_for_sids)
  get_privileges_for_sids: sid = S-1-1-0
  Privilege set: 0x0
[2010/11/07 20:24:06.676394,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-2]
[2010/11/07 20:24:06.676437,  3] lib/privileges.c:95(get_privileges)
  get_privileges: No privileges assigned to SID [S-1-5-11]
[2010/11/07 20:24:06.676509,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.676545,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.676580,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.676615,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.676649,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.676702,  5] passdb/pdb_interface.c:1596(lookup_global_sam_rid)
  lookup_global_sam_rid: looking up RID 513.
[2010/11/07 20:24:06.676739,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.676774,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
[2010/11/07 20:24:06.676819,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
[2010/11/07 20:24:06.676854,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.676888,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.676951,  5] passdb/pdb_tdb.c:611(tdbsam_getsampwrid)
  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
[2010/11/07 20:24:06.677003,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677039,  5] passdb/pdb_interface.c:1658(lookup_global_sam_rid)
  Can't find a unix id for an unmapped group
[2010/11/07 20:24:06.677079,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677142,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677178,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677213,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677247,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.677281,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.677345,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677407,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677443,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677478,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677512,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.677546,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.677610,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677672,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677708,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677743,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:06.677778,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.677812,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.677877,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.677934,  3] ../libcli/auth/ntlmssp_sign.c:396(ntlmssp_sign_init)
  NTLMSSP Sign/Seal - Initialising with flags:
[2010/11/07 20:24:06.677971,  3] ../libcli/auth/ntlmssp.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0xa2088205
    NTLMSSP_NEGOTIATE_UNICODE
    NTLMSSP_REQUEST_TARGET
    NTLMSSP_NEGOTIATE_NTLM
    NTLMSSP_NEGOTIATE_ALWAYS_SIGN
    NTLMSSP_NEGOTIATE_NTLM2
    NTLMSSP_NEGOTIATE_VERSION
    NTLMSSP_NEGOTIATE_128
    NTLMSSP_NEGOTIATE_56
[2010/11/07 20:24:06.678127,  3] smbd/password.c:294(register_existing_vuid)
  register_existing_vuid: User name: root	Real name: root
[2010/11/07 20:24:06.678165,  3] smbd/password.c:304(register_existing_vuid)
  register_existing_vuid: UNIX uid 0 is UNIX user root, and will be vuid 100
[2010/11/07 20:24:06.678555,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:24:06.678595,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:24:06.678632,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:24:06.678673,  3] smbd/password.c:235(register_homes_share)
  Adding homes service for user 'root' using home directory: '/root'
[2010/11/07 20:24:06.678745,  6] param/loadparm.c:7323(lp_file_list_changed)
  lp_file_list_changed()
  file /usr/local/samba/lib/smb.conf -> /usr/local/samba/lib/smb.conf  last mod_time: Sun Nov  7 20:21:51 2010
  
[2010/11/07 20:24:06.678837,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.678859,  5] lib/util.c:589(show_msg)
  size=146
  smb_com=0x73
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51203
  smb_tid=0
  smb_pid=65279
  smb_uid=100
  smb_mid=45248
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    9 (0x9)
  smb_bcc=103
[2010/11/07 20:24:06.733856,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x56
[2010/11/07 20:24:06.733932,  3] smbd/process.c:1663(process_smb)
  Transaction 3 of length 90 (0 toread)
[2010/11/07 20:24:06.733996,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.734032,  5] lib/util.c:589(show_msg)
  size=86
  smb_com=0x75
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=0
  smb_pid=65279
  smb_uid=100
  smb_mid=45312
  smt_wct=4
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=   86 (0x56)
  smb_vwv[ 2]=    8 (0x8)
  smb_vwv[ 3]=    1 (0x1)
  smb_bcc=43
[2010/11/07 20:24:06.734445,  3] smbd/process.c:1468(switch_message)
  switch message SMBtconX (pid 11448) conn 0x0
[2010/11/07 20:24:06.734508,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.734571,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.734631,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.734725,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.734804,  4] smbd/reply.c:786(reply_tcon_and_X)
  Client requested device type [?????] for share [IPC$]
[2010/11/07 20:24:06.734897,  5] smbd/service.c:1216(make_connection)
  making a connection to 'normal' service ipc$
[2010/11/07 20:24:06.734966,  3] lib/access.c:334(allow_access)
  Allowed connection from ::ffff:172.19.123.123 (::ffff:172.19.123.123)
[2010/11/07 20:24:06.735061,  5] lib/username.c:205(Get_Pwnam_alloc)
  Finding user root
[2010/11/07 20:24:06.735123,  5] lib/username.c:149(Get_Pwnam_internals)
  Trying _Get_Pwnam(), username as lowercase is root
[2010/11/07 20:24:06.735188,  5] lib/username.c:182(Get_Pwnam_internals)
  Get_Pwnam_internals did find user [root]!
[2010/11/07 20:24:06.735262,  3] smbd/service.c:802(make_connection_snum)
  Connect path is '/tmp' for service [IPC$]
[2010/11/07 20:24:06.735370,  3] smbd/vfs.c:98(vfs_init_default)
  Initialising default vfs hooks
[2010/11/07 20:24:06.735441,  5] smbd/vfs.c:88(smb_register_vfs)
  Successfully added vfs backend '/[Default VFS]/'
[2010/11/07 20:24:06.735502,  3] smbd/vfs.c:124(vfs_init_custom)
  Initialising custom vfs hooks from [/[Default VFS]/]
  Successfully loaded vfs module [/[Default VFS]/] with the new modules system
[2010/11/07 20:24:06.735597,  5] smbd/connection.c:132(claim_connection)
  claiming [IPC$]
[2010/11/07 20:24:06.736146,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.736222,  5] ../libcli/security/security_token.c:63(security_token_debug)
  Security token SIDs (7):
    SID[  0]: S-1-5-21-3336553926-1907721212-1242502600-1000
    SID[  1]: S-1-5-21-3336553926-1907721212-1242502600-513
    SID[  2]: S-1-22-2-0
    SID[  3]: S-1-1-0
    SID[  4]: S-1-5-2
    SID[  5]: S-1-5-11
    SID[  6]: S-1-22-1-0
   Privileges (0x               0):
   Rights (0x               0):
[2010/11/07 20:24:06.736530,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 1 supplementary groups
  Group[  0]: 0
[2010/11/07 20:24:06.736673,  5] smbd/uid.c:354(change_to_user)
  change_to_user uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.736742,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.736804,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:06.736865,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:06.736959,  5] smbd/uid.c:369(change_to_root_user)
  change_to_root_user: now uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.737049,  3] smbd/service.c:1052(make_connection_snum)
  pc-info1 (::ffff:172.19.123.123) connect to service IPC$ initially as user root (uid=0, gid=0) (pid 11448)
[2010/11/07 20:24:06.737129,  3] smbd/reply.c:865(reply_tcon_and_X)
  tconX service=IPC$ 
[2010/11/07 20:24:06.792771,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x66
[2010/11/07 20:24:06.792850,  3] smbd/process.c:1663(process_smb)
  Transaction 4 of length 106 (0 toread)
[2010/11/07 20:24:06.792913,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.792949,  5] lib/util.c:589(show_msg)
  size=102
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45376
  smt_wct=24
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=57054 (0xDEDE)
  smb_vwv[ 2]= 4096 (0x1000)
  smb_vwv[ 3]= 5632 (0x1600)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=40704 (0x9F00)
  smb_vwv[ 8]=  513 (0x201)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    0 (0x0)
  smb_vwv[14]=    0 (0x0)
  smb_vwv[15]=  768 (0x300)
  smb_vwv[16]=    0 (0x0)
  smb_vwv[17]=  256 (0x100)
  smb_vwv[18]=    0 (0x0)
  smb_vwv[19]=16384 (0x4000)
  smb_vwv[20]=16384 (0x4000)
  smb_vwv[21]=  512 (0x200)
  smb_vwv[22]=    0 (0x0)
  smb_vwv[23]=  768 (0x300)
  smb_bcc=19
[2010/11/07 20:24:06.793840,  3] smbd/process.c:1468(switch_message)
  switch message SMBntcreateX (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:06.793907,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:06.793973,  5] ../libcli/security/security_token.c:63(security_token_debug)
  Security token SIDs (7):
    SID[  0]: S-1-5-21-3336553926-1907721212-1242502600-1000
    SID[  1]: S-1-5-21-3336553926-1907721212-1242502600-513
    SID[  2]: S-1-22-2-0
    SID[  3]: S-1-1-0
    SID[  4]: S-1-5-2
    SID[  5]: S-1-5-11
    SID[  6]: S-1-22-1-0
   Privileges (0x               0):
   Rights (0x               0):
[2010/11/07 20:24:06.794279,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 1 supplementary groups
  Group[  0]: 0
[2010/11/07 20:24:06.794403,  5] smbd/uid.c:354(change_to_user)
  change_to_user uid=(0,0) gid=(0,0)
[2010/11/07 20:24:06.794475,  4] smbd/vfs.c:723(vfs_ChDir)
  vfs_ChDir to /tmp
[2010/11/07 20:24:06.794567,  4] smbd/nttrans.c:286(nt_open_pipe)
  nt_open_pipe: Opening pipe \spoolss.
[2010/11/07 20:24:06.794641,  5] smbd/files.c:124(file_new)
  allocated file structure 4206, fnum = 8302 (1 used)
[2010/11/07 20:24:06.794735,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \spoolss
[2010/11/07 20:24:06.794849,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \spoolss (pipes_open=0)
[2010/11/07 20:24:06.794916,  5] smbd/nttrans.c:375(do_ntcreate_pipe_open)
  do_ntcreate_pipe_open: open pipe = \spoolss
[2010/11/07 20:24:06.853473,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x88
[2010/11/07 20:24:06.853545,  3] smbd/process.c:1663(process_smb)
  Transaction 5 of length 140 (0 toread)
[2010/11/07 20:24:06.853608,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.853644,  5] lib/util.c:589(show_msg)
  size=136
  smb_com=0x2f
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=100
  smb_mid=45440
  smt_wct=14
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=57054 (0xDEDE)
  smb_vwv[ 2]= 8302 (0x206E)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=65535 (0xFFFF)
  smb_vwv[ 6]=65535 (0xFFFF)
  smb_vwv[ 7]=    8 (0x8)
  smb_vwv[ 8]=   72 (0x48)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=   72 (0x48)
  smb_vwv[11]=   64 (0x40)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    0 (0x0)
  smb_bcc=73
[2010/11/07 20:24:06.854313,  3] smbd/process.c:1468(switch_message)
  switch message SMBwriteX (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:06.854376,  4] smbd/uid.c:261(change_to_user)
  change_to_user: Skipping user change - already user
[2010/11/07 20:24:06.854448,  6] smbd/pipes.c:298(reply_pipe_write_and_X)
  reply_pipe_write_and_X: 206e name: spoolss len: 72
[2010/11/07 20:24:06.854516,  6] rpc_server/srv_pipe_hnd.c:519(np_write_send)
  np_write_send: len: 72
[2010/11/07 20:24:06.854623,  3] rpc_server/srv_pipe.c:908(api_pipe_bind_req)
  api_pipe_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2010/11/07 20:24:06.854694,  5] rpc_server/srv_pipe.c:942(api_pipe_bind_req)
  api_pipe_bind_req: make response. 942
[2010/11/07 20:24:06.854759,  3] rpc_server/srv_pipe.c:334(check_bind_req)
  check_bind_req for \spoolss
[2010/11/07 20:24:06.854826,  3] rpc_server/srv_pipe.c:341(check_bind_req)
  check_bind_req: \PIPE\spoolss -> \PIPE\spoolss
[2010/11/07 20:24:06.854937,  3] smbd/pipes.c:359(pipe_write_andx_done)
  writeX-IPC nwritten=72
[2010/11/07 20:24:06.905830,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x3b
[2010/11/07 20:24:06.905899,  3] smbd/process.c:1663(process_smb)
  Transaction 6 of length 63 (0 toread)
[2010/11/07 20:24:06.905962,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.905998,  5] lib/util.c:589(show_msg)
  size=59
  smb_com=0x2e
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=100
  smb_mid=45504
  smt_wct=12
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=57054 (0xDEDE)
  smb_vwv[ 2]= 8302 (0x206E)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]= 1024 (0x400)
  smb_vwv[ 6]= 1024 (0x400)
  smb_vwv[ 7]=65535 (0xFFFF)
  smb_vwv[ 8]=65535 (0xFFFF)
  smb_vwv[ 9]= 1024 (0x400)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    0 (0x0)
  smb_bcc=0
[2010/11/07 20:24:06.906608,  3] smbd/process.c:1468(switch_message)
  switch message SMBreadX (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:06.906672,  4] smbd/uid.c:261(change_to_user)
  change_to_user: Skipping user change - already user
[2010/11/07 20:24:06.906741,  6] rpc_server/srv_pipe_hnd.c:283(read_from_internal_pipe)
   name: \spoolss len: 1024
[2010/11/07 20:24:06.906809,  3] rpc_server/srv_pipe_hnd.c:120(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 25
[2010/11/07 20:24:06.906891,  3] smbd/pipes.c:483(pipe_read_andx_done)
  readX-IPC min=1024 max=1024 nread=68
[2010/11/07 20:24:06.976827,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x10c
[2010/11/07 20:24:06.976900,  3] smbd/process.c:1663(process_smb)
  Transaction 7 of length 272 (0 toread)
[2010/11/07 20:24:06.976963,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.976999,  5] lib/util.c:589(show_msg)
  size=268
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45568
  smt_wct=16
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=  184 (0xB8)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]= 1024 (0x400)
  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]=   84 (0x54)
  smb_vwv[11]=  184 (0xB8)
  smb_vwv[12]=   84 (0x54)
  smb_vwv[13]=    2 (0x2)
  smb_vwv[14]=   38 (0x26)
  smb_vwv[15]= 8302 (0x206E)
  smb_bcc=201
[2010/11/07 20:24:06.977702,  3] smbd/process.c:1468(switch_message)
  switch message SMBtrans (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:06.977766,  4] smbd/uid.c:261(change_to_user)
  change_to_user: Skipping user change - already user
[2010/11/07 20:24:06.977860,  3] smbd/ipc.c:557(handle_trans)
  trans <\PIPE\> data=184 params=0 setup=2
[2010/11/07 20:24:06.977930,  5] smbd/ipc.c:590(handle_trans)
  calling named_pipe
[2010/11/07 20:24:06.977989,  3] smbd/ipc.c:508(named_pipe)
  named pipe command on <> name
[2010/11/07 20:24:06.978055,  5] smbd/ipc.c:431(api_fd_reply)
  api_fd_reply
[2010/11/07 20:24:06.978116,  3] smbd/ipc.c:472(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 206e)
[2010/11/07 20:24:06.978180,  6] rpc_server/srv_pipe_hnd.c:519(np_write_send)
  np_write_send: len: 184
[2010/11/07 20:24:06.978260,  5] rpc_server/srv_pipe.c:1566(api_pipe_request)
  Requested \PIPE\\spoolss
[2010/11/07 20:24:06.978327,  4] rpc_server/srv_pipe.c:1606(api_rpcTNP)
  api_rpcTNP: \spoolss op 0x45 - api_rpcTNP: rpc command: SPOOLSS_OPENPRINTEREX
[2010/11/07 20:24:06.978399,  6] rpc_server/srv_pipe.c:1640(api_rpcTNP)
  api_rpc_cmds[69].fn == 0xb7178f89
  checking name: \\10.242.2.6
[2010/11/07 20:24:06.978542,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[1] [0000] 00 00 00 00 01 00 00 00   00 00 00 00 D6 4C D6 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:06.978704,  3] rpc_server/srv_spoolss_nt.c:502(set_printer_hnd_printertype)
  Setting printer type=\\10.242.2.6
  Printer is a print server
[2010/11/07 20:24:06.978792,  4] rpc_server/srv_spoolss_nt.c:562(set_printer_hnd_name)
  Setting printer name=\\10.242.2.6 (len=12)
[2010/11/07 20:24:06.979087,  5] rpc_server/srv_spoolss_nt.c:769(open_printer_hnd)
  1 printer handles active
[2010/11/07 20:24:06.979156,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 01 00 00 00   00 00 00 00 D6 4C D6 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:06.979313,  4] rpc_server/srv_spoolss_nt.c:1813(_spoolss_OpenPrinterEx)
  Setting print server access = SERVER_ACCESS_ADMINISTER
[2010/11/07 20:24:06.979388,  5] rpc_server/srv_pipe.c:1674(api_rpcTNP)
  api_rpcTNP: called \spoolss successfully
[2010/11/07 20:24:06.979477,  6] rpc_server/srv_pipe_hnd.c:283(read_from_internal_pipe)
   name: \spoolss len: 1024
[2010/11/07 20:24:06.979557,  3] rpc_server/srv_pipe_hnd.c:120(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2010/11/07 20:24:06.979636,  5] smbd/ipc.c:59(copy_trans_params_and_data)
  copy_trans_params_and_data: params[0..0] data[0..48] (align 0)
[2010/11/07 20:24:06.979700,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:06.979737,  5] lib/util.c:589(show_msg)
  size=104
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51203
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45568
  smt_wct=10
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   48 (0x30)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   48 (0x30)
  smb_vwv[ 7]=   56 (0x38)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=49
[2010/11/07 20:24:07.039960,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0xc0
[2010/11/07 20:24:07.040118,  3] smbd/process.c:1663(process_smb)
  Transaction 8 of length 196 (0 toread)
[2010/11/07 20:24:07.040182,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:07.040218,  5] lib/util.c:589(show_msg)
  size=192
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45632
  smt_wct=16
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=  108 (0x6C)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]= 1024 (0x400)
  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]=   84 (0x54)
  smb_vwv[11]=  108 (0x6C)
  smb_vwv[12]=   84 (0x54)
  smb_vwv[13]=    2 (0x2)
  smb_vwv[14]=   38 (0x26)
  smb_vwv[15]= 8302 (0x206E)
  smb_bcc=125
[2010/11/07 20:24:07.040917,  3] smbd/process.c:1468(switch_message)
  switch message SMBtrans (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:07.041016,  4] smbd/uid.c:261(change_to_user)
  change_to_user: Skipping user change - already user
[2010/11/07 20:24:07.041091,  3] smbd/ipc.c:557(handle_trans)
  trans <\PIPE\> data=108 params=0 setup=2
[2010/11/07 20:24:07.041161,  5] smbd/ipc.c:590(handle_trans)
  calling named_pipe
[2010/11/07 20:24:07.041221,  3] smbd/ipc.c:508(named_pipe)
  named pipe command on <> name
[2010/11/07 20:24:07.041283,  5] smbd/ipc.c:431(api_fd_reply)
  api_fd_reply
[2010/11/07 20:24:07.041344,  3] smbd/ipc.c:472(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 206e)
[2010/11/07 20:24:07.041411,  6] rpc_server/srv_pipe_hnd.c:519(np_write_send)
  np_write_send: len: 108
[2010/11/07 20:24:07.041498,  5] rpc_server/srv_pipe.c:1566(api_pipe_request)
  Requested \PIPE\\spoolss
[2010/11/07 20:24:07.041569,  4] rpc_server/srv_pipe.c:1606(api_rpcTNP)
  api_rpcTNP: \spoolss op 0x1a - api_rpcTNP: rpc command: SPOOLSS_GETPRINTERDATA
[2010/11/07 20:24:07.041645,  6] rpc_server/srv_pipe.c:1640(api_rpcTNP)
  api_rpc_cmds[26].fn == 0xb717f3ff
[2010/11/07 20:24:07.041726,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 01 00 00 00   00 00 00 00 D6 4C D6 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:07.041893,  4] rpc_server/srv_spoolss_nt.c:9125(_spoolss_GetPrinterDataEx)
  _spoolss_GetPrinterDataEx
[2010/11/07 20:24:07.041973,  5] rpc_server/srv_pipe.c:1674(api_rpcTNP)
  api_rpcTNP: called \spoolss successfully
[2010/11/07 20:24:07.042065,  6] rpc_server/srv_pipe_hnd.c:283(read_from_internal_pipe)
   name: \spoolss len: 1024
[2010/11/07 20:24:07.042145,  3] rpc_server/srv_pipe_hnd.c:120(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2010/11/07 20:24:07.042224,  5] smbd/ipc.c:59(copy_trans_params_and_data)
  copy_trans_params_and_data: params[0..0] data[0..44] (align 0)
[2010/11/07 20:24:07.042288,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:07.042324,  5] lib/util.c:589(show_msg)
  size=100
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51203
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45632
  smt_wct=10
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   44 (0x2C)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   44 (0x2C)
  smb_vwv[ 7]=   56 (0x38)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=45
[2010/11/07 20:24:07.106703,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0xf6
[2010/11/07 20:24:07.106772,  3] smbd/process.c:1663(process_smb)
  Transaction 9 of length 250 (0 toread)
[2010/11/07 20:24:07.106835,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:07.106870,  5] lib/util.c:589(show_msg)
  size=246
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45696
  smt_wct=16
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=  162 (0xA2)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]= 1024 (0x400)
  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]=   84 (0x54)
  smb_vwv[11]=  162 (0xA2)
  smb_vwv[12]=   84 (0x54)
  smb_vwv[13]=    2 (0x2)
  smb_vwv[14]=   38 (0x26)
  smb_vwv[15]= 8302 (0x206E)
  smb_bcc=179
[2010/11/07 20:24:07.107566,  3] smbd/process.c:1468(switch_message)
  switch message SMBtrans (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:07.107629,  4] smbd/uid.c:261(change_to_user)
  change_to_user: Skipping user change - already user
[2010/11/07 20:24:07.107697,  3] smbd/ipc.c:557(handle_trans)
  trans <\PIPE\> data=162 params=0 setup=2
[2010/11/07 20:24:07.107764,  5] smbd/ipc.c:590(handle_trans)
  calling named_pipe
[2010/11/07 20:24:07.107825,  3] smbd/ipc.c:508(named_pipe)
  named pipe command on <> name
[2010/11/07 20:24:07.107887,  5] smbd/ipc.c:431(api_fd_reply)
  api_fd_reply
[2010/11/07 20:24:07.108003,  3] smbd/ipc.c:472(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 206e)
[2010/11/07 20:24:07.108070,  6] rpc_server/srv_pipe_hnd.c:519(np_write_send)
  np_write_send: len: 162
[2010/11/07 20:24:07.108147,  5] rpc_server/srv_pipe.c:1566(api_pipe_request)
  Requested \PIPE\\spoolss
[2010/11/07 20:24:07.108214,  4] rpc_server/srv_pipe.c:1606(api_rpcTNP)
  api_rpcTNP: \spoolss op 0x41 - api_rpcTNP: rpc command: SPOOLSS_REMOTEFINDFIRSTPRINTERCHANGENOTIFYEX
[2010/11/07 20:24:07.108286,  6] rpc_server/srv_pipe.c:1640(api_rpcTNP)
  api_rpc_cmds[65].fn == 0xb717992b
[2010/11/07 20:24:07.108369,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 01 00 00 00   00 00 00 00 D6 4C D6 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:07.108640,  5] rpc_server/srv_spoolss_nt.c:2487(spoolss_connect_to_client)
  spoolss_connect_to_client: Using address ::ffff:172.19.123.123 (no name resolution necessary)
[2010/11/07 20:24:07.108899,  3] libsmb/cliconnect.c:2566(cli_start_connection)
  Connecting to host=PC-INFO1
[2010/11/07 20:24:07.109022,  3] lib/util_sock.c:894(open_socket_out_send)
  Connecting to ::ffff:172.19.123.123 at port 445
[2010/11/07 20:24:07.112153,  3] lib/util_sock.c:894(open_socket_out_send)
  Connecting to ::ffff:172.19.123.123 at port 139
[2010/11/07 20:24:07.152312,  5] lib/util_sock.c:314(print_socket_options)
  Socket options:
  	SO_KEEPALIVE = 0
  	SO_REUSEADDR = 0
  	SO_BROADCAST = 0
  	TCP_NODELAY = 1
  	TCP_KEEPCNT = 9
  	TCP_KEEPIDLE = 7200
  	TCP_KEEPINTVL = 75
  	IPTOS_LOWDELAY = 16
  	IPTOS_THROUGHPUT = 16
  	SO_SNDBUF = 65536
  	SO_RCVBUF = 65536
  	SO_SNDLOWAT = 1
  	SO_RCVLOWAT = 1
  	SO_SNDTIMEO = 0
  	SO_RCVTIMEO = 0
  	TCP_QUICKACK = 1
[2010/11/07 20:24:07.621676,  5] rpc_client/cli_pipe.c:1645(rpc_pipe_bind_send)
  Bind RPC Pipe: host PC-INFO1 auth_type 0, auth_level 1
[2010/11/07 20:24:07.621817,  5] rpc_client/cli_pipe.c:740(rpc_api_pipe_send)
  rpc_api_pipe: host PC-INFO1
[2010/11/07 20:24:07.752214,  5] rpc_client/cli_pipe.c:95(rpc_read_send)
  rpc_read_send: data_to_read: 52
[2010/11/07 20:24:07.872493,  5] rpc_client/cli_pipe.c:1517(check_bind_response)
  check_bind_response: accepted!
[2010/11/07 20:24:07.873260,  5] rpc_client/cli_pipe.c:740(rpc_api_pipe_send)
  rpc_api_pipe: host PC-INFO1
[2010/11/07 20:24:07.933549,  5] rpc_client/cli_pipe.c:95(rpc_read_send)
  rpc_read_send: data_to_read: 32
[2010/11/07 20:24:07.987451,  5] rpc_server/srv_pipe.c:1674(api_rpcTNP)
  api_rpcTNP: called \spoolss successfully
[2010/11/07 20:24:07.987593,  6] rpc_server/srv_pipe_hnd.c:283(read_from_internal_pipe)
   name: \spoolss len: 1024
[2010/11/07 20:24:07.987675,  3] rpc_server/srv_pipe_hnd.c:120(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 0
[2010/11/07 20:24:07.987763,  5] smbd/ipc.c:59(copy_trans_params_and_data)
  copy_trans_params_and_data: params[0..0] data[0..28] (align 0)
[2010/11/07 20:24:07.987833,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:07.987871,  5] lib/util.c:589(show_msg)
  size=84
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51203
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45696
  smt_wct=10
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   28 (0x1C)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=   28 (0x1C)
  smb_vwv[ 7]=   56 (0x38)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=29
[2010/11/07 20:24:08.041221,  6] smbd/process.c:1661(process_smb)
  got message type 0x0 of len 0x98
[2010/11/07 20:24:08.041332,  3] smbd/process.c:1663(process_smb)
  Transaction 10 of length 156 (0 toread)
[2010/11/07 20:24:08.041369,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:08.041390,  5] lib/util.c:589(show_msg)
  size=152
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45760
  smt_wct=16
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=   68 (0x44)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]= 1024 (0x400)
  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]=   84 (0x54)
  smb_vwv[11]=   68 (0x44)
  smb_vwv[12]=   84 (0x54)
  smb_vwv[13]=    2 (0x2)
  smb_vwv[14]=   38 (0x26)
  smb_vwv[15]= 8302 (0x206E)
  smb_bcc=85
[2010/11/07 20:24:08.041799,  3] smbd/process.c:1468(switch_message)
  switch message SMBtrans (pid 11448) conn 0xb7d7a1a0
[2010/11/07 20:24:08.041837,  4] smbd/uid.c:261(change_to_user)
  change_to_user: Skipping user change - already user
[2010/11/07 20:24:08.041882,  3] smbd/ipc.c:557(handle_trans)
  trans <\PIPE\> data=68 params=0 setup=2
[2010/11/07 20:24:08.041921,  5] smbd/ipc.c:590(handle_trans)
  calling named_pipe
[2010/11/07 20:24:08.041954,  3] smbd/ipc.c:508(named_pipe)
  named pipe command on <> name
[2010/11/07 20:24:08.041989,  5] smbd/ipc.c:431(api_fd_reply)
  api_fd_reply
[2010/11/07 20:24:08.042023,  3] smbd/ipc.c:472(api_fd_reply)
  Got API command 0x26 on pipe "spoolss" (pnum 206e)
[2010/11/07 20:24:08.042061,  6] rpc_server/srv_pipe_hnd.c:519(np_write_send)
  np_write_send: len: 68
[2010/11/07 20:24:08.042115,  5] rpc_server/srv_pipe.c:1566(api_pipe_request)
  Requested \PIPE\\spoolss
[2010/11/07 20:24:08.042155,  4] rpc_server/srv_pipe.c:1606(api_rpcTNP)
  api_rpcTNP: \spoolss op 0x43 - api_rpcTNP: rpc command: SPOOLSS_ROUTERREFRESHPRINTERCHANGENOTIFY
[2010/11/07 20:24:08.042194,  6] rpc_server/srv_pipe.c:1640(api_rpcTNP)
  api_rpc_cmds[67].fn == 0xb7179433
[2010/11/07 20:24:08.042239,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 01 00 00 00   00 00 00 00 D6 4C D6 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.042330,  4] rpc_server/srv_spoolss_nt.c:3716(_spoolss_RouterRefreshPrinterChangeNotify)
  Printer type 2
[2010/11/07 20:24:08.042365,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 01 00 00 00   00 00 00 00 D6 4C D6 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.042452,  4] rpc_server/srv_spoolss_nt.c:3511(printserver_notify_info)
  printserver_notify_info
[2010/11/07 20:24:08.042508,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \winreg
[2010/11/07 20:24:08.042559,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \winreg (pipes_open=0)
[2010/11/07 20:24:08.042629,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.042670,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:08.042705,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.042741,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:08.042779,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:08.042913,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:08.042996,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[1] [0000] 00 00 00 00 02 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.043133,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 02 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.043456,  2] rpc_server/srv_spoolss_util.c:2025(winreg_get_printer)
  winreg_get_printer: Could not open key SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers\IMP-INFO2: WERR_BADFILE
[2010/11/07 20:24:08.043504,  4] rpc_server/srv_spoolss_nt.c:3550(printserver_notify_info)
  printserver_notify_info: Failed to get printer [IMP-INFO2]
[2010/11/07 20:24:08.043563,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \winreg
[2010/11/07 20:24:08.043610,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \winreg (pipes_open=0)
[2010/11/07 20:24:08.043655,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.043694,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:08.043729,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.043764,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:08.043798,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:08.043887,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:08.044024,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[1] [0000] 00 00 00 00 03 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.044226,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 03 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.044796,  2] rpc_server/srv_spoolss_util.c:2025(winreg_get_printer)
  winreg_get_printer: Could not open key SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers\IMP-INFO: WERR_BADFILE
[2010/11/07 20:24:08.044872,  4] rpc_server/srv_spoolss_nt.c:3550(printserver_notify_info)
  printserver_notify_info: Failed to get printer [IMP-INFO]
[2010/11/07 20:24:08.044956,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \winreg
[2010/11/07 20:24:08.045041,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \winreg (pipes_open=0)
[2010/11/07 20:24:08.045124,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.045194,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:08.045257,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.045320,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:08.045382,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:08.045548,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:08.045661,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[1] [0000] 00 00 00 00 04 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.045860,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 04 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.046434,  2] rpc_server/srv_spoolss_util.c:2025(winreg_get_printer)
  winreg_get_printer: Could not open key SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers\PDF: WERR_BADFILE
[2010/11/07 20:24:08.046510,  4] rpc_server/srv_spoolss_nt.c:3550(printserver_notify_info)
  printserver_notify_info: Failed to get printer [PDF]
[2010/11/07 20:24:08.046593,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \winreg
[2010/11/07 20:24:08.046678,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \winreg (pipes_open=0)
[2010/11/07 20:24:08.046761,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.046845,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:08.046909,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.046972,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:08.047033,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:08.047198,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:08.047312,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[1] [0000] 00 00 00 00 05 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.047516,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.048095,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[2] [0000] 00 00 00 00 06 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.048305,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.048660,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.048917,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.049103,  2] rpc_server/srv_spoolss_util.c:943(winreg_printer_query_binary)
  winreg_printer_query_binary: Could not query value Default DevMode: WERR_BADFILE
[2010/11/07 20:24:08.049191,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \winreg
[2010/11/07 20:24:08.049276,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \winreg (pipes_open=0)
[2010/11/07 20:24:08.049401,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[3] [0000] 00 00 00 00 07 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.049604,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.050127,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[4] [0000] 00 00 00 00 08 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.050338,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.050583,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.050825,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.051020,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 08 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.051185,  3] rpc_server/rpc_handles.c:278(close_policy_hnd)
  Closed policy
[2010/11/07 20:24:08.051276,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.051444,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 07 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.051608,  3] rpc_server/rpc_handles.c:278(close_policy_hnd)
  Closed policy
[2010/11/07 20:24:08.051708,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.051874,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 06 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.052055,  3] rpc_server/rpc_handles.c:278(close_policy_hnd)
  Closed policy
[2010/11/07 20:24:08.052147,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.052312,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 05 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.052476,  3] rpc_server/rpc_handles.c:278(close_policy_hnd)
  Closed policy
[2010/11/07 20:24:08.052580,  4] rpc_server/srv_spoolss_nt.c:3378(construct_notify_printer_info)
  construct_notify_printer_info: Notify type: [PRINTER_NOTIFY_TYPE], number of notify info: [9] on printer: [IMP-HOME1]
[2010/11/07 20:24:08.052660,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [0]: type [0], field [1]
[2010/11/07 20:24:08.052728,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [1]: type [0], field [14]
[2010/11/07 20:24:08.052890,  4] printing/printing.c:1280(print_cache_expired)
  print_cache_expired: cache expired for queue IMP-HOME1 (last_qscan_time = 1289157671, time now = 1289157848, qcachetime = 30)
[2010/11/07 20:24:08.053368,  5] printing/printing.c:1486(print_queue_update_with_lock)
  print_queue_update_with_lock: printer share = IMP-HOME1
[2010/11/07 20:24:08.053559,  4] printing/printing.c:1280(print_cache_expired)
  print_cache_expired: cache expired for queue IMP-HOME1 (last_qscan_time = 1289157671, time now = 1289157848, qcachetime = 30)
[2010/11/07 20:24:08.053695,  5] printing/printing.c:1112(set_updating_pid)
  set_updating_pid:  updating lpq cache for print share IMP-HOME1
[2010/11/07 20:24:08.053794,  5] printing/printing.c:1334(print_queue_update_internal)
  print_queue_update_internal: printer = IMP-HOME1, type = 8, lpq command = [IMP-HOME1]
[2010/11/07 20:24:08.053891,  5] printing/print_cups.c:1128(cups_queue_get)
  cups_queue_get(IMP-HOME1, 0xbfeb4e0c, 0xbfeb4ad0)
[2010/11/07 20:24:08.056001,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [2]: type [0], field [12]
[2010/11/07 20:24:08.056128,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [3]: type [0], field [5]
[2010/11/07 20:24:08.056219,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [4]: type [0], field [6]
[2010/11/07 20:24:08.056289,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [5]: type [0], field [4]
[2010/11/07 20:24:08.056365,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [6]: type [0], field [3]
[2010/11/07 20:24:08.056402,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [7]: type [0], field [c]
[2010/11/07 20:24:08.056443,  4] rpc_server/srv_spoolss_nt.c:3383(construct_notify_printer_info)
  construct_notify_printer_info: notify [8]: type [0], field [d]
[2010/11/07 20:24:08.056492,  4] rpc_server/rpc_ncacn_np.c:125(make_internal_rpc_pipe_p)
  Create pipe requested \winreg
[2010/11/07 20:24:08.056546,  4] rpc_server/rpc_ncacn_np.c:168(make_internal_rpc_pipe_p)
  Created internal pipe \winreg (pipes_open=0)
[2010/11/07 20:24:08.056598,  3] smbd/sec_ctx.c:211(push_sec_ctx)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.056638,  3] smbd/uid.c:429(push_conn_ctx)
  push_conn_ctx(100) : conn_ctx_stack_ndx = 0
[2010/11/07 20:24:08.056675,  3] smbd/sec_ctx.c:311(set_sec_ctx)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2010/11/07 20:24:08.056710,  5] ../libcli/security/security_token.c:53(security_token_debug)
  Security token: (NULL)
[2010/11/07 20:24:08.056745,  5] auth/token_util.c:653(debug_unix_user_token)
  UNIX token of user 0
  Primary group is 0 and contains 0 supplementary groups
[2010/11/07 20:24:08.056848,  3] smbd/sec_ctx.c:419(pop_sec_ctx)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2010/11/07 20:24:08.056919,  4] rpc_server/rpc_handles.c:194(create_rpc_handle_internal)
  Opened policy hnd[1] [0000] 00 00 00 00 09 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.057043,  4] rpc_server/rpc_handles.c:229(find_policy_by_hnd_internal)
  Found policy hnd[0] [0000] 00 00 00 00 09 00 00 00   00 00 00 00 D6 4C D8 FC   ........ .....L..
  [0010] B8 2C 00 00                                       .,.. 
[2010/11/07 20:24:08.057355,  2] rpc_server/srv_spoolss_util.c:2025(winreg_get_printer)
  winreg_get_printer: Could not open key SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers\IMP-RES1: WERR_BADFILE
[2010/11/07 20:24:08.057399,  4] rpc_server/srv_spoolss_nt.c:3550(printserver_notify_info)
  printserver_notify_info: Failed to get printer [IMP-RES1]
[2010/11/07 20:24:08.057471,  5] rpc_server/srv_pipe.c:1674(api_rpcTNP)
  api_rpcTNP: called \spoolss successfully
[2010/11/07 20:24:08.057535,  6] rpc_server/srv_pipe_hnd.c:283(read_from_internal_pipe)
   name: \spoolss len: 1024
[2010/11/07 20:24:08.057581,  3] rpc_server/srv_pipe_hnd.c:120(free_pipe_context)
  free_pipe_context: destroying talloc pool of size 1002
[2010/11/07 20:24:08.057629,  5] smbd/ipc.c:59(copy_trans_params_and_data)
  copy_trans_params_and_data: params[0..0] data[0..500] (align 0)
[2010/11/07 20:24:08.057677,  5] lib/util.c:579(show_msg)
[2010/11/07 20:24:08.057697,  5] lib/util.c:589(show_msg)
  size=556
  smb_com=0x25
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51203
  smb_tid=1
  smb_pid=1164
  smb_uid=100
  smb_mid=45760
  smt_wct=10
  smb_vwv[ 0]=    0 (0x0)
  smb_vwv[ 1]=  500 (0x1F4)
  smb_vwv[ 2]=    0 (0x0)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=   56 (0x38)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=  500 (0x1F4)
  smb_vwv[ 7]=   56 (0x38)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    0 (0x0)
  smb_bcc=501
[2010/11/07 20:24:08.063666,  3] printing/printing.c:1353(print_queue_update_internal)
  print_queue_update_internal: 0 jobs in queue for IMP-HOME1
[2010/11/07 20:24:08.063890,  5] printing/printing.c:1112(set_updating_pid)
  set_updating_pid: not  updating lpq cache for print share IMP-HOME1
