[2007/11/30 09:00:42, 1] nsswitch/winbindd.c:main(990) winbindd version 3.0.26a-0.fc7 started. Copyright Andrew Tridgell and the Samba Team 1992-2007 [2007/11/30 09:00:42, 5] lib/debug.c:debug_dump_status(391) 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 doing parameter debug hires timestamp = Yes doing parameter debug pid = Yes doing parameter max log size = 1000 doing parameter time server = Yes doing parameter printcap name = /etc/printcap doing parameter logon path = doing parameter domain logons = Yes doing parameter os level = 65 doing parameter preferred master = Yes doing parameter domain master = Yes doing parameter dns proxy = No doing parameter wins support = Yes doing parameter idmap uid = 16777216-33554431 doing parameter idmap gid = 16777216-33554431 doing parameter winbind enum users = Yes doing parameter winbind enum groups = Yes doing parameter create mask = 0644 doing parameter ea support = Yes doing parameter map acl inherit = Yes doing parameter printing = cups doing parameter cups options = raw doing parameter print command = doing parameter lpq command = %p doing parameter lprm command = doing parameter map archive = No doing parameter map readonly = no doing parameter store dos attributes = Yes [2007/11/30 09:00:42.331958, 2, pid=5650] param/loadparm.c:do_section(3796) Processing section "[homes]" [2007/11/30 09:00:42.331988, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 0 for homes [2007/11/30 09:00:42.332003, 10, pid=5650] param/loadparm.c:hash_a_service(2614) hash_a_service: creating tdb servicehash [2007/11/30 09:00:42.332036, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 0 for service name homes doing parameter comment = ~%U doing parameter read only = No doing parameter browseable = No [2007/11/30 09:00:42.332096, 2, pid=5650] param/loadparm.c:do_section(3796) Processing section "[netlogon]" [2007/11/30 09:00:42.332126, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 1 for netlogon [2007/11/30 09:00:42.332141, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 1 for service name netlogon doing parameter path = /srv/samba/netlogon doing parameter guest ok = Yes doing parameter browseable = No doing parameter share modes = No [2007/11/30 09:00:42.332206, 2, pid=5650] param/loadparm.c:do_section(3796) Processing section "[profiles]" [2007/11/30 09:00:42.332234, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 2 for profiles [2007/11/30 09:00:42.332248, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 2 for service name profiles doing parameter path = /srv/samba/profiles doing parameter guest ok = Yes doing parameter browseable = No [2007/11/30 09:00:42.332296, 2, pid=5650] param/loadparm.c:do_section(3796) Processing section "[printers]" [2007/11/30 09:00:42.332321, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 3 for printers [2007/11/30 09:00:42.332335, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 3 for service name printers doing parameter path = /var/spool/samba doing parameter printable = Yes doing parameter browseable = No [2007/11/30 09:00:42.332387, 2, pid=5650] param/loadparm.c:do_section(3796) Processing section "[c$]" [2007/11/30 09:00:42.332417, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 4 for c$ [2007/11/30 09:00:42.332432, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 4 for service name c$ doing parameter path = / doing parameter browseable = No [2007/11/30 09:00:42.332485, 2, pid=5650] param/loadparm.c:do_section(3796) Processing section "[pub]" [2007/11/30 09:00:42.332512, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 5 for pub [2007/11/30 09:00:42.332527, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 5 for service name pub doing parameter path = /srv/pub doing parameter guest ok = Yes [2007/11/30 09:00:42.332561, 4, pid=5650] param/loadparm.c:lp_load(5071) pm_process() returned Yes [2007/11/30 09:00:42.332595, 8, pid=5650] param/loadparm.c:add_a_service(2577) add_a_service: Creating snum = 6 for IPC$ [2007/11/30 09:00:42.332610, 10, pid=5650] param/loadparm.c:hash_a_service(2624) hash_a_service: hashing index 6 for service name IPC$ [2007/11/30 09:00:42.332628, 3, pid=5650] param/loadparm.c:lp_add_ipc(2711) adding IPC service [2007/11/30 09:00:42.332642, 10, pid=5650] param/loadparm.c:set_server_role(4315) set_server_role: role = ROLE_DOMAIN_PDC [2007/11/30 09:00:42.332668, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332692, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332714, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332734, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332754, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332774, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332794, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332813, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332833, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332857, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332880, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332902, 5, pid=5650] lib/charcnv.c:charset_name(82) Substituting charset 'ANSI_X3.4-1968' for LOCALE [2007/11/30 09:00:42.332969, 2, pid=5650] lib/interface.c:add_interface(81) added interface ip=192.168.64.1 bcast=192.168.64.255 nmask=255.255.255.0 [2007/11/30 09:00:42.332993, 2, pid=5650] lib/interface.c:add_interface(81) added interface ip=192.168.1.2 bcast=192.168.1.255 nmask=255.255.255.0 [2007/11/30 09:00:42.333015, 5, pid=5650] lib/util.c:init_names(309) Netbios name list:- my_netbios_names[0]="GODAI" [2007/11/30 09:00:42.333065, 2, pid=5650] lib/interface.c:add_interface(81) added interface ip=192.168.64.1 bcast=192.168.64.255 nmask=255.255.255.0 [2007/11/30 09:00:42.333082, 2, pid=5650] lib/interface.c:add_interface(81) added interface ip=192.168.1.2 bcast=192.168.1.255 nmask=255.255.255.0 [2007/11/30 09:00:42.333147, 5, pid=5650] lib/gencache.c:gencache_init(61) Opening cache file at /var/lib/samba/gencache.tdb [2007/11/30 09:00:42.333179, 5, pid=5650] libsmb/namecache.c:namecache_enable(58) namecache_enable: enabling netbios namecache, timeout 660 seconds [2007/11/30 09:00:42.333203, 10, pid=5650] nsswitch/idmap_cache.c:idmap_cache_init(60) Opening cache file at /var/lib/samba/idmap_cache.tdb [2007/11/30 09:00:42.333558, 8, pid=5651] lib/util.c:fcntl_lock(2013) fcntl_lock fd=8 op=6 offset=0 count=1 type=1 [2007/11/30 09:00:42.333611, 8, pid=5651] lib/util.c:fcntl_lock(2032) fcntl_lock: Lock call successful [2007/11/30 09:00:42.333663, 4, pid=5651] lib/time.c:TimeInit(1258) TimeInit: Serverzone is -18000 [2007/11/30 09:00:42.333727, 2, pid=5651] lib/tallocmsg.c:register_msg_pool_usage(105) Registered MSG_REQ_POOL_USAGE [2007/11/30 09:00:42.333754, 2, pid=5651] lib/dmallocmsg.c:register_dmalloc_msgs(75) Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED [2007/11/30 09:00:42.333933, 0, pid=5651] nsswitch/winbindd_cache.c:initialize_winbindd_cache(2222) initialize_winbindd_cache: clearing cache and re-creating with version number 1 [2007/11/30 09:00:42.334469, 2, pid=5651] nsswitch/winbindd_util.c:add_trusted_domain(177) Added domain ZARTSOFT S-1-5-21-2274471336-3138038065-606154707 [2007/11/30 09:00:42.334500, 2, pid=5651] nsswitch/winbindd_util.c:add_trusted_domain(177) Added domain BUILTIN S-1-5-32 [2007/11/30 09:00:42.334563, 10, pid=5651] nsswitch/winbindd_util.c:open_winbindd_socket(927) open_winbindd_socket: opened socket fd 12 [2007/11/30 09:00:42.334837, 10, pid=5651] nsswitch/winbindd_util.c:open_winbindd_priv_socket(939) open_winbindd_priv_socket: opened socket fd 13 [2007/11/30 09:00:42.335571, 10, pid=5651] lib/events.c:event_add_timed(129) Added timed event "async_request_timeout": 555555a79670 [2007/11/30 09:00:42.335607, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999954 [2007/11/30 09:00:42.347737, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 17 [2007/11/30 09:00:42.347771, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987791 [2007/11/30 09:00:42.347794, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987768 [2007/11/30 09:00:42.347817, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn INTERFACE_VERSION [2007/11/30 09:00:42.347831, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_interface_version(491) [ 5654]: request interface version [2007/11/30 09:00:42.347874, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987688 [2007/11/30 09:00:42.347903, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987659 [2007/11/30 09:00:42.347929, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987633 [2007/11/30 09:00:42.347957, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn WINBINDD_PRIV_PIPE_DIR [2007/11/30 09:00:42.347976, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_priv_pipe_dir(524) [ 5654]: request location of privileged pipe [2007/11/30 09:00:42.348012, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987550 [2007/11/30 09:00:42.348040, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987522 [2007/11/30 09:00:42.348068, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987494 [2007/11/30 09:00:42.348105, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 18 [2007/11/30 09:00:42.348132, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987430 [2007/11/30 09:00:42.348164, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/987398 [2007/11/30 09:00:42.348193, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn GID_TO_SID [2007/11/30 09:00:42.348214, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_gid_to_sid(477) [ 5654]: gid to sid 1515 [2007/11/30 09:00:42.348839, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/986729 [2007/11/30 09:00:42.348894, 10, pid=5651] lib/events.c:event_add_timed(129) Added timed event "async_request_timeout": 555555a48250 [2007/11/30 09:00:42.348917, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/986645 [2007/11/30 09:00:42.350728, 10, pid=5651] lib/events.c:timed_event_destructor(66) Destroying timed event 555555a48250 "async_request_timeout" [2007/11/30 09:00:42.350792, 10, pid=5651] nsswitch/winbindd_cache.c:cache_retrieve_response(2300) Retrieving response for pid 5656 [2007/11/30 09:00:42.350850, 10, pid=5651] nsswitch/winbindd_sid.c:gid2sid_recv(462) gid2sid: gid 1515 has sid S-1-5-21-2274471336-3138038065-606154707-515 [2007/11/30 09:00:42.350897, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/984665 [2007/11/30 09:00:42.350935, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/984627 [2007/11/30 09:00:42.351621, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983941 [2007/11/30 09:00:42.351653, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:00:42.351672, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5654]: sid to gid S-1-1-0 [2007/11/30 09:00:42.351707, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-1-0) [2007/11/30 09:00:42.351738, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:00:42.351756, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-1-0 [2007/11/30 09:00:42.351778, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-1-0 [2007/11/30 09:00:42.351798, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983764 [2007/11/30 09:00:42.351826, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983736 [2007/11/30 09:00:42.351852, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983710 [2007/11/30 09:00:42.351885, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:00:42.351903, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5654]: ping [2007/11/30 09:00:42.351922, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983640 [2007/11/30 09:00:42.351949, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983614 [2007/11/30 09:00:42.352010, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983552 [2007/11/30 09:00:42.352042, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:00:42.352061, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5654]: sid to gid S-1-5-2 [2007/11/30 09:00:42.352079, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-2) [2007/11/30 09:00:42.352098, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:00:42.352115, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-2 [2007/11/30 09:00:42.352134, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-2 [2007/11/30 09:00:42.352153, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983409 [2007/11/30 09:00:42.352180, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983382 [2007/11/30 09:00:42.352205, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983357 [2007/11/30 09:00:42.352233, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:00:42.352251, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5654]: ping [2007/11/30 09:00:42.352270, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983292 [2007/11/30 09:00:42.352296, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983266 [2007/11/30 09:00:42.352354, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983209 [2007/11/30 09:00:42.352385, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:00:42.352423, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5654]: sid to gid S-1-5-11 [2007/11/30 09:00:42.352441, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-11) [2007/11/30 09:00:42.352460, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:00:42.352477, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-11 [2007/11/30 09:00:42.352495, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-11 [2007/11/30 09:00:42.352514, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983048 [2007/11/30 09:00:42.352542, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/983020 [2007/11/30 09:00:42.352567, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/982995 [2007/11/30 09:00:42.352597, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:00:42.352616, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5654]: ping [2007/11/30 09:00:42.352634, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/982927 [2007/11/30 09:00:42.352661, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/982901 [2007/11/30 09:00:42.394386, 10, pid=5651] lib/events.c:timed_event_destructor(66) Destroying timed event 555555a79670 "async_request_timeout" [2007/11/30 09:00:42.394420, 10, pid=5651] nsswitch/winbindd_cache.c:cache_retrieve_response(2300) Retrieving response for pid 5653 [2007/11/30 09:00:42.394463, 5, pid=5651] nsswitch/winbindd_util.c:init_child_recv(425) Received child initialization response for domain ZARTSOFT [2007/11/30 09:00:42.394490, 8, pid=5651] nsswitch/winbindd_cm.c:connection_ok(1442) connection_ok: Connection to for domain ZARTSOFT has NULL cli! [2007/11/30 09:00:42.394541, 10, pid=5651] lib/gencache.c:gencache_get(226) Returning valid cache entry: key = SAF/DOMAIN/ZARTSOFT, value = GODAI, timeout = Fri Nov 30 09:15:42 2007 [2007/11/30 09:00:42.394582, 5, pid=5651] libsmb/namequery.c:saf_fetch(136) saf_fetch: Returning "GODAI" for "ZARTSOFT" domain [2007/11/30 09:00:42.394610, 10, pid=5651] nsswitch/winbindd_cm.c:cm_open_connection(1282) cm_open_connection: saf_servername is 'GODAI' for domain ZARTSOFT [2007/11/30 09:00:42.394637, 10, pid=5651] nsswitch/winbindd_cm.c:cm_open_connection(1312) cm_open_connection: dcname is 'GODAI' for domain ZARTSOFT [2007/11/30 09:00:42.394668, 10, pid=5651] lib/gencache.c:gencache_get(212) Cache entry with key = AD_SITENAME/DOMAIN/ZARTSOFT.RU couldn't be found [2007/11/30 09:00:42.394690, 5, pid=5651] libads/dns.c:sitename_fetch(674) sitename_fetch: No stored sitename for ZARTSOFT.RU [2007/11/30 09:00:42.394713, 10, pid=5651] libsmb/namequery.c:internal_resolve_name(1166) internal_resolve_name: looking up GODAI#20 (sitename (null)) [2007/11/30 09:00:42.394741, 10, pid=5651] lib/gencache.c:gencache_get(226) Returning valid cache entry: key = NBT/GODAI#20, value = 192.168.64.1:0, timeout = Fri Nov 30 09:00:49 2007 [2007/11/30 09:00:42.394767, 5, pid=5651] libsmb/namecache.c:namecache_fetch(214) name GODAI#20 found. [2007/11/30 09:00:42.394954, 10, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(612) cm_prepare_connection: connecting to DC GODAI for domain ZARTSOFT [2007/11/30 09:00:42.395000, 3, pid=5651] nsswitch/winbindd_cm.c:cm_get_ipc_userpass(507) cm_get_ipc_userpass: No auth-user defined [2007/11/30 09:00:42.395034, 10, pid=5651] passdb/secrets.c:secrets_named_mutex(930) secrets_named_mutex: got mutex for GODAI [2007/11/30 09:00:42.395189, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,194) [2007/11/30 09:00:42.395236, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,194) wrote 194 [2007/11/30 09:00:42.398327, 10, pid=5651] lib/util_sock.c:read_smb_length_return_keepalive(623) got smb length of 127 [2007/11/30 09:00:42.398365, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:42.398378, 5, pid=5651] lib/util.c:show_msg(516) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 6400 (0x1900) smb_vwv[ 8]= 22 (0x16) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]= 128 (0x80) smb_vwv[12]=22801 (0x5911) smb_vwv[13]= 1427 (0x593) smb_vwv[14]=51251 (0xC833) smb_vwv[15]=54273 (0xD401) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2007/11/30 09:00:42.398548, 10, pid=5651] lib/util.c:dump_data(2285) [000] 67 6F 64 61 69 00 00 00 00 00 00 00 00 00 00 00 godai... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2007/11/30 09:00:42.398621, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:42.398633, 5, pid=5651] lib/util.c:show_msg(516) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 6400 (0x1900) smb_vwv[ 8]= 22 (0x16) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]= 128 (0x80) smb_vwv[12]=22801 (0x5911) smb_vwv[13]= 1427 (0x593) smb_vwv[14]=51251 (0xC833) smb_vwv[15]=54273 (0xD401) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2007/11/30 09:00:42.398822, 10, pid=5651] lib/util.c:dump_data(2285) [000] 67 6F 64 61 69 00 00 00 00 00 00 00 00 00 00 00 godai... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2007/11/30 09:00:42.398925, 5, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(723) connecting to GODAI from GODAI with username [ZARTSOFT]\[GODAI$] [2007/11/30 09:00:42.398949, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(793) Doing spnego session setup (blob length=58) [2007/11/30 09:00:42.398985, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(818) got OID=1 3 6 1 4 1 311 2 2 10 [2007/11/30 09:00:42.399004, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(826) got principal=NONE [2007/11/30 09:00:42.399132, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,164) [2007/11/30 09:00:42.399170, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,164) wrote 164 [2007/11/30 09:00:42.401692, 10, pid=5651] lib/util_sock.c:read_smb_length_return_keepalive(623) got smb length of 332 [2007/11/30 09:00:42.401721, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:42.401734, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:00:42.401831, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 83 41 41 77 13 9D 35 91 00 ......`. AAw..5.. [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:00:42.402112, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:42.402125, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:00:42.402220, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 83 41 41 77 13 9D 35 91 00 ......`. AAw..5.. [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:00:42.402486, 3, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1018) Got challenge flags: [2007/11/30 09:00:42.402505, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:00:42.402568, 3, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1040) NTLMSSP: Set final flags: [2007/11/30 09:00:42.402585, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:00:42.402655, 5, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1112) NTLMSSP challenge set by NTLM2 [2007/11/30 09:00:42.402688, 5, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1113) challenge is: [2007/11/30 09:00:42.402705, 5, pid=5651] lib/util.c:dump_data(2285) [000] CE 29 9D 66 82 B9 0E 5D .).f...] [2007/11/30 09:00:42.402831, 3, pid=5651] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338) NTLMSSP Sign/Seal - Initialising with flags: [2007/11/30 09:00:42.402856, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:00:42.402935, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,264) [2007/11/30 09:00:42.402974, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,264) wrote 264 [2007/11/30 09:00:52.402281, 10, pid=5651] lib/util_sock.c:read_socket_with_timeout(476) read_socket_with_timeout: timeout read. select timed out. [2007/11/30 09:00:52.402324, 10, pid=5651] lib/util_sock.c:receive_smb_raw(672) receive_smb_raw: length < 0! [2007/11/30 09:00:52.402344, 10, pid=5651] libsmb/clientgen.c:client_receive_smb(65) client_receive_smb failed [2007/11/30 09:00:52.402362, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:52.402374, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:00:52.402473, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 83 41 41 77 13 9D 35 91 00 ......`. AAw..5.. [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:00:52.402727, 0, pid=5651] libsmb/clientgen.c:cli_receive_smb(112) Receiving SMB: Server stopped responding [2007/11/30 09:00:52.402858, 4, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(731) authenticated session setup failed with NT_STATUS_IO_TIMEOUT [2007/11/30 09:00:52.403003, 10, pid=5651] passdb/secrets.c:secrets_named_mutex_release(942) secrets_named_mutex: released mutex for GODAI [2007/11/30 09:00:52.403027, 10, pid=5651] libsmb/conncache.c:add_failed_connection_entry(140) add_failed_connection_entry: added domain ZARTSOFT (GODAI) to failed conn cache [2007/11/30 09:00:52.403059, 10, pid=5651] lib/gencache.c:gencache_del(173) Deleting cache entry (key = SAF/DOMAIN/ZARTSOFT) [2007/11/30 09:00:52.403105, 10, pid=5651] libsmb/namequery.c:saf_delete(100) saf_delete: domain = [ZARTSOFT] [2007/11/30 09:00:52.403168, 10, pid=5651] nsswitch/winbindd_cm.c:cm_open_connection(1312) cm_open_connection: dcname is 'GODAI' for domain ZARTSOFT [2007/11/30 09:00:52.403192, 10, pid=5651] libsmb/conncache.c:check_negative_conn_cache_timeout(86) check_negative_conn_cache: returning negative entry for ZARTSOFT, GODAI [2007/11/30 09:00:52.403212, 8, pid=5651] libsmb/namequery.c:get_sorted_dc_list(1626) get_sorted_dc_list: attempting lookup for name ZARTSOFT (sitename NULL) using [lmhosts wins host bcast] [2007/11/30 09:00:52.403246, 10, pid=5651] lib/gencache.c:gencache_get(212) Cache entry with key = SAF/DOMAIN/ZARTSOFT couldn't be found [2007/11/30 09:00:52.403270, 5, pid=5651] libsmb/namequery.c:saf_fetch(133) saf_fetch: failed to find server for "ZARTSOFT" domain [2007/11/30 09:00:52.403292, 3, pid=5651] libsmb/namequery.c:get_dc_list(1489) get_dc_list: preferred server list: ", *" [2007/11/30 09:00:52.403314, 10, pid=5651] libsmb/namequery.c:internal_resolve_name(1166) internal_resolve_name: looking up ZARTSOFT#1c (sitename (null)) [2007/11/30 09:00:52.403341, 10, pid=5651] lib/gencache.c:gencache_get(226) Returning expired cache entry: key = NBT/ZARTSOFT#1C, value = 192.168.64.1:0,192.168.1.2:0, timeout = Fri Nov 30 08:54:07 2007 [2007/11/30 09:00:52.403382, 5, pid=5651] libsmb/namecache.c:namecache_fetch(210) no entry for ZARTSOFT#1C found. [2007/11/30 09:00:52.403404, 3, pid=5651] libsmb/namequery.c:resolve_lmhosts(966) resolve_lmhosts: Attempting lmhosts lookup for name ZARTSOFT<0x1c> [2007/11/30 09:00:52.403446, 4, pid=5651] libsmb/namequery.c:getlmhostsent(717) getlmhostsent: lmhost entry: 127.0.0.1 localhost [2007/11/30 09:00:52.403475, 3, pid=5651] libsmb/namequery.c:resolve_wins(863) resolve_wins: Attempting wins lookup for name ZARTSOFT<0x1c> [2007/11/30 09:00:52.403506, 10, pid=5651] lib/gencache.c:gencache_get(212) Cache entry with key = WINS_SRV_DEAD/127.0.0.1,0.0.0.0 couldn't be found [2007/11/30 09:00:52.403525, 4, pid=5651] lib/wins_srv.c:wins_srv_is_dead(111) wins_srv_is_dead: 127.0.0.1 is alive [2007/11/30 09:00:52.403545, 3, pid=5651] libsmb/namequery.c:resolve_wins(902) resolve_wins: using WINS server 127.0.0.1 and tag '*' [2007/11/30 09:00:52.403586, 10, pid=5651] lib/util_sock.c:open_socket_in(839) bind succeeded on port 0 [2007/11/30 09:00:52.403628, 5, pid=5651] libsmb/nmblib.c:send_udp(777) Sending a packet of len 50 to (127.0.0.1) on port 137 [2007/11/30 09:00:52.404044, 10, pid=5651] lib/util_sock.c:read_udp_socket(294) read_udp_socket: lastip 127.0.0.1 lastport 137 read: 68 [2007/11/30 09:00:52.404089, 10, pid=5651] libsmb/nmblib.c:parse_nmb(506) parse_nmb: packet id = 22692 [2007/11/30 09:00:52.404109, 5, pid=5651] libsmb/nmblib.c:read_packet(755) Received a packet of len 68 from (127.0.0.1) port 137 [2007/11/30 09:00:52.404129, 4, pid=5651] libsmb/nmblib.c:debug_nmb_packet(112) nmb packet from 127.0.0.1(137) header: id=22692 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=Yes rec_des=Yes trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ZARTSOFT<1c> rr_type=32 rr_class=1 ttl=258978 answers 0 char ....@....... hex E000C0A84001E000C0A80102 [2007/11/30 09:00:52.404184, 2, pid=5651] libsmb/namequery.c:name_query(604) Got a positive name query response from 127.0.0.1 ( 192.168.64.1 192.168.1.2 ) [2007/11/30 09:00:52.404214, 10, pid=5651] libsmb/namequery.c:remove_duplicate_addrs2(435) remove_duplicate_addrs2: looking for duplicate address/port pairs [2007/11/30 09:00:52.404234, 5, pid=5651] libsmb/namecache.c:namecache_store(135) namecache_store: storing 2 addresses for ZARTSOFT#1c: 192.168.64.1:0,192.168.1.2:0 [2007/11/30 09:00:52.404265, 10, pid=5651] lib/gencache.c:gencache_set(140) Adding cache entry with key = NBT/ZARTSOFT#1C; value = 192.168.64.1:0,192.168.1.2:0 and timeout = Fri Nov 30 09:11:52 2007 (660 seconds ahead) [2007/11/30 09:00:52.404357, 10, pid=5651] libsmb/namequery.c:internal_resolve_name(1293) internal_resolve_name: returning 2 addresses: 192.168.64.1:0 192.168.1.2:0 [2007/11/30 09:00:52.404415, 8, pid=5651] libsmb/namequery.c:get_dc_list(1505) Adding 2 DC's from auto lookup [2007/11/30 09:00:52.404441, 10, pid=5651] libsmb/namequery.c:remove_duplicate_addrs2(435) remove_duplicate_addrs2: looking for duplicate address/port pairs [2007/11/30 09:00:52.404460, 4, pid=5651] libsmb/namequery.c:get_dc_list(1599) get_dc_list: returning 2 ip addresses in an unordered list [2007/11/30 09:00:52.404479, 4, pid=5651] libsmb/namequery.c:get_dc_list(1600) get_dc_list: 192.168.64.1:0 192.168.1.2:0 [2007/11/30 09:00:52.404861, 8, pid=5651] lib/util.c:fcntl_lock(2013) fcntl_lock fd=21 op=6 offset=0 count=1 type=0 [2007/11/30 09:00:52.404896, 3, pid=5651] lib/util.c:fcntl_lock(2026) fcntl_lock: lock failed at offset 0 count 1 op 6 type 0 (Resource temporarily unavailable) [2007/11/30 09:00:52.404975, 4, pid=5651] libsmb/clidgram.c:cli_send_mailslot(103) send_mailslot: Sending to mailslot \MAILSLOT\NET\NTLOGON from GODAI<00> to ZARTSOFT<1c> IP 192.168.64.1 [2007/11/30 09:00:52.506337, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:00:53.008289, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:00:53.510241, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:00:54.012211, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:00:54.514588, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:00:55.016507, 10, pid=5651] libsmb/namequery.c:name_status_find(303) name_status_find: looking up ZARTSOFT#1c at 192.168.64.1 [2007/11/30 09:00:55.016559, 10, pid=5651] lib/gencache.c:gencache_get(212) Cache entry with key = NBT/ZARTSOFT#1C.20.192.168.64.1 couldn't be found [2007/11/30 09:00:55.016579, 5, pid=5651] libsmb/namecache.c:namecache_status_fetch(346) namecache_status_fetch: no entry for NBT/ZARTSOFT#1C.20.192.168.64.1 found. [2007/11/30 09:00:55.016612, 10, pid=5651] lib/util_sock.c:open_socket_in(839) bind succeeded on port 0 [2007/11/30 09:00:55.016636, 5, pid=5651] libsmb/nmblib.c:send_udp(777) Sending a packet of len 50 to (192.168.64.1) on port 137 [2007/11/30 09:00:55.017274, 10, pid=5651] lib/util_sock.c:read_udp_socket(294) read_udp_socket: lastip 192.168.64.1 lastport 137 read: 265 [2007/11/30 09:00:55.017300, 10, pid=5651] libsmb/nmblib.c:parse_nmb(506) parse_nmb: packet id = 24065 [2007/11/30 09:00:55.017324, 5, pid=5651] libsmb/nmblib.c:read_packet(755) Received a packet of len 265 from (192.168.64.1) port 137 [2007/11/30 09:00:55.017346, 4, pid=5651] libsmb/nmblib.c:debug_nmb_packet(112) nmb packet from 192.168.64.1(137) header: id=24065 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ZARTSOFT<1c> rr_type=33 rr_class=1 ttl=0 answers 0 char .GODAI hex 09474F44414920202020202020202020 answers 10 char .d.GODAI hex 006400474F4441492020202020202020 answers 20 char .d.GODAI hex 2020036400474F444149202020202020 answers 30 char d...__MSBRO hex 2020202020640001025F5F4D5342524F answers 40 char WSE__....ZARTSOF hex 5753455F5F0201E4005A415254534F46 answers 50 char T .d.ZARTS hex 54202020202020201D64005A41525453 answers 60 char OFT .d.ZAR hex 4F4654202020202020201B64005A4152 answers 70 char TSOFT ...Z hex 54534F4654202020202020201CE4005A answers 80 char ARTSOFT .. hex 415254534F4654202020202020201EE4 answers 90 char .ZARTSOFT hex 005A415254534F465420202020202020 answers a0 char ................ hex 00E40000000000000000000000000000 answers b0 char ................ hex 00000000000000000000000000000000 answers c0 char ................ hex 00000000000000000000000000000000 answers d0 char . hex 00 [2007/11/30 09:00:55.017585, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) GODAI#00: flags = 0x64 [2007/11/30 09:00:55.017605, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) GODAI#03: flags = 0x64 [2007/11/30 09:00:55.017623, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) GODAI#20: flags = 0x64 [2007/11/30 09:00:55.017641, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) __MSBROWSE__#01: flags = 0xe4 [2007/11/30 09:00:55.017660, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1d: flags = 0x64 [2007/11/30 09:00:55.017678, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1b: flags = 0x64 [2007/11/30 09:00:55.017696, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1c: flags = 0xe4 [2007/11/30 09:00:55.017716, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1e: flags = 0xe4 [2007/11/30 09:00:55.017734, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#00: flags = 0xe4 [2007/11/30 09:00:55.017760, 10, pid=5651] libsmb/namequery.c:name_status_find(342) name_status_find: name found, name GODAI ip address is 192.168.64.1 [2007/11/30 09:00:55.017782, 5, pid=5651] libsmb/namecache.c:namecache_store(135) namecache_store: storing 1 address for GODAI#20: 192.168.64.1:0 [2007/11/30 09:00:55.017808, 10, pid=5651] lib/gencache.c:gencache_set(140) Adding cache entry with key = NBT/GODAI#20; value = 192.168.64.1:0 and timeout = Fri Nov 30 09:11:55 2007 (660 seconds ahead) [2007/11/30 09:00:55.017845, 10, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(612) cm_prepare_connection: connecting to DC GODAI for domain ZARTSOFT [2007/11/30 09:00:55.017884, 3, pid=5651] nsswitch/winbindd_cm.c:cm_get_ipc_userpass(507) cm_get_ipc_userpass: No auth-user defined [2007/11/30 09:00:55.017912, 10, pid=5651] passdb/secrets.c:secrets_named_mutex(930) secrets_named_mutex: got mutex for GODAI [2007/11/30 09:00:55.017984, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,194) [2007/11/30 09:00:55.018037, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,194) wrote 194 [2007/11/30 09:00:55.019276, 10, pid=5651] lib/util_sock.c:read_smb_length_return_keepalive(623) got smb length of 127 [2007/11/30 09:00:55.019302, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:55.019315, 5, pid=5651] lib/util.c:show_msg(516) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 22 (0x16) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]=32896 (0x8080) smb_vwv[12]= 6325 (0x18B5) smb_vwv[13]= 1435 (0x59B) smb_vwv[14]=51251 (0xC833) smb_vwv[15]=54273 (0xD401) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2007/11/30 09:00:55.019481, 10, pid=5651] lib/util.c:dump_data(2285) [000] 67 6F 64 61 69 00 00 00 00 00 00 00 00 00 00 00 godai... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2007/11/30 09:00:55.019550, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:55.019563, 5, pid=5651] lib/util.c:show_msg(516) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 22 (0x16) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]=32896 (0x8080) smb_vwv[12]= 6325 (0x18B5) smb_vwv[13]= 1435 (0x59B) smb_vwv[14]=51251 (0xC833) smb_vwv[15]=54273 (0xD401) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2007/11/30 09:00:55.019753, 10, pid=5651] lib/util.c:dump_data(2285) [000] 67 6F 64 61 69 00 00 00 00 00 00 00 00 00 00 00 godai... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2007/11/30 09:00:55.019830, 5, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(723) connecting to GODAI from GODAI with username [ZARTSOFT]\[GODAI$] [2007/11/30 09:00:55.019851, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(793) Doing spnego session setup (blob length=58) [2007/11/30 09:00:55.019880, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(818) got OID=1 3 6 1 4 1 311 2 2 10 [2007/11/30 09:00:55.019899, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(826) got principal=NONE [2007/11/30 09:00:55.019991, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,164) [2007/11/30 09:00:55.020028, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,164) wrote 164 [2007/11/30 09:00:55.022574, 10, pid=5651] lib/util_sock.c:read_smb_length_return_keepalive(623) got smb length of 332 [2007/11/30 09:00:55.022604, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:55.022617, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:00:55.022720, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 BC DB 15 9C D1 CB B6 0F 00 ......`. ........ [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:00:55.022974, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:00:55.022986, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:00:55.023085, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 BC DB 15 9C D1 CB B6 0F 00 ......`. ........ [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:00:55.023377, 3, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1018) Got challenge flags: [2007/11/30 09:00:55.023397, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:00:55.023462, 3, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1040) NTLMSSP: Set final flags: [2007/11/30 09:00:55.023479, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:00:55.023551, 5, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1112) NTLMSSP challenge set by NTLM2 [2007/11/30 09:00:55.023569, 5, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1113) challenge is: [2007/11/30 09:00:55.023586, 5, pid=5651] lib/util.c:dump_data(2285) [000] 67 75 B3 85 87 56 5F EF gu...V_. [2007/11/30 09:00:55.023711, 3, pid=5651] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338) NTLMSSP Sign/Seal - Initialising with flags: [2007/11/30 09:00:55.023730, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:00:55.023808, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,264) [2007/11/30 09:00:55.023846, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,264) wrote 264 [2007/11/30 09:01:05.023929, 10, pid=5651] lib/util_sock.c:read_socket_with_timeout(476) read_socket_with_timeout: timeout read. select timed out. [2007/11/30 09:01:05.023967, 10, pid=5651] lib/util_sock.c:receive_smb_raw(672) receive_smb_raw: length < 0! [2007/11/30 09:01:05.023988, 10, pid=5651] libsmb/clientgen.c:client_receive_smb(65) client_receive_smb failed [2007/11/30 09:01:05.024006, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:01:05.024018, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:01:05.024138, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 BC DB 15 9C D1 CB B6 0F 00 ......`. ........ [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:01:05.024392, 0, pid=5651] libsmb/clientgen.c:cli_receive_smb(112) Receiving SMB: Server stopped responding [2007/11/30 09:01:05.024512, 4, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(731) authenticated session setup failed with NT_STATUS_IO_TIMEOUT [2007/11/30 09:01:05.024647, 10, pid=5651] passdb/secrets.c:secrets_named_mutex_release(942) secrets_named_mutex: released mutex for GODAI [2007/11/30 09:01:05.024673, 10, pid=5651] libsmb/conncache.c:add_failed_connection_entry(118) add_failed_connection_entry: domain ZARTSOFT (GODAI) already tried and failed [2007/11/30 09:01:05.024704, 10, pid=5651] lib/gencache.c:gencache_del(173) Deleting cache entry (key = SAF/DOMAIN/ZARTSOFT) [2007/11/30 09:01:05.024759, 10, pid=5651] nsswitch/winbindd_cm.c:cm_open_connection(1312) cm_open_connection: dcname is 'GODAI' for domain ZARTSOFT [2007/11/30 09:01:05.024782, 10, pid=5651] libsmb/conncache.c:check_negative_conn_cache_timeout(86) check_negative_conn_cache: returning negative entry for ZARTSOFT, GODAI [2007/11/30 09:01:05.024803, 8, pid=5651] libsmb/namequery.c:get_sorted_dc_list(1626) get_sorted_dc_list: attempting lookup for name ZARTSOFT (sitename NULL) using [lmhosts wins host bcast] [2007/11/30 09:01:05.024834, 10, pid=5651] lib/gencache.c:gencache_get(212) Cache entry with key = SAF/DOMAIN/ZARTSOFT couldn't be found [2007/11/30 09:01:05.024853, 5, pid=5651] libsmb/namequery.c:saf_fetch(133) saf_fetch: failed to find server for "ZARTSOFT" domain [2007/11/30 09:01:05.024874, 3, pid=5651] libsmb/namequery.c:get_dc_list(1489) get_dc_list: preferred server list: ", *" [2007/11/30 09:01:05.024914, 10, pid=5651] libsmb/namequery.c:internal_resolve_name(1166) internal_resolve_name: looking up ZARTSOFT#1c (sitename (null)) [2007/11/30 09:01:05.024946, 10, pid=5651] lib/gencache.c:gencache_get(226) Returning valid cache entry: key = NBT/ZARTSOFT#1C, value = 192.168.64.1:0,192.168.1.2:0, timeout = Fri Nov 30 09:11:52 2007 [2007/11/30 09:01:05.024985, 5, pid=5651] libsmb/namecache.c:namecache_fetch(214) name ZARTSOFT#1C found. [2007/11/30 09:01:05.025010, 8, pid=5651] libsmb/namequery.c:get_dc_list(1505) Adding 2 DC's from auto lookup [2007/11/30 09:01:05.025033, 10, pid=5651] libsmb/namequery.c:remove_duplicate_addrs2(435) remove_duplicate_addrs2: looking for duplicate address/port pairs [2007/11/30 09:01:05.025067, 4, pid=5651] libsmb/namequery.c:get_dc_list(1599) get_dc_list: returning 2 ip addresses in an unordered list [2007/11/30 09:01:05.025085, 4, pid=5651] libsmb/namequery.c:get_dc_list(1600) get_dc_list: 192.168.64.1:0 192.168.1.2:0 [2007/11/30 09:01:05.025606, 8, pid=5651] lib/util.c:fcntl_lock(2013) fcntl_lock fd=21 op=6 offset=0 count=1 type=0 [2007/11/30 09:01:05.025641, 3, pid=5651] lib/util.c:fcntl_lock(2026) fcntl_lock: lock failed at offset 0 count 1 op 6 type 0 (Resource temporarily unavailable) [2007/11/30 09:01:05.025673, 4, pid=5651] libsmb/clidgram.c:cli_send_mailslot(103) send_mailslot: Sending to mailslot \MAILSLOT\NET\NTLOGON from GODAI<00> to ZARTSOFT<1c> IP 192.168.64.1 [2007/11/30 09:01:05.128984, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:01:05.630929, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:01:06.132924, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:01:06.634884, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:01:07.136840, 5, pid=5651] nsswitch/winbindd_cm.c:receive_getdc_response(954) Did not receive packet for \MAILSLOT\NET\GETDC140A8C0 [2007/11/30 09:01:07.637780, 10, pid=5651] libsmb/namequery.c:name_status_find(303) name_status_find: looking up ZARTSOFT#1c at 192.168.64.1 [2007/11/30 09:01:07.637843, 10, pid=5651] lib/gencache.c:gencache_get(212) Cache entry with key = NBT/ZARTSOFT#1C.20.192.168.64.1 couldn't be found [2007/11/30 09:01:07.637863, 5, pid=5651] libsmb/namecache.c:namecache_status_fetch(346) namecache_status_fetch: no entry for NBT/ZARTSOFT#1C.20.192.168.64.1 found. [2007/11/30 09:01:07.637895, 10, pid=5651] lib/util_sock.c:open_socket_in(839) bind succeeded on port 0 [2007/11/30 09:01:07.637920, 5, pid=5651] libsmb/nmblib.c:send_udp(777) Sending a packet of len 50 to (192.168.64.1) on port 137 [2007/11/30 09:01:07.638554, 10, pid=5651] lib/util_sock.c:read_udp_socket(294) read_udp_socket: lastip 192.168.64.1 lastport 137 read: 265 [2007/11/30 09:01:07.638579, 10, pid=5651] libsmb/nmblib.c:parse_nmb(506) parse_nmb: packet id = 19214 [2007/11/30 09:01:07.638602, 5, pid=5651] libsmb/nmblib.c:read_packet(755) Received a packet of len 265 from (192.168.64.1) port 137 [2007/11/30 09:01:07.638624, 4, pid=5651] libsmb/nmblib.c:debug_nmb_packet(112) nmb packet from 192.168.64.1(137) header: id=19214 opcode=Query(0) response=Yes header: flags: bcast=No rec_avail=No rec_des=No trunc=No auth=Yes header: rcode=0 qdcount=0 ancount=1 nscount=0 arcount=0 answers: nmb_name=ZARTSOFT<1c> rr_type=33 rr_class=1 ttl=0 answers 0 char .GODAI hex 09474F44414920202020202020202020 answers 10 char .d.GODAI hex 006400474F4441492020202020202020 answers 20 char .d.GODAI hex 2020036400474F444149202020202020 answers 30 char d...__MSBRO hex 2020202020640001025F5F4D5342524F answers 40 char WSE__....ZARTSOF hex 5753455F5F0201E4005A415254534F46 answers 50 char T .d.ZARTS hex 54202020202020201D64005A41525453 answers 60 char OFT .d.ZAR hex 4F4654202020202020201B64005A4152 answers 70 char TSOFT ...Z hex 54534F4654202020202020201CE4005A answers 80 char ARTSOFT .. hex 415254534F4654202020202020201EE4 answers 90 char .ZARTSOFT hex 005A415254534F465420202020202020 answers a0 char ................ hex 00E40000000000000000000000000000 answers b0 char ................ hex 00000000000000000000000000000000 answers c0 char ................ hex 00000000000000000000000000000000 answers d0 char . hex 00 [2007/11/30 09:01:07.638844, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) GODAI#00: flags = 0x64 [2007/11/30 09:01:07.638863, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) GODAI#03: flags = 0x64 [2007/11/30 09:01:07.638882, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) GODAI#20: flags = 0x64 [2007/11/30 09:01:07.638919, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) __MSBROWSE__#01: flags = 0xe4 [2007/11/30 09:01:07.638939, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1d: flags = 0x64 [2007/11/30 09:01:07.638957, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1b: flags = 0x64 [2007/11/30 09:01:07.638975, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1c: flags = 0xe4 [2007/11/30 09:01:07.638992, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#1e: flags = 0xe4 [2007/11/30 09:01:07.639010, 10, pid=5651] libsmb/namequery.c:parse_node_status(185) ZARTSOFT#00: flags = 0xe4 [2007/11/30 09:01:07.639035, 10, pid=5651] libsmb/namequery.c:name_status_find(342) name_status_find: name found, name GODAI ip address is 192.168.64.1 [2007/11/30 09:01:07.639057, 5, pid=5651] libsmb/namecache.c:namecache_store(135) namecache_store: storing 1 address for GODAI#20: 192.168.64.1:0 [2007/11/30 09:01:07.639083, 10, pid=5651] lib/gencache.c:gencache_set(140) Adding cache entry with key = NBT/GODAI#20; value = 192.168.64.1:0 and timeout = Fri Nov 30 09:12:07 2007 (660 seconds ahead) [2007/11/30 09:01:07.639121, 10, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(612) cm_prepare_connection: connecting to DC GODAI for domain ZARTSOFT [2007/11/30 09:01:07.639159, 3, pid=5651] nsswitch/winbindd_cm.c:cm_get_ipc_userpass(507) cm_get_ipc_userpass: No auth-user defined [2007/11/30 09:01:07.639188, 10, pid=5651] passdb/secrets.c:secrets_named_mutex(930) secrets_named_mutex: got mutex for GODAI [2007/11/30 09:01:07.639257, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,194) [2007/11/30 09:01:07.639306, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,194) wrote 194 [2007/11/30 09:01:07.640528, 10, pid=5651] lib/util_sock.c:read_smb_length_return_keepalive(623) got smb length of 127 [2007/11/30 09:01:07.640553, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:01:07.640567, 5, pid=5651] lib/util.c:show_msg(516) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 9728 (0x2600) smb_vwv[ 8]= 22 (0x16) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]=32896 (0x8080) smb_vwv[12]=16323 (0x3FC3) smb_vwv[13]= 1442 (0x5A2) smb_vwv[14]=51251 (0xC833) smb_vwv[15]=54273 (0xD401) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2007/11/30 09:01:07.640733, 10, pid=5651] lib/util.c:dump_data(2285) [000] 67 6F 64 61 69 00 00 00 00 00 00 00 00 00 00 00 godai... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2007/11/30 09:01:07.640802, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:01:07.640814, 5, pid=5651] lib/util.c:show_msg(516) size=127 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 9728 (0x2600) smb_vwv[ 8]= 22 (0x16) smb_vwv[ 9]=64768 (0xFD00) smb_vwv[10]=33011 (0x80F3) smb_vwv[11]=32896 (0x8080) smb_vwv[12]=16323 (0x3FC3) smb_vwv[13]= 1442 (0x5A2) smb_vwv[14]=51251 (0xC833) smb_vwv[15]=54273 (0xD401) smb_vwv[16]= 254 (0xFE) smb_bcc=58 [2007/11/30 09:01:07.640978, 10, pid=5651] lib/util.c:dump_data(2285) [000] 67 6F 64 61 69 00 00 00 00 00 00 00 00 00 00 00 godai... ........ [010] 60 28 06 06 2B 06 01 05 05 02 A0 1E 30 1C A0 0E `(..+... ....0... [020] 30 0C 06 0A 2B 06 01 04 01 82 37 02 02 0A A3 0A 0...+... ..7..... [030] 30 08 A0 06 1B 04 4E 4F 4E 45 0.....NO NE [2007/11/30 09:01:07.641070, 5, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(723) connecting to GODAI from GODAI with username [ZARTSOFT]\[GODAI$] [2007/11/30 09:01:07.641091, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(793) Doing spnego session setup (blob length=58) [2007/11/30 09:01:07.641120, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(818) got OID=1 3 6 1 4 1 311 2 2 10 [2007/11/30 09:01:07.641139, 3, pid=5651] libsmb/cliconnect.c:cli_session_setup_spnego(826) got principal=NONE [2007/11/30 09:01:07.641230, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,164) [2007/11/30 09:01:07.641266, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,164) wrote 164 [2007/11/30 09:01:07.643835, 10, pid=5651] lib/util_sock.c:read_smb_length_return_keepalive(623) got smb length of 332 [2007/11/30 09:01:07.643866, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:01:07.643880, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:01:07.643976, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 48 9B 51 90 34 ED 5E 2B 00 ......`H .Q.4.^+. [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:01:07.644230, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:01:07.644242, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:01:07.644341, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 48 9B 51 90 34 ED 5E 2B 00 ......`H .Q.4.^+. [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:01:07.644629, 3, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1018) Got challenge flags: [2007/11/30 09:01:07.644648, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:01:07.644712, 3, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1040) NTLMSSP: Set final flags: [2007/11/30 09:01:07.644730, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:01:07.644802, 5, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1112) NTLMSSP challenge set by NTLM2 [2007/11/30 09:01:07.644821, 5, pid=5651] libsmb/ntlmssp.c:ntlmssp_client_challenge(1113) challenge is: [2007/11/30 09:01:07.644837, 5, pid=5651] lib/util.c:dump_data(2285) [000] EA F8 E5 EC A2 90 3A C6 ......:. [2007/11/30 09:01:07.644960, 3, pid=5651] libsmb/ntlmssp_sign.c:ntlmssp_sign_init(338) NTLMSSP Sign/Seal - Initialising with flags: [2007/11/30 09:01:07.644980, 3, pid=5651] libsmb/ntlmssp.c:debug_ntlmssp_flags(63) 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 [2007/11/30 09:01:07.645057, 6, pid=5651] libsmb/clientgen.c:write_socket(153) write_socket(20,264) [2007/11/30 09:01:07.645096, 6, pid=5651] libsmb/clientgen.c:write_socket(156) write_socket(20,264) wrote 264 [2007/11/30 09:01:17.644197, 10, pid=5651] lib/util_sock.c:read_socket_with_timeout(476) read_socket_with_timeout: timeout read. select timed out. [2007/11/30 09:01:17.644235, 10, pid=5651] lib/util_sock.c:receive_smb_raw(672) receive_smb_raw: length < 0! [2007/11/30 09:01:17.644255, 10, pid=5651] libsmb/clientgen.c:client_receive_smb(65) client_receive_smb failed [2007/11/30 09:01:17.644273, 5, pid=5651] lib/util.c:show_msg(506) [2007/11/30 09:01:17.644285, 5, pid=5651] lib/util.c:show_msg(516) size=332 smb_com=0x73 smb_rcls=22 smb_reh=0 smb_err=49152 smb_flg=136 smb_flg2=51201 smb_tid=0 smb_pid=5651 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]= 221 (0xDD) smb_bcc=289 [2007/11/30 09:01:17.644386, 10, pid=5651] lib/util.c:dump_data(2285) [000] A1 81 DA 30 81 D7 A0 03 0A 01 01 A1 0C 06 0A 2B ...0.... .......+ [010] 06 01 04 01 82 37 02 02 0A A2 81 C1 04 81 BE 4E .....7.. .......N [020] 54 4C 4D 53 53 50 00 02 00 00 00 10 00 10 00 30 TLMSSP.. .......0 [030] 00 00 00 15 82 89 60 48 9B 51 90 34 ED 5E 2B 00 ......`H .Q.4.^+. [040] 00 00 00 00 00 00 00 7E 00 7E 00 40 00 00 00 5A .......~ .~.@...Z [050] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 02 .A.R.T.S .O.F.T.. [060] 00 10 00 5A 00 41 00 52 00 54 00 53 00 4F 00 46 ...Z.A.R .T.S.O.F [070] 00 54 00 01 00 0A 00 47 00 4F 00 44 00 41 00 49 .T.....G .O.D.A.I [080] 00 04 00 22 00 6C 00 6F 00 63 00 61 00 6C 00 2E ...".l.o .c.a.l.. [090] 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 00 74 .z.a.r.t .s.o.f.t [0A0] 00 2E 00 72 00 75 00 03 00 2E 00 67 00 6F 00 64 ...r.u.. ...g.o.d [0B0] 00 61 00 69 00 2E 00 6C 00 6F 00 63 00 61 00 6C .a.i...l .o.c.a.l [0C0] 00 2E 00 7A 00 61 00 72 00 74 00 73 00 6F 00 66 ...z.a.r .t.s.o.f [0D0] 00 74 00 2E 00 72 00 75 00 00 00 00 00 55 00 6E .t...r.u .....U.n [0E0] 00 69 00 78 00 00 00 53 00 61 00 6D 00 62 00 61 .i.x...S .a.m.b.a [0F0] 00 20 00 33 00 2E 00 30 00 2E 00 32 00 36 00 61 . .3...0 ...2.6.a [100] 00 2D 00 30 00 2E 00 66 00 63 00 37 00 00 00 5A .-.0...f .c.7...Z [110] 00 41 00 52 00 54 00 53 00 4F 00 46 00 54 00 00 .A.R.T.S .O.F.T.. [120] 00 . [2007/11/30 09:01:17.644664, 0, pid=5651] libsmb/clientgen.c:cli_receive_smb(112) Receiving SMB: Server stopped responding [2007/11/30 09:01:17.644783, 4, pid=5651] nsswitch/winbindd_cm.c:cm_prepare_connection(731) authenticated session setup failed with NT_STATUS_IO_TIMEOUT [2007/11/30 09:01:17.644919, 10, pid=5651] passdb/secrets.c:secrets_named_mutex_release(942) secrets_named_mutex: released mutex for GODAI [2007/11/30 09:01:17.644943, 10, pid=5651] libsmb/conncache.c:add_failed_connection_entry(118) add_failed_connection_entry: domain ZARTSOFT (GODAI) already tried and failed [2007/11/30 09:01:17.644981, 10, pid=5651] lib/gencache.c:gencache_del(173) Deleting cache entry (key = SAF/DOMAIN/ZARTSOFT) [2007/11/30 09:01:17.645454, 10, pid=5651] nsswitch/winbindd_cm.c:set_domain_offline(302) set_domain_offline: called for domain ZARTSOFT [2007/11/30 09:01:17.645478, 10, pid=5651] lib/events.c:event_add_timed(129) Added timed event "check_domain_online_handler": 555555a7a220 [2007/11/30 09:01:17.645498, 10, pid=5651] nsswitch/winbindd_cm.c:set_domain_offline(347) set_domain_offline: added event handler for domain ZARTSOFT [2007/11/30 09:01:17.645529, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999948 [2007/11/30 09:01:17.645564, 10, pid=5651] lib/events.c:event_add_timed(129) Added timed event "async_request_timeout": 5555559f3090 [2007/11/30 09:01:17.645592, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 20 [2007/11/30 09:01:17.645618, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999859 [2007/11/30 09:01:17.645651, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 21 [2007/11/30 09:01:17.645674, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999803 [2007/11/30 09:01:17.645705, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn INTERFACE_VERSION [2007/11/30 09:01:17.645724, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_interface_version(491) [ 5657]: request interface version [2007/11/30 09:01:17.645748, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 22 [2007/11/30 09:01:17.645772, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999705 [2007/11/30 09:01:17.645804, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn INTERFACE_VERSION [2007/11/30 09:01:17.645824, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_interface_version(491) [ 5660]: request interface version [2007/11/30 09:01:17.645849, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 23 [2007/11/30 09:01:17.645872, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999605 [2007/11/30 09:01:17.645912, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn INTERFACE_VERSION [2007/11/30 09:01:17.645931, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_interface_version(491) [ 5670]: request interface version [2007/11/30 09:01:17.645966, 10, pid=5651] lib/events.c:timed_event_destructor(66) Destroying timed event 5555559f3090 "async_request_timeout" [2007/11/30 09:01:17.646030, 10, pid=5651] nsswitch/winbindd_cache.c:cache_retrieve_response(2300) Retrieving response for pid 5653 [2007/11/30 09:01:17.646260, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999217 [2007/11/30 09:01:17.646314, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn INTERFACE_VERSION [2007/11/30 09:01:17.646334, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_interface_version(491) [ 5657]: request interface version [2007/11/30 09:01:17.646361, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999117 [2007/11/30 09:01:17.646398, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn WINBINDD_PRIV_PIPE_DIR [2007/11/30 09:01:17.646418, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_priv_pipe_dir(524) [ 5660]: request location of privileged pipe [2007/11/30 09:01:17.646448, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/999029 [2007/11/30 09:01:17.646478, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn WINBINDD_PRIV_PIPE_DIR [2007/11/30 09:01:17.646497, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_priv_pipe_dir(524) [ 5670]: request location of privileged pipe [2007/11/30 09:01:17.646527, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998950 [2007/11/30 09:01:17.646557, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn WINBINDD_PRIV_PIPE_DIR [2007/11/30 09:01:17.646576, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_priv_pipe_dir(524) [ 5657]: request location of privileged pipe [2007/11/30 09:01:17.646611, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998866 [2007/11/30 09:01:17.646643, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998834 [2007/11/30 09:01:17.646673, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998804 [2007/11/30 09:01:17.646702, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 20 [2007/11/30 09:01:17.646726, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998751 [2007/11/30 09:01:17.646764, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 21 [2007/11/30 09:01:17.646786, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998691 [2007/11/30 09:01:17.646824, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn GID_TO_SID [2007/11/30 09:01:17.646844, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_gid_to_sid(477) [ 5660]: gid to sid 1515 [2007/11/30 09:01:17.646869, 6, pid=5651] nsswitch/winbindd.c:new_connection(628) accepted socket 22 [2007/11/30 09:01:17.646891, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998586 [2007/11/30 09:01:17.646922, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn GID_TO_SID [2007/11/30 09:01:17.646954, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_gid_to_sid(477) [ 5670]: gid to sid 1515 [2007/11/30 09:01:17.646986, 10, pid=5651] lib/events.c:event_add_timed(129) Added timed event "async_request_timeout": 555555a79670 [2007/11/30 09:01:17.647009, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998468 [2007/11/30 09:01:17.647042, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.647063, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5657]: ping [2007/11/30 09:01:17.647083, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998394 [2007/11/30 09:01:17.647111, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998366 [2007/11/30 09:01:17.647218, 10, pid=5651] lib/events.c:timed_event_destructor(66) Destroying timed event 555555a79670 "async_request_timeout" [2007/11/30 09:01:17.647243, 10, pid=5651] nsswitch/winbindd_cache.c:cache_retrieve_response(2300) Retrieving response for pid 5656 [2007/11/30 09:01:17.647288, 10, pid=5651] nsswitch/winbindd_sid.c:gid2sid_recv(462) gid2sid: gid 1515 has sid S-1-5-21-2274471336-3138038065-606154707-515 [2007/11/30 09:01:17.647312, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998165 [2007/11/30 09:01:17.647347, 10, pid=5651] lib/events.c:event_add_timed(129) Added timed event "async_request_timeout": 555555a7a2c0 [2007/11/30 09:01:17.647372, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/998105 [2007/11/30 09:01:17.647539, 10, pid=5651] lib/events.c:timed_event_destructor(66) Destroying timed event 555555a7a2c0 "async_request_timeout" [2007/11/30 09:01:17.647562, 10, pid=5651] nsswitch/winbindd_cache.c:cache_retrieve_response(2300) Retrieving response for pid 5656 [2007/11/30 09:01:17.647601, 10, pid=5651] nsswitch/winbindd_sid.c:gid2sid_recv(462) gid2sid: gid 1515 has sid S-1-5-21-2274471336-3138038065-606154707-515 [2007/11/30 09:01:17.647624, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/997853 [2007/11/30 09:01:17.647655, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/997823 [2007/11/30 09:01:17.648498, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996980 [2007/11/30 09:01:17.648532, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.648553, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5670]: sid to gid S-1-1-0 [2007/11/30 09:01:17.648574, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-1-0) [2007/11/30 09:01:17.648597, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.648617, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-1-0 [2007/11/30 09:01:17.648638, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-1-0 [2007/11/30 09:01:17.648659, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996819 [2007/11/30 09:01:17.648686, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996791 [2007/11/30 09:01:17.648713, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996764 [2007/11/30 09:01:17.648742, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.648763, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5670]: ping [2007/11/30 09:01:17.648782, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996695 [2007/11/30 09:01:17.648811, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996667 [2007/11/30 09:01:17.648883, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996595 [2007/11/30 09:01:17.648915, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.648937, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5670]: sid to gid S-1-5-2 [2007/11/30 09:01:17.648957, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-2) [2007/11/30 09:01:17.648977, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.648996, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-2 [2007/11/30 09:01:17.649015, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-2 [2007/11/30 09:01:17.649057, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996420 [2007/11/30 09:01:17.649084, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996393 [2007/11/30 09:01:17.649133, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996345 [2007/11/30 09:01:17.649164, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.649183, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5670]: ping [2007/11/30 09:01:17.649208, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996269 [2007/11/30 09:01:17.649235, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996242 [2007/11/30 09:01:17.649372, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/996106 [2007/11/30 09:01:17.649405, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.649425, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5660]: sid to gid S-1-1-0 [2007/11/30 09:01:17.649445, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-1-0) [2007/11/30 09:01:17.649467, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.649484, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-1-0 [2007/11/30 09:01:17.649503, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-1-0 [2007/11/30 09:01:17.649522, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995955 [2007/11/30 09:01:17.649553, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995924 [2007/11/30 09:01:17.649583, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.649602, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5657]: sid to gid S-1-0-0 [2007/11/30 09:01:17.649621, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-0-0) [2007/11/30 09:01:17.649640, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.649658, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-0-0 [2007/11/30 09:01:17.649676, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-0-0 [2007/11/30 09:01:17.649700, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.649720, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5670]: sid to gid S-1-5-11 [2007/11/30 09:01:17.649738, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-11) [2007/11/30 09:01:17.649758, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.649776, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-11 [2007/11/30 09:01:17.649794, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-11 [2007/11/30 09:01:17.649816, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995661 [2007/11/30 09:01:17.649855, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.649874, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5660]: ping [2007/11/30 09:01:17.649908, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995569 [2007/11/30 09:01:17.649941, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995537 [2007/11/30 09:01:17.649972, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.649993, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5657]: ping [2007/11/30 09:01:17.650017, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.650035, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5670]: ping [2007/11/30 09:01:17.650054, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995423 [2007/11/30 09:01:17.650088, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995389 [2007/11/30 09:01:17.650119, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.650140, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5660]: sid to gid S-1-5-2 [2007/11/30 09:01:17.650160, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-2) [2007/11/30 09:01:17.650181, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.650205, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-2 [2007/11/30 09:01:17.650226, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-2 [2007/11/30 09:01:17.650247, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995230 [2007/11/30 09:01:17.650277, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995200 [2007/11/30 09:01:17.650304, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995173 [2007/11/30 09:01:17.650334, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.650354, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5660]: ping [2007/11/30 09:01:17.650375, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995102 [2007/11/30 09:01:17.650405, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/995072 [2007/11/30 09:01:17.650501, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994977 [2007/11/30 09:01:17.650532, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.650553, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5660]: sid to gid S-1-5-11 [2007/11/30 09:01:17.650575, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-11) [2007/11/30 09:01:17.650596, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.650616, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-11 [2007/11/30 09:01:17.650636, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-11 [2007/11/30 09:01:17.650657, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994820 [2007/11/30 09:01:17.650686, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994791 [2007/11/30 09:01:17.650713, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994764 [2007/11/30 09:01:17.650741, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.650761, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5660]: ping [2007/11/30 09:01:17.650798, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994680 [2007/11/30 09:01:17.650828, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994649 [2007/11/30 09:01:17.651229, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994249 [2007/11/30 09:01:17.651261, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.651283, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5657]: sid to gid S-1-1-0 [2007/11/30 09:01:17.651303, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-1-0) [2007/11/30 09:01:17.651325, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.651343, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-1-0 [2007/11/30 09:01:17.651364, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-1-0 [2007/11/30 09:01:17.651386, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994091 [2007/11/30 09:01:17.651415, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994062 [2007/11/30 09:01:17.651463, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/994015 [2007/11/30 09:01:17.651493, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.651512, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5657]: ping [2007/11/30 09:01:17.651533, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/993944 [2007/11/30 09:01:17.651561, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/993916 [2007/11/30 09:01:17.653015, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/992464 [2007/11/30 09:01:17.653159, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/992319 [2007/11/30 09:01:17.653196, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.653219, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5657]: sid to gid S-1-5-2 [2007/11/30 09:01:17.653240, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-2) [2007/11/30 09:01:17.653264, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.653283, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-2 [2007/11/30 09:01:17.653304, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-2 [2007/11/30 09:01:17.653326, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/992151 [2007/11/30 09:01:17.653355, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/992122 [2007/11/30 09:01:17.653401, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/992076 [2007/11/30 09:01:17.653431, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.653453, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5657]: ping [2007/11/30 09:01:17.653473, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/992004 [2007/11/30 09:01:17.653500, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991977 [2007/11/30 09:01:17.653598, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991880 [2007/11/30 09:01:17.653630, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn SID_TO_GID [2007/11/30 09:01:17.653671, 3, pid=5651] nsswitch/winbindd_sid.c:winbindd_sid_to_gid(308) [ 5657]: sid to gid S-1-5-11 [2007/11/30 09:01:17.653693, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(679) find_lookup_domain_from_sid(S-1-5-11) [2007/11/30 09:01:17.653714, 10, pid=5651] nsswitch/winbindd_util.c:find_lookup_domain_from_sid(682) calling find_domain_from_sid [2007/11/30 09:01:17.653733, 5, pid=5651] nsswitch/winbindd_async.c:winbindd_lookupsid_async(729) Could not find domain for sid S-1-5-11 [2007/11/30 09:01:17.653753, 5, pid=5651] nsswitch/winbindd_sid.c:sid2gid_lookupsid_recv(274) sid2gid_lookupsid_recv: Could not convert get sid type for S-1-5-11 [2007/11/30 09:01:17.653774, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991703 [2007/11/30 09:01:17.653801, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991676 [2007/11/30 09:01:17.653847, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991630 [2007/11/30 09:01:17.653878, 10, pid=5651] nsswitch/winbindd.c:process_request(314) process_request: request fn PING [2007/11/30 09:01:17.653898, 3, pid=5651] nsswitch/winbindd_misc.c:winbindd_ping(470) [ 5657]: ping [2007/11/30 09:01:17.653918, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991559 [2007/11/30 09:01:17.653944, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/991533 [2007/11/30 09:01:17.654723, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/990756 [2007/11/30 09:01:17.656115, 10, pid=5651] lib/events.c:get_timed_events_timeout(295) timed_events_timeout: 299/989364 [2007/11/30 09:01:25.940901, 5, pid=5651] lib/gencache.c:gencache_shutdown(94) Closing cache file