Strange AD Issues on 9.3

[TFS_Rein]

I have a physical FreeNAS 9.3 box with 24GB of ecc RAM, and dual L5630 CPU's that is having some weird issues joining a windows 2012 r2 level domain. When I first joined the domain, everything worked perfectly. I mapped some permissions out and was happy. I rebooted the server and now my issue is that I see no active directory users in the change permissions list. Domain Groups show up as normal. Browsing the SMB share doesn't work now due to the box being unable to resolve the users properly.

My box successfully joins the domain, "wbinfo -u" and "wbinfo -g" and "wbinfo -t" all return users, groups, and success respectfully. I went through the common troubleshooting tips listed in the manual

http://doc.freenas.org/9.3/freenas_directoryservice.html

with no fix including clearing the active directory cache and running the various commands. They all return an expected '0' code.

This is a nearly fresh reinstall of freenas where I basically configured networking on the box (dns, default gateway, etc) and then joined the domain. I can both forward and reverse lookup my domain controllers, and ping them fine. I went through and double checked that all relevant DNS SRV records were present on both my DNS servers.

Let me know which logs I should pull from and I will gladly upload them. Otherwise here is my console output:

Show : Console output

May 2 01:05:06 freenas GEOM_ELI: Device da2p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da1p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da0p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da4p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da9p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da8p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da7p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da5p1.eli created.
May 2 01:05:06 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:06 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:06 freenas GEOM_ELI: Device da6p1.eli created.
May 2 01:05:07 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:07 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:07 freenas GEOM_ELI: Device da3p1.eli created.
May 2 01:05:07 freenas GEOM_ELI: Encryption: AES-XTS 256
May 2 01:05:07 freenas GEOM_ELI: Crypto: hardware
May 2 01:05:07 freenas notifier: geli: Cannot access da2p1 (error=1).
May 2 01:05:07 freenas notifier: Stopping collectd.
May 2 01:05:10 freenas notifier: Waiting for PIDS: 6310.
May 2 01:05:10 freenas notifier: Starting collectd.
May 2 01:06:12 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:12 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:13 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:13 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:28 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:28 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:28 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:28 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:39 freenas cachetool.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:06:40 freenas cachetool.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:12 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:12 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:12 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:12 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:38 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:39 freenas ActiveDirectory: /usr/local/bin/python /usr/local/www/freenasUI/middleware/notifier.py stop cifs
May 2 01:13:41 freenas notifier: Stopping winbindd.
May 2 01:13:41 freenas winbindd[7722]: [2015/05/02 01:13:41.412498, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
May 2 01:13:41 freenas winbindd[7722]: Got sig[15] terminate (is_parent=1)
May 2 01:13:41 freenas notifier: Waiting for PIDS: 7722.
May 2 01:13:41 freenas notifier: Stopping smbd.
May 2 01:13:41 freenas notifier: Waiting for PIDS: 7718.
May 2 01:13:41 freenas notifier: Stopping nmbd.
May 2 01:13:41 freenas nmbd[7714]: [2015/05/02 01:13:41.425438, 0] ../source3/nmbd/nmbd.c:57(terminate)
May 2 01:13:41 freenas nmbd[7714]: Got SIGTERM: going down...
May 2 01:13:41 freenas notifier: Waiting for PIDS: 7714.
May 2 01:13:42 freenas ActiveDirectory: /usr/sbin/service ix-kerberos quietstop
May 2 01:13:42 freenas ActiveDirectory: /usr/sbin/service ix-nsswitch quietstop
May 2 01:13:44 freenas ActiveDirectory: /usr/sbin/service ix-pam quietstop
May 2 01:13:44 freenas ActiveDirectory: /usr/sbin/service ix-activedirectory forcestop
May 2 01:13:49 freenas ActiveDirectory: /usr/sbin/service ix-cache quietstop &
May 2 01:13:51 freenas ActiveDirectory: /usr/sbin/service samba_server forcestop
May 2 01:13:52 freenas ActiveDirectory: /usr/sbin/service ix-pre-samba start
May 2 01:13:54 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
May 2 01:13:54 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:13:55 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:13:55 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:13:55 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:13:55 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:13:55 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:13:55 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpqvd9Pd -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
May 2 01:13:56 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/bin/getent passwd 'ESXi'
May 2 01:13:56 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -L
May 2 01:13:56 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/net sam rights grant ESXi SeTakeOwnershipPrivilege SeBackupPrivilege SeRestorePrivilege
May 2 01:13:56 freenas ActiveDirectory: /usr/sbin/service ix-kinit forcestop
May 2 01:13:56 freenas ActiveDirectory: /usr/sbin/service ix-hostname quietstart
May 2 01:13:59 freenas adtool: [common.pipesubr:59] Popen()ing: klist
May 2 01:13:59 freenas adtool: [common.pipesubr:59] Popen()ing: /usr/bin/kinit --renewable --password-file=/tmp/tmpbGN7Pq freenasadmin@CAMDOGHOME.LOCAL
May 2 01:14:03 freenas ActiveDirectory: /usr/sbin/service ix-hostname quietstart
May 2 01:14:04 freenas ActiveDirectory: /usr/sbin/service ix-kerberos quietstart
May 2 01:14:06 freenas ActiveDirectory: /usr/sbin/service ix-nsswitch quietstart
May 2 01:14:08 freenas ActiveDirectory: /usr/sbin/service ix-kinit quietstart
May 2 01:14:10 freenas ActiveDirectory: /usr/sbin/service ix-kinit status
May 2 01:14:12 freenas ActiveDirectory: /usr/local/bin/python /usr/local/www/freenasUI/middleware/notifier.py start cifs
May 2 01:14:16 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
May 2 01:14:16 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:14:16 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:17 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:17 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:17 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:17 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:17 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:17 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmp7MGUt0 -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
May 2 01:14:18 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/bin/getent passwd 'ESXi'
May 2 01:14:18 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -L
May 2 01:14:18 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/net sam rights grant ESXi SeTakeOwnershipPrivilege SeBackupPrivilege SeRestorePrivilege
May 2 01:14:18 freenas notifier: Importing account for ESXi...ok
May 2 01:14:18 freenas notifier:
May 2 01:14:18 freenas notifier: Granted SeTakeOwnershipPrivilege to FREENAS\ESXi
May 2 01:14:18 freenas notifier: Granted SeBackupPrivilege to FREENAS\ESXi
May 2 01:14:18 freenas notifier: Granted SeRestorePrivilege to FREENAS\ESXi
May 2 01:14:18 freenas notifier:
May 2 01:14:18 freenas notifier: Performing sanity check on Samba configuration: OK
May 2 01:14:18 freenas notifier: Starting nmbd.
May 2 01:14:18 freenas notifier: Starting smbd.
May 2 01:14:18 freenas nmbd[13311]: [2015/05/02 01:14:18.858008, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:14:18 freenas nmbd[13311]: STATUS=daemon 'nmbd' finished starting up and ready to serve connectionssend_host_announcement: type 809b03 for host FREENAS on subnet 10.0.13.8 for workgroup CAMDOGHOME
May 2 01:14:18 freenas notifier: Starting winbindd.
May 2 01:14:18 freenas smbd[13315]: [2015/05/02 01:14:18.923118, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:14:18 freenas smbd[13315]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionswaiting for connections
May 2 01:14:18 freenas winbindd[13319]: [2015/05/02 01:14:18.995832, 0] ../source3/winbindd/winbindd_util.c:634(init_domain_list)
May 2 01:14:18 freenas winbindd[13319]: Could not fetch our SID - did we join?
May 2 01:14:18 freenas winbindd[13319]: [2015/05/02 01:14:18.996180, 0] ../source3/winbindd/winbindd.c:1240(winbindd_register_handlers)
May 2 01:14:18 freenas winbindd[13319]: unable to initialize domain list
May 2 01:14:19 freenas ActiveDirectory: /usr/sbin/service ix-activedirectory quietstart
May 2 01:14:23 freenas generate_ldap_conf.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:14:25 freenas ActiveDirectory: /usr/sbin/service ix-activedirectory status
May 2 01:14:28 freenas ActiveDirectory: /usr/local/bin/python /usr/local/www/freenasUI/middleware/notifier.py stop cifs
May 2 01:14:30 freenas notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
May 2 01:14:30 freenas notifier: Stopping smbd.
May 2 01:14:30 freenas notifier: Waiting for PIDS: 13315.
May 2 01:14:30 freenas notifier: Stopping nmbd.
May 2 01:14:30 freenas nmbd[13311]: [2015/05/02 01:14:30.378628, 0] ../source3/nmbd/nmbd.c:57(terminate)
May 2 01:14:30 freenas nmbd[13311]: Got SIGTERM: going down...
May 2 01:14:30 freenas notifier: Waiting for PIDS: 13311.
May 2 01:14:31 freenas ActiveDirectory: /usr/local/bin/python /usr/local/www/freenasUI/middleware/notifier.py start cifs
May 2 01:14:35 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
May 2 01:14:35 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:14:35 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:35 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:35 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:36 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:36 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:36 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:14:36 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpPm7zKY -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
May 2 01:14:36 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/bin/getent passwd 'ESXi'
May 2 01:14:36 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -L
May 2 01:14:37 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/net sam rights grant ESXi SeTakeOwnershipPrivilege SeBackupPrivilege SeRestorePrivilege
May 2 01:14:37 freenas notifier: Importing account for ESXi...ok
May 2 01:14:37 freenas notifier:
May 2 01:14:37 freenas notifier: Granted SeTakeOwnershipPrivilege to FREENAS\ESXi
May 2 01:14:37 freenas notifier: Granted SeBackupPrivilege to FREENAS\ESXi
May 2 01:14:37 freenas notifier: Granted SeRestorePrivilege to FREENAS\ESXi
May 2 01:14:37 freenas notifier:
May 2 01:14:37 freenas notifier: Performing sanity check on Samba configuration: OK
May 2 01:14:37 freenas notifier: Starting nmbd.
May 2 01:14:37 freenas notifier: Starting smbd.
May 2 01:14:37 freenas nmbd[14501]: [2015/05/02 01:14:37.541310, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:14:37 freenas nmbd[14501]: STATUS=daemon 'nmbd' finished starting up and ready to serve connectionssend_host_announcement: type 809b03 for host FREENAS on subnet 10.0.13.8 for workgroup CAMDOGHOME
May 2 01:14:37 freenas notifier: Starting winbindd.
May 2 01:14:37 freenas smbd[14505]: [2015/05/02 01:14:37.605909, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:14:37 freenas smbd[14505]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionswaiting for connections
May 2 01:14:37 freenas winbindd[14511]: [2015/05/02 01:14:37.701323, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:14:38 freenas ActiveDirectory: /usr/sbin/service ix-pam quietstart
May 2 01:14:40 freenas ActiveDirectory: /usr/sbin/service ix-cache quietstart &
May 2 01:14:42 freenas cachetool.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:14:42 freenas winbindd[15047]: STATUS=daemon 'winbindd' finished starting up and ready to serve connectionsget_dc_list: preferred server list: "winsrv1.camdoghome.local, *"
May 2 01:14:42 freenas winbindd[14511]: STATUS=daemon 'winbindd' finished starting up and ready to serve connectionsconnection_ok: Connection to (null) for domain CAMDOGHOME is not connected
May 2 01:14:43 freenas cachetool.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:14:48 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:15:42 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:15:43 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:15:43 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:15:43 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:18:40 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
May 2 01:18:40 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:18:40 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:18:40 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:18:41 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:18:41 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:18:41 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:18:41 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:18:41 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpQvOEMF -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
May 2 01:18:42 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/bin/getent passwd 'ESXi'
May 2 01:18:42 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -L
May 2 01:18:42 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/net sam rights grant ESXi SeTakeOwnershipPrivilege SeBackupPrivilege SeRestorePrivilege
May 2 01:18:42 freenas notifier: Importing account for ESXi...ok
May 2 01:18:42 freenas notifier:
May 2 01:18:42 freenas notifier: Granted SeTakeOwnershipPrivilege to FREENAS\ESXi
May 2 01:18:42 freenas notifier: Granted SeBackupPrivilege to FREENAS\ESXi
May 2 01:18:42 freenas notifier: Granted SeRestorePrivilege to FREENAS\ESXi
May 2 01:18:42 freenas notifier:
May 2 01:18:42 freenas notifier: Performing sanity check on Samba configuration: OK
May 2 01:18:49 freenas generate_ldap_conf.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:09 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
May 2 01:19:09 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:09 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:19:09 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:19:09 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:19:09 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:19:10 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:19:10 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: zfs list -H -o mountpoint
May 2 01:19:10 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpg_iI93 -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
May 2 01:19:10 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/bin/getent passwd 'ESXi'
May 2 01:19:10 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/pdbedit -L
May 2 01:19:10 freenas generate_smb4_conf.py: [common.pipesubr:59] Popen()ing: /usr/local/bin/net sam rights grant ESXi SeTakeOwnershipPrivilege SeBackupPrivilege SeRestorePrivilege
May 2 01:19:11 freenas notifier: Importing account for ESXi...ok
May 2 01:19:11 freenas notifier:
May 2 01:19:11 freenas notifier: Granted SeTakeOwnershipPrivilege to FREENAS\ESXi
May 2 01:19:11 freenas notifier: Granted SeBackupPrivilege to FREENAS\ESXi
May 2 01:19:11 freenas notifier: Granted SeRestorePrivilege to FREENAS\ESXi
May 2 01:19:11 freenas notifier:
May 2 01:19:11 freenas notifier: Stopping winbindd.
May 2 01:19:11 freenas winbindd[14511]: [2015/05/02 01:19:11.258793, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
May 2 01:19:11 freenas winbindd[14511]: Got sig[15] terminate (is_parent=1)
May 2 01:19:11 freenas winbindd[17424]: [2015/05/02 01:19:11.261593, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
May 2 01:19:11 freenas winbindd[15979]: [2015/05/02 01:19:11.261631, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
May 2 01:19:11 freenas winbindd[15980]: [2015/05/02 01:19:11.261631, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
May 2 01:19:11 freenas winbindd[17424]: Got sig[15] terminate (is_parent=0)
May 2 01:19:11 freenas winbindd[15048]: [2015/05/02 01:19:11.261659, 0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
May 2 01:19:11 freenas winbindd[15979]: Got sig[15] terminate (is_parent=0)
May 2 01:19:11 freenas winbindd[15980]: Got sig[15] terminate (is_parent=0)
May 2 01:19:11 freenas winbindd[15048]: Got sig[15] terminate (is_parent=0)
May 2 01:19:11 freenas notifier: Waiting for PIDS: 14511.
May 2 01:19:11 freenas notifier: Stopping smbd.
May 2 01:19:11 freenas notifier: Waiting for PIDS: 14505.
May 2 01:19:11 freenas notifier: Stopping nmbd.
May 2 01:19:11 freenas nmbd[14501]: [2015/05/02 01:19:11.271937, 0] ../source3/nmbd/nmbd.c:57(terminate)
May 2 01:19:11 freenas nmbd[14501]: Got SIGTERM: going down...
May 2 01:19:11 freenas notifier: Waiting for PIDS: 14501.
May 2 01:19:11 freenas notifier: Performing sanity check on Samba configuration: OK
May 2 01:19:11 freenas notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
May 2 01:19:11 freenas notifier: smbd not running? (check /var/run/samba/smbd.pid).
May 2 01:19:11 freenas notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
May 2 01:19:11 freenas notifier: Performing sanity check on Samba configuration: OK
May 2 01:19:11 freenas notifier: Starting nmbd.
May 2 01:19:11 freenas notifier: Starting smbd.
May 2 01:19:11 freenas nmbd[18636]: [2015/05/02 01:19:11.544963, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:19:11 freenas nmbd[18636]: STATUS=daemon 'nmbd' finished starting up and ready to serve connectionssend_host_announcement: type 809b03 for host FREENAS on subnet 10.0.13.8 for workgroup CAMDOGHOME
May 2 01:19:11 freenas notifier: Starting winbindd.
May 2 01:19:11 freenas smbd[18640]: [2015/05/02 01:19:11.609529, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:19:11 freenas smbd[18640]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionswaiting for connections
May 2 01:19:11 freenas winbindd[18644]: [2015/05/02 01:19:11.698040, 0] ../lib/util/become_daemon.c:136(daemon_ready)
May 2 01:19:11 freenas notifier: Stopping mdnsd.
May 2 01:19:11 freenas smbd[18640]: dnssd_clientstub DNSServiceProcessResult called with DNSServiceRef with no ProcessReply function
May 2 01:19:11 freenas notifier: Waiting for PIDS: 7767.
May 2 01:19:11 freenas notifier: Starting mdnsd.
May 2 01:19:16 freenas winbindd[19118]: STATUS=daemon 'winbindd' finished starting up and ready to serve connectionsget_dc_list: preferred server list: "winsrv1.camdoghome.local, *"
May 2 01:19:16 freenas winbindd[18644]: STATUS=daemon 'winbindd' finished starting up and ready to serve connectionsconnection_ok: Connection to (null) for domain CAMDOGHOME is not connected
May 2 01:19:22 freenas cachetool.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:22 freenas cachetool.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:30 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:30 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:30 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:19:31 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:23:21 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:23:21 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:23:21 freenas manage.py: [common.pipesubr:59] Popen()ing: klist
May 2 01:23:21 freenas manage.py: [common.pipesubr:59] Popen()ing: klist

 

[sapwood]

I'm having literally the exact same issues... though I can't seem to get Users or Groups from the dropdown in permissions (or by typing the beginning of a name). wbinfo returns the same things, so I'm at a loss as well.

Have you done anything more with it recently?

 

[TFS_Rein]

My issue was that the first time around everything worked perfectly. I updated and then started having those issues. I ended up just starting from scratch and updating to the current build before starting AD integration. It's been working fine for me ever since

 

[sapwood]

Thanks for the response, I might just scrap it and put the contents somewhere else in that case because I can't find anything specific and consistently wrong.

 

[depasseg]

I've experienced the AD service becoming disabled on a reboot. verify the checkbox is enabled. In fact, when I went to grab this screenshot, I noticed this box was disabled. grrrr.

 

[sapwood]

I've been jumping around between versions just to see if anything would get fixed by luck. I can't even get my hostnames to resolve correctly because I have the network on DHCP, which has different nameservers that can't resolve my DC. I would expect it shouldn't be an issue if the global configuration settings are going to my DNS instead, but for some reason it won't update it on it's own. This was resolving fine in earlier versions. I haven't actually configured the interface though, so I'm not sure if that's an issue. Basically the more updated I go, the more the GUI breaks from what would be expected.

 

[Cellobita]

I've experienced the AD service becoming disabled on a reboot. verify the checkbox is enabled. In fact, when I went to grab this screenshot, I noticed this box was disabled. grrrr.

View attachment 7629

I have been experiencing the same problem - whenever the server is rebooted, the "Enable" checkbox "unchecks" itself, and no clients can access the server until I log in to the GUI and reenable it manually.

Is there any workaround for this?

 

[sapwood]

You're on the 9.3 STABLE?

 

[Cellobita]

Yeah, FreeNAS-9.3-STABLE-201504152200

 

[depasseg]

yep me too. Has been happening for a couple months.

 

[sapwood]

probably something that should be opened up as a genuine bug report on https://bugs.freenas.org/projects/freenas/issues.

If the UI won't save the setting, maybe the script behind the UI (seems like most of its python) can't read that checkbox or something. I'm clueless though, and a bit of a noob... so I'm spouting ideas.

looks like freeadmin.js is what handles that box, and in there the logic looks fine. I'm not sure how it actually handles the services for freebsd that're behind it though, so I don't have much of a chance in understanding it.

bwahahaha, someone spelled area aria, which is apparently Italian.

 

[TFS_Rein]

I've definitely seen my fair share of issues with freenas and AD. Its gotten better, but still a PITA. Seems to help immensely if your DNS is setup properly though.

 

[sapwood]

On follow up, I finally found another thread about it with a real answer. So much for telling myself I wouldn't look at this...

https://forums.freenas.org/index.php?threads/active-directory-users-not-showing-up-in-gui.26910/

Basically in the advanced settings you need to change idmap backend to rid rather than ad. This isn't even an option to view or change from the GUI in version 9.2.1.5-RELEASE from another server I have setup. So apparently they must not check between versions for how the previous config is setup? That or it is brand new and needs to be set...