9603 – winbind (INTERNAL ERROR: Signal 11) after 'ls -la'

Bug 9603 - winbind (INTERNAL ERROR: Signal 11) after 'ls -la'

Summary: winbind (INTERNAL ERROR: Signal 11) after 'ls -la'

Status:	RESOLVED FIXED

Alias:	None

Product:	Samba 4.0
Classification:	Unclassified
Component:	Winbind (show other bugs)
Version:	4.0.1
Hardware:	x64 Linux

Importance:	P5 normal (vote)
Target Milestone:	---
Assignee:	Samba QA Contact
QA Contact:	Samba QA Contact

URL:
Keywords:

Depends on:
Blocks:

Reported:	2013-01-26 09:54 UTC by Sergey Sidlyarenko
Modified:	2018-07-05 22:52 UTC (History)
CC List:	2 users (show)

See Also:

Attachments
Add an attachment (proposed patch, testcase, etc.)

Note You need to log in before you can comment on or make changes to this bug.

Description Sergey Sidlyarenko 2013-01-26 09:54:28 UTC

Tested on two Debian (last updated sid) servers.
root@ns1:/home/DC1/administrator# uname -a
Linux ns1 3.2.0-4-amd64 #1 SMP Debian 3.2.35-2 x86_64 GNU/Linux

/etc/samba/smb.conf
[global]
     netbios name = NS1
     workgroup = DC1
     server string = Samba File Server

     wins support = yes
     dns proxy = yes
     name resolve order = wins bcast host

     interfaces = lo br0
     bind interfaces only = yes

     server role = dc
     server role check:inhibit = yes
     realm = DC1.LEFOYER.RU

     log level = 3
     log file = /var/log/samba/samba.log

     passdb backend = samba4
     idmap_ldb:use rfc2307 = yes
     server services = -nbt -wrepl
     server signing = auto
     dns forwarder = 192.168.101.4
     domain logons = yes
     domain master = yes
     logon path = \\ns1.dc1.lefoyer.ru\profiles\%U
     logon home = \\%N\%U
     logon drive = L:
     logon script = STARTUP.BAT


 [netlogon]
     path = /var/lib/samba/sysvol/dc1.lefoyer.ru/scripts
     browseable = no
     read only = yes

 [sysvol]
     path = /var/lib/samba/sysvol
     read only = no

 [profiles]
     path = /var/lib/samba/profiles
     read only = no

 [homes]
     comment = Домашние каталоги
     path = /home/DC1/%U
     read only = no
     browseable = no
     create mask = 0600
     directory mask = 0700
     vfs objects = acl_xattr full_audit
     full_audit:success = connect opendir disconnect unlink mkdir rmdir open rename
     full_audit:failure = connect opendir disconnect unlink mkdir rmdir open rename
     root preexec = /etc/samba/mkhomedir.sh %U

-----------------------------------------------------------
Configured pam & nsswitch.conf

Created folder /home/DC1/administrator with attr root:"Domain User".
From joined Windows 7 workstation created file 1.txt in this folder.


Test:
Restart samba4

root@ns1:/home/samba/samba-4.0.1# wbinfo -u
Administrator
Guest
krbtgt


root@ns1:/home/samba/samba-4.0.1# getent passwd
root:x:0:0:root:/root:/bin/bash
...
DC1\Administrator:*:0:100::/home/DC1/Administrator:/bin/false
DC1\Guest:*:3000011:3000012::/home/DC1/Guest:/bin/false
DC1\krbtgt:*:3000018:100::/home/DC1/krbtgt:/bin/false


root@ns1:/home/samba/samba-4.0.1# getent group
root:x:0:
...
Enterprise Read-Only Domain Controllers:*:3000019:
Domain Admins:*:3000008:
Domain Users:*:100:
Domain Guests:*:3000012:
Domain Computers:*:3000017:
Domain Controllers:*:3000020:
Schema Admins:*:3000007:
Enterprise Admins:*:3000006:
Group Policy Creator Owners:*:3000004:
Read-Only Domain Controllers:*:3000021:
DnsUpdateProxy:*:3000022:


!!!!! (very long wait after ls)
root@ns1:/home/samba/samba-4.0.1# cd /home/DC1/administrator/ ; ls -la
total 16
drwx------  2 root    users 4096 Jan 26 12:54 .
drwxrwx---  3 root    users 4096 Jan 25 18:53 ..
-rwxrwx--x+ 1 3000000 users    4 Jan 25 18:54 1.txt


