search for: cm_prepare_connection

...ib/util_tdb.c:493(tdb_chainlock_with_timeout_internal) May 21 08:15:45  winbindd[32708]: tdb_chainlock_with_timeout_internal: alarm (40) timed out for key DCXXX in tdb /var/run/samba/mutex.tdb May 21 08:15:45  winbindd[32708]: [2018/05/21 08:15:45.546208,  0] ../source3/winbindd/winbindd_cm.c:918(cm_prepare_connection) May 21 08:15:45  winbindd[32708]:   cm_prepare_connection: mutex grab failed for DCXXXX May 21 08:16:47  winbindd[29020]: [2018/05/21 08:16:47.246684,  0] ../source3/lib/util_tdb.c:493(tdb_chainlock_with_timeout_internal) May 21 08:16:47  winbindd[29020]: tdb_chainlock_with_timeout_internal: a...

...nder large traffic, most of the time they all work well. and the transaction per second can reach more than 900. but sometimes, winbindd will return NT_STATUS_NO_LOGON_SERVERS. below are some relevant logs. ---------------------- [2013/11/15 22:08:24.477711, 0, pid=4905] winbindd/winbindd_cm.c:835(cm_prepare_connection) cm_prepare_connection: getpeername failed with: Transport endpoint is not connected [2013/11/15 22:08:24.478174, 0, pid=4904] winbindd/winbindd_cm.c:835(cm_prepare_connection) cm_prepare_connection: getpeername failed with: Transport endpoint is not connected [2013/11/15 22:09:04.482330, 1,...

...out_internal) >> May 21 08:15:45  winbindd[32708]: >> tdb_chainlock_with_timeout_internal: alarm (40) timed out for key >> DCXXX in tdb /var/run/samba/mutex.tdb May 21 08:15:45 >> winbindd[32708]: [2018/05/21 08:15:45.546208, >> 0] ../source3/winbindd/winbindd_cm.c:918(cm_prepare_connection) May >> 21 08:15:45  winbindd[32708]:   cm_prepare_connection: mutex grab >> failed for DCXXXX >> >> May 21 08:16:47  winbindd[29020]: [2018/05/21 08:16:47.246684,  0] >> ../source3/lib/util_tdb.c:493(tdb_chainlock_with_timeout_internal) >> May 21 08:16:47  winbi...

...sesssetup.c:134 my understanding of the code is that getpwnam fails, which is supposed to query winbindd. In the log file log.wb-BITINTRA I can see the following error: [2019/03/12 09:20:24.540456, 10, pid=15439, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1014(cm_prepare_connection)   cm_prepare_connection: connecting to DC WG101SC0002.BITIntra.de for domain BITINTRA [2019/03/12 09:21:04.540067,  5, pid=15439, effective(0, 0), real(0, 0)] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)   tdb(/opt/samba/var/lock/mutex.tdb): tdb_brlock failed (fd=22) at offset 592 rw_type=2 fl...

...t;wbinfo -t" takes sometimes more than 30 secondes to display the result. For some reason, the server loses it's connection to the domain controller. I found the following line in /var/log/samba/log.wb-MY contains : [2019/02/13 15:02:19.850547,  0] ../source3/winbindd/winbindd_cm.c:918(cm_prepare_connection)   cm_prepare_connection: mutex grab failed for my.domain [2019/02/13 15:03:09.928405,  0] ../source3/lib/util_tdb.c:493(tdb_chainlock_with_timeout_internal)   tdb_chainlock_with_timeout_internal: alarm (40) timed out for key my.domain in tdb /var/run/samba/mutex.tdb [2019/02/13 15:03:09.928477...

.../../../auth/gensec/spnego.c:1600]: state[2] error[0 (0x0)] state[struct gensec_spnego_update_state (0x55c027835590)] timer[(nil)] finish[../../auth/gensec/spnego.c:2070] [2019/05/30 09:34:10.371447, 1, pid=1606, effective(0, 0), real(0, 0), class=winbind] ../../source3/winbindd/winbindd_cm.c:1163(cm_prepare_connection) authenticated session setup to SCSFOO402.foo.corp.example.com using FOO\MY-HOST$ failed with NT_STATUS_LOGON_FAILURE [2019/05/30 09:34:10.371594, 3, pid=1606, effective(0, 0), real(0, 0), class=winbind] ../../source3/winbindd/winbindd_cm.c:673(cm_get_ipc_userpass) cm_get_ipc_userpass: No auth...

..._chainlock_with_timeout_internal) > May 21 08:15:45  winbindd[32708]: > tdb_chainlock_with_timeout_internal: alarm (40) timed out for key > DCXXX in tdb /var/run/samba/mutex.tdb May 21 08:15:45 > winbindd[32708]: [2018/05/21 08:15:45.546208, > 0] ../source3/winbindd/winbindd_cm.c:918(cm_prepare_connection) May > 21 08:15:45  winbindd[32708]:   cm_prepare_connection: mutex grab > failed for DCXXXX > > May 21 08:16:47  winbindd[29020]: [2018/05/21 08:16:47.246684,  0] > ../source3/lib/util_tdb.c:493(tdb_chainlock_with_timeout_internal) > May 21 08:16:47  winbindd[29020]: > tdb_c...

