Many winbindd errors in console

Status
Not open for further replies.

SkyMonkey

Contributor
Joined
Mar 13, 2013
Messages
102
I recently upgraded from 9.3-STABLE-201602031011 to 9.3-STABLE-201605170422.

I am seeing a lot of samba/winbindd errors in the console. Everything appears to be working fine, but these errors are new with the exception of the sam_rids_to_names error, which has been on this system for years now.

I suspect there's something going on with samba settings that might have changed in the newer version/default version of samba in the last versions of 9.3.

I believe the long string of access denied errors are occuring when my main win7 box is booting/waking up (which maps 4 cifs shares as mapped drives). Ignore the UPS error warning as well. Mostly concerned with the tdp transactions errors, GID errors, etc.

Again, nothing seems to be malfunctioning, but there are way more console errors than previously, and I'd just like to make sure nothing needs to be addressed.

Thanks!

Code:
Aug 10 00:57:54 freenas winbindd[2936]: [2016/08/10 00:57:54.204599,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 00:57:54 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 00:57:54 freenas winbindd[2938]: [2016/08/10 00:57:54.345972,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 00:57:54 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 00:57:54 freenas winbindd[2938]: [2016/08/10 00:57:54.346023,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 00:57:54 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 02:02:54 freenas winbindd[2936]: [2016/08/10 02:02:54.260505,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 02:02:54 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 02:02:54 freenas winbindd[2938]: [2016/08/10 02:02:54.384035,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 02:02:54 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 02:02:54 freenas winbindd[2938]: [2016/08/10 02:02:54.384086,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 02:02:54 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 03:00:56 freenas winbindd[2936]: [2016/08/10 03:00:56.322076,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 03:00:56 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 03:00:56 freenas winbindd[2938]: [2016/08/10 03:00:56.394753,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 03:00:56 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 03:00:56 freenas winbindd[2938]: [2016/08/10 03:00:56.394786,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 03:00:56 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 04:12:55 freenas winbindd[2936]: [2016/08/10 04:12:55.787109,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 04:12:55 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 04:12:55 freenas winbindd[2938]: [2016/08/10 04:12:55.795492,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 04:12:55 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 04:12:55 freenas winbindd[2938]: [2016/08/10 04:12:55.795535,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 04:12:55 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 05:17:57 freenas winbindd[2936]: [2016/08/10 05:17:57.368081,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 05:17:57 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 05:17:57 freenas winbindd[2938]: [2016/08/10 05:17:57.455531,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 05:17:57 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 05:17:57 freenas winbindd[2938]: [2016/08/10 05:17:57.455583,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 05:17:57 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 06:22:58 freenas winbindd[2936]: [2016/08/10 06:22:58.965716,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 06:22:58 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 06:22:59 freenas winbindd[2938]: [2016/08/10 06:22:59.058998,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 06:22:59 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 06:22:59 freenas winbindd[2938]: [2016/08/10 06:22:59.059049,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 06:22:59 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 21:16:24 freenas winbindd[2936]: [2016/08/10 21:16:24.614041,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 21:16:24 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 21:16:24 freenas winbindd[2938]: [2016/08/10 21:16:24.627493,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 21:16:24 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 21:16:24 freenas winbindd[2938]: [2016/08/10 21:16:24.627541,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 21:16:24 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 21:16:30 freenas winbindd[2936]: [2016/08/10 21:16:30.967043,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 21:16:30 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 21:16:50 freenas smbd[2888]: [2016/08/10 21:16:50.331446,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:16:50 freenas smbd[2888]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:16:50 freenas smbd[2888]: [2016/08/10 21:16:50.332410,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:16:50 freenas smbd[2888]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:17:30 freenas winbindd[2936]: [2016/08/10 21:17:30.522583,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 21:17:30 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.105368,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.105858,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.106808,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.107215,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.108096,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.108507,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.109387,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.109807,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.110666,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.111063,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.111926,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.112326,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.113190,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.113577,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.114433,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.114853,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.115731,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.116203,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.117123,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.117506,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.118367,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.118827,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.119710,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.120278,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.121267,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.121696,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.122515,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.122925,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.123823,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.124205,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.125069,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.125469,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.126365,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.126794,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.127701,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.128118,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.128938,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.129333,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.130232,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:18:02 freenas smbd[2955]: [2016/08/10 21:18:02.130664,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:18:02 freenas smbd[2955]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:19:05 freenas smbd[3023]: [2016/08/10 21:19:05.175872,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:19:05 freenas smbd[3023]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:19:06 freenas smbd[3024]: [2016/08/10 21:19:06.084247,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:19:06 freenas smbd[3024]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:19:06 freenas smbd[3025]: [2016/08/10 21:19:06.994241,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:19:06 freenas smbd[3025]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:19:07 freenas smbd[3026]: [2016/08/10 21:19:07.903496,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:19:07 freenas smbd[3026]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:19:08 freenas smbd[3027]: [2016/08/10 21:19:08.812574,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:19:08 freenas smbd[3027]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 21:19:09 freenas smbd[3043]: [2016/08/10 21:19:09.722854,  1] ../source3/smbd/service.c:552(make_connection_snum)
Aug 10 21:19:09 freenas smbd[3043]:   create_connection_session_info failed: NT_STATUS_ACCESS_DENIED
Aug 10 22:47:47 freenas upsmon[2785]: UPS ups battery is low
Aug 10 23:06:34 freenas smbd[2928]: [2016/08/10 23:06:34.695668,  0] ../source3/smbd/server.c:586(smbd_accept_connection)
Aug 10 23:06:34 freenas smbd[2928]:   accept: Software caused connection abort
Aug 10 23:06:34 freenas winbindd[2936]: [2016/08/10 23:06:34.702395,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 23:06:34 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
Aug 10 23:06:34 freenas winbindd[2938]: [2016/08/10 23:06:34.823861,  1] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log)
Aug 10 23:06:34 freenas winbindd[2938]:   tdb(/var/db/samba4/winbindd_idmap.tdb): tdb_transaction_commit: transaction error pending
Aug 10 23:06:34 freenas winbindd[2938]: [2016/08/10 23:06:34.823893,  1] ../source3/winbindd/idmap_tdb_common.c:140(idmap_tdb_common_allocate_id)
Aug 10 23:06:34 freenas winbindd[2938]:   Error allocating a new GID
Aug 10 23:07:33 freenas winbindd[2936]: [2016/08/10 23:07:33.707118,  0] ../source3/winbindd/winbindd_samr.c:699(sam_rids_to_names)
Aug 10 23:07:33 freenas winbindd[2936]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-626537768-3214805501-4061107804
 

SkyMonkey

Contributor
Joined
Mar 13, 2013
Messages
102
Nope, no change.....I've not done anything, as it does seem to be working OK at the moment desipte all the errors, but it doesn't seem like normal behavior...
 
Status
Not open for further replies.
Top