!!!!!!!!! (winbind not worked now)
root@ns1:/home/DC1/administrator# wbinfo -p
Ping to winbindd failed
could not ping winbindd!






-----------------------------------------------------------------
root@ns1:/home/DC1/administrator# cat /var/log/samba/samba.log
[2013/01/26 13:45:58,  0] ../source4/smbd/server.c:369(binary_smbd_main)
  samba version 4.0.1 started.
  Copyright Andrew Tridgell and the Samba Team 1992-2012
[2013/01/26 13:45:58,  3] ../source4/smbd/server.c:380(binary_smbd_main)
  Becoming a daemon.
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'gssapi_spnego' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'gssapi_krb5' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'gssapi_krb5_sasl' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'sasl-DIGEST-MD5' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'schannel' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'spnego' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'ntlmssp' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'krb5' registered
[2013/01/26 13:45:58,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'fake_gssapi_krb5' registered
[2013/01/26 13:45:58,  3] ../source4/ntptr/ntptr_base.c:67(ntptr_register)
  NTPTR backend 'simple_ldb'
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'default' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'posix' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'unixuid' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'unixuid' for type 3 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'unixuid' for type 2 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'cifs' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'smb2' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'simple' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'cifsposix' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'default' for type 3 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'default' for type 2 registered
[2013/01/26 13:45:58,  3] ../source4/ntvfs/ntvfs_base.c:79(ntvfs_register)
  NTVFS backend 'nbench' for type 1 registered
[2013/01/26 13:45:58,  3] ../source4/smbd/process_model.c:97(register_process_model)
  PROCESS_MODEL 'single' registered
[2013/01/26 13:45:58,  3] ../source4/smbd/process_model.c:97(register_process_model)
  PROCESS_MODEL 'prefork' registered
[2013/01/26 13:45:58,  3] ../source4/smbd/process_model.c:97(register_process_model)
  PROCESS_MODEL 'onefork' registered
[2013/01/26 13:45:58,  3] ../source4/smbd/process_model.c:97(register_process_model)
  PROCESS_MODEL 'standard' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'sam' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'sam_ignoredomain' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'anonymous' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'winbind' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'winbind_wbclient' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'name_to_ntstatus' registered
[2013/01/26 13:45:58,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'unix' registered
[2013/01/26 13:45:58,  3] ../source4/param/share.c:124(share_register)
  SHARE backend [classic] registered.
[2013/01/26 13:45:58,  3] ../source4/param/share.c:124(share_register)
  SHARE backend [ldb] registered.
[2013/01/26 13:45:59,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of privilege.ldb
[2013/01/26 13:45:59,  0] ../source4/smbd/server.c:475(binary_smbd_main)
  samba: using 'standard' process model
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'rpcecho' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'epmapper' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'remote' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'srvsvc' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'wkssvc' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'unixinfo' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'samr' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'winreg' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'netlogon' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'dssetup' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'lsarpc' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'backupkey' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'spoolss' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'drsuapi' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'browser' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'eventlog6' registered
[2013/01/26 13:45:59,  3] ../source4/rpc_server/dcerpc_server.c:1205(dcerpc_register_ep_server)
  DCERPC endpoint server 'dnsserver' registered
[2013/01/26 13:45:59,  2] ../source4/dsdb/repl/drepl_partitions.c:116(dreplsrv_load_partitions)
  dreplsrv_partition[CN=Configuration,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/repl/drepl_partitions.c:116(dreplsrv_load_partitions)
  dreplsrv_partition[CN=Schema,CN=Configuration,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/repl/drepl_partitions.c:116(dreplsrv_load_partitions)
  dreplsrv_partition[DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/repl/drepl_partitions.c:116(dreplsrv_load_partitions)
  dreplsrv_partition[DC=DomainDnsZones,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/repl/drepl_partitions.c:116(dreplsrv_load_partitions)
  dreplsrv_partition[DC=ForestDnsZones,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:45:59,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of idmap.ldb
[2013/01/26 13:45:59.292016,  3] ../source3/param/loadparm.c:4805(lp_load_ex)
  lp_load_ex: refreshing parameters
[2013/01/26 13:45:59.292176,  3] ../source3/param/loadparm.c:749(init_globals)
  Initialising global parameters
[2013/01/26 13:45:59.292308,  2] ../source3/param/loadparm.c:542(max_open_files)
  rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384)
[2013/01/26 13:45:59.292450,  3] ../lib/util/params.c:550(pm_process)
  params.c:pm_process() - Processing configuration file "/etc/samba/smb.conf"
[2013/01/26 13:45:59.292535,  3] ../source3/param/loadparm.c:3532(do_section)
  Processing section "[global]"
[2013/01/26 13:45:59.292890,  2] ../source3/param/loadparm.c:3549(do_section)
  Processing section "[netlogon]"
[2013/01/26 13:45:59.293112,  2] ../source3/param/loadparm.c:3549(do_section)
  Processing section "[sysvol]"
[2013/01/26 13:45:59.293283,  2] ../source3/param/loadparm.c:3549(do_section)
  Processing section "[profiles]"
[2013/01/26 13:45:59.293421,  2] ../source3/param/loadparm.c:3549(do_section)
  Processing section "[homes]"
[2013/01/26 13:45:59,  2] ../source4/dsdb/kcc/kcc_service.c:127(kccsrv_load_partitions)
  kccsrv_partition[DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/kcc/kcc_service.c:127(kccsrv_load_partitions)
  kccsrv_partition[CN=Configuration,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/kcc/kcc_service.c:127(kccsrv_load_partitions)
  kccsrv_partition[CN=Schema,CN=Configuration,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/kcc/kcc_service.c:127(kccsrv_load_partitions)
  kccsrv_partition[DC=DomainDnsZones,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59,  2] ../source4/dsdb/kcc/kcc_service.c:127(kccsrv_load_partitions)
  kccsrv_partition[DC=ForestDnsZones,DC=dc1,DC=lefoyer,DC=ru] loaded
[2013/01/26 13:45:59.293717,  3] ../source3/param/loadparm.c:1771(lp_add_ipc)
  adding IPC service
[2013/01/26 13:45:59.314432,  2] ../source3/lib/interface.c:341(add_interface)
  added interface lo ip=::1 bcast=::1 netmask=ffff:ffff:ffff:ffff:ffff:ffff:ffff:ffff
[2013/01/26 13:45:59.314654,  2] ../source3/lib/interface.c:341(add_interface)
  added interface lo ip=127.0.0.1 bcast=127.255.255.255 netmask=255.0.0.0
[2013/01/26 13:45:59.314750,  2] ../source3/lib/interface.c:341(add_interface)
  added interface br0 ip=fe80::20c:29ff:fe0b:939e%br0 bcast=fe80::ffff:ffff:ffff:ffff%br0 netmask=ffff:ffff:ffff:ffff::
[2013/01/26 13:45:59.314914,  2] ../source3/lib/interface.c:341(add_interface)
  added interface br0 ip=192.168.100.200 bcast=192.168.100.255 netmask=255.255.255.0
[2013/01/26 13:45:59.315127,  3] ../source3/smbd/server.c:1259(main)
  loaded services
[2013/01/26 13:45:59.315586,  3] ../source3/profile/profile.c:189(profile_setup)
  Initialised profile area
[2013/01/26 13:45:59,  3] ../source4/dsdb/dns/dns_update.c:340(dnsupdate_check_names)
  Calling DNS name update script
[2013/01/26 13:45:59.315757,  0] ../source3/smbd/server.c:1280(main)
  standard input is not a socket, assuming -D option
[2013/01/26 13:45:59.325997,  3] ../source3/smbd/server.c:1291(main)
  Becoming a daemon.
[2013/01/26 13:45:59,  3] ../source4/dsdb/dns/dns_update.c:355(dnsupdate_check_names)
  Calling SPN name update script
[2013/01/26 13:45:59,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:46:00.532912,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of idmap.ldb
[2013/01/26 13:46:00,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:00,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:00,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:00,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:00,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:00,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:00,  3] ../libcli/auth/schannel_state_tdb.c:110(schannel_store_session_key_tdb)
  schannel_store_session_key_tdb: stored schannel info with key SECRETS/SCHANNEL/NS1
[2013/01/26 13:46:00,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:00,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:00,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:46:00,  3] ../libcli/auth/schannel_state_tdb.c:180(schannel_fetch_session_key_tdb)
  schannel_fetch_session_key_tdb: restored schannel info key SECRETS/SCHANNEL/NS1
[2013/01/26 13:46:00,  3] ../libcli/auth/schannel_state_tdb.c:180(schannel_fetch_session_key_tdb)
  schannel_fetch_session_key_tdb: restored schannel info key SECRETS/SCHANNEL/NS1
[2013/01/26 13:46:00,  3] ../libcli/auth/schannel_state_tdb.c:110(schannel_store_session_key_tdb)
  schannel_store_session_key_tdb: stored schannel info with key SECRETS/SCHANNEL/NS1
[2013/01/26 13:46:00,  1] ../source4/auth/gensec/gensec_gssapi.c:339(gensec_gssapi_client_start)
  Could not determine hostname for target computer, cannot use kerberos
[2013/01/26 13:46:00,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_client.c:170(ntlmssp_client_challenge)
  Got challenge flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60898235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_client.c:295(ntlmssp_client_challenge)
  NTLMSSP: Set final flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_sign.c:547(ntlmssp_sign_init)
  NTLMSSP Sign/Seal - Initialising with flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_server.c:358(ntlmssp_server_preauth)
  Got user=[NS1$] domain=[DC1] workstation=[NS1] len1=24 len2=140
[2013/01/26 13:46:00,  3] ../source4/auth/ntlm/auth.c:270(auth_check_password_send)
  auth_check_password_send: Checking password for unmapped user [DC1]\[NS1$]@[NS1]
  auth_check_password_send: mapped user is: [DC1]\[NS1$]@[NS1]
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_sign.c:547(ntlmssp_sign_init)
  NTLMSSP Sign/Seal - Initialising with flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  1] ../source4/auth/gensec/gensec_gssapi.c:339(gensec_gssapi_client_start)
  Could not determine hostname for target computer, cannot use kerberos
[2013/01/26 13:46:00,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_client.c:170(ntlmssp_client_challenge)
  Got challenge flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60898235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_client.c:295(ntlmssp_client_challenge)
  NTLMSSP: Set final flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_sign.c:547(ntlmssp_sign_init)
  NTLMSSP Sign/Seal - Initialising with flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_server.c:358(ntlmssp_server_preauth)
  Got user=[NS1$] domain=[DC1] workstation=[NS1] len1=24 len2=140
[2013/01/26 13:46:00,  3] ../source4/auth/ntlm/auth.c:270(auth_check_password_send)
  auth_check_password_send: Checking password for unmapped user [DC1]\[NS1$]@[NS1]
  auth_check_password_send: mapped user is: [DC1]\[NS1$]@[NS1]
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_sign.c:547(ntlmssp_sign_init)
  NTLMSSP Sign/Seal - Initialising with flags:
[2013/01/26 13:46:00,  3] ../auth/ntlmssp/ntlmssp_util.c:34(debug_ntlmssp_flags)
  Got NTLMSSP neg_flags=0x60088235
[2013/01/26 13:46:00.954484,  3] ../source3/rpc_server/svcctl/srv_svcctl_reg.c:565(svcctl_init_winreg)
  Initialise the svcctl registry keys if needed.
[2013/01/26 13:46:00.970755,  3] ../source3/rpc_server/eventlog/srv_eventlog_reg.c:59(eventlog_init_winreg)
  Initialise the eventlog registry keys if needed.
[2013/01/26 13:46:00.971681,  3] ../source3/printing/queue_process.c:241(start_background_queue)
  start_background_queue: Starting background LPQ thread
[2013/01/26 13:46:01.024230,  3] ../source3/printing/pcap.c:138(pcap_cache_reload)
  reloading printcap cache
[2013/01/26 13:46:01.039582,  3] ../source3/printing/pcap.c:189(pcap_cache_reload)
  reload status: ok
[2013/01/26 13:46:01.062402,  2] ../source3/smbd/server.c:940(smbd_parent_loop)
  waiting for connections
[2013/01/26 13:46:01,  3] ../source4/dsdb/dns/dns_update.c:325(dnsupdate_spnupdate_done)
  Completed SPN update check OK
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: ldb_wrap open of secrets.ldb
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: AS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:40600 for krbtgt/DC1.LEFOYER.RU@DC1.LEFOYER.RU
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: No preauth found, returning PREAUTH-REQUIRED -- NS1$@DC1.LEFOYER.RU
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: AS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:43202 for krbtgt/DC1.LEFOYER.RU@DC1.LEFOYER.RU
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Client sent patypes: encrypted-timestamp
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Looking for PKINIT pa-data -- NS1$@DC1.LEFOYER.RU
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Looking for ENC-TS pa-data -- NS1$@DC1.LEFOYER.RU
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: ENC-TS Pre-authentication succeeded -- NS1$@DC1.LEFOYER.RU using aes256-cts-hmac-sha1-96
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: AS-REQ authtime: 2013-01-26T13:46:01 starttime: unset endtime: 2013-01-26T23:46:01 renew till: unset
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Client supported enctypes: aes256-cts-hmac-sha1-96, aes128-cts-hmac-sha1-96, des3-cbc-sha1, des3-cbc-md5, arcfour-hmac-md5, using aes256-cts-hmac-sha1-96/aes256-cts-hmac-sha1-96
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:50996 for DNS/ns1.dc1.lefoyer.ru@DC1.LEFOYER.RU [canonicalize]
[2013/01/26 13:46:01,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ authtime: 2013-01-26T13:46:01 starttime: 2013-01-26T13:46:01 endtime: 2013-01-26T23:46:01 renew till: unset
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: ; Communication with 127.0.1.1#53 failed: operation canceled
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: could not talk to any default name server
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: ; Communication with 127.0.1.1#53 failed: operation canceled
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: could not talk to any default name server
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: ; Communication with 127.0.1.1#53 failed: operation canceled
[2013/01/26 13:46:01,  0] ../lib/util/util_runcmd.c:334(samba_runcmd_io_handler)
  /usr/sbin/samba_dnsupdate: could not talk to any default name server
[2013/01/26 13:46:01,  3] ../source4/dsdb/dns/dns_update.c:296(dnsupdate_nameupdate_done)
  Completed DNS update check OK
[2013/01/26 13:46:30,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:30,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:30,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:30,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:48,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:48,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:46:48,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:46:48,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:47:00.639889,  2] ../source3/smbd/server.c:436(remove_child_pid)
  Could not find child 6649 -- ignoring
[2013/01/26 13:47:01.013996,  3] ../source3/printing/queue_process.c:75(print_queue_housekeeping)
  Printcap cache time expired.
[2013/01/26 13:47:01.014126,  3] ../source3/printing/pcap.c:138(pcap_cache_reload)
  reloading printcap cache
[2013/01/26 13:47:01.017073,  3] ../source3/printing/pcap.c:189(pcap_cache_reload)
  reload status: ok
[2013/01/26 13:48:00.710824,  2] ../source3/smbd/server.c:436(remove_child_pid)
  Could not find child 6651 -- ignoring
[2013/01/26 13:48:05,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:48:05,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:48:05,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:48:05,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:49:00.781475,  2] ../source3/smbd/server.c:436(remove_child_pid)
  Could not find child 6653 -- ignoring
[2013/01/26 13:49:27,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:49:27,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:49:27,  3] ../lib/util/util_net.c:78(interpret_string_addr_internal)
  interpret_string_addr_internal: getaddrinfo failed for name (null) (flags 4) [Name or service not known]
[2013/01/26 13:49:27.172383,  3] ../source3/lib/access.c:338(allow_access)
  Allowed connection from ::1 (::1)
[2013/01/26 13:49:27.173518,  3] ../source3/smbd/oplock.c:985(init_oplocks)
  init_oplocks: initializing messages.
[2013/01/26 13:49:27.174190,  3] ../source3/smbd/process.c:1789(process_smb)
  Transaction 0 of length 194 (0 toread)
[2013/01/26 13:49:27.174496,  3] ../source3/smbd/process.c:1392(switch_message)
  switch message SMBnegprot (pid 6657) conn 0x0
[2013/01/26 13:49:27.178764,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [PC NETWORK PROGRAM 1.0]
[2013/01/26 13:49:27.178998,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [MICROSOFT NETWORKS 1.03]
[2013/01/26 13:49:27.179122,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [MICROSOFT NETWORKS 3.0]
[2013/01/26 13:49:27.179231,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [LANMAN1.0]
[2013/01/26 13:49:27.179321,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [LM1.2X002]
[2013/01/26 13:49:27.179428,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [DOS LANMAN2.1]
[2013/01/26 13:49:27.179513,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [LANMAN2.1]
[2013/01/26 13:49:27.179618,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [Samba]
[2013/01/26 13:49:27.179736,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [NT LANMAN 1.0]
[2013/01/26 13:49:27.179853,  3] ../source3/smbd/negprot.c:563(reply_negprot)
  Requested protocol [NT LM 0.12]
[2013/01/26 13:49:27.183272,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'gssapi_spnego' registered
[2013/01/26 13:49:27.183422,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'gssapi_krb5' registered
[2013/01/26 13:49:27.183522,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'gssapi_krb5_sasl' registered
[2013/01/26 13:49:27.202336,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'sasl-DIGEST-MD5' registered
[2013/01/26 13:49:27.202492,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'schannel' registered
[2013/01/26 13:49:27.202609,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'spnego' registered
[2013/01/26 13:49:27.202746,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'ntlmssp' registered
[2013/01/26 13:49:27.202868,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'krb5' registered
[2013/01/26 13:49:27.202963,  3] ../auth/gensec/gensec_start.c:868(gensec_register)
  GENSEC backend 'fake_gssapi_krb5' registered
[2013/01/26 13:49:27.207657,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:49:27.211484,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'sam' registered
[2013/01/26 13:49:27.211596,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'sam_ignoredomain' registered
[2013/01/26 13:49:27.211690,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'anonymous' registered
[2013/01/26 13:49:27.211788,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'winbind' registered
[2013/01/26 13:49:27.211874,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'winbind_wbclient' registered
[2013/01/26 13:49:27.211967,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'name_to_ntstatus' registered
[2013/01/26 13:49:27.212062,  3] ../source4/auth/ntlm/auth.c:673(auth_register)
  AUTH backend 'unix' registered
[2013/01/26 13:49:27.228113,  3] ../source3/smbd/negprot.c:385(reply_nt1)
  using SPNEGO
[2013/01/26 13:49:27.228255,  3] ../source3/smbd/negprot.c:669(reply_negprot)
  Selected protocol NT LANMAN 1.0
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: AS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:44905 for krbtgt/DC1.LEFOYER.RU@DC1.LEFOYER.RU
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: No preauth found, returning PREAUTH-REQUIRED -- NS1$@DC1.LEFOYER.RU
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: AS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:49617 for krbtgt/DC1.LEFOYER.RU@DC1.LEFOYER.RU
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Client sent patypes: encrypted-timestamp
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Looking for PKINIT pa-data -- NS1$@DC1.LEFOYER.RU
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Looking for ENC-TS pa-data -- NS1$@DC1.LEFOYER.RU
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: ENC-TS Pre-authentication succeeded -- NS1$@DC1.LEFOYER.RU using aes256-cts-hmac-sha1-96
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: AS-REQ authtime: 2013-01-26T13:49:27 starttime: unset endtime: 2013-01-26T23:49:27 renew till: unset
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Client supported enctypes: aes256-cts-hmac-sha1-96, aes128-cts-hmac-sha1-96, des3-cbc-sha1, des3-cbc-md5, arcfour-hmac-md5, using aes256-cts-hmac-sha1-96/aes256-cts-hmac-sha1-96
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: Requested flags: forwardable
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:53329 for cifs/ns1.dc1.lefoyer.ru@DC1.LEFOYER.RU [canonicalize]
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ authtime: 2013-01-26T13:49:27 starttime: 2013-01-26T13:49:27 endtime: 2013-01-26T23:49:27 renew till: unset
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:39188 for krbtgt/DC1.LEFOYER.RU@DC1.LEFOYER.RU [forwarded, forwardable]
[2013/01/26 13:49:27,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ authtime: 2013-01-26T13:49:27 starttime: 2013-01-26T13:49:27 endtime: 2013-01-26T23:49:27 renew till: unset
[2013/01/26 13:49:27.562111,  3] ../source3/smbd/process.c:1789(process_smb)
  Transaction 1 of length 2684 (0 toread)
[2013/01/26 13:49:27.562244,  3] ../source3/smbd/process.c:1392(switch_message)
  switch message SMBsesssetupX (pid 6657) conn 0x0
[2013/01/26 13:49:27.562391,  3] ../source3/smbd/sesssetup.c:594(reply_sesssetup_and_X)
  wct=12 flg2=0xc803
[2013/01/26 13:49:27.562568,  3] ../source3/smbd/sesssetup.c:137(reply_sesssetup_and_X_spnego)
  Doing spnego session setup
[2013/01/26 13:49:27.562682,  3] ../source3/smbd/sesssetup.c:178(reply_sesssetup_and_X_spnego)
  NativeOS=[Unix] NativeLanMan=[Samba 4.0.1] PrimaryDomain=[DC1]
[2013/01/26 13:49:27.564443,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of secrets.ldb
[2013/01/26 13:49:27.579012,  3] ../lib/ldb-samba/ldb_wrap.c:318(ldb_wrap_connect)
  ldb_wrap open of privilege.ldb
[2013/01/26 13:49:27,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:49:27,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:49:27.592143,  3] ../source3/smbd/password.c:144(register_homes_share)
  Adding homes service for user 'DC1\NS1$' using home directory: '/home/DC1/NS1$'
[2013/01/26 13:49:27.592458,  3] ../source3/param/loadparm.c:1723(lp_add_home)
  adding home's share [NS1$] for user 'DC1\NS1$' at '/home/DC1/%U'
[2013/01/26 13:49:27.593693,  3] ../source3/smbd/process.c:1789(process_smb)
  Transaction 2 of length 106 (0 toread)
[2013/01/26 13:49:27.593827,  3] ../source3/smbd/process.c:1392(switch_message)
  switch message SMBtconX (pid 6657) conn 0x0
[2013/01/26 13:49:27.594394,  3] ../source3/lib/access.c:338(allow_access)
  Allowed connection from ::1 (::1)
[2013/01/26 13:49:27.594644,  3] ../source3/smbd/service.c:646(make_connection_snum)
  Connect path is '/tmp' for service [IPC$]
[2013/01/26 13:49:27.594908,  3] ../source3/smbd/vfs.c:113(vfs_init_default)
  Initialising default vfs hooks
[2013/01/26 13:49:27.595119,  3] ../source3/smbd/vfs.c:139(vfs_init_custom)
  Initialising custom vfs hooks from [/[Default VFS]/]
[2013/01/26 13:49:27.595227,  3] ../source3/smbd/vfs.c:139(vfs_init_custom)
  Initialising custom vfs hooks from [acl_xattr]
[2013/01/26 13:49:27.597846,  2] ../lib/util/modules.c:199(do_smb_load_module)
  Module 'acl_xattr' loaded
[2013/01/26 13:49:27.597968,  3] ../source3/smbd/vfs.c:139(vfs_init_custom)
  Initialising custom vfs hooks from [dfs_samba4]
[2013/01/26 13:49:27.598111,  2] ../source3/modules/vfs_acl_xattr.c:193(connect_acl_xattr)
  connect_acl_xattr: setting 'inherit acls = true' 'dos filemode = true' and 'force unknown acl user = true' for service IPC$
[2013/01/26 13:49:27.603378,  3] ../source3/smbd/service.c:891(make_connection_snum)
  __1 (ipv6:::1:38007) connect to service IPC$ initially as user DC1\NS1$ (uid=3000023, gid=3000020) (pid 6657)
[2013/01/26 13:49:27.603868,  3] ../source3/smbd/reply.c:999(reply_tcon_and_X)
  tconX service=IPC$
[2013/01/26 13:49:27.604267,  3] ../source3/smbd/process.c:1789(process_smb)
  Transaction 3 of length 108 (0 toread)
[2013/01/26 13:49:27.604363,  3] ../source3/smbd/process.c:1392(switch_message)
  switch message SMBntcreateX (pid 6657) conn 0x296bf10
[2013/01/26 13:49:27.606424,  3] ../source3/smbd/process.c:1789(process_smb)
  Transaction 4 of length 160 (0 toread)
[2013/01/26 13:49:27.606521,  3] ../source3/smbd/process.c:1392(switch_message)
  switch message SMBtrans (pid 6657) conn 0x296bf10
[2013/01/26 13:49:27.606678,  3] ../source3/smbd/ipc.c:589(handle_trans)
  trans <\PIPE\> data=72 params=0 setup=2
[2013/01/26 13:49:27.606827,  3] ../source3/smbd/ipc.c:540(named_pipe)
  named pipe command on <> name
[2013/01/26 13:49:27.606917,  3] ../source3/smbd/ipc.c:504(api_fd_reply)
  Got API command 0x26 on pipe "netlogon" (pnum e9eb)
[2013/01/26 13:49:27.607587,  3] ../source3/smbd/process.c:1789(process_smb)
  Transaction 5 of length 104 (0 toread)
[2013/01/26 13:49:27.607696,  3] ../source3/smbd/process.c:1392(switch_message)
  switch message SMBntcreateX (pid 6657) conn 0x296bf10
[2013/01/26 13:50:00.821179,  2] ../source3/smbd/server.c:436(remove_child_pid)
  Could not find child 6658 -- ignoring
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:50:02.646462,  3] ../source3/smbd/service.c:1165(close_cnum)
  __1 (ipv6:::1:38007) closed connection to service IPC$
[2013/01/26 13:50:02.647623,  3] ../source3/smbd/server_exit.c:218(exit_server_common)
  Server exit (failed to receive smb request)
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:50:02,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ NS1$@DC1.LEFOYER.RU from ipv4:192.168.100.200:60957 for host/ns1.dc1.lefoyer.ru@DC1.LEFOYER.RU [canonicalize]
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_samba3_send_reply_done: tstream_writev_queue_recv() - 32:Broken pipe'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_samba3_send_reply_done: tstream_writev_queue_recv() - 32:Broken pipe]
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[wbsrv_call_loop: tstream_read_pdu_blob_recv() - NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:50:02,  3] ../source4/auth/kerberos/krb5_init_context.c:80(smb_krb5_debug_wrapper)
  Kerberos: TGS-REQ authtime: 2013-01-26T13:49:27 starttime: 2013-01-26T13:50:02 endtime: 2013-01-26T23:49:27 renew till: unset
[2013/01/26 13:50:02,  0] ../lib/util/fault.c:72(fault_report)
  ===============================================================
[2013/01/26 13:50:02,  0] ../lib/util/fault.c:73(fault_report)
  INTERNAL ERROR: Signal 11 in pid 6626 (4.0.1)
  Please read the Trouble-Shooting section of the Samba HOWTO
[2013/01/26 13:50:02,  0] ../lib/util/fault.c:75(fault_report)
  ===============================================================
[2013/01/26 13:50:02,  0] ../lib/util/fault.c:144(smb_panic_default)
  PANIC: internal error
[2013/01/26 13:50:02,  3] ../lib/util/util_net.c:78(interpret_string_addr_internal)
  interpret_string_addr_internal: getaddrinfo failed for name (null) (flags 4) [Name or service not known]
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_RESET'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_RESET]
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:50:02,  3] ../source4/smbd/service_stream.c:63(stream_terminate_connection)
  Terminating connection - 'NT_STATUS_CONNECTION_DISCONNECTED'
[2013/01/26 13:50:02,  3] ../source4/smbd/process_single.c:104(single_terminate)
  single_terminate: reason[NT_STATUS_CONNECTION_DISCONNECTED]
[2013/01/26 13:51:00.891249,  2] ../source3/smbd/server.c:436(remove_child_pid)
  Could not find child 6660 -- ignoring

Comment 1 tbskyd 2013-04-11 10:24:49 UTC

hi:
   I got the same situation on rhel 6.4 and samba 4.0.4. winbind is working fine.
but if I login the server after 1 day and do "ls -la" then samba panic with signal 11. I will try 4.0.5 later....

Comment 2 Volker Lendecke 2013-04-11 10:27:34 UTC

(In reply to comment #1)
> hi:
>    I got the same situation on rhel 6.4 and samba 4.0.4. winbind is working
> fine.
> but if I login the server after 1 day and do "ls -la" then samba panic with
> signal 11. I will try 4.0.5 later....

Can you get us a full backtrace?

Comment 3 Stefan Metzmacher 2013-08-07 10:05:09 UTC

There will be a lot of crash fixes in the next bugfix release
(likely to be 4.0.9)

Comment 4 Björn Jacke 2018-07-05 22:52:01 UTC

I think we can assume this was fixed in the meantime.