...t;> May 21 08:15:45  winbindd[32708]: >>> tdb_chainlock_with_timeout_internal: alarm (40) timed out for key >>> DCXXX in tdb /var/run/samba/mutex.tdb May 21 08:15:45 >>> winbindd[32708]: [2018/05/21 08:15:45.546208, >>> 0] ../source3/winbindd/winbindd_cm.c:918(cm_prepare_connection) May >>> 21 08:15:45  winbindd[32708]:   cm_prepare_connection: mutex grab >>> failed for DCXXXX >>> >>> May 21 08:16:47  winbindd[29020]: [2018/05/21 08:16:47.246684,  0] >>> ../source3/lib/util_tdb.c:493(tdb_chainlock_with_timeout_internal) >>&gt...

...tdb_chainlock_with_timeout_internal: alarm (40) timed out for key DOMAINSERVER in tdb /home/samba-server/samba/3.4.5/var/locks/mutex.tdb [2010/02/03 04:14:27, 1] lib/server_mutex.c:71(grab_named_mutex) Could not get the lock for DOMAINSERVER [2010/02/03 04:14:27, 0] winbindd/winbindd_cm.c:782(cm_prepare_connection) cm_prepare_connection: mutex grab failed for DOMAINSERVER [2010/02/03 04:14:55, 0] libsmb/namequery.c:75(saf_store) saf_store: refusing to store 0 length domain or servername! [2010/02/03 04:14:55, 1] rpc_client/cli_pipe.c:949(cli_pipe_validate_current_pdu) cli_pipe_validate_current_pdu: R...

...nding of the code is that getpwnam fails, which is > supposed to query winbindd. > In the log file log.wb-BITINTRA I can see the following error: > > [2019/03/12 09:20:24.540456, 10, pid=15439, effective(0, 0), real(0, > 0), > class=winbind] ../source3/winbindd/winbindd_cm.c:1014(cm_prepare_connection) > cm_prepare_connection: connecting to DC WG101SC0002.BITIntra.de for > domain BITINTRA [2019/03/12 09:21:04.540067,  5, pid=15439, > effective(0, 0), real(0, > 0)] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) > tdb(/opt/samba/var/lock/mutex.tdb): tdb_brlock failed (fd=22) at > of...

...nst an Active Directory (Windows 2012) The AD is formed by 3 windows servers. The linux box has a connection established against one of them thru port 445. Then this server downs and a bunch of messages like the following are shown in /var/log/messages May 19 16:40:59 pv4il0168 winbindd[18357]: cm_prepare_connection: getpeername failed with: Transport endpoint is not connected An execution of the following command [PRO] [root at pv4il0168 samba]# wbinfo -t checking the trust secret for domain XXXXXX via RPC calls failed error code was NT_STATUS_DOMAIN_CONTROLLER_NOT_FOUND (0xc0000233) failed to call wbcCheck...

...h/winbindd_cm.c:cm_open_connection(1336) cm_open_connection: saf_servername is PDC.ABC.ORG for domain ABC [2008/09/03 11:10:56, 10] nsswitch/winbindd_cm.c:cm_open_connection(1366) cm_open_connection: dcname is 'PDC.ABC.ORG' for domain ABC [2008/09/03 11:10:56, 10] nsswitch/winbindd_cm.c:cm_prepare_connection(654) cm_prepare_connection: connecting to DC PDC.ABC.ORG for domain ABC [2008/09/03 11:10:56, 5] nsswitch/winbindd_cm.c:cm_prepare_connection(733) connecting to PDC.ABC.ORG from LINUX1 with kerberos principal [LINUX1$@ABC.ORG] [2008/09/03 11:10:56, 2] libsmb/cliconnect.c:cli_session_setup_kerbe...

...Y cli_rpc_open failed on pipe \NETLOGON to machine SRV01. Error was Write error: Broken pipe [2005/10/27 12:15:01, 0] rpc_client/cli_pipe.c:cli_rpc_close(1767) cli_rpc_open failed on pipe \NETLOGON to machine SRV01. Error was Write error: Broken pipe [2005/10/27 12:15:01, 0] nsswitch/winbindd_cm.c:cm_prepare_connection(234) cm_prepare_connection: Socket is not connected $ net ads join -U goliveira goliveira's password: [2005/10/27 17:48:52, 0] utils/net_ads.c:ads_startup(191) ads_connect: Unknown error -1765328332

..., effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1917(cm_open_connection)   cm_open_connection: dcname is 'MIMIR' for domain YGGDRASIL [2016/07/22 11:23:28.793277, 10, pid=1153, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1023(cm_prepare_connection)   cm_prepare_connection: connecting to DC MIMIR for domain YGGDRASIL ldb: unable to stat module /usr/lib64/samba/ldb : No such file or directory [2016/07/22 11:23:28.794755,  5, pid=1153, effective(0, 0), real(0, 0), class=winbind] ../source3/winbindd/winbindd_cm.c:1142(cm_prepare_connection)   co...

...to an Active Directory server The getent did not show any user and winbindd claim : [2011/09/07 11:33:29.417355, 1] libsmb/cliconnect.c:1769(cli_negprot_done) cli_negprot: SMB signing is mandatory and the server doesn't support it. [2011/09/07 11:33:29.417444, 1] winbindd/winbindd_cm.c:856(cm_prepare_connection) cli_negprot failed: NT_STATUS_ACCESS_DENIED [2011/09/07 11:33:29.696520, 1] libsmb/cliconnect.c:1769(cli_negprot_done) cli_negprot: SMB signing is mandatory and the server doesn't support it. [2011/09/07 11:33:29.696599, 1] winbindd/winbindd_cm.c:856(cm_prepare_connection) cli_negprot...

...09/05/07 09:52:31, 0] smbd/service.c:make_connection_snum(897) make_connection: connection to IPC$ denied due to security descriptor. Error messages seen: log.winbindd: ( my gut feeling is that if I sort this one, it'll all start working! ) [2009/05/07 09:36:22, 1] winbindd/winbindd_cm.c:cm_prepare_connection(967) failed tcon_X with NT_STATUS_ACCESS_DENIED log.nmbd: [2009/05/07 09:34:31, 2] nmbd/nmbd_browsesync.c:sync_with_dmb(151) sync_with_dmb: Initiating sync with domain master browser SERVER<20> at IP 192.168.xx.yyy for workgroup DOMAIN [2009/05/07 09:49:33, 2] nmbd/nmbd_browsesync.c:...

...at getpwnam fails, which is >> supposed to query winbindd. >> In the log file log.wb-BITINTRA I can see the following error: >> >> [2019/03/12 09:20:24.540456, 10, pid=15439, effective(0, 0), real(0, >> 0), >> class=winbind] ../source3/winbindd/winbindd_cm.c:1014(cm_prepare_connection) >> cm_prepare_connection: connecting to DC WG101SC0002.BITIntra.de for >> domain BITINTRA [2019/03/12 09:21:04.540067,  5, pid=15439, >> effective(0, 0), real(0, >> 0)] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) >> tdb(/opt/samba/var/lock/mutex.tdb): tdb_brlock faile...

...imed out for key R-DC-CLIENT.COM in tdb /var/vol/3/.ctera/samba/lock/mutex.tdb [2018/06/14 14:54:23.899204, 1, pid=3228] ../source3/lib/server_mutex.c:97(grab_named_mutex) Could not get the lock for R-DC-CLIENT.COM [2018/06/14 14:54:23.899276, 0, pid=3228] ../source3/winbindd/winbindd_cm.c:1022(cm_prepare_connection) cm_prepare_connection: mutex grab failed for R-DC-CLIENT.COM [2018/06/14 14:54:23.899298, 1, pid=3228] ../source3/winbindd/winbindd_cm.c:1253(cm_prepare_connection) Failed to prepare SMB connection to R-DC-CLIENT.COM: NT_STATUS_POSSIBLE_DEADLOCK The issue can be reproduced manually by using...

...ction_ok) connection_ok: Connection to HQ-GC.norma.com for domain SOFTLAB is not connected [2011/06/09 22:13:58, 0] libads/kerberos.c:693(print_kdc_line) print_kdc_line: can't resolve name for kdc with non-default port [fd00::32d]. Error hostname nor servname provided, or not known .cm_prepare_connection: Unknown error: 0 [2011/06/09 22:13:58, 0] libads/kerberos.c:693(print_kdc_line) print_kdc_line: can't resolve name for kdc with non-default port [fd00::32d]. Error hostname nor servname provided, or not known .cm_prepare_connection: Unknown error: 0 [2011/06/09 22:13:58, 0] libads/ker...

...ock_with_timeout_internal: alarm (40) timed out for key PFDC1 in tdb /usr/local/samba/var/lock/mutex.tdb [2016/12/08 15:44:17.333614, 1] ../source3/lib/server_mutex.c:97(grab_named_mutex) Could not get the lock for PFDC1 [2016/12/08 15:44:17.333664, 0] ../source3/winbindd/winbindd_cm.c:1039(cm_prepare_connection) cm_prepare_connection: mutex grab failed for PFDC1 [2016/12/08 15:45:50.041081, 1] ../source3/winbindd/winbindd.c:395(winbindd_sig_hup_handler) Reloading services after SIGHUP [2016/12/08 15:45:50.041662, 0] ../source3/winbindd/winbindd.c:279(winbindd_sig_term_handler) Got sig[15] ter...