[2009/09/24 09:06:20, 3] param/loadparm.c:9011(lp_load_ex) lp_load_ex: refreshing parameters Initialising global parameters [2009/09/24 09:06:20, 3] ../lib/util/params.c:550(pm_process) params.c:pm_process() - Processing configuration file "/etc/samba//smb.conf" [2009/09/24 09:06:20, 3] param/loadparm.c:7698(do_section) Processing section "[global]" doing parameter panic action = "/usr/local/bin/panic-action %d" doing parameter pid directory = /var/run/samba/ doing parameter lock directory = /usr/var/locks/ doing parameter log file = /var/log/samba/%m.log doing parameter interfaces = localhost eth0 doing parameter bind interfaces only = true doing parameter log level = 10 [2009/09/24 09:06:20, 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 netbios name = Nucleus [2009/09/24 09:06:20, 4] param/loadparm.c:7060(handle_netbios_name) handle_netbios_name: set global_myname to: NUCLEUS doing parameter server string = doing parameter workgroup = MUC doing parameter os level = 20 doing parameter security = domain doing parameter password server = Genome doing parameter local master = no doing parameter domain master = no doing parameter preferred master = no doing parameter mangling method = hash2 doing parameter utmp = no doing parameter unix extensions = yes doing parameter guest account = nobody doing parameter map to guest = Never doing parameter load printers = no doing parameter admin users = MUC\Administrator doing parameter veto files = /.bin/.viminfo/.bash_history/Platform.ini/ doing parameter delete veto files = yes doing parameter idmap backend = tdb doing parameter idmap uid = 30000-35000 doing parameter idmap gid = 30000-35000 doing parameter winbind nss info = template doing parameter template homedir = /home/%U doing parameter template shell = /bin/false doing parameter winbind trusted domains only = no doing parameter winbind use default domain = yes doing parameter winbind enum users = yes doing parameter winbind enum groups = yes doing parameter wins server = 192.168.29.4 doing parameter unix charset = UTF-8 doing parameter display charset = UTF-8 doing parameter dos charset = 850 doing parameter use sendfile = yes doing parameter large readwrite = yes doing parameter socket options = TCP_NODELAY SO_KEEPALIVE SO_SNDBUF=8192 SO_RCVBUF=8192 doing parameter username level = 0 doing parameter kernel oplocks = true doing parameter veto oplock files = /*.doc/*.xls/*.mdb/ doing parameter map archive = no doing parameter map system = no doing parameter map hidden = no doing parameter writeable = yes doing parameter inherit permissions = yes doing parameter include = /etc/samba/shares.conf [2009/09/24 09:06:20, 3] ../lib/util/params.c:550(pm_process) params.c:pm_process() - Processing configuration file "/etc/samba/shares.conf" [2009/09/24 09:06:20, 2] param/loadparm.c:7715(do_section) Processing section "[intranet]" [2009/09/24 09:06:20, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 0 for intranet [2009/09/24 09:06:20, 10] param/loadparm.c:6062(hash_a_service) hash_a_service: creating servicehash [2009/09/24 09:06:20, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 0 for service name intranet doing parameter comment = Intranet (Documentroot) doing parameter path = /srv/www/htdocs/intranet.mr.lfmg.de doing parameter browsable = yes doing parameter force create mode = 0664 doing parameter force directory mode = 775 doing parameter guest ok = no doing parameter valid users = +MUC\webmaster doing parameter invalid users = [2009/09/24 09:06:20, 2] param/loadparm.c:7715(do_section) Processing section "[qm]" [2009/09/24 09:06:20, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 1 for qm [2009/09/24 09:06:20, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 1 for service name qm doing parameter comment = Intranet (QM-Dokumente) doing parameter path = /srv/www/htdocs/intranet.mr.lfmg.de/cms/uploads/documents/qm doing parameter browsable = yes doing parameter force create mode = 0664 doing parameter force directory mode = 775 doing parameter guest ok = no doing parameter valid users = +MUC\webmaster,MUC\csalje doing parameter invalid users = doing parameter veto files = /.bin/.viminfo/.bash_history/Platform.ini/*.php/ [2009/09/24 09:06:20, 2] param/loadparm.c:7715(do_section) Processing section "[protocols]" [2009/09/24 09:06:20, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 2 for protocols [2009/09/24 09:06:20, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 2 for service name protocols doing parameter comment = Intranet (Besprechungsprotokolle) doing parameter path = /srv/www/htdocs/intranet.mr.lfmg.de/mitarbeiterbesprechungen/protocols doing parameter browsable = yes doing parameter force create mode = 0664 doing parameter force directory mode = 775 doing parameter guest ok = no doing parameter valid users = +MUC\webmaster, MUC\bock doing parameter invalid users = doing parameter veto files = /.bin/.viminfo/.bash_history/Platform.ini/*.php/ [2009/09/24 09:06:20, 2] param/loadparm.c:7715(do_section) Processing section "[packages]" [2009/09/24 09:06:20, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 3 for packages [2009/09/24 09:06:20, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 3 for service name packages doing parameter comment = Prism Packages doing parameter path = /srv/www/htdocs/packages.mr.lfmg.de doing parameter browsable = no doing parameter force create mode = 0664 doing parameter force directory mode = 2775 doing parameter guest ok = no doing parameter valid users = +MUC\systemadministration doing parameter invalid users = doing parameter veto files = /.bin/.viminfo/.bash_history/Platform.ini/.htaccess/.htpasswd/ [2009/09/24 09:06:20, 4] param/loadparm.c:9046(lp_load_ex) pm_process() returned Yes [2009/09/24 09:06:20, 7] param/loadparm.c:9251(lp_servicenumber) lp_servicenumber: couldn't find homes [2009/09/24 09:06:20, 8] param/loadparm.c:6024(add_a_service) add_a_service: Creating snum = 4 for IPC$ [2009/09/24 09:06:20, 10] param/loadparm.c:6071(hash_a_service) hash_a_service: hashing index 4 for service name IPC$ [2009/09/24 09:06:20, 3] param/loadparm.c:6169(lp_add_ipc) adding IPC service [2009/09/24 09:06:20, 10] param/loadparm.c:8259(set_server_role) set_server_role: role = ROLE_DOMAIN_MEMBER [2009/09/24 09:06:20, 2] lib/interface.c:340(add_interface) added interface lo ip=127.0.0.1 bcast=127.255.255.255 netmask=255.0.0.0 [2009/09/24 09:06:20, 2] lib/interface.c:340(add_interface) added interface eth0 ip=192.168.29.2 bcast=192.168.29.255 netmask=255.255.255.0 [2009/09/24 09:06:20, 5] lib/util.c:266(init_names) Netbios name list:- my_netbios_names[0]="NUCLEUS" [2009/09/24 09:06:20, 2] lib/interface.c:340(add_interface) added interface lo ip=127.0.0.1 bcast=127.255.255.255 netmask=255.0.0.0 [2009/09/24 09:06:20, 2] lib/interface.c:340(add_interface) added interface eth0 ip=192.168.29.2 bcast=192.168.29.255 netmask=255.255.255.0 [2009/09/24 09:06:20, 5] lib/gencache.c:61(gencache_init) Opening cache file at /usr/var/locks/gencache.tdb [2009/09/24 09:06:20, 5] libsmb/namecache.c:59(namecache_enable) namecache_enable: enabling netbios namecache, timeout 660 seconds [2009/09/24 09:06:20, 8] ../lib/util/util.c:243(fcntl_lock) fcntl_lock 10 13 0 1 1 [2009/09/24 09:06:20, 8] ../lib/util/util.c:278(fcntl_lock) fcntl_lock: Lock call successful [2009/09/24 09:06:20, 4] lib/time.c:787(TimeInit) TimeInit: Serverzone is -7200 [2009/09/24 09:06:20, 0] winbindd/winbindd_cache.c:2578(initialize_winbindd_cache) initialize_winbindd_cache: clearing cache and re-creating with version number 1 [2009/09/24 09:06:20, 5] smbd/connection.c:142(claim_connection) claiming [] [2009/09/24 09:06:20, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked) Locking key FA780000FFFFFFFF0000 [2009/09/24 09:06:20, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked) Allocated locked data 0x0x805bc000 [2009/09/24 09:06:20, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr) Unlocking key FA780000FFFFFFFF0000 [2009/09/24 09:06:20, 5] lib/messages.c:297(messaging_register) Overriding messaging pointer for type 1 - private_data=(nil) [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:4073(wcache_tdc_add_domain) wcache_tdc_add_domain: Adding domain BUILTIN (), SID S-1-5-32, flags = 0x0, attributes = 0x0, type = 0x0 [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3882(pack_tdc_domains) pack_tdc_domains: Packing 1 trusted domains [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3901(pack_tdc_domains) pack_tdc_domains: Packing domain BUILTIN () [2009/09/24 09:06:20, 10] winbindd/winbindd_util.c:212(add_trusted_domain) idmap config BUILTIN : range = not defined [2009/09/24 09:06:20, 2] winbindd/winbindd_util.c:235(add_trusted_domain) Added domain BUILTIN S-1-5-32 [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:4073(wcache_tdc_add_domain) wcache_tdc_add_domain: Adding domain NUCLEUS (), SID S-1-5-21-3081873947-908747446-877470218, flags = 0x0, attributes = 0x0, type = 0x0 [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3882(pack_tdc_domains) pack_tdc_domains: Packing 2 trusted domains [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3901(pack_tdc_domains) pack_tdc_domains: Packing domain BUILTIN () [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3901(pack_tdc_domains) pack_tdc_domains: Packing domain NUCLEUS () [2009/09/24 09:06:20, 10] winbindd/winbindd_util.c:212(add_trusted_domain) idmap config NUCLEUS : range = not defined [2009/09/24 09:06:20, 2] winbindd/winbindd_util.c:235(add_trusted_domain) Added domain NUCLEUS S-1-5-21-3081873947-908747446-877470218 [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:4073(wcache_tdc_add_domain) wcache_tdc_add_domain: Adding domain MUC (), SID S-1-5-21-1362721961-1801182073-732966438, flags = 0x0, attributes = 0x0, type = 0x0 [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3882(pack_tdc_domains) pack_tdc_domains: Packing 3 trusted domains [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3901(pack_tdc_domains) pack_tdc_domains: Packing domain BUILTIN () [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3901(pack_tdc_domains) pack_tdc_domains: Packing domain NUCLEUS () [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3901(pack_tdc_domains) pack_tdc_domains: Packing domain MUC () [2009/09/24 09:06:20, 10] winbindd/winbindd_util.c:212(add_trusted_domain) idmap config MUC : range = not defined [2009/09/24 09:06:20, 2] winbindd/winbindd_util.c:235(add_trusted_domain) Added domain MUC S-1-5-21-1362721961-1801182073-732966438 [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:479(set_domain_online_request) set_domain_online_request: called for domain MUC [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:508(set_domain_online_request) set_domain_online_request: domain MUC was globally offline. [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "check_domain_online_handler": 0x805bdef8 [2009/09/24 09:06:20, 10] winbindd/winbindd_util.c:1302(open_winbindd_socket) open_winbindd_socket: opened socket fd 14 [2009/09/24 09:06:20, 10] winbindd/winbindd_util.c:1314(open_winbindd_priv_socket) open_winbindd_priv_socket: opened socket fd 15 [2009/09/24 09:06:20, 10] winbindd/winbindd_dual.c:125(async_request) Sending request to child pid 0 (domain=MUC) [2009/09/24 09:06:20, 10] winbindd/winbindd_dual.c:1249(fork_domain_child) fork_domain_child called for domain 'MUC' [2009/09/24 09:06:20, 10] winbindd/winbindd_dual.c:1283(fork_domain_child) Child process 30971 [2009/09/24 09:06:20, 10] lib/events.c:148(get_timed_events_timeout) timed_events_timeout: 5/0 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_request_timeout_handler": 0x8055b130 [2009/09/24 09:06:20, 10] lib/events.c:148(get_timed_events_timeout) timed_events_timeout: 5/0 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x8055b130 "async_request_timeout_handler" [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:2667(cache_retrieve_response) Retrieving response for pid 30971 [2009/09/24 09:06:20, 5] winbindd/winbindd_util.c:672(init_child_recv) Received child initialization response for domain MUC [2009/09/24 09:06:20, 8] winbindd/winbindd_cm.c:1589(connection_ok) connection_ok: Connection to for domain MUC has NULL cli! [2009/09/24 09:06:20, 10] lib/gencache.c:194(gencache_get) Cache entry with key = SAFJOIN/DOMAIN/MUC couldn't be found [2009/09/24 09:06:20, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = SAF/DOMAIN/MUC, value = GENOME, timeout = Thu Sep 24 09:21:20 2009 [2009/09/24 09:06:20, 5] libsmb/namequery.c:200(saf_fetch) saf_fetch: Returning "GENOME" for "MUC" domain [2009/09/24 09:06:20, 10] lib/gencache.c:194(gencache_get) Cache entry with key = NEG_CONN_CACHE/MUC,GENOME couldn't be found [2009/09/24 09:06:20, 9] libsmb/conncache.c:150(check_negative_conn_cache) check_negative_conn_cache returning result 0 for domain MUC server GENOME [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:1430(cm_open_connection) cm_open_connection: saf_servername is 'GENOME' for domain MUC [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:1462(cm_open_connection) cm_open_connection: dcname is 'GENOME' for domain MUC [2009/09/24 09:06:20, 10] lib/gencache.c:194(gencache_get) Cache entry with key = NEG_CONN_CACHE/MUC,GENOME couldn't be found [2009/09/24 09:06:20, 9] libsmb/conncache.c:150(check_negative_conn_cache) check_negative_conn_cache returning result 0 for domain MUC server GENOME [2009/09/24 09:06:20, 10] lib/gencache.c:194(gencache_get) Cache entry with key = AD_SITENAME/DOMAIN/ couldn't be found [2009/09/24 09:06:20, 5] libads/dns.c:814(sitename_fetch) sitename_fetch: No stored sitename for [2009/09/24 09:06:20, 10] libsmb/namequery.c:1505(internal_resolve_name) internal_resolve_name: looking up GENOME#20 (sitename (null)) [2009/09/24 09:06:20, 10] lib/gencache.c:208(gencache_get) Returning valid cache entry: key = NBT/GENOME#20, value = 192.168.29.4:0, timeout = Thu Sep 24 09:16:42 2009 [2009/09/24 09:06:20, 5] libsmb/namecache.c:212(namecache_fetch) name GENOME#20 found. [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:774(cm_prepare_connection) cm_prepare_connection: connecting to DC GENOME for domain MUC [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=1 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 1 [2009/09/24 09:06:20, 5] winbindd/winbindd_cm.c:882(cm_prepare_connection) connecting to GENOME from NUCLEUS with username [MUC]\[NUCLEUS$] [2009/09/24 09:06:20, 3] libsmb/cliconnect.c:939(cli_session_setup_spnego) Doing spnego session setup (blob length=58) [2009/09/24 09:06:20, 3] libsmb/cliconnect.c:966(cli_session_setup_spnego) got OID=1.3.6.1.4.1.311.2.2.10 [2009/09/24 09:06:20, 3] libsmb/cliconnect.c:974(cli_session_setup_spnego) got principal=NONE [2009/09/24 09:06:20, 6] libsmb/clientgen.c:245(write_socket) write_socket(19,162) [2009/09/24 09:06:20, 6] libsmb/clientgen.c:248(write_socket) write_socket(19,162) wrote 162 [2009/09/24 09:06:20, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 262 [2009/09/24 09:06:20, 5] lib/util.c:632(show_msg) [2009/09/24 09:06:20, 5] lib/util.c:642(show_msg) size=262 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=30970 smb_uid=100 smb_mid=2 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 177 (0xB1) smb_bcc=219 [2009/09/24 09:06:20, 10] ../lib/util/util.c:304(_dump_data) [0000] A1 81 AE 30 81 AB A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [0010] 06 01 04 01 82 37 02 02 0A A2 81 95 04 81 92 4E .....7.. .......N [0020] 54 4C 4D 53 53 50 00 02 00 00 00 06 00 06 00 30 TLMSSP.. .......0 [0030] 00 00 00 15 82 89 60 95 65 06 6E BE DF D6 EE 00 ......`. e.n..... [0040] 00 00 00 00 00 00 00 5C 00 5C 00 36 00 00 00 4D .......\ .\.6...M [0050] 00 55 00 43 00 02 00 06 00 4D 00 55 00 43 00 01 .U.C.... .M.U.C.. [0060] 00 0C 00 47 00 45 00 4E 00 4F 00 4D 00 45 00 04 ...G.E.N .O.M.E.. [0070] 00 14 00 6D 00 72 00 2E 00 6C 00 66 00 6D 00 67 ...m.r.. .l.f.m.g [0080] 00 2E 00 64 00 65 00 03 00 22 00 67 00 65 00 6E ...d.e.. .".g.e.n [0090] 00 6F 00 6D 00 65 00 2E 00 6D 00 72 00 2E 00 6C .o.m.e.. .m.r...l [00A0] 00 66 00 6D 00 67 00 2E 00 64 00 65 00 00 00 00 .f.m.g.. .d.e.... [00B0] 00 55 00 6E 00 69 00 78 00 00 00 53 00 61 00 6D .U.n.i.x ...S.a.m [00C0] 00 62 00 61 00 20 00 33 00 2E 00 33 00 2E 00 37 .b.a. .3 ...3...7 [00D0] 00 00 00 4D 00 55 00 43 00 00 00 ...M.U.C ... [2009/09/24 09:06:20, 5] lib/util.c:632(show_msg) [2009/09/24 09:06:20, 5] lib/util.c:642(show_msg) size=262 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=30970 smb_uid=100 smb_mid=2 smt_wct=4 smb_vwv[ 0]= 255 (0xFF) smb_vwv[ 1]= 0 (0x0) smb_vwv[ 2]= 0 (0x0) smb_vwv[ 3]= 177 (0xB1) smb_bcc=219 [2009/09/24 09:06:20, 10] ../lib/util/util.c:304(_dump_data) [0000] A1 81 AE 30 81 AB A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [0010] 06 01 04 01 82 37 02 02 0A A2 81 95 04 81 92 4E .....7.. .......N [0020] 54 4C 4D 53 53 50 00 02 00 00 00 06 00 06 00 30 TLMSSP.. .......0 [0030] 00 00 00 15 82 89 60 95 65 06 6E BE DF D6 EE 00 ......`. e.n..... [0040] 00 00 00 00 00 00 00 5C 00 5C 00 36 00 00 00 4D .......\ .\.6...M [0050] 00 55 00 43 00 02 00 06 00 4D 00 55 00 43 00 01 .U.C.... .M.U.C.. [0060] 00 0C 00 47 00 45 00 4E 00 4F 00 4D 00 45 00 04 ...G.E.N .O.M.E.. [0070] 00 14 00 6D 00 72 00 2E 00 6C 00 66 00 6D 00 67 ...m.r.. .l.f.m.g [0080] 00 2E 00 64 00 65 00 03 00 22 00 67 00 65 00 6E ...d.e.. .".g.e.n [0090] 00 6F 00 6D 00 65 00 2E 00 6D 00 72 00 2E 00 6C .o.m.e.. .m.r...l [00A0] 00 66 00 6D 00 67 00 2E 00 64 00 65 00 00 00 00 .f.m.g.. .d.e.... [00B0] 00 55 00 6E 00 69 00 78 00 00 00 53 00 61 00 6D .U.n.i.x ...S.a.m [00C0] 00 62 00 61 00 20 00 33 00 2E 00 33 00 2E 00 37 .b.a. .3 ...3...7 [00D0] 00 00 00 4D 00 55 00 43 00 00 00 ...M.U.C ... [2009/09/24 09:06:20, 3] libsmb/ntlmssp.c:1023(ntlmssp_client_challenge) Got challenge flags: [2009/09/24 09:06:20, 3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0x60898215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_CHAL_TARGET_INFO NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/24 09:06:20, 3] libsmb/ntlmssp.c:1045(ntlmssp_client_challenge) NTLMSSP: Set final flags: [2009/09/24 09:06:20, 3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0x60088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/24 09:06:20, 5] libsmb/ntlmssp.c:1119(ntlmssp_client_challenge) NTLMSSP challenge set by NTLM2 [2009/09/24 09:06:20, 5] libsmb/ntlmssp.c:1120(ntlmssp_client_challenge) challenge is: [2009/09/24 09:06:20, 5] ../lib/util/util.c:304(_dump_data) [0000] F5 72 83 EE 39 D0 84 0C .r..9... [2009/09/24 09:06:20, 3] libsmb/ntlmssp_sign.c:342(ntlmssp_sign_init) NTLMSSP Sign/Seal - Initialising with flags: [2009/09/24 09:06:20, 3] libsmb/ntlmssp.c:62(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0x60088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_NTLM2 NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2009/09/24 09:06:20, 6] libsmb/clientgen.c:245(write_socket) write_socket(19,262) [2009/09/24 09:06:20, 6] libsmb/clientgen.c:248(write_socket) write_socket(19,262) wrote 262 [2009/09/24 09:06:20, 10] lib/util_sock.c:789(read_smb_length_return_keepalive) got smb length of 35 [2009/09/24 09:06:20, 5] lib/util.c:632(show_msg) [2009/09/24 09:06:20, 5] lib/util.c:642(show_msg) size=35 smb_com=0x73 smb_rcls=109 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=30970 smb_uid=100 smb_mid=3 smt_wct=0 smb_bcc=0 [2009/09/24 09:06:20, 5] lib/util.c:632(show_msg) [2009/09/24 09:06:20, 5] lib/util.c:642(show_msg) size=35 smb_com=0x73 smb_rcls=109 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=30970 smb_uid=100 smb_mid=3 smt_wct=0 smb_bcc=0 [2009/09/24 09:06:20, 4] winbindd/winbindd_cm.c:891(cm_prepare_connection) authenticated session setup failed with Logon failure [2009/09/24 09:06:20, 3] winbindd/winbindd_cm.c:570(cm_get_ipc_userpass) cm_get_ipc_userpass: No auth-user defined [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:942(cm_prepare_connection) cm_prepare_connection: falling back to anonymous connection for DC GENOME [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=4 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 4 [2009/09/24 09:06:20, 5] winbindd/winbindd_cm.c:946(cm_prepare_connection) Connected anonymously [2009/09/24 09:06:20, 10] libsmb/clientgen.c:467(cli_init_creds) cli_init_creds: user domain [2009/09/24 09:06:20, 10] libsmb/namequery.c:86(saf_store) saf_store: domain = [MUC], server = [GENOME], expire = [1253776880] [2009/09/24 09:06:20, 10] lib/gencache.c:131(gencache_set) Adding cache entry with key = SAF/DOMAIN/MUC; value = GENOME and timeout = Thu Sep 24 09:21:20 2009 (900 seconds ahead) [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=5 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 5 [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3064(set_global_winbindd_state_online) set_global_winbindd_state_online: online requested. [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:3067(set_global_winbindd_state_online) set_global_winbindd_state_online: rejecting. [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:402(set_domain_online) set_domain_online: called for domain MUC [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805bdef8 "check_domain_online_handler" [2009/09/24 09:06:20, 10] winbindd/winbindd_cm.c:1945(set_dc_type_and_flags) set_dc_type_and_flags: setting up flags for primary domain [2009/09/24 09:06:20, 5] winbindd/winbindd_cm.c:1791(set_dc_type_and_flags_connect) set_dc_type_and_flags_connect: domain MUC [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=6 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 6 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:2554(rpc_pipe_bind_send) Bind RPC Pipe: host GENOME auth_type 0, auth_level 0 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 0b 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0048 000a auth_len : 0000 000c call_id : 00000001 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_rb [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_bba 0010 max_tsize: 10b8 0012 max_rsize: 10b8 0014 assoc_gid: 00000000 0018 num_contexts: 01 001c context_id : 0000 001e num_transfer_syntaxes: 01 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 00001f smb_io_rpc_iface [2009/09/24 09:06:20, 7] rpc_parse/parse_prs.c:88(prs_debug) 000020 smb_io_uuid uuid 0020 data : 3919286a 0024 data : b10c 0026 data : 11d0 0028 data : 9b a8 002a data : 00 c0 4f d9 2e f5 0030 version: 00000000 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000034 smb_io_rpc_iface [2009/09/24 09:06:20, 7] rpc_parse/parse_prs.c:88(prs_debug) 000034 smb_io_uuid uuid 0034 data : 8a885d04 0038 data : 1ceb 003a data : 11c9 003c data : 9f e8 003e data : 08 00 2b 10 48 60 0044 version: 00000002 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=72, this_data=72, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=7 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 7 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 0c 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0044 000a auth_len : 0000 000c call_id : 00000001 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x80551f08 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x80551f08 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x80551f08 "async_trigger" [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 68 at offset 0: NT_STATUS_OK [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1432(rpc_api_pipe_got_pdu) rpc_api_pipe: host GENOME returned 68 bytes. [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 0c 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0044 000a auth_len : 0000 000c call_id : 00000001 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_ba [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_bba 0010 max_tsize: 10b8 0012 max_rsize: 10b8 0014 assoc_gid: 000053f0 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000018 smb_io_rpc_addr_str 0018 len: 000e 001a str: \PIPE\dssetup. [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000028 smb_io_rpc_results 0028 num_results: 01 002c result : 0000 002e reason : 0000 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000030 smb_io_rpc_iface [2009/09/24 09:06:20, 7] rpc_parse/parse_prs.c:88(prs_debug) 000030 smb_io_uuid uuid 0030 data : 8a885d04 0034 data : 1ceb 0036 data : 11c9 0038 data : 9f e8 003a data : 08 00 2b 10 48 60 0040 version: 00000002 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:2401(check_bind_response) check_bind_response: accepted! [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:3672(cli_rpc_pipe_open_noauth) cli_rpc_pipe_open_noauth: opened pipe \lsarpc to machine GENOME and bound anonymously. [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) dssetup_DsRoleGetPrimaryDomainInformation: struct dssetup_DsRoleGetPrimaryDomainInformation in: struct dssetup_DsRoleGetPrimaryDomainInformation level : DS_ROLE_BASIC_INFORMATION (1) [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 00 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 001a 000a auth_len : 0000 000c call_id : 00000002 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_req hdr_req 0010 alloc_hint: 00000002 0014 context_id: 0000 0016 opnum : 0000 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=26, this_data=26, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=8 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 8 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 02 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 005c 000a auth_len : 0000 000c call_id : 00000002 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x80563488 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x80563488 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x80563488 "async_trigger" [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_resp rpc_hdr_resp 0010 alloc_hint: 00000044 0014 context_id: 0000 0016 cancel_ct : 00 0017 reserved : 00 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:904(cli_pipe_validate_current_pdu) cli_pipe_validate_current_pdu: got pdu len 92, data_len 68, ss_len 0 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 92 at offset 0: NT_STATUS_OK [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1432(rpc_api_pipe_got_pdu) rpc_api_pipe: host GENOME returned 136 bytes. [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) dssetup_DsRoleGetPrimaryDomainInformation: struct dssetup_DsRoleGetPrimaryDomainInformation out: struct dssetup_DsRoleGetPrimaryDomainInformation info : * info : union dssetup_DsRoleInfo(case 1) basic: struct dssetup_DsRolePrimaryDomInfoBasic role : DS_ROLE_PRIMARY_DC (5) flags : 0x01000000 (16777216) 0: DS_ROLE_PRIMARY_DS_RUNNING 0: DS_ROLE_PRIMARY_DS_MIXED_MODE 0: DS_ROLE_UPGRADE_IN_PROGRESS 1: DS_ROLE_PRIMARY_DOMAIN_GUID_PRESENT domain : * domain : 'MUC' dns_domain : NULL forest : NULL domain_guid : b4423e0a-9078-490c-a2ad-48abadb833b3 result : WERR_OK [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=9 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 9 [2009/09/24 09:06:20, 10] rpc_client/rpc_transport_np.c:40(rpc_transport_np_state_destructor) rpc_pipe_destructor: closed \lsarpc [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=10 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 10 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:2554(rpc_pipe_bind_send) Bind RPC Pipe: host GENOME auth_type 0, auth_level 0 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 0b 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0048 000a auth_len : 0000 000c call_id : 00000003 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_rb [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_bba 0010 max_tsize: 10b8 0012 max_rsize: 10b8 0014 assoc_gid: 00000000 0018 num_contexts: 01 001c context_id : 0000 001e num_transfer_syntaxes: 01 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 00001f smb_io_rpc_iface [2009/09/24 09:06:20, 7] rpc_parse/parse_prs.c:88(prs_debug) 000020 smb_io_uuid uuid 0020 data : 12345778 0024 data : 1234 0026 data : abcd 0028 data : ef 00 002a data : 01 23 45 67 89 ab 0030 version: 00000000 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000034 smb_io_rpc_iface [2009/09/24 09:06:20, 7] rpc_parse/parse_prs.c:88(prs_debug) 000034 smb_io_uuid uuid 0034 data : 8a885d04 0038 data : 1ceb 003a data : 11c9 003c data : 9f e8 003e data : 08 00 2b 10 48 60 0044 version: 00000002 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=72, this_data=72, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=11 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 11 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 0c 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0044 000a auth_len : 0000 000c call_id : 00000003 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x805bdef8 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x805bdef8 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805bdef8 "async_trigger" [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 68 at offset 0: NT_STATUS_OK [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1432(rpc_api_pipe_got_pdu) rpc_api_pipe: host GENOME returned 68 bytes. [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 0c 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0044 000a auth_len : 0000 000c call_id : 00000003 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_ba [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_bba 0010 max_tsize: 10b8 0012 max_rsize: 10b8 0014 assoc_gid: 000053f0 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000018 smb_io_rpc_addr_str 0018 len: 000d 001a str: \PIPE\lsarpc. [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000027 smb_io_rpc_results 0028 num_results: 01 002c result : 0000 002e reason : 0000 [2009/09/24 09:06:20, 6] rpc_parse/parse_prs.c:88(prs_debug) 000030 smb_io_rpc_iface [2009/09/24 09:06:20, 7] rpc_parse/parse_prs.c:88(prs_debug) 000030 smb_io_uuid uuid 0030 data : 8a885d04 0034 data : 1ceb 0036 data : 11c9 0038 data : 9f e8 003a data : 08 00 2b 10 48 60 0040 version: 00000002 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:2401(check_bind_response) check_bind_response: accepted! [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:3672(cli_rpc_pipe_open_noauth) cli_rpc_pipe_open_noauth: opened pipe \lsarpc to machine GENOME and bound anonymously. [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_OpenPolicy2: struct lsa_OpenPolicy2 in: struct lsa_OpenPolicy2 system_name : * system_name : '\\GENOME' attr : * attr: struct lsa_ObjectAttribute len : 0x00000018 (24) root_dir : NULL object_name : NULL attributes : 0x00000000 (0) sec_desc : NULL sec_qos : * sec_qos: struct lsa_QosInfo len : 0x0000000c (12) impersonation_level : 0x0002 (2) context_mode : 0x01 (1) effective_only : 0x00 (0) access_mask : 0x02000000 (33554432) 0: LSA_POLICY_VIEW_LOCAL_INFORMATION 0: LSA_POLICY_VIEW_AUDIT_INFORMATION 0: LSA_POLICY_GET_PRIVATE_INFORMATION 0: LSA_POLICY_TRUST_ADMIN 0: LSA_POLICY_CREATE_ACCOUNT 0: LSA_POLICY_CREATE_SECRET 0: LSA_POLICY_CREATE_PRIVILEGE 0: LSA_POLICY_SET_DEFAULT_QUOTA_LIMITS 0: LSA_POLICY_SET_AUDIT_REQUIREMENTS 0: LSA_POLICY_AUDIT_LOG_ADMIN 0: LSA_POLICY_SERVER_ADMIN 0: LSA_POLICY_LOOKUP_NAMES 0: LSA_POLICY_NOTIFICATION [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 00 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0060 000a auth_len : 0000 000c call_id : 00000004 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_req hdr_req 0010 alloc_hint: 00000048 0014 context_id: 0000 0016 opnum : 002c [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=96, this_data=96, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=12 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 12 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 02 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0030 000a auth_len : 0000 000c call_id : 00000004 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x805525d8 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x805525d8 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805525d8 "async_trigger" [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_resp rpc_hdr_resp 0010 alloc_hint: 00000018 0014 context_id: 0000 0016 cancel_ct : 00 0017 reserved : 00 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:904(cli_pipe_validate_current_pdu) cli_pipe_validate_current_pdu: got pdu len 48, data_len 24, ss_len 0 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 48 at offset 0: NT_STATUS_OK [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1432(rpc_api_pipe_got_pdu) rpc_api_pipe: host GENOME returned 48 bytes. [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_OpenPolicy2: struct lsa_OpenPolicy2 out: struct lsa_OpenPolicy2 handle : * handle: struct policy_handle handle_type : 0x00000000 (0) uuid : 00000001-0000-0000-bb4a-6c1aaa660000 result : NT_STATUS_OK [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_QueryInfoPolicy2: struct lsa_QueryInfoPolicy2 in: struct lsa_QueryInfoPolicy2 handle : * handle: struct policy_handle handle_type : 0x00000000 (0) uuid : 00000001-0000-0000-bb4a-6c1aaa660000 level : LSA_POLICY_INFO_DNS (12) [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 00 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 002e 000a auth_len : 0000 000c call_id : 00000005 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_req hdr_req 0010 alloc_hint: 00000016 0014 context_id: 0000 0016 opnum : 002e [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=46, this_data=46, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=13 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 13 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 03 0003 flags : 23 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0020 000a auth_len : 0000 000c call_id : 00000005 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x805b5330 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x805b5330 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805b5330 "async_trigger" [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_resp rpc_hdr_resp 0010 alloc_hint: 00000000 0014 context_id: 0000 0016 cancel_ct : 00 0017 reserved : 00 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000018 smb_io_rpc_hdr_fault fault 0018 status : DCERPC_FAULT_OP_RNG_ERROR 001c reserved: 00000000 [2009/09/24 09:06:20, 1] rpc_client/cli_pipe.c:948(cli_pipe_validate_current_pdu) cli_pipe_validate_current_pdu: RPC fault code DCERPC_FAULT_OP_RNG_ERROR received from host GENOME! [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 32 at offset 0: NT code 0x1c010002 [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_OpenPolicy: struct lsa_OpenPolicy in: struct lsa_OpenPolicy system_name : * system_name : 0x005c (92) attr : * attr: struct lsa_ObjectAttribute len : 0x00000018 (24) root_dir : NULL object_name : NULL attributes : 0x00000000 (0) sec_desc : NULL sec_qos : * sec_qos: struct lsa_QosInfo len : 0x0000000c (12) impersonation_level : 0x0002 (2) context_mode : 0x01 (1) effective_only : 0x00 (0) access_mask : 0x02000000 (33554432) 0: LSA_POLICY_VIEW_LOCAL_INFORMATION 0: LSA_POLICY_VIEW_AUDIT_INFORMATION 0: LSA_POLICY_GET_PRIVATE_INFORMATION 0: LSA_POLICY_TRUST_ADMIN 0: LSA_POLICY_CREATE_ACCOUNT 0: LSA_POLICY_CREATE_SECRET 0: LSA_POLICY_CREATE_PRIVILEGE 0: LSA_POLICY_SET_DEFAULT_QUOTA_LIMITS 0: LSA_POLICY_SET_AUDIT_REQUIREMENTS 0: LSA_POLICY_AUDIT_LOG_ADMIN 0: LSA_POLICY_SERVER_ADMIN 0: LSA_POLICY_LOOKUP_NAMES 0: LSA_POLICY_NOTIFICATION [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 00 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0044 000a auth_len : 0000 000c call_id : 00000006 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_req hdr_req 0010 alloc_hint: 0000002c 0014 context_id: 0000 0016 opnum : 0006 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=68, this_data=68, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=14 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 14 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 02 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0030 000a auth_len : 0000 000c call_id : 00000006 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x805b5330 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x805b5330 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805b5330 "async_trigger" [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_resp rpc_hdr_resp 0010 alloc_hint: 00000018 0014 context_id: 0000 0016 cancel_ct : 00 0017 reserved : 00 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:904(cli_pipe_validate_current_pdu) cli_pipe_validate_current_pdu: got pdu len 48, data_len 24, ss_len 0 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 48 at offset 0: NT_STATUS_OK [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1432(rpc_api_pipe_got_pdu) rpc_api_pipe: host GENOME returned 48 bytes. [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_OpenPolicy: struct lsa_OpenPolicy out: struct lsa_OpenPolicy handle : * handle: struct policy_handle handle_type : 0x00000000 (0) uuid : 00000002-0000-0000-bb4a-6c1aaa660000 result : NT_STATUS_OK [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_QueryInfoPolicy: struct lsa_QueryInfoPolicy in: struct lsa_QueryInfoPolicy handle : * handle: struct policy_handle handle_type : 0x00000000 (0) uuid : 00000002-0000-0000-bb4a-6c1aaa660000 level : LSA_POLICY_INFO_ACCOUNT_DOMAIN (5) [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 00 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 002e 000a auth_len : 0000 000c call_id : 00000007 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_req hdr_req 0010 alloc_hint: 00000016 0014 context_id: 0000 0016 opnum : 0007 [2009/09/24 09:06:20, 5] rpc_client/cli_pipe.c:1279(rpc_api_pipe_send) rpc_api_pipe: host GENOME [2009/09/24 09:06:20, 10] libsmb/clitrans.c:825(cli_ship_trans) num_setup=2, max_setup=0, param_total=0, this_param=0, max_param=0, data_total=46, this_data=46, max_data=4280, param_offset=82, param_disp=0, data_disp=0 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=15 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 15 [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000000 smb_io_rpc_hdr rpc_hdr 0000 major : 05 0001 minor : 00 0002 pkt_type : 02 0003 flags : 03 0004 pack_type0: 10 0005 pack_type1: 00 0006 pack_type2: 00 0007 pack_type3: 00 0008 frag_len : 0060 000a auth_len : 0000 000c call_id : 00000007 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_trigger": 0x80563618 [2009/09/24 09:06:20, 10] lib/events.c:105(run_events) Running timed event "async_trigger" 0x80563618 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x80563618 "async_trigger" [2009/09/24 09:06:20, 5] rpc_parse/parse_prs.c:88(prs_debug) 000010 smb_io_rpc_hdr_resp rpc_hdr_resp 0010 alloc_hint: 00000048 0014 context_id: 0000 0016 cancel_ct : 00 0017 reserved : 00 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:904(cli_pipe_validate_current_pdu) cli_pipe_validate_current_pdu: got pdu len 96, data_len 72, ss_len 0 [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1381(rpc_api_pipe_got_pdu) rpc_api_pipe: got frag len of 96 at offset 0: NT_STATUS_OK [2009/09/24 09:06:20, 10] rpc_client/cli_pipe.c:1432(rpc_api_pipe_got_pdu) rpc_api_pipe: host GENOME returned 144 bytes. [2009/09/24 09:06:20, 1] ../librpc/ndr/ndr.c:251(ndr_print_function_debug) lsa_QueryInfoPolicy: struct lsa_QueryInfoPolicy out: struct lsa_QueryInfoPolicy info : * info : * info : union lsa_PolicyInformation(case 5) account_domain: struct lsa_DomainInfo name: struct lsa_StringLarge length : 0x0006 (6) size : 0x0008 (8) string : * string : 'MUC' sid : * sid : S-1-5-21-1362721961-1801182073-732966438 result : NT_STATUS_OK [2009/09/24 09:06:20, 5] winbindd/winbindd_cm.c:1923(set_dc_type_and_flags_connect) set_dc_type_and_flags_connect: domain MUC is NOT in native mode. [2009/09/24 09:06:20, 5] winbindd/winbindd_cm.c:1926(set_dc_type_and_flags_connect) set_dc_type_and_flags_connect: domain MUC is NOT running active directory. [2009/09/24 09:06:20, 10] libsmb/async_smb.c:510(cli_chain_cork) cli_chain_cork: mid=16 [2009/09/24 09:06:20, 10] libsmb/async_smb.c:939(handle_incoming_pdu) handle_incoming_pdu: got mid 16 [2009/09/24 09:06:20, 10] rpc_client/rpc_transport_np.c:40(rpc_transport_np_state_destructor) rpc_pipe_destructor: closed \lsarpc [2009/09/24 09:06:20, 10] winbindd/winbindd_dual.c:125(async_request) Sending request to child pid 30971 (domain=MUC) [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_request_timeout_handler": 0x805bdc70 [2009/09/24 09:06:20, 10] lib/events.c:148(get_timed_events_timeout) timed_events_timeout: 300/0 [2009/09/24 09:06:20, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805bdc70 "async_request_timeout_handler" [2009/09/24 09:06:20, 10] winbindd/winbindd_cache.c:2667(cache_retrieve_response) Retrieving response for pid 30971 [2009/09/24 09:06:23, 6] winbindd/winbindd.c:834(new_connection) accepted socket 20 [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn INTERFACE_VERSION [2009/09/24 09:06:23, 3] winbindd/winbindd_misc.c:754(winbindd_interface_version) [30972]: request interface version [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn WINBINDD_PRIV_PIPE_DIR [2009/09/24 09:06:23, 3] winbindd/winbindd_misc.c:787(winbindd_priv_pipe_dir) [30972]: request location of privileged pipe [2009/09/24 09:06:23, 6] winbindd/winbindd.c:834(new_connection) accepted socket 21 [2009/09/24 09:06:23, 2] winbindd/winbindd.c:878(remove_client) final write to client failed: Broken pipe [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn DOMAIN_INFO [2009/09/24 09:06:23, 3] winbindd/winbindd_misc.c:654(winbindd_domain_info) [30972]: domain_info [] [2009/09/24 09:06:23, 3] winbindd/winbindd_misc.c:660(winbindd_domain_info) Did not find domain [] [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn PING [2009/09/24 09:06:23, 3] winbindd/winbindd_misc.c:733(winbindd_ping) [30972]: ping [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn LOOKUPNAME [2009/09/24 09:06:23, 3] winbindd/winbindd_sid.c:107(winbindd_lookupname) [30972]: lookupname MUC\Administrator [2009/09/24 09:06:23, 10] winbindd/winbindd_dual.c:125(async_request) Sending request to child pid 30971 (domain=MUC) [2009/09/24 09:06:23, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_request_timeout_handler": 0x805b5f10 [2009/09/24 09:06:23, 10] lib/events.c:148(get_timed_events_timeout) timed_events_timeout: 300/0 [2009/09/24 09:06:23, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x805b5f10 "async_request_timeout_handler" [2009/09/24 09:06:23, 10] winbindd/winbindd_cache.c:2667(cache_retrieve_response) Retrieving response for pid 30971 [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn DOMAIN_INFO [2009/09/24 09:06:23, 3] winbindd/winbindd_misc.c:654(winbindd_domain_info) [30972]: domain_info [MUC] [2009/09/24 09:06:23, 10] winbindd/winbindd.c:537(process_request) process_request: request fn AUTH_CRAP [2009/09/24 09:06:23, 3] winbindd/winbindd_pam.c:1776(winbindd_pam_auth_crap) [30972]: pam auth crap domain: [MUC] user: muehlfeld [2009/09/24 09:06:23, 8] lib/util.c:1879(is_myname) is_myname("MUC") returns 0 [2009/09/24 09:06:23, 10] winbindd/winbindd_dual.c:125(async_request) Sending request to child pid 30971 (domain=MUC) [2009/09/24 09:06:23, 10] lib/events.c:287(s3_event_debug) s3_event: Added timed event "async_request_timeout_handler": 0x80563618 [2009/09/24 09:06:23, 10] lib/events.c:148(get_timed_events_timeout) timed_events_timeout: 300/0 [2009/09/24 09:06:23, 10] lib/events.c:287(s3_event_debug) s3_event: Destroying timer event 0x80563618 "async_request_timeout_handler" [2009/09/24 09:06:23, 10] winbindd/winbindd_cache.c:2667(cache_retrieve_response) Retrieving response for pid 30971