[2009/11/26 11:16:31, 0] smbd/server.c:1068(main) smbd version 3.4.0 started. Copyright Andrew Tridgell and the Samba Team 1992-2009 [2009/11/26 11:16:31, 5] lib/debug.c:407(debug_dump_status) INFO: Current debug levels: all: True/10 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 workgroup = HYTEST doing parameter realm = HYTEST.HELSINKI.FI doing parameter security = ads doing parameter kerberos method = dedicated keytab doing parameter dedicated keytab file = /etc/krb5.keytab doing parameter idmap config HYTEST:backend = ad doing parameter idmap config HYTEST:range = 70000-1999999 doing parameter idmap config HYTEST:readonly = yes doing parameter idmap config HYTEST:schema_mode = rfc2307 doing parameter idmap alloc backend = tdb doing parameter idmap cache time = 0 doing parameter idmap negative cache time = 0 doing parameter winbind nss info = rfc2307 doing parameter winbind normalize names = yes doing parameter winbind use default domain = yes doing parameter winbind rpc only = no doing parameter winbind refresh tickets = true doing parameter winbind enum users = yes doing parameter winbind enum groups = yes doing parameter client use spnego = yes doing parameter template shell = /sbin/nologin doing parameter template homedir = /tmp/%D/%U [2009/11/26 11:16:31, 4] param/loadparm.c:9051(lp_load_ex) pm_process() returned Yes [2009/11/26 11:16:31, 7] param/loadparm.c:9256(lp_servicenumber) lp_servicenumber: couldn't find homes [2009/11/26 11:16:31, 10] param/loadparm.c:8264(set_server_role) set_server_role: role = ROLE_DOMAIN_MEMBER [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UCS-2LE [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UCS-2LE [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UTF-16LE [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UTF-16LE [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UCS-2BE [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UCS-2BE [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UTF-16BE [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UTF-16BE [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UTF8 [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UTF8 [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UTF-8 [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UTF-8 [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset ASCII [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset ASCII [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset 646 [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset 646 [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset ISO-8859-1 [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset ISO-8859-1 [2009/11/26 11:16:31, 5] lib/iconv.c:104(smb_register_charset) Attempting to register new charset UCS2-HEX [2009/11/26 11:16:31, 5] lib/iconv.c:112(smb_register_charset) Registered charset UCS2-HEX [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 2] lib/tallocmsg.c:106(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2009/11/26 11:16:31, 2] lib/dmallocmsg.c:77(register_dmalloc_msgs) Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED [2009/11/26 11:16:31, 3] param/loadparm.c:9016(lp_load_ex) lp_load_ex: refreshing parameters Initialising global parameters [2009/11/26 11:16:31, 3] ../lib/util/params.c:550(pm_process) params.c:pm_process() - Processing configuration file "/etc/samba/smb.conf" [2009/11/26 11:16:31, 3] param/loadparm.c:7703(do_section) Processing section "[global]" doing parameter debug level = 10 [2009/11/26 11:16:31, 5] lib/debug.c:407(debug_dump_status) INFO: Current debug levels: all: True/10 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 workgroup = HYTEST doing parameter realm = HYTEST.HELSINKI.FI doing parameter security = ads doing parameter kerberos method = dedicated keytab doing parameter dedicated keytab file = /etc/krb5.keytab doing parameter idmap config HYTEST:backend = ad doing parameter idmap config HYTEST:range = 70000-1999999 doing parameter idmap config HYTEST:readonly = yes doing parameter idmap config HYTEST:schema_mode = rfc2307 doing parameter idmap alloc backend = tdb doing parameter idmap cache time = 0 doing parameter idmap negative cache time = 0 doing parameter winbind nss info = rfc2307 doing parameter winbind normalize names = yes doing parameter winbind use default domain = yes doing parameter winbind rpc only = no doing parameter winbind refresh tickets = true doing parameter winbind enum users = yes doing parameter winbind enum groups = yes doing parameter client use spnego = yes doing parameter template shell = /sbin/nologin doing parameter template homedir = /tmp/%D/%U [2009/11/26 11:16:31, 2] param/loadparm.c:7720(do_section) Processing section "[test]" [2009/11/26 11:16:31, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 0 for test [2009/11/26 11:16:31, 10] param/loadparm.c:6062(hash_a_service) hash_a_service: creating servicehash [2009/11/26 11:16:31, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 0 for service name test doing parameter path = /tmp doing parameter read only = no [2009/11/26 11:16:31, 4] param/loadparm.c:9051(lp_load_ex) pm_process() returned Yes [2009/11/26 11:16:31, 7] param/loadparm.c:9256(lp_servicenumber) lp_servicenumber: couldn't find homes [2009/11/26 11:16:31, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 1 for IPC$ [2009/11/26 11:16:31, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 1 for service name IPC$ [2009/11/26 11:16:31, 3] param/loadparm.c:6174(lp_add_ipc) adding IPC service [2009/11/26 11:16:31, 10] param/loadparm.c:8264(set_server_role) set_server_role: role = ROLE_DOMAIN_MEMBER [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 7] param/loadparm.c:9256(lp_servicenumber) lp_servicenumber: couldn't find printers [2009/11/26 11:16:31, 3] printing/pcap.c:136(pcap_cache_reload) reloading printcap cache [2009/11/26 11:16:31, 5] printing/print_cups.c:403(cups_pcap_load_async) cups_pcap_load_async: asynchronously loading cups printers [2009/11/26 11:16:31, 10] printing/print_cups.c:420(cups_pcap_load_async) cups_pcap_load_async: child pid = 3823 [2009/11/26 11:16:31, 10] printing/print_cups.c:571(cups_cache_reload) cups_cache_reload: sync read on fd 4 [2009/11/26 11:16:31, 5] printing/print_cups.c:453(cups_async_callback) cups_async_callback: callback received for printer data. fd = 4 [2009/11/26 11:16:31, 5] printing/print_cups.c:164(cups_cache_reload_async) reloading cups printcap cache [2009/11/26 11:16:31, 10] printing/print_cups.c:87(cups_connect) connecting to cups server /var/run/cups/cups.sock:631 [2009/11/26 11:16:31, 2] printing/print_cups.c:545(cups_async_callback) cups_async_callback: failed to read a new printer list [2009/11/26 11:16:31, 3] printing/pcap.c:243(pcap_cache_reload) reload status: error [2009/11/26 11:16:31, 3] printing/pcap.c:136(pcap_cache_reload) reloading printcap cache [2009/11/26 11:16:31, 5] printing/print_cups.c:403(cups_pcap_load_async) cups_pcap_load_async: asynchronously loading cups printers [2009/11/26 11:16:31, 10] printing/print_cups.c:420(cups_pcap_load_async) cups_pcap_load_async: child pid = 3824 [2009/11/26 11:16:31, 10] printing/print_cups.c:571(cups_cache_reload) cups_cache_reload: sync read on fd 4 [2009/11/26 11:16:31, 5] printing/print_cups.c:453(cups_async_callback) cups_async_callback: callback received for printer data. fd = 4 [2009/11/26 11:16:31, 5] printing/print_cups.c:164(cups_cache_reload_async) reloading cups printcap cache [2009/11/26 11:16:31, 10] printing/print_cups.c:87(cups_connect) connecting to cups server /var/run/cups/cups.sock:631 [2009/11/26 11:16:31, 2] printing/print_cups.c:545(cups_async_callback) cups_async_callback: failed to read a new printer list [2009/11/26 11:16:31, 3] printing/pcap.c:243(pcap_cache_reload) reload status: error [2009/11/26 11:16:31, 7] param/loadparm.c:9256(lp_servicenumber) lp_servicenumber: couldn't find printers [2009/11/26 11:16:31, 7] param/loadparm.c:9256(lp_servicenumber) lp_servicenumber: couldn't find printers [2009/11/26 11:16:31, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:16:31, 2] lib/interface.c:340(add_interface) added interface eth0 ip=128.214.0.106 bcast=128.214.0.255 netmask=255.255.255.0 [2009/11/26 11:16:31, 5] lib/util.c:266(init_names) Netbios name list:- my_netbios_names[0]="KAKKOSMASSE" [2009/11/26 11:16:31, 3] smbd/server.c:1110(main) loaded services [2009/11/26 11:16:31, 3] smbd/server.c:1125(main) Becoming a daemon. [2009/11/26 11:16:31, 8] ../lib/util/util.c:243(fcntl_lock) fcntl_lock 9 13 0 1 1 [2009/11/26 11:16:31, 8] ../lib/util/util.c:278(fcntl_lock) fcntl_lock: Lock call successful [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend ldapsam [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'ldapsam' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend ldapsam_compat [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'ldapsam_compat' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend NDS_ldapsam [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'NDS_ldapsam' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend NDS_ldapsam_compat [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'NDS_ldapsam_compat' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend smbpasswd [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'smbpasswd' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend tdbsam [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'tdbsam' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:63(smb_register_passdb) Attempting to register passdb backend wbc_sam [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:76(smb_register_passdb) Successfully added passdb backend 'wbc_sam' [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:133(make_pdb_method_name) Attempting to find a passdb backend to match tdbsam (tdbsam) [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:154(make_pdb_method_name) Found pdb backend tdbsam [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:165(make_pdb_method_name) pdb backend tdbsam has a valid init [2009/11/26 11:16:31, 5] lib/gencache.c:61(gencache_init) Opening cache file at /var/run/samba/gencache.tdb [2009/11/26 11:16:31, 5] libsmb/namecache.c:59(namecache_enable) namecache_enable: enabling netbios namecache, timeout 660 seconds [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:73(reghook_cache_init) reghook_cache_init: new tree with default ops 0x88cba0 for key [] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1488(regdb_fetch_values) regdb_fetch_values: Looking for value of key [HKLM\SOFTWARE\Microsoft\Windows NT\CurrentVersion\Ports] [2009/11/26 11:16:31, 8] registry/reg_backend_db.c:1436(regdb_unpack_values) specific: [Samba Printer Port], len: 2 [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1488(regdb_fetch_values) regdb_fetch_values: Looking for value of key [HKLM\SOFTWARE\Microsoft\Windows NT\CurrentVersion\Print\Printers] [2009/11/26 11:16:31, 8] registry/reg_backend_db.c:1436(regdb_unpack_values) specific: [DefaultSpoolDirectory], len: 70 [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1488(regdb_fetch_values) regdb_fetch_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] [2009/11/26 11:16:31, 8] registry/reg_backend_db.c:1436(regdb_unpack_values) specific: [DisplayName], len: 20 [2009/11/26 11:16:31, 8] registry/reg_backend_db.c:1436(regdb_unpack_values) specific: [ErrorControl], len: 4 [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1488(regdb_fetch_values) regdb_fetch_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\Eventlog] [2009/11/26 11:16:31, 8] registry/reg_backend_db.c:1436(regdb_unpack_values) specific: [DisplayName], len: 20 [2009/11/26 11:16:31, 8] registry/reg_backend_db.c:1436(regdb_unpack_values) specific: [ErrorControl], len: 4 [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cd20 for key [/HKLM/SYSTEM/CurrentControlSet/Control/Print] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SYSTEM/CurrentControlSet/Control/Print] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cd20 for key [/HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion/Print/Printers] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion/Print/Printers] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cd20 for key [/HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion/Ports] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion/Ports] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cd60 for key [/HKLM/SYSTEM/CurrentControlSet/Services/LanmanServer/Shares] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SYSTEM/CurrentControlSet/Services/LanmanServer/Shares] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cc40 for key [/HKLM/SOFTWARE/Samba/smbconf] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SOFTWARE/Samba/smbconf] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cda0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/Netlogon/Parameters] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SYSTEM/CurrentControlSet/Services/Netlogon/Parameters] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cde0 for key [/HKLM/SYSTEM/CurrentControlSet/Control/ProductOptions] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SYSTEM/CurrentControlSet/Control/ProductOptions] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88ce20 for key [/HKLM/SYSTEM/CurrentControlSet/Services/Tcpip/Parameters] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SYSTEM/CurrentControlSet/Services/Tcpip/Parameters] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88ce60 for key [/HKPT] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKPT] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2006/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cea0 for key [/HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:97(reghook_cache_add) reghook_cache_add: Adding ops 0x88cee0 for key [/HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion/Perflib] [2009/11/26 11:16:31, 8] lib/adt_tree.c:200(pathtree_add) pathtree_add: Enter [2009/11/26 11:16:31, 10] lib/adt_tree.c:267(pathtree_add) pathtree_add: Successfully added node [HKLM/SOFTWARE/Microsoft/Windows NT/CurrentVersion/Perflib] to tree [2009/11/26 11:16:31, 8] lib/adt_tree.c:269(pathtree_add) pathtree_add: Exit [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = IDMAP/UID2SID/0 couldn't be found [2009/11/26 11:16:31, 5] passdb/lookup_sid.c:1325(uid_to_sid) uid_to_sid: winbind failed to find a sid for uid 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 4] passdb/pdb_tdb.c:518(tdbsam_open) tdbsam_open: successfully opened /var/lib/samba/passdb.tdb [2009/11/26 11:16:31, 5] passdb/pdb_tdb.c:557(tdbsam_getsampwnam) pdb_getsampwnam (TDB): error fetching database. Key: USER_root [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] passdb/pdb_interface.c:1213(pdb_default_uid_to_sid) pdb_default_uid_to_rid: Did not find user root (0) [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1142(legacy_uid_to_sid) LEGACY: uid 0 -> sid S-1-22-1-0 [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = IDMAP/GID2SID/0 couldn't be found [2009/11/26 11:16:31, 5] passdb/lookup_sid.c:1378(gid_to_sid) gid_to_sid: winbind failed to find a sid for gid 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1173(legacy_gid_to_sid) LEGACY: gid 0 -> sid S-1-22-2-0 [2009/11/26 11:16:31, 10] auth/token_util.c:353(create_local_nt_token) Create local NT token for S-1-22-1-0 [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-544, value = -1, timeout = Tue Nov 24 18:26:26 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-544 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-544 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-544, value = -1, timeout = Thu Nov 26 11:16:31 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-544 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-544 [2009/11/26 11:16:31, 10] groupdb/mapping.c:765(pdb_create_builtin_alias) Trying to create builtin alias 544 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:953(lookup_sid) lookup_sid called for SID 'S-1-5-32-544' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:708(check_dom_sid_to_level) Accepting SID S-1-5-32 in level 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:468(lookup_rids) lookup_rids called for domain sid 'S-1-5-32' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:988(lookup_sid) Sid S-1-5-32-544 -> BUILTIN\Administrators(4) [2009/11/26 11:16:31, 3] groupdb/mapping.c:786(pdb_create_builtin_alias) pdb_create_builtin_alias: Could not get a gid out of winbind [2009/11/26 11:16:31, 5] auth/token_util.c:303(create_builtin_administrators) create_builtin_administrators: Failed to create Administrators [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 2] auth/token_util.c:450(create_local_nt_token) WARNING: Failed to create BUILTIN\Administrators group! Can Winbind allocate gids? [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-545, value = -1, timeout = Tue Nov 24 18:26:26 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-545 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-545 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-545, value = -1, timeout = Thu Nov 26 11:16:31 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-545 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-545 [2009/11/26 11:16:31, 10] groupdb/mapping.c:765(pdb_create_builtin_alias) Trying to create builtin alias 545 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:953(lookup_sid) lookup_sid called for SID 'S-1-5-32-545' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:708(check_dom_sid_to_level) Accepting SID S-1-5-32 in level 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:468(lookup_rids) lookup_rids called for domain sid 'S-1-5-32' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:988(lookup_sid) Sid S-1-5-32-545 -> BUILTIN\Users(4) [2009/11/26 11:16:31, 3] groupdb/mapping.c:786(pdb_create_builtin_alias) pdb_create_builtin_alias: Could not get a gid out of winbind [2009/11/26 11:16:31, 5] auth/token_util.c:274(create_builtin_users) create_builtin_users: Failed to create Users [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 2] auth/token_util.c:474(create_local_nt_token) WARNING: Failed to create BUILTIN\Users group! Can Winbind allocate gids? [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-1-0] [2009/11/26 11:16:31, 5] lib/privileges.c:128(get_privileges_for_sids) get_privileges_for_sids: sid = S-1-5-32-544 Privilege set: SE_PRIV 0xff0 0x0 0x0 0x0 [2009/11/26 11:16:31, 5] lib/privileges.c:128(get_privileges_for_sids) get_privileges_for_sids: sid = S-1-1-0 Privilege set: SE_PRIV 0x0 0x0 0x0 0x0 [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-2] [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-11] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (1) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\Spooler] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/Spooler/Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1370(regdb_fetch_keys) regdb_fetch_keys: no subkeys found for key [HKLM\SYSTEM\CurrentControlSet\Services\Spooler\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\Spooler\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\Spooler\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/NETLOGON/Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1370(regdb_fetch_keys) regdb_fetch_keys: no subkeys found for key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\NETLOGON\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/RemoteRegistry/Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1370(regdb_fetch_keys) regdb_fetch_keys: no subkeys found for key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\RemoteRegistry\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/WINS] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/WINS] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM] [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/WINS] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/WINS] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 7] registry/reg_api.c:133(regkey_open_onelevel) regkey_open_onelevel: name = [Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:440(regdb_open) regdb_open: incrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:125(reghook_cache_find) reghook_cache_find: Searching for keyname [/HKLM/SYSTEM/CurrentControlSet/Services/WINS/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:352(pathtree_find) pathtree_find: Enter [/HKLM/SYSTEM/CurrentControlSet/Services/WINS/Security] [2009/11/26 11:16:31, 10] lib/adt_tree.c:425(pathtree_find) pathtree_find: Exit [2009/11/26 11:16:31, 10] registry/reg_cachehook.c:130(reghook_cache_find) reghook_cache_find: found ops 0x88cba0 for key [/HKLM/SYSTEM/CurrentControlSet/Services/WINS/Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1370(regdb_fetch_keys) regdb_fetch_keys: no subkeys found for key [HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1583(regdb_get_secdesc) regdb_get_secdesc: Getting secdesc of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (4) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (3) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:1525(regdb_store_values) regdb_store_values: Looking for value of key [HKLM\SYSTEM\CurrentControlSet\Services\WINS\Security] [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (2) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (1) [2009/11/26 11:16:31, 10] registry/reg_backend_db.c:474(regdb_close) regdb_close: decrementing refcount (0) [2009/11/26 11:16:31, 10] printing/nt_printing.c:700(update_c_setprinter) update_c_setprinter: c_setprinter = 0 [2009/11/26 11:16:31, 6] libads/ldap.c:346(ads_find_dc) ads_find_dc: (ldap) looking for realm 'HYTEST.HELSINKI.FI' [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = AD_SITENAME/DOMAIN/HYTEST.HELSINKI.FI, value = Default-First-Site-Name, timeout = Tue Jan 19 05:14:07 2038 [2009/11/26 11:16:31, 5] libads/dns.c:817(sitename_fetch) sitename_fetch: Returning sitename for HYTEST.HELSINKI.FI: "Default-First-Site-Name" [2009/11/26 11:16:31, 4] libsmb/namequery_dc.c:73(ads_dc_name) ads_dc_name: domain=HYTEST [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = AD_SITENAME/DOMAIN/HYTEST.HELSINKI.FI, value = Default-First-Site-Name, timeout = Tue Jan 19 05:14:07 2038 [2009/11/26 11:16:31, 5] libads/dns.c:817(sitename_fetch) sitename_fetch: Returning sitename for HYTEST.HELSINKI.FI: "Default-First-Site-Name" [2009/11/26 11:16:31, 6] libads/ldap.c:366(ads_find_dc) ads_find_dc: (cldap) looking for realm 'HYTEST.HELSINKI.FI' [2009/11/26 11:16:31, 8] libsmb/namequery.c:2155(get_sorted_dc_list) get_sorted_dc_list: attempting lookup for name HYTEST.HELSINKI.FI (sitename Default-First-Site-Name) using [ads] [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = SAFJOIN/DOMAIN/HYTEST.HELSINKI.FI couldn't be found [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = SAF/DOMAIN/HYTEST.HELSINKI.FI, value = hydct2.hytest.helsinki.fi, timeout = Thu Nov 26 11:04:52 2009 [2009/11/26 11:16:31, 5] libsmb/namequery.c:197(saf_fetch) saf_fetch: failed to find server for "HYTEST.HELSINKI.FI" domain [2009/11/26 11:16:31, 3] libsmb/namequery.c:1971(get_dc_list) get_dc_list: preferred server list: ", *" [2009/11/26 11:16:31, 10] libsmb/namequery.c:1505(internal_resolve_name) internal_resolve_name: looking up HYTEST.HELSINKI.FI#1c (sitename Default-First-Site-Name) [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = NBT/HYTEST.HELSINKI.FI#1C, value = 128.214.38.25:389, timeout = Thu Nov 26 10:55:38 2009 [2009/11/26 11:16:31, 5] libsmb/namecache.c:208(namecache_fetch) no entry for HYTEST.HELSINKI.FI#1C found. [2009/11/26 11:16:31, 5] libsmb/namequery.c:1399(resolve_ads) resolve_ads: Attempting to resolve DCs for HYTEST.HELSINKI.FI using DNS [2009/11/26 11:16:31, 4] libads/dns.c:432(ads_dns_lookup_srv) ads_dns_lookup_srv: 1 records returned in the answer section. [2009/11/26 11:16:31, 10] libads/dns.c:213(ads_dns_parse_rr_srv) ads_dns_parse_rr_srv: Parsed hydct2.hytest.helsinki.fi [0, 100, 389] [2009/11/26 11:16:31, 10] libsmb/namequery.c:582(remove_duplicate_addrs2) remove_duplicate_addrs2: looking for duplicate address/port pairs [2009/11/26 11:16:31, 5] libsmb/namecache.c:122(namecache_store) namecache_store: storing 1 address for HYTEST.HELSINKI.FI#1c: 128.214.38.25 [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = NBT/HYTEST.HELSINKI.FI#1C; value = 128.214.38.25:389 and timeout = Thu Nov 26 11:27:31 2009 (660 seconds ahead) [2009/11/26 11:16:31, 10] libsmb/namequery.c:1652(internal_resolve_name) internal_resolve_name: returning 1 addresses: 128.214.38.25:389 [2009/11/26 11:16:31, 8] libsmb/namequery.c:1992(get_dc_list) Adding 1 DC's from auto lookup [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = NEG_CONN_CACHE/HYTEST.HELSINKI.FI,128.214.38.25, value = c0000001, timeout = Tue Nov 24 16:47:57 2009 [2009/11/26 11:16:31, 9] libsmb/conncache.c:150(check_negative_conn_cache) check_negative_conn_cache returning result 0 for domain HYTEST.HELSINKI.FI server 128.214.38.25 [2009/11/26 11:16:31, 10] libsmb/namequery.c:582(remove_duplicate_addrs2) remove_duplicate_addrs2: looking for duplicate address/port pairs [2009/11/26 11:16:31, 4] libsmb/namequery.c:2104(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2009/11/26 11:16:31, 4] libsmb/namequery.c:2105(get_dc_list) get_dc_list: 128.214.38.25:389 [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = NEG_CONN_CACHE/HYTEST.HELSINKI.FI,128.214.38.25 couldn't be found [2009/11/26 11:16:31, 9] libsmb/conncache.c:150(check_negative_conn_cache) check_negative_conn_cache returning result 0 for domain HYTEST.HELSINKI.FI server 128.214.38.25 [2009/11/26 11:16:31, 5] libads/ldap.c:203(ads_try_connect) ads_try_connect: sending CLDAP request to 128.214.38.25 (realm: HYTEST.HELSINKI.FI) [2009/11/26 11:16:31, 10] libads/dns.c:778(sitename_store) sitename_store: realm = [HYTEST], sitename = [Default-First-Site-Name], expire = [2147483647] [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = AD_SITENAME/DOMAIN/HYTEST; value = Default-First-Site-Name and timeout = Tue Jan 19 05:14:07 2038 (888256656 seconds ahead) [2009/11/26 11:16:31, 10] libads/dns.c:778(sitename_store) sitename_store: realm = [hytest.helsinki.fi], sitename = [Default-First-Site-Name], expire = [2147483647] [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = AD_SITENAME/DOMAIN/HYTEST.HELSINKI.FI; value = Default-First-Site-Name and timeout = Tue Jan 19 05:14:07 2038 (888256656 seconds ahead) [2009/11/26 11:16:31, 3] libads/ldap.c:621(ads_connect) Successfully contacted LDAP server 128.214.38.25 [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = AD_SITENAME/DOMAIN/HYTEST.HELSINKI.FI, value = Default-First-Site-Name, timeout = Tue Jan 19 05:14:07 2038 [2009/11/26 11:16:31, 5] libads/dns.c:817(sitename_fetch) sitename_fetch: Returning sitename for HYTEST.HELSINKI.FI: "Default-First-Site-Name" [2009/11/26 11:16:31, 10] libads/ldap.c:165(ads_closest_dc) ads_closest_dc: NBT_SERVER_CLOSEST flag set [2009/11/26 11:16:31, 10] libads/kerberos.c:852(create_local_private_krb5_conf_for_domain) create_local_private_krb5_conf_for_domain: fname = /var/run/samba/smb_krb5/krb5.conf.HYTEST, realm = HYTEST.HELSINKI.FI, domain = HYTEST [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = SAFJOIN/DOMAIN/HYTEST.HELSINKI.FI couldn't be found [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = SAF/DOMAIN/HYTEST.HELSINKI.FI couldn't be found [2009/11/26 11:16:31, 5] libsmb/namequery.c:197(saf_fetch) saf_fetch: failed to find server for "HYTEST.HELSINKI.FI" domain [2009/11/26 11:16:31, 3] libsmb/namequery.c:1971(get_dc_list) get_dc_list: preferred server list: ", *" [2009/11/26 11:16:31, 10] libsmb/namequery.c:1505(internal_resolve_name) internal_resolve_name: looking up HYTEST.HELSINKI.FI#1c (sitename Default-First-Site-Name) [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = NBT/HYTEST.HELSINKI.FI#1C, value = 128.214.38.25:389, timeout = Thu Nov 26 11:27:31 2009 [2009/11/26 11:16:31, 5] libsmb/namecache.c:212(namecache_fetch) name HYTEST.HELSINKI.FI#1C found. [2009/11/26 11:16:31, 8] libsmb/namequery.c:1992(get_dc_list) Adding 1 DC's from auto lookup [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = NEG_CONN_CACHE/HYTEST.HELSINKI.FI,128.214.38.25 couldn't be found [2009/11/26 11:16:31, 9] libsmb/conncache.c:150(check_negative_conn_cache) check_negative_conn_cache returning result 0 for domain HYTEST.HELSINKI.FI server 128.214.38.25 [2009/11/26 11:16:31, 10] libsmb/namequery.c:582(remove_duplicate_addrs2) remove_duplicate_addrs2: looking for duplicate address/port pairs [2009/11/26 11:16:31, 4] libsmb/namequery.c:2104(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2009/11/26 11:16:31, 4] libsmb/namequery.c:2105(get_dc_list) get_dc_list: 128.214.38.25:389 [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = SAFJOIN/DOMAIN/HYTEST.HELSINKI.FI couldn't be found [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = SAF/DOMAIN/HYTEST.HELSINKI.FI couldn't be found [2009/11/26 11:16:31, 5] libsmb/namequery.c:197(saf_fetch) saf_fetch: failed to find server for "HYTEST.HELSINKI.FI" domain [2009/11/26 11:16:31, 3] libsmb/namequery.c:1971(get_dc_list) get_dc_list: preferred server list: ", *" [2009/11/26 11:16:31, 10] libsmb/namequery.c:1505(internal_resolve_name) internal_resolve_name: looking up HYTEST.HELSINKI.FI#1c (sitename (null)) [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = NBT/HYTEST.HELSINKI.FI#1C, value = 128.214.38.25:389, timeout = Thu Nov 26 11:27:31 2009 [2009/11/26 11:16:31, 5] libsmb/namecache.c:212(namecache_fetch) name HYTEST.HELSINKI.FI#1C found. [2009/11/26 11:16:31, 8] libsmb/namequery.c:1992(get_dc_list) Adding 1 DC's from auto lookup [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = NEG_CONN_CACHE/HYTEST.HELSINKI.FI,128.214.38.25 couldn't be found [2009/11/26 11:16:31, 9] libsmb/conncache.c:150(check_negative_conn_cache) check_negative_conn_cache returning result 0 for domain HYTEST.HELSINKI.FI server 128.214.38.25 [2009/11/26 11:16:31, 10] libsmb/namequery.c:582(remove_duplicate_addrs2) remove_duplicate_addrs2: looking for duplicate address/port pairs [2009/11/26 11:16:31, 4] libsmb/namequery.c:2104(get_dc_list) get_dc_list: returning 1 ip addresses in an ordered list [2009/11/26 11:16:31, 4] libsmb/namequery.c:2105(get_dc_list) get_dc_list: 128.214.38.25:389 [2009/11/26 11:16:31, 10] libads/kerberos.c:803(get_kdc_ip_string) get_kdc_ip_string: Returning kdc = 128.214.38.25 [2009/11/26 11:16:31, 5] libads/kerberos.c:920(create_local_private_krb5_conf_for_domain) create_local_private_krb5_conf_for_domain: wrote file /var/run/samba/smb_krb5/krb5.conf.HYTEST with realm HYTEST.HELSINKI.FI KDC list = kdc = 128.214.38.25 [2009/11/26 11:16:31, 4] libsmb/namequery_dc.c:143(ads_dc_name) ads_dc_name: using server='HYDCT2.HYTEST.HELSINKI.FI' IP=128.214.38.25 [2009/11/26 11:16:31, 5] libads/ldap.c:203(ads_try_connect) ads_try_connect: sending CLDAP request to HYDCT2.HYTEST.HELSINKI.FI (realm: HYTEST.HELSINKI.FI) [2009/11/26 11:16:31, 10] libads/dns.c:778(sitename_store) sitename_store: realm = [HYTEST], sitename = [Default-First-Site-Name], expire = [2147483647] [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = AD_SITENAME/DOMAIN/HYTEST; value = Default-First-Site-Name and timeout = Tue Jan 19 05:14:07 2038 (888256656 seconds ahead) [2009/11/26 11:16:31, 10] libads/dns.c:778(sitename_store) sitename_store: realm = [hytest.helsinki.fi], sitename = [Default-First-Site-Name], expire = [2147483647] [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = AD_SITENAME/DOMAIN/HYTEST.HELSINKI.FI; value = Default-First-Site-Name and timeout = Tue Jan 19 05:14:07 2038 (888256656 seconds ahead) [2009/11/26 11:16:31, 3] libads/ldap.c:621(ads_connect) Successfully contacted LDAP server 128.214.38.25 [2009/11/26 11:16:31, 10] libads/ldap.c:62(ldap_open_with_timeout) Opening connection to LDAP server 'hydct2.hytest.helsinki.fi:389', timeout 15 seconds [2009/11/26 11:16:31, 10] libads/ldap.c:76(ldap_open_with_timeout) Connected to LDAP server 'hydct2.hytest.helsinki.fi:389' [2009/11/26 11:16:31, 3] libads/ldap.c:675(ads_connect) Connected to LDAP server hydct2.hytest.helsinki.fi [2009/11/26 11:16:31, 10] libads/ldap.c:165(ads_closest_dc) ads_closest_dc: NBT_SERVER_CLOSEST flag set [2009/11/26 11:16:31, 10] libsmb/namequery.c:86(saf_store) saf_store: domain = [HYTEST], server = [hydct2.hytest.helsinki.fi], expire = [1259227891] [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = SAF/DOMAIN/HYTEST; value = hydct2.hytest.helsinki.fi and timeout = Thu Nov 26 11:31:31 2009 (900 seconds ahead) [2009/11/26 11:16:31, 10] libsmb/namequery.c:86(saf_store) saf_store: domain = [HYTEST.HELSINKI.FI], server = [hydct2.hytest.helsinki.fi], expire = [1259227891] [2009/11/26 11:16:31, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = SAF/DOMAIN/HYTEST.HELSINKI.FI; value = hydct2.hytest.helsinki.fi and timeout = Thu Nov 26 11:31:31 2009 (900 seconds ahead) [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 5] lib/charcnv.c:82(charset_name) Substituting charset 'UTF-8' for LOCALE [2009/11/26 11:16:31, 4] libads/ldap.c:2849(ads_current_time) time offset is 0 seconds [2009/11/26 11:16:31, 4] libads/sasl.c:1112(ads_sasl_bind) Found SASL mechanism GSS-SPNEGO [2009/11/26 11:16:31, 3] libads/sasl.c:780(ads_sasl_spnego_bind) ads_sasl_spnego_bind: got OID=1.3.6.1.4.1.311.2.2.30 [2009/11/26 11:16:31, 3] libads/sasl.c:780(ads_sasl_spnego_bind) ads_sasl_spnego_bind: got OID=1.2.840.48018.1.2.2 [2009/11/26 11:16:31, 3] libads/sasl.c:780(ads_sasl_spnego_bind) ads_sasl_spnego_bind: got OID=1.2.840.113554.1.2.2 [2009/11/26 11:16:31, 3] libads/sasl.c:780(ads_sasl_spnego_bind) ads_sasl_spnego_bind: got OID=1.2.840.113554.1.2.2.3 [2009/11/26 11:16:31, 3] libads/sasl.c:780(ads_sasl_spnego_bind) ads_sasl_spnego_bind: got OID=1.3.6.1.4.1.311.2.2.10 [2009/11/26 11:16:31, 3] libads/sasl.c:789(ads_sasl_spnego_bind) ads_sasl_spnego_bind: got server principal name = not_defined_in_RFC4178@please_ignore [2009/11/26 11:16:31, 3] libsmb/clikrb5.c:688(ads_krb5_mk_req) ads_krb5_mk_req: krb5_cc_get_principal failed (No credentials cache found) [2009/11/26 11:16:31, 10] libads/sasl.c:810(ads_sasl_spnego_bind) ads_sasl_spnego_krb5_bind failed with: No credentials cache found, calling kinit [2009/11/26 11:16:31, 10] libads/kerberos.c:187(kerberos_kinit_password_ext) kerberos_kinit_password: as KAKKOSMASSE$@HYTEST.HELSINKI.FI using [MEMORY:prtpub_cache] as ccache and config [/var/run/samba/smb_krb5/krb5.conf.HYTEST] [2009/11/26 11:16:31, 3] libsmb/clikrb5.c:621(ads_cleanup_expired_creds) ads_cleanup_expired_creds: Ticket in ccache[MEMORY:prtpub_cache] expiration Thu, 26 Nov 2009 21:16:31 EET [2009/11/26 11:16:31, 10] libsmb/clikrb5.c:719(ads_krb5_mk_req) ads_krb5_mk_req: Ticket (ldap/hydct2.hytest.helsinki.fi@HYTEST.HELSINKI.FI) in ccache (MEMORY:prtpub_cache) is valid until: (Thu, 26 Nov 2009 21:16:31 EET - 1259262991) [2009/11/26 11:16:31, 3] libsmb/clikrb5.c:730(ads_krb5_mk_req) ads_krb5_mk_req: server marked as OK to delegate to, building forwardable TGT [2009/11/26 11:16:31, 10] libsmb/clikrb5.c:897(get_krb5_smb_session_key) Got KRB5 session key of length 16 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 6] passdb/pdb_interface.c:279(pdb_getsampwsid) pdb_getsampwsid: Building guest account [2009/11/26 11:16:31, 10] passdb/pdb_get_set.c:572(pdb_set_username) pdb_set_username: setting username nobody, was [2009/11/26 11:16:31, 10] passdb/pdb_get_set.c:641(pdb_set_fullname) pdb_set_full_name: setting full name nobody, was [2009/11/26 11:16:31, 10] passdb/pdb_get_set.c:595(pdb_set_domain) pdb_set_domain: setting domain KAKKOSMASSE, was [2009/11/26 11:16:31, 10] passdb/pdb_get_set.c:501(pdb_set_user_sid) pdb_set_user_sid: setting user sid S-1-5-21-3831401082-1701414953-643464508-501 [2009/11/26 11:16:31, 10] passdb/pdb_compat.c:72(pdb_set_user_sid_from_rid) pdb_set_user_sid_from_rid: setting user sid S-1-5-21-3831401082-1701414953-643464508-501 from rid 501 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] lib/system_smbd.c:122(sys_getgrouplist) sys_getgrouplist: user [nobody] [2009/11/26 11:16:31, 10] lib/gencache.c:194(gencache_get) Cache entry with key = IDMAP/GID2SID/65534 couldn't be found [2009/11/26 11:16:31, 5] passdb/lookup_sid.c:1378(gid_to_sid) gid_to_sid: winbind failed to find a sid for gid 65534 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1173(legacy_gid_to_sid) LEGACY: gid 65534 -> sid S-1-22-2-65534 [2009/11/26 11:16:31, 5] auth/auth_util.c:646(make_server_info_sam) make_server_info_sam: made server info for user nobody -> nobody [2009/11/26 11:16:31, 10] auth/token_util.c:353(create_local_nt_token) Create local NT token for S-1-5-21-3831401082-1701414953-643464508-501 [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-544, value = -1, timeout = Thu Nov 26 11:16:31 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-544 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-544 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-544, value = -1, timeout = Thu Nov 26 11:16:31 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-544 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-544 [2009/11/26 11:16:31, 10] groupdb/mapping.c:765(pdb_create_builtin_alias) Trying to create builtin alias 544 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:953(lookup_sid) lookup_sid called for SID 'S-1-5-32-544' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:708(check_dom_sid_to_level) Accepting SID S-1-5-32 in level 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:468(lookup_rids) lookup_rids called for domain sid 'S-1-5-32' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:988(lookup_sid) Sid S-1-5-32-544 -> BUILTIN\Administrators(4) [2009/11/26 11:16:31, 3] groupdb/mapping.c:786(pdb_create_builtin_alias) pdb_create_builtin_alias: Could not get a gid out of winbind [2009/11/26 11:16:31, 5] auth/token_util.c:303(create_builtin_administrators) create_builtin_administrators: Failed to create Administrators [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 2] auth/token_util.c:450(create_local_nt_token) WARNING: Failed to create BUILTIN\Administrators group! Can Winbind allocate gids? [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-545, value = -1, timeout = Thu Nov 26 11:16:31 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-545 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-545 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-545, value = -1, timeout = Thu Nov 26 11:16:31 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-545 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-545 [2009/11/26 11:16:31, 10] groupdb/mapping.c:765(pdb_create_builtin_alias) Trying to create builtin alias 545 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:953(lookup_sid) lookup_sid called for SID 'S-1-5-32-545' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:708(check_dom_sid_to_level) Accepting SID S-1-5-32 in level 1 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:468(lookup_rids) lookup_rids called for domain sid 'S-1-5-32' [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:988(lookup_sid) Sid S-1-5-32-545 -> BUILTIN\Users(4) [2009/11/26 11:16:31, 3] groupdb/mapping.c:786(pdb_create_builtin_alias) pdb_create_builtin_alias: Could not get a gid out of winbind [2009/11/26 11:16:31, 5] auth/token_util.c:274(create_builtin_users) create_builtin_users: Failed to create Users [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 2] auth/token_util.c:474(create_local_nt_token) WARNING: Failed to create BUILTIN\Users group! Can Winbind allocate gids? [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-21-3831401082-1701414953-643464508-501] [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-2-65534] [2009/11/26 11:16:31, 5] lib/privileges.c:128(get_privileges_for_sids) get_privileges_for_sids: sid = S-1-1-0 Privilege set: SE_PRIV 0x0 0x0 0x0 0x0 [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-2] [2009/11/26 11:16:31, 3] lib/privileges.c:63(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-32-546] [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-1-0, value = -1, timeout = Tue Nov 24 18:26:26 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-1-0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-1-0 [2009/11/26 11:16:31, 10] auth/auth_util.c:750(create_local_token) Could not convert SID S-1-1-0 to gid, ignoring it [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-2, value = -1, timeout = Tue Nov 24 18:26:26 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-2 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-2 [2009/11/26 11:16:31, 10] auth/auth_util.c:750(create_local_token) Could not convert SID S-1-5-2 to gid, ignoring it [2009/11/26 11:16:31, 10] lib/gencache.c:208(gencache_get) Returning expired cache entry: key = IDMAP/SID2GID/S-1-5-32-546, value = -1, timeout = Tue Nov 24 18:26:26 2009 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1502(sid_to_gid) winbind failed to find a gid for sid S-1-5-32-546 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:210(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 3] smbd/uid.c:428(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2009/11/26 11:16:31, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:31, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:31, 3] smbd/sec_ctx.c:418(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:31, 10] passdb/lookup_sid.c:1247(legacy_sid_to_gid) LEGACY: mapping failed for sid S-1-5-32-546 [2009/11/26 11:16:31, 10] auth/auth_util.c:750(create_local_token) Could not convert SID S-1-5-32-546 to gid, ignoring it [2009/11/26 11:16:31, 10] auth/token_util.c:528(debug_nt_user_token) NT user token of user S-1-5-21-3831401082-1701414953-643464508-501 contains 6 SIDs SID[ 0]: S-1-5-21-3831401082-1701414953-643464508-501 SID[ 1]: S-1-22-2-65534 SID[ 2]: S-1-1-0 SID[ 3]: S-1-5-2 SID[ 4]: S-1-5-32-546 SID[ 5]: S-1-22-1-65534 SE_PRIV 0x0 0x0 0x0 0x0 [2009/11/26 11:16:31, 10] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 65534 Primary group is 65534 and contains 1 supplementary groups Group[ 0]: 65534 [2009/11/26 11:16:31, 3] printing/printing.c:1415(start_background_queue) start_background_queue: Starting background LPQ thread [2009/11/26 11:16:31, 5] printing/printing.c:1434(start_background_queue) [2009/11/26 11:16:31, 3] ../lib/util/util_net.c:56(interpret_string_addr_internal) start_background_queue: background LPQ thread started interpret_string_addr_internal: getaddrinfo failed for name :: [Address family for hostname not supported] [2009/11/26 11:16:31, 3] ../lib/util/util_net.c:56(interpret_string_addr_internal) interpret_string_addr_internal: getaddrinfo failed for name :: [Address family for hostname not supported] [2009/11/26 11:16:31, 5] smbd/connection.c:142(claim_connection) [2009/11/26 11:16:31, 10] lib/util_sock.c:943(open_socket_in) claiming [smbd lpq backend] bind succeeded on port 445 [2009/11/26 11:16:31, 5] lib/util_sock.c:371(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 [2009/11/26 11:16:31, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 Locking key F90E0000FFFFFFFF736D SO_RCVTIMEO = 0 [2009/11/26 11:16:31, 5] lib/util_sock.c:371(print_socket_options) [2009/11/26 11:16:31, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Socket options: Allocated locked data 0x0x24118b8 SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 [2009/11/26 11:16:31, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) 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 Unlocking key F90E0000FFFFFFFF736D [2009/11/26 11:16:31, 5] printing/printing.c:1468(start_background_queue) start_background_queue: background LPQ thread waiting for messages [2009/11/26 11:16:31, 10] lib/util_sock.c:943(open_socket_in) bind succeeded on port 139 [2009/11/26 11:16:31, 5] lib/util_sock.c:371(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 [2009/11/26 11:16:31, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 16384 SO_RCVBUF = 87380 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:16:31, 5] smbd/connection.c:142(claim_connection) claiming [] [2009/11/26 11:16:31, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key F10E0000FFFFFFFF0000 [2009/11/26 11:16:31, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2411430 [2009/11/26 11:16:31, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key F10E0000FFFFFFFF0000 [2009/11/26 11:16:31, 2] smbd/server.c:675(smbd_parent_loop) waiting for connections [2009/11/26 11:16:38, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 50628 SO_RCVBUF = 87424 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:16:38, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 50628 SO_RCVBUF = 87424 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:16:38, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:16:38, 3] smbd/oplock.c:911(init_oplocks) init_oplocks: initializing messages. [2009/11/26 11:16:38, 3] smbd/oplock_linux.c:219(linux_init_kernel_oplocks) Linux kernel oplocks enabled [2009/11/26 11:16:38, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x2411be0 [2009/11/26 11:16:38, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(keepalive) 0x2411be0 [2009/11/26 11:16:38, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x23ab368 [2009/11/26 11:16:38, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(deadtime) 0x23ab368 [2009/11/26 11:16:38, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x2411cf8 [2009/11/26 11:16:38, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x2411cf8 [2009/11/26 11:16:38, 10] lib/util_sock.c:781(read_smb_length_return_keepalive) got smb length of 190 [2009/11/26 11:16:38, 6] smbd/process.c:1450(process_smb) got message type 0x0 of len 0xbe [2009/11/26 11:16:38, 3] smbd/process.c:1453(process_smb) Transaction 0 of length 194 (0 toread) [2009/11/26 11:16:38, 5] lib/util.c:632(show_msg) [2009/11/26 11:16:38, 5] lib/util.c:642(show_msg) size=190 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=65535 smb_pid=3834 smb_uid=0 smb_mid=1 smt_wct=0 smb_bcc=155 [2009/11/26 11:16:38, 10] ../lib/util/util.c:304(_dump_data) [0000] 02 50 43 20 4E 45 54 57 4F 52 4B 20 50 52 4F 47 .PC NETW ORK PROG [0010] 52 41 4D 20 31 2E 30 00 02 4D 49 43 52 4F 53 4F RAM 1.0. .MICROSO [0020] 46 54 20 4E 45 54 57 4F 52 4B 53 20 31 2E 30 33 FT NETWO RKS 1.03 [0030] 00 02 4D 49 43 52 4F 53 4F 46 54 20 4E 45 54 57 ..MICROS OFT NETW [0040] 4F 52 4B 53 20 33 2E 30 00 02 4C 41 4E 4D 41 4E ORKS 3.0 ..LANMAN [0050] 31 2E 30 00 02 4C 4D 31 2E 32 58 30 30 32 00 02 1.0..LM1 .2X002.. [0060] 44 4F 53 20 4C 41 4E 4D 41 4E 32 2E 31 00 02 4C DOS LANM AN2.1..L [0070] 41 4E 4D 41 4E 32 2E 31 00 02 53 61 6D 62 61 00 ANMAN2.1 ..Samba. [0080] 02 4E 54 20 4C 41 4E 4D 41 4E 20 31 2E 30 00 02 .NT LANM AN 1.0.. [0090] 4E 54 20 4C 4D 20 30 2E 31 32 00 NT LM 0. 12. [2009/11/26 11:16:38, 3] smbd/process.c:1272(switch_message) switch message SMBnegprot (pid 3835) conn 0x0 [2009/11/26 11:16:38, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:38, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:38, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:38, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [PC NETWORK PROGRAM 1.0] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [MICROSOFT NETWORKS 1.03] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [MICROSOFT NETWORKS 3.0] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LANMAN1.0] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LM1.2X002] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [DOS LANMAN2.1] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LANMAN2.1] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [Samba] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [NT LANMAN 1.0] [2009/11/26 11:16:38, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [NT LM 0.12] [2009/11/26 11:16:38, 10] lib/util.c:1979(set_remote_arch) set_remote_arch: Client arch is 'Samba' [2009/11/26 11:16:38, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:16:38, 5] smbd/connection.c:142(claim_connection) claiming [] [2009/11/26 11:16:38, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key FB0E0000FFFFFFFF0000 [2009/11/26 11:16:38, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2415af8 [2009/11/26 11:16:38, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key FB0E0000FFFFFFFF0000 [2009/11/26 11:16:38, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:16:38, 10] lib/util.c:2626(name_to_fqdn) name_to_fqdn: lookup for KAKKOSMASSE -> kakkosmasse. [2009/11/26 11:16:38, 3] smbd/negprot.c:387(reply_nt1) using SPNEGO [2009/11/26 11:16:38, 3] smbd/negprot.c:672(reply_negprot) Selected protocol NT LANMAN 1.0 [2009/11/26 11:16:38, 5] smbd/negprot.c:679(reply_negprot) negprot index=8 [2009/11/26 11:16:38, 5] lib/util.c:632(show_msg) [2009/11/26 11:16:38, 5] lib/util.c:642(show_msg) size=180 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=65535 smb_pid=3834 smb_uid=0 smb_mid=1 smt_wct=17 smb_vwv[ 0]= 8 (0x8) 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]=64256 (0xFB00) smb_vwv[ 8]= 14 (0xE) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]= 128 (0x80) smb_vwv[12]=20831 (0x515F) smb_vwv[13]=31016 (0x7928) smb_vwv[14]=51822 (0xCA6E) smb_vwv[15]=34817 (0x8801) smb_vwv[16]= 255 (0xFF) smb_bcc=111 [2009/11/26 11:16:38, 10] ../lib/util/util.c:304(_dump_data) [0000] 6B 61 6B 6B 6F 73 6D 61 73 73 65 00 00 00 00 00 kakkosma sse..... [0010] 60 5D 06 06 2B 06 01 05 05 02 A0 53 30 51 A0 24 `]..+... ...S0Q.$ [0020] 30 22 06 09 2A 86 48 86 F7 12 01 02 02 06 09 2A 0"..*.H. .......* [0030] 86 48 82 F7 12 01 02 02 06 0A 2B 06 01 04 01 82 .H...... ..+..... [0040] 37 02 02 0A A3 29 30 27 A0 25 1B 23 63 69 66 73 7....)0' .%.#cifs [0050] 2F 6B 61 6B 6B 6F 73 6D 61 73 73 65 40 48 59 54 /kakkosm asse@HYT [0060] 45 53 54 2E 48 45 4C 53 49 4E 4B 49 2E 46 49 EST.HELS INKI.FI [2009/11/26 11:16:38, 5] lib/util_sock.c:526(read_socket_with_timeout) read_socket_with_timeout: blocking read. EOF from client. [2009/11/26 11:16:38, 10] smbd/process.c:271(receive_smb_raw_talloc) receive_smb_raw: NT_STATUS_END_OF_FILE [2009/11/26 11:16:38, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:16:38, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:16:38, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:16:38, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/11/26 11:16:38, 3] smbd/connection.c:31(yield_connection) Yielding connection to [2009/11/26 11:16:38, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key FB0E0000FFFFFFFF0000 [2009/11/26 11:16:38, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2406210 [2009/11/26 11:16:38, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key FB0E0000FFFFFFFF0000 [2009/11/26 11:16:38, 3] smbd/server.c:848(exit_server_common) Server exit (failed to receive smb request) [2009/11/26 11:19:22, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 50628 SO_RCVBUF = 87424 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:19:22, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 50628 SO_RCVBUF = 87424 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:19:22, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:19:22, 3] smbd/oplock.c:911(init_oplocks) init_oplocks: initializing messages. [2009/11/26 11:19:22, 3] smbd/oplock_linux.c:219(linux_init_kernel_oplocks) Linux kernel oplocks enabled [2009/11/26 11:19:22, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x2411be0 [2009/11/26 11:19:22, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(keepalive) 0x2411be0 [2009/11/26 11:19:22, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x23ab368 [2009/11/26 11:19:22, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(deadtime) 0x23ab368 [2009/11/26 11:19:22, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x2411cf8 [2009/11/26 11:19:22, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x2411cf8 [2009/11/26 11:19:22, 10] lib/util_sock.c:781(read_smb_length_return_keepalive) got smb length of 190 [2009/11/26 11:19:22, 6] smbd/process.c:1450(process_smb) got message type 0x0 of len 0xbe [2009/11/26 11:19:22, 3] smbd/process.c:1453(process_smb) Transaction 0 of length 194 (0 toread) [2009/11/26 11:19:22, 5] lib/util.c:632(show_msg) [2009/11/26 11:19:22, 5] lib/util.c:642(show_msg) size=190 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=65535 smb_pid=3867 smb_uid=0 smb_mid=1 smt_wct=0 smb_bcc=155 [2009/11/26 11:19:22, 10] ../lib/util/util.c:304(_dump_data) [0000] 02 50 43 20 4E 45 54 57 4F 52 4B 20 50 52 4F 47 .PC NETW ORK PROG [0010] 52 41 4D 20 31 2E 30 00 02 4D 49 43 52 4F 53 4F RAM 1.0. .MICROSO [0020] 46 54 20 4E 45 54 57 4F 52 4B 53 20 31 2E 30 33 FT NETWO RKS 1.03 [0030] 00 02 4D 49 43 52 4F 53 4F 46 54 20 4E 45 54 57 ..MICROS OFT NETW [0040] 4F 52 4B 53 20 33 2E 30 00 02 4C 41 4E 4D 41 4E ORKS 3.0 ..LANMAN [0050] 31 2E 30 00 02 4C 4D 31 2E 32 58 30 30 32 00 02 1.0..LM1 .2X002.. [0060] 44 4F 53 20 4C 41 4E 4D 41 4E 32 2E 31 00 02 4C DOS LANM AN2.1..L [0070] 41 4E 4D 41 4E 32 2E 31 00 02 53 61 6D 62 61 00 ANMAN2.1 ..Samba. [0080] 02 4E 54 20 4C 41 4E 4D 41 4E 20 31 2E 30 00 02 .NT LANM AN 1.0.. [0090] 4E 54 20 4C 4D 20 30 2E 31 32 00 NT LM 0. 12. [2009/11/26 11:19:22, 3] smbd/process.c:1272(switch_message) switch message SMBnegprot (pid 3868) conn 0x0 [2009/11/26 11:19:22, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:19:22, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:19:22, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:19:22, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [PC NETWORK PROGRAM 1.0] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [MICROSOFT NETWORKS 1.03] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [MICROSOFT NETWORKS 3.0] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LANMAN1.0] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LM1.2X002] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [DOS LANMAN2.1] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LANMAN2.1] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [Samba] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [NT LANMAN 1.0] [2009/11/26 11:19:22, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [NT LM 0.12] [2009/11/26 11:19:22, 10] lib/util.c:1979(set_remote_arch) set_remote_arch: Client arch is 'Samba' [2009/11/26 11:19:22, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:19:22, 5] smbd/connection.c:142(claim_connection) claiming [] [2009/11/26 11:19:22, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 1C0F0000FFFFFFFF0000 [2009/11/26 11:19:22, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2415af8 [2009/11/26 11:19:22, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 1C0F0000FFFFFFFF0000 [2009/11/26 11:19:22, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:19:22, 10] lib/util.c:2626(name_to_fqdn) name_to_fqdn: lookup for KAKKOSMASSE -> kakkosmasse. [2009/11/26 11:19:22, 3] smbd/negprot.c:387(reply_nt1) using SPNEGO [2009/11/26 11:19:22, 3] smbd/negprot.c:672(reply_negprot) Selected protocol NT LANMAN 1.0 [2009/11/26 11:19:22, 5] smbd/negprot.c:679(reply_negprot) negprot index=8 [2009/11/26 11:19:22, 5] lib/util.c:632(show_msg) [2009/11/26 11:19:22, 5] lib/util.c:642(show_msg) size=180 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=65535 smb_pid=3867 smb_uid=0 smb_mid=1 smt_wct=17 smb_vwv[ 0]= 8 (0x8) 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]= 7168 (0x1C00) smb_vwv[ 8]= 15 (0xF) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]= 128 (0x80) smb_vwv[12]= 4553 (0x11C9) smb_vwv[13]=31114 (0x798A) smb_vwv[14]=51822 (0xCA6E) smb_vwv[15]=34817 (0x8801) smb_vwv[16]= 255 (0xFF) smb_bcc=111 [2009/11/26 11:19:22, 10] ../lib/util/util.c:304(_dump_data) [0000] 6B 61 6B 6B 6F 73 6D 61 73 73 65 00 00 00 00 00 kakkosma sse..... [0010] 60 5D 06 06 2B 06 01 05 05 02 A0 53 30 51 A0 24 `]..+... ...S0Q.$ [0020] 30 22 06 09 2A 86 48 86 F7 12 01 02 02 06 09 2A 0"..*.H. .......* [0030] 86 48 82 F7 12 01 02 02 06 0A 2B 06 01 04 01 82 .H...... ..+..... [0040] 37 02 02 0A A3 29 30 27 A0 25 1B 23 63 69 66 73 7....)0' .%.#cifs [0050] 2F 6B 61 6B 6B 6F 73 6D 61 73 73 65 40 48 59 54 /kakkosm asse@HYT [0060] 45 53 54 2E 48 45 4C 53 49 4E 4B 49 2E 46 49 EST.HELS INKI.FI [2009/11/26 11:19:22, 5] lib/util_sock.c:526(read_socket_with_timeout) read_socket_with_timeout: blocking read. EOF from client. [2009/11/26 11:19:22, 10] smbd/process.c:271(receive_smb_raw_talloc) receive_smb_raw: NT_STATUS_END_OF_FILE [2009/11/26 11:19:22, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:19:22, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:19:22, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:19:22, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/11/26 11:19:22, 3] smbd/connection.c:31(yield_connection) Yielding connection to [2009/11/26 11:19:22, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 1C0F0000FFFFFFFF0000 [2009/11/26 11:19:22, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2406210 [2009/11/26 11:19:22, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 1C0F0000FFFFFFFF0000 [2009/11/26 11:19:22, 3] smbd/server.c:848(exit_server_common) Server exit (failed to receive smb request) [2009/11/26 11:19:30, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 50628 SO_RCVBUF = 87424 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:19:30, 5] lib/util_sock.c:371(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 = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 50628 SO_RCVBUF = 87424 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2009/11/26 11:19:30, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:19:30, 3] smbd/oplock.c:911(init_oplocks) init_oplocks: initializing messages. [2009/11/26 11:19:30, 3] smbd/oplock_linux.c:219(linux_init_kernel_oplocks) Linux kernel oplocks enabled [2009/11/26 11:19:30, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x2411be0 [2009/11/26 11:19:30, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(keepalive) 0x2411be0 [2009/11/26 11:19:30, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x23ab368 [2009/11/26 11:19:30, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(deadtime) 0x23ab368 [2009/11/26 11:19:30, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "smbd_idle_event_handler": 0x2411cf8 [2009/11/26 11:19:30, 10] smbd/process.c:719(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x2411cf8 [2009/11/26 11:19:30, 10] lib/util_sock.c:781(read_smb_length_return_keepalive) got smb length of 190 [2009/11/26 11:19:30, 6] smbd/process.c:1450(process_smb) got message type 0x0 of len 0xbe [2009/11/26 11:19:30, 3] smbd/process.c:1453(process_smb) Transaction 0 of length 194 (0 toread) [2009/11/26 11:19:30, 5] lib/util.c:632(show_msg) [2009/11/26 11:19:30, 5] lib/util.c:642(show_msg) size=190 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 smb_flg2=51201 smb_tid=65535 smb_pid=3885 smb_uid=0 smb_mid=1 smt_wct=0 smb_bcc=155 [2009/11/26 11:19:30, 10] ../lib/util/util.c:304(_dump_data) [0000] 02 50 43 20 4E 45 54 57 4F 52 4B 20 50 52 4F 47 .PC NETW ORK PROG [0010] 52 41 4D 20 31 2E 30 00 02 4D 49 43 52 4F 53 4F RAM 1.0. .MICROSO [0020] 46 54 20 4E 45 54 57 4F 52 4B 53 20 31 2E 30 33 FT NETWO RKS 1.03 [0030] 00 02 4D 49 43 52 4F 53 4F 46 54 20 4E 45 54 57 ..MICROS OFT NETW [0040] 4F 52 4B 53 20 33 2E 30 00 02 4C 41 4E 4D 41 4E ORKS 3.0 ..LANMAN [0050] 31 2E 30 00 02 4C 4D 31 2E 32 58 30 30 32 00 02 1.0..LM1 .2X002.. [0060] 44 4F 53 20 4C 41 4E 4D 41 4E 32 2E 31 00 02 4C DOS LANM AN2.1..L [0070] 41 4E 4D 41 4E 32 2E 31 00 02 53 61 6D 62 61 00 ANMAN2.1 ..Samba. [0080] 02 4E 54 20 4C 41 4E 4D 41 4E 20 31 2E 30 00 02 .NT LANM AN 1.0.. [0090] 4E 54 20 4C 4D 20 30 2E 31 32 00 NT LM 0. 12. [2009/11/26 11:19:30, 3] smbd/process.c:1272(switch_message) switch message SMBnegprot (pid 3886) conn 0x0 [2009/11/26 11:19:30, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:19:30, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:19:30, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:19:30, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [PC NETWORK PROGRAM 1.0] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [MICROSOFT NETWORKS 1.03] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [MICROSOFT NETWORKS 3.0] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LANMAN1.0] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LM1.2X002] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [DOS LANMAN2.1] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [LANMAN2.1] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [Samba] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [NT LANMAN 1.0] [2009/11/26 11:19:30, 3] smbd/negprot.c:567(reply_negprot) Requested protocol [NT LM 0.12] [2009/11/26 11:19:30, 10] lib/util.c:1979(set_remote_arch) set_remote_arch: Client arch is 'Samba' [2009/11/26 11:19:30, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:19:30, 5] smbd/connection.c:142(claim_connection) claiming [] [2009/11/26 11:19:30, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 2E0F0000FFFFFFFF0000 [2009/11/26 11:19:30, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2415af8 [2009/11/26 11:19:30, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 2E0F0000FFFFFFFF0000 [2009/11/26 11:19:30, 6] param/loadparm.c:6994(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Nov 26 11:16:18 2009 [2009/11/26 11:19:30, 10] lib/util.c:2626(name_to_fqdn) name_to_fqdn: lookup for KAKKOSMASSE -> kakkosmasse. [2009/11/26 11:19:30, 3] smbd/negprot.c:387(reply_nt1) using SPNEGO [2009/11/26 11:19:30, 3] smbd/negprot.c:672(reply_negprot) Selected protocol NT LANMAN 1.0 [2009/11/26 11:19:30, 5] smbd/negprot.c:679(reply_negprot) negprot index=8 [2009/11/26 11:19:30, 5] lib/util.c:632(show_msg) [2009/11/26 11:19:30, 5] lib/util.c:642(show_msg) size=180 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=65535 smb_pid=3885 smb_uid=0 smb_mid=1 smt_wct=17 smb_vwv[ 0]= 8 (0x8) 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]=11776 (0x2E00) smb_vwv[ 8]= 15 (0xF) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]= 128 (0x80) smb_vwv[12]=54909 (0xD67D) smb_vwv[13]=31118 (0x798E) smb_vwv[14]=51822 (0xCA6E) smb_vwv[15]=34817 (0x8801) smb_vwv[16]= 255 (0xFF) smb_bcc=111 [2009/11/26 11:19:30, 10] ../lib/util/util.c:304(_dump_data) [0000] 6B 61 6B 6B 6F 73 6D 61 73 73 65 00 00 00 00 00 kakkosma sse..... [0010] 60 5D 06 06 2B 06 01 05 05 02 A0 53 30 51 A0 24 `]..+... ...S0Q.$ [0020] 30 22 06 09 2A 86 48 86 F7 12 01 02 02 06 09 2A 0"..*.H. .......* [0030] 86 48 82 F7 12 01 02 02 06 0A 2B 06 01 04 01 82 .H...... ..+..... [0040] 37 02 02 0A A3 29 30 27 A0 25 1B 23 63 69 66 73 7....)0' .%.#cifs [0050] 2F 6B 61 6B 6B 6F 73 6D 61 73 73 65 40 48 59 54 /kakkosm asse@HYT [0060] 45 53 54 2E 48 45 4C 53 49 4E 4B 49 2E 46 49 EST.HELS INKI.FI [2009/11/26 11:19:30, 5] lib/util_sock.c:526(read_socket_with_timeout) read_socket_with_timeout: blocking read. EOF from client. [2009/11/26 11:19:30, 10] smbd/process.c:271(receive_smb_raw_talloc) receive_smb_raw: NT_STATUS_END_OF_FILE [2009/11/26 11:19:30, 3] smbd/sec_ctx.c:310(set_sec_ctx) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2009/11/26 11:19:30, 5] auth/token_util.c:522(debug_nt_user_token) NT user token: (NULL) [2009/11/26 11:19:30, 5] auth/token_util.c:548(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2009/11/26 11:19:30, 5] smbd/uid.c:368(change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2009/11/26 11:19:30, 3] smbd/connection.c:31(yield_connection) Yielding connection to [2009/11/26 11:19:30, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key 2E0F0000FFFFFFFF0000 [2009/11/26 11:19:30, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x2406210 [2009/11/26 11:19:30, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key 2E0F0000FFFFFFFF0000 [2009/11/26 11:19:30, 3] smbd/server.c:848(exit_server_common) Server exit (failed to receive smb request)