2014-05-23 14:26:37	Local7.Debug	127.0.0.1	Kiwi Syslog Server - Test message number 0001
2014-05-23 14:26:55	User.Debug	192.168.4.200	May 23 14:26:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:27:01	User.Debug	192.168.4.200	May 23 14:26:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:27:01	Cron.Info	192.168.4.200	May 23 14:27:00 /usr/sbin/cron[65096]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:27:01	Cron.Info	192.168.4.200	May 23 14:27:00 /usr/sbin/cron[65097]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:27:02	User.Debug	192.168.4.200	May 23 14:27:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:27:02	User.Debug	192.168.4.200	May 23 14:27:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:27:05	User.Debug	192.168.4.200	May 23 14:27:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:27:05	User.Debug	192.168.4.200	May 23 14:27:04 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:27:05	User.Debug	192.168.4.200	May 23 14:27:04 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-65096
2014-05-23 14:27:05	User.Debug	192.168.4.200	May 23 14:27:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:27:31	User.Debug	192.168.4.200	May 23 14:27:30 manage.py: [middleware.notifier:227] Calling: restart(cifs) 
2014-05-23 14:27:31	User.Debug	192.168.4.200	May 23 14:27:30 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service ix-samba quietstart
2014-05-23 14:27:33	User.Debug	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:27:33	User.Debug	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: /sbin/zfs list -H -o name -t volume,filesystem
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint,name
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 last message repeated 2 times
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpLGwkZh -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.655224,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.655224,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.657349,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.657349,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.659055,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.659055,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.660698,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.660698,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.662652,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.662652,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.664177,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.664177,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.665409,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.665409,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.667181,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.667181,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.669057,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.669057,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.671030,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.671030,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]: [2014/05/23 14:27:32.673117,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]: [2014/05/23 14:27:32.673117,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:27:33	Daemon.Error	192.168.4.200	May 23 14:27:32 winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	System4.Info	192.168.4.200	May 23 14:27:32 kernel: May 23 14:27:32 freenas winbindd[63438]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='bhoover' ntgroup='bhoover'
2014-05-23 14:27:33	User.Notice	192.168.4.200	May 23 14:27:32 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='New_Tampa' ntgroup='New_Tampa'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cmickelson' ntgroup='cmickelson'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='ftpuser' ntgroup='ftpuser'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='stierney' ntgroup='stierney'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='lcampagna' ntgroup='lcampagna'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='public' ntgroup='public'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='freenasusers' ntgroup='freenasusers'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cschappacher' ntgroup='cschappacher'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:33 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jpatron' ntgroup='jpatron'
2014-05-23 14:27:34	User.Notice	192.168.4.200	May 23 14:27:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='btsync' ntgroup='btsync'
2014-05-23 14:27:35	User.Notice	192.168.4.200	May 23 14:27:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='tfeury' ntgroup='tfeury'
2014-05-23 14:27:35	User.Notice	192.168.4.200	May 23 14:27:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='test' ntgroup='test'
2014-05-23 14:27:35	User.Notice	192.168.4.200	May 23 14:27:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jdevenny' ntgroup='jdevenny'
2014-05-23 14:27:35	User.Notice	192.168.4.200	May 23 14:27:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='gmorris' ntgroup='gmorris'
2014-05-23 14:27:35	User.Debug	192.168.4.200	May 23 14:27:34 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service ix-samba quietstart
2014-05-23 14:27:35	User.Debug	192.168.4.200	May 23 14:27:34 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server forcestop
2014-05-23 14:27:35	Daemon.Notice	192.168.4.200	May 23 14:27:34 notifier: Stopping winbindd.
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[63436]: [2014/05/23 14:27:34.891010,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[63436]: [2014/05/23 14:27:34.891010,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[63436]:   Got sig[15] terminate (is_parent=1)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[63436]:   Got sig[15] terminate (is_parent=1)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[63438]: [2014/05/23 14:27:34.895858,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[63438]: [2014/05/23 14:27:34.895858,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[64238]: [2014/05/23 14:27:34.895960,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[64238]: [2014/05/23 14:27:34.895960,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[64238]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[64238]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[63438]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[63438]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[64240]: [2014/05/23 14:27:34.901517,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[64240]: [2014/05/23 14:27:34.901517,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:27:35	Daemon.Error	192.168.4.200	May 23 14:27:34 winbindd[64240]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:27:35	System4.Info	192.168.4.200	May 23 14:27:34 kernel: May 23 14:27:34 freenas winbindd[64240]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:27:35	Daemon.Notice	192.168.4.200	May 23 14:27:34 notifier: Waiting for PIDS: 63436.
2014-05-23 14:27:35	Daemon.Notice	192.168.4.200	May 23 14:27:34 notifier: Stopping smbd.
2014-05-23 14:27:36	Daemon.Notice	192.168.4.200	May 23 14:27:35 notifier: Waiting for PIDS: 63432, 63432.
2014-05-23 14:27:36	Daemon.Notice	192.168.4.200	May 23 14:27:35 notifier: Stopping nmbd.
2014-05-23 14:27:36	Daemon.Notice	192.168.4.200	May 23 14:27:35 notifier: Waiting for PIDS: 63428.
2014-05-23 14:27:36	User.Debug	192.168.4.200	May 23 14:27:35 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server forcestop
2014-05-23 14:27:36	User.Debug	192.168.4.200	May 23 14:27:35 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server quietrestart
2014-05-23 14:27:37	User.Debug	192.168.4.200	May 23 14:27:36 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server quietrestart
2014-05-23 14:27:37	User.Debug	192.168.4.200	May 23 14:27:36 manage.py: [middleware.notifier:220] Popen()ing: /bin/pgrep -F /var/run/samba/smbd.pid smbd
2014-05-23 14:27:47	User.Debug	192.168.4.200	May 23 14:27:46 manage.py: [middleware.notifier:227] Calling: restart(cifs) 
2014-05-23 14:27:47	User.Debug	192.168.4.200	May 23 14:27:46 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service ix-samba quietstart
2014-05-23 14:27:48	User.Debug	192.168.4.200	May 23 14:27:47 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:27:48	User.Debug	192.168.4.200	May 23 14:27:47 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: /sbin/zfs list -H -o name -t volume,filesystem
2014-05-23 14:27:48	User.Notice	192.168.4.200	May 23 14:27:47 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
2014-05-23 14:27:48	User.Notice	192.168.4.200	May 23 14:27:47 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint,name
2014-05-23 14:27:48	User.Notice	192.168.4.200	May 23 14:27:47 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint
2014-05-23 14:27:48	User.Notice	192.168.4.200	May 23 14:27:47 last message repeated 2 times
2014-05-23 14:27:48	User.Notice	192.168.4.200	May 23 14:27:47 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpPQlgEf -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='bhoover' ntgroup='bhoover'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='New_Tampa' ntgroup='New_Tampa'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cmickelson' ntgroup='cmickelson'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='ftpuser' ntgroup='ftpuser'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='stierney' ntgroup='stierney'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='lcampagna' ntgroup='lcampagna'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='public' ntgroup='public'
2014-05-23 14:27:49	User.Notice	192.168.4.200	May 23 14:27:48 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='freenasusers' ntgroup='freenasusers'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cschappacher' ntgroup='cschappacher'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jpatron' ntgroup='jpatron'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='btsync' ntgroup='btsync'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='tfeury' ntgroup='tfeury'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='test' ntgroup='test'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jdevenny' ntgroup='jdevenny'
2014-05-23 14:27:50	User.Notice	192.168.4.200	May 23 14:27:49 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='gmorris' ntgroup='gmorris'
2014-05-23 14:27:50	User.Debug	192.168.4.200	May 23 14:27:49 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service ix-samba quietstart
2014-05-23 14:27:50	User.Debug	192.168.4.200	May 23 14:27:49 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server forcestop
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: smbd not running? (check /var/run/samba/smbd.pid).
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
2014-05-23 14:27:51	User.Debug	192.168.4.200	May 23 14:27:50 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server forcestop
2014-05-23 14:27:51	User.Debug	192.168.4.200	May 23 14:27:50 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server quietrestart
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: Performing sanity check on Samba configuration: OK
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: smbd not running? (check /var/run/samba/smbd.pid).
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: Performing sanity check on Samba configuration: OK
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: Starting nmbd.
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: Starting smbd.
2014-05-23 14:27:51	Daemon.Notice	192.168.4.200	May 23 14:27:50 notifier: Starting winbindd.
2014-05-23 14:27:51	User.Debug	192.168.4.200	May 23 14:27:50 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server quietrestart
2014-05-23 14:27:51	User.Debug	192.168.4.200	May 23 14:27:50 manage.py: [middleware.notifier:220] Popen()ing: /bin/pgrep -F /var/run/samba/smbd.pid smbd
2014-05-23 14:27:51	Daemon.Error	192.168.4.200	May 23 14:27:50 winbindd[65657]: [2014/05/23 14:27:50.779783,  0] ../source3/winbindd/winbindd_cache.c:3196(initialize_winbindd_cache)
2014-05-23 14:27:51	System4.Info	192.168.4.200	May 23 14:27:50 kernel: May 23 14:27:50 freenas winbindd[65657]: [2014/05/23 14:27:50.779783,  0] ../source3/winbindd/winbindd_cache.c:3196(initialize_winbindd_cache)
2014-05-23 14:27:51	Daemon.Error	192.168.4.200	May 23 14:27:50 winbindd[65657]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
2014-05-23 14:27:51	System4.Info	192.168.4.200	May 23 14:27:50 kernel: May 23 14:27:50 freenas winbindd[65657]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
2014-05-23 14:28:01	Cron.Info	192.168.4.200	May 23 14:28:00 /usr/sbin/cron[65675]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:28:01	Cron.Info	192.168.4.200	May 23 14:28:00 /usr/sbin/cron[65676]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]: [2014/05/23 14:28:01.181113,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]: [2014/05/23 14:28:01.181113,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]: [2014/05/23 14:28:01.391620,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]: [2014/05/23 14:28:01.391620,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]: [2014/05/23 14:28:01.426520,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]: [2014/05/23 14:28:01.426520,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1010
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1010
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]: [2014/05/23 14:28:01.427326,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]: [2014/05/23 14:28:01.427326,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]: [2014/05/23 14:28:01.428089,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]: [2014/05/23 14:28:01.428089,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]: [2014/05/23 14:28:01.428785,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]: [2014/05/23 14:28:01.428785,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:28:02	Daemon.Error	192.168.4.200	May 23 14:28:01 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:28:02	System4.Info	192.168.4.200	May 23 14:28:01 kernel: May 23 14:28:01 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:28:02	User.Debug	192.168.4.200	May 23 14:28:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:28:02	User.Debug	192.168.4.200	May 23 14:28:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:28:02	User.Debug	192.168.4.200	May 23 14:28:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:28:04	User.Debug	192.168.4.200	May 23 14:28:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:28:04	User.Debug	192.168.4.200	May 23 14:28:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-65675
2014-05-23 14:28:04	User.Debug	192.168.4.200	May 23 14:28:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:29:00	Cron.Info	192.168.4.200	May 23 14:29:00 /usr/sbin/cron[65785]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:29:00	Cron.Info	192.168.4.200	May 23 14:29:00 /usr/sbin/cron[65786]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:29:01	User.Debug	192.168.4.200	May 23 14:29:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:29:02	User.Debug	192.168.4.200	May 23 14:29:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:29:02	User.Debug	192.168.4.200	May 23 14:29:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:29:04	User.Debug	192.168.4.200	May 23 14:29:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:29:04	User.Debug	192.168.4.200	May 23 14:29:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-65785
2014-05-23 14:29:04	User.Debug	192.168.4.200	May 23 14:29:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:29:18	User.Debug	192.168.4.200	May 23 14:29:17 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:30:01	User.Debug	192.168.4.200	May 23 14:29:17 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:30:01	Cron.Info	192.168.4.200	May 23 14:30:00 /usr/sbin/cron[65922]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:30:01	Cron.Info	192.168.4.200	May 23 14:30:00 /usr/sbin/cron[65921]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:30:01	Cron.Info	192.168.4.200	May 23 14:30:00 /usr/sbin/cron[65923]: (root) CMD (/usr/libexec/atrun)
2014-05-23 14:30:02	User.Debug	192.168.4.200	May 23 14:30:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:30:02	User.Debug	192.168.4.200	May 23 14:30:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:30:04	User.Debug	192.168.4.200	May 23 14:30:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:30:04	User.Debug	192.168.4.200	May 23 14:30:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:30:04	User.Debug	192.168.4.200	May 23 14:30:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-65921
2014-05-23 14:30:04	User.Debug	192.168.4.200	May 23 14:30:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:31:01	Cron.Info	192.168.4.200	May 23 14:31:00 /usr/sbin/cron[66035]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:31:01	Cron.Info	192.168.4.200	May 23 14:31:00 /usr/sbin/cron[66036]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:31:02	User.Debug	192.168.4.200	May 23 14:31:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:31:02	User.Debug	192.168.4.200	May 23 14:31:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:31:05	User.Debug	192.168.4.200	May 23 14:31:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:31:05	User.Debug	192.168.4.200	May 23 14:31:04 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:31:05	User.Debug	192.168.4.200	May 23 14:31:04 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66035
2014-05-23 14:31:05	User.Debug	192.168.4.200	May 23 14:31:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]: [2014/05/23 14:31:42.351489,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]: [2014/05/23 14:31:42.351489,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]: [2014/05/23 14:31:42.720868,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]: [2014/05/23 14:31:42.720868,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]: [2014/05/23 14:31:42.741091,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]: [2014/05/23 14:31:42.741091,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]: [2014/05/23 14:31:42.741327,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]: [2014/05/23 14:31:42.741327,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]: [2014/05/23 14:31:42.741538,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]: [2014/05/23 14:31:42.741538,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]: [2014/05/23 14:31:42.741747,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]: [2014/05/23 14:31:42.741747,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:31:43	Daemon.Error	192.168.4.200	May 23 14:31:42 winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:31:43	System4.Info	192.168.4.200	May 23 14:31:42 kernel: May 23 14:31:42 freenas winbindd[65659]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:31:56	User.Debug	192.168.4.200	May 23 14:31:55 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:32:01	User.Debug	192.168.4.200	May 23 14:31:55 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:32:01	Cron.Info	192.168.4.200	May 23 14:32:00 /usr/sbin/cron[66145]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:32:01	Cron.Info	192.168.4.200	May 23 14:32:00 /usr/sbin/cron[66146]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:32:02	User.Debug	192.168.4.200	May 23 14:32:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:32:02	User.Debug	192.168.4.200	May 23 14:32:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:32:04	User.Debug	192.168.4.200	May 23 14:32:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:32:04	User.Debug	192.168.4.200	May 23 14:32:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:32:04	User.Debug	192.168.4.200	May 23 14:32:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66145
2014-05-23 14:32:04	User.Debug	192.168.4.200	May 23 14:32:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:33:01	Cron.Info	192.168.4.200	May 23 14:33:00 /usr/sbin/cron[66251]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:33:01	Cron.Info	192.168.4.200	May 23 14:33:00 /usr/sbin/cron[66252]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:33:01	Cron.Info	192.168.4.200	May 23 14:33:00 /usr/sbin/cron[66253]: (operator) CMD (/usr/libexec/save-entropy)
2014-05-23 14:33:02	User.Debug	192.168.4.200	May 23 14:33:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:33:02	User.Debug	192.168.4.200	May 23 14:33:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:33:04	User.Debug	192.168.4.200	May 23 14:33:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:33:04	User.Debug	192.168.4.200	May 23 14:33:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:33:04	User.Debug	192.168.4.200	May 23 14:33:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66251
2014-05-23 14:33:04	User.Debug	192.168.4.200	May 23 14:33:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:33:39	Daemon.Error	192.168.4.200	May 23 14:33:39 winbindd[65659]: [2014/05/23 14:33:39.051732,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:33:39	System4.Info	192.168.4.200	May 23 14:33:39 kernel: May 23 14:33:39 freenas winbindd[65659]: [2014/05/23 14:33:39.051732,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:33:39	Daemon.Error	192.168.4.200	May 23 14:33:39 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:33:39	System4.Info	192.168.4.200	May 23 14:33:39 kernel: May 23 14:33:39 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:34:00	Cron.Info	192.168.4.200	May 23 14:34:00 /usr/sbin/cron[66344]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:34:00	Cron.Info	192.168.4.200	May 23 14:34:00 /usr/sbin/cron[66345]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:34:02	User.Debug	192.168.4.200	May 23 14:34:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:34:02	User.Debug	192.168.4.200	May 23 14:34:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:34:04	User.Debug	192.168.4.200	May 23 14:34:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:34:04	User.Debug	192.168.4.200	May 23 14:34:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:34:04	User.Debug	192.168.4.200	May 23 14:34:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66344
2014-05-23 14:34:04	User.Debug	192.168.4.200	May 23 14:34:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:35:01	Cron.Info	192.168.4.200	May 23 14:35:00 /usr/sbin/cron[66410]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:35:01	Cron.Info	192.168.4.200	May 23 14:35:00 /usr/sbin/cron[66411]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:35:01	Cron.Info	192.168.4.200	May 23 14:35:00 /usr/sbin/cron[66412]: (root) CMD (/usr/libexec/atrun)
2014-05-23 14:35:02	User.Debug	192.168.4.200	May 23 14:35:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:35:02	User.Debug	192.168.4.200	May 23 14:35:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:35:04	User.Debug	192.168.4.200	May 23 14:35:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:35:04	User.Debug	192.168.4.200	May 23 14:35:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:35:04	User.Debug	192.168.4.200	May 23 14:35:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66410
2014-05-23 14:35:04	User.Debug	192.168.4.200	May 23 14:35:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:36:01	Cron.Info	192.168.4.200	May 23 14:36:00 /usr/sbin/cron[66458]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:36:01	Cron.Info	192.168.4.200	May 23 14:36:00 /usr/sbin/cron[66459]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:36:02	User.Debug	192.168.4.200	May 23 14:36:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:36:02	User.Debug	192.168.4.200	May 23 14:36:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:36:05	User.Debug	192.168.4.200	May 23 14:36:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:36:05	User.Debug	192.168.4.200	May 23 14:36:04 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:36:05	User.Debug	192.168.4.200	May 23 14:36:04 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66458
2014-05-23 14:36:05	User.Debug	192.168.4.200	May 23 14:36:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:36:55	User.Debug	192.168.4.200	May 23 14:36:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:37:01	User.Debug	192.168.4.200	May 23 14:36:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:37:01	Cron.Info	192.168.4.200	May 23 14:37:00 /usr/sbin/cron[66507]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:37:01	Cron.Info	192.168.4.200	May 23 14:37:00 /usr/sbin/cron[66508]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:37:02	User.Debug	192.168.4.200	May 23 14:37:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:37:02	User.Debug	192.168.4.200	May 23 14:37:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:37:04	User.Debug	192.168.4.200	May 23 14:37:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:37:04	User.Debug	192.168.4.200	May 23 14:37:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:37:04	User.Debug	192.168.4.200	May 23 14:37:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66507
2014-05-23 14:37:04	User.Debug	192.168.4.200	May 23 14:37:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:38:01	Cron.Info	192.168.4.200	May 23 14:38:00 /usr/sbin/cron[66552]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:38:01	Cron.Info	192.168.4.200	May 23 14:38:00 /usr/sbin/cron[66553]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:38:02	User.Debug	192.168.4.200	May 23 14:38:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:38:02	User.Debug	192.168.4.200	May 23 14:38:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:38:04	User.Debug	192.168.4.200	May 23 14:38:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:38:04	User.Debug	192.168.4.200	May 23 14:38:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:38:04	User.Debug	192.168.4.200	May 23 14:38:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66552
2014-05-23 14:38:04	User.Debug	192.168.4.200	May 23 14:38:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:38:53	Daemon.Error	192.168.4.200	May 23 14:38:52 winbindd[65659]: [2014/05/23 14:38:52.118769,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:38:53	System4.Info	192.168.4.200	May 23 14:38:52 kernel: May 23 14:38:52 freenas winbindd[65659]: [2014/05/23 14:38:52.118769,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:38:53	Daemon.Error	192.168.4.200	May 23 14:38:52 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:38:53	System4.Info	192.168.4.200	May 23 14:38:52 kernel: May 23 14:38:52 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:01	Cron.Info	192.168.4.200	May 23 14:39:00 /usr/sbin/cron[66602]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:39:01	Cron.Info	192.168.4.200	May 23 14:39:00 /usr/sbin/cron[66603]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:39:02	User.Debug	192.168.4.200	May 23 14:39:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:39:02	User.Debug	192.168.4.200	May 23 14:39:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:39:05	User.Debug	192.168.4.200	May 23 14:39:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:39:05	User.Debug	192.168.4.200	May 23 14:39:04 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:39:05	User.Debug	192.168.4.200	May 23 14:39:04 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-66602
2014-05-23 14:39:05	User.Debug	192.168.4.200	May 23 14:39:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:39:27	User.Debug	192.168.4.200	May 23 14:39:26 manage.py: [middleware.notifier:227] Calling: restart(cifs) 
2014-05-23 14:39:27	User.Debug	192.168.4.200	May 23 14:39:26 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service ix-samba quietstart
2014-05-23 14:39:28	User.Debug	192.168.4.200	May 23 14:39:27 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:39:28	User.Debug	192.168.4.200	May 23 14:39:27 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: /sbin/zfs list -H -o name -t volume,filesystem
2014-05-23 14:39:28	User.Notice	192.168.4.200	May 23 14:39:27 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
2014-05-23 14:39:28	User.Notice	192.168.4.200	May 23 14:39:27 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint,name
2014-05-23 14:39:28	User.Notice	192.168.4.200	May 23 14:39:27 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint
2014-05-23 14:39:28	User.Notice	192.168.4.200	May 23 14:39:27 last message repeated 2 times
2014-05-23 14:39:28	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpWLhKd1 -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.088537,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.088537,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.090665,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.090665,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.092443,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.092443,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.094098,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.094098,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.096077,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.096077,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.097205,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.097205,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.098442,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.098442,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.100180,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.100180,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.102009,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.102009,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.103630,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.103630,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:29	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:30	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:30	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]: [2014/05/23 14:39:28.105794,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:30	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]: [2014/05/23 14:39:28.105794,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:30	Daemon.Error	192.168.4.200	May 23 14:39:28 winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:30	System4.Info	192.168.4.200	May 23 14:39:28 kernel: May 23 14:39:28 freenas winbindd[65659]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='bhoover' ntgroup='bhoover'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='New_Tampa' ntgroup='New_Tampa'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cmickelson' ntgroup='cmickelson'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='ftpuser' ntgroup='ftpuser'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='stierney' ntgroup='stierney'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='lcampagna' ntgroup='lcampagna'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:28 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='public' ntgroup='public'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='freenasusers' ntgroup='freenasusers'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cschappacher' ntgroup='cschappacher'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jpatron' ntgroup='jpatron'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='btsync' ntgroup='btsync'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='tfeury' ntgroup='tfeury'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='test' ntgroup='test'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jdevenny' ntgroup='jdevenny'
2014-05-23 14:39:30	User.Notice	192.168.4.200	May 23 14:39:29 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='gmorris' ntgroup='gmorris'
2014-05-23 14:39:31	User.Debug	192.168.4.200	May 23 14:39:30 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service ix-samba quietstart
2014-05-23 14:39:31	User.Debug	192.168.4.200	May 23 14:39:30 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server forcestop
2014-05-23 14:39:31	Daemon.Notice	192.168.4.200	May 23 14:39:30 notifier: Stopping winbindd.
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65657]: [2014/05/23 14:39:30.298550,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65657]: [2014/05/23 14:39:30.298550,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65657]:   Got sig[15] terminate (is_parent=1)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65657]:   Got sig[15] terminate (is_parent=1)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65659]: [2014/05/23 14:39:30.303549,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65659]: [2014/05/23 14:39:30.303549,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65659]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65659]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65695]: [2014/05/23 14:39:30.303908,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65695]: [2014/05/23 14:39:30.303908,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65695]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65695]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65697]: [2014/05/23 14:39:30.304883,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65697]: [2014/05/23 14:39:30.304883,  0] ../source3/winbindd/winbindd.c:234(winbindd_sig_term_handler)
2014-05-23 14:39:31	Daemon.Error	192.168.4.200	May 23 14:39:30 winbindd[65697]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:39:31	System4.Info	192.168.4.200	May 23 14:39:30 kernel: May 23 14:39:30 freenas winbindd[65697]:   Got sig[15] terminate (is_parent=0)
2014-05-23 14:39:31	Daemon.Notice	192.168.4.200	May 23 14:39:30 notifier: Waiting for PIDS: 65657.
2014-05-23 14:39:31	Daemon.Notice	192.168.4.200	May 23 14:39:30 notifier: Stopping smbd.
2014-05-23 14:39:31	Daemon.Notice	192.168.4.200	May 23 14:39:30 notifier: Waiting for PIDS: 65652.
2014-05-23 14:39:31	Daemon.Notice	192.168.4.200	May 23 14:39:30 notifier: Stopping nmbd.
2014-05-23 14:39:31	Daemon.Notice	192.168.4.200	May 23 14:39:30 notifier: Waiting for PIDS: 65648.
2014-05-23 14:39:31	User.Debug	192.168.4.200	May 23 14:39:30 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server forcestop
2014-05-23 14:39:31	User.Debug	192.168.4.200	May 23 14:39:30 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server quietrestart
2014-05-23 14:39:31	User.Debug	192.168.4.200	May 23 14:39:30 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server quietrestart
2014-05-23 14:39:31	User.Debug	192.168.4.200	May 23 14:39:30 manage.py: [middleware.notifier:220] Popen()ing: /bin/pgrep -F /var/run/samba/smbd.pid smbd
2014-05-23 14:39:33	User.Debug	192.168.4.200	May 23 14:39:33 manage.py: [middleware.notifier:227] Calling: restart(cifs) 
2014-05-23 14:39:33	User.Debug	192.168.4.200	May 23 14:39:33 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service ix-samba quietstart
2014-05-23 14:39:35	User.Debug	192.168.4.200	May 23 14:39:34 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:39:35	User.Debug	192.168.4.200	May 23 14:39:34 generate_smb4_conf.py: [middleware.notifier:220] Popen()ing: /sbin/zfs list -H -o name -t volume,filesystem
2014-05-23 14:39:35	User.Notice	192.168.4.200	May 23 14:39:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /sbin/sysctl -n 'kern.maxfilesperproc'
2014-05-23 14:39:35	User.Notice	192.168.4.200	May 23 14:39:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint,name
2014-05-23 14:39:35	User.Notice	192.168.4.200	May 23 14:39:34 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: zfs list -H -o mountpoint
2014-05-23 14:39:35	User.Notice	192.168.4.200	May 23 14:39:34 last message repeated 2 times
2014-05-23 14:39:35	User.Notice	192.168.4.200	May 23 14:39:35 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/pdbedit -d 0 -i smbpasswd:/tmp/tmpc1nzJf -s /usr/local/etc/smb4.conf -e tdbsam:/var/etc/private/passdb.tdb
2014-05-23 14:39:36	User.Notice	192.168.4.200	May 23 14:39:35 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='bhoover' ntgroup='bhoover'
2014-05-23 14:39:36	User.Notice	192.168.4.200	May 23 14:39:35 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='New_Tampa' ntgroup='New_Tampa'
2014-05-23 14:39:36	User.Notice	192.168.4.200	May 23 14:39:35 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cmickelson' ntgroup='cmickelson'
2014-05-23 14:39:36	User.Notice	192.168.4.200	May 23 14:39:35 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='ftpuser' ntgroup='ftpuser'
2014-05-23 14:39:36	User.Notice	192.168.4.200	May 23 14:39:35 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='stierney' ntgroup='stierney'
2014-05-23 14:39:36	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='lcampagna' ntgroup='lcampagna'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='public' ntgroup='public'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='freenasusers' ntgroup='freenasusers'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='cschappacher' ntgroup='cschappacher'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jpatron' ntgroup='jpatron'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='btsync' ntgroup='btsync'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='tfeury' ntgroup='tfeury'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:36 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='test' ntgroup='test'
2014-05-23 14:39:37	User.Notice	192.168.4.200	May 23 14:39:37 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='jdevenny' ntgroup='jdevenny'
2014-05-23 14:39:38	User.Notice	192.168.4.200	May 23 14:39:37 generate_smb4_conf.py: [common.pipesubr:58] Popen()ing: /usr/local/bin/net groupmap add unixgroup='gmorris' ntgroup='gmorris'
2014-05-23 14:39:38	User.Debug	192.168.4.200	May 23 14:39:37 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service ix-samba quietstart
2014-05-23 14:39:38	User.Debug	192.168.4.200	May 23 14:39:37 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server forcestop
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: smbd not running? (check /var/run/samba/smbd.pid).
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
2014-05-23 14:39:38	User.Debug	192.168.4.200	May 23 14:39:37 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server forcestop
2014-05-23 14:39:38	User.Debug	192.168.4.200	May 23 14:39:37 manage.py: [middleware.notifier:185] Executing: /usr/sbin/service samba_server quietrestart
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: Performing sanity check on Samba configuration: OK
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: winbindd not running? (check /var/run/samba/winbindd.pid).
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: smbd not running? (check /var/run/samba/smbd.pid).
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: nmbd not running? (check /var/run/samba/nmbd.pid).
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:37 notifier: Performing sanity check on Samba configuration: OK
2014-05-23 14:39:38	Daemon.Notice	192.168.4.200	May 23 14:39:38 notifier: Starting nmbd.
2014-05-23 14:39:39	Daemon.Notice	192.168.4.200	May 23 14:39:38 notifier: Starting smbd.
2014-05-23 14:39:39	Daemon.Notice	192.168.4.200	May 23 14:39:38 notifier: Starting winbindd.
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67112]: [2014/05/23 14:39:38.255166,  0] ../source3/winbindd/winbindd_cache.c:3196(initialize_winbindd_cache)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67112]: [2014/05/23 14:39:38.255166,  0] ../source3/winbindd/winbindd_cache.c:3196(initialize_winbindd_cache)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67112]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67112]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
2014-05-23 14:39:39	User.Debug	192.168.4.200	May 23 14:39:38 manage.py: [middleware.notifier:199] Executed: /usr/sbin/service samba_server quietrestart
2014-05-23 14:39:39	User.Debug	192.168.4.200	May 23 14:39:38 manage.py: [middleware.notifier:220] Popen()ing: /bin/pgrep -F /var/run/samba/smbd.pid smbd
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]: [2014/05/23 14:39:38.525631,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]: [2014/05/23 14:39:38.525631,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]: [2014/05/23 14:39:38.652140,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]: [2014/05/23 14:39:38.652140,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]: [2014/05/23 14:39:38.679834,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]: [2014/05/23 14:39:38.679834,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1010
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1010
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]: [2014/05/23 14:39:38.680106,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]: [2014/05/23 14:39:38.680106,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]: [2014/05/23 14:39:38.680326,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]: [2014/05/23 14:39:38.680326,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]: [2014/05/23 14:39:38.680532,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]: [2014/05/23 14:39:38.680532,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:39	Daemon.Error	192.168.4.200	May 23 14:39:38 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:39:39	System4.Info	192.168.4.200	May 23 14:39:38 kernel: May 23 14:39:38 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:39:50	Daemon.Error	192.168.4.200	May 23 14:39:49 winbindd[67114]: [2014/05/23 14:39:49.923422,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:50	System4.Info	192.168.4.200	May 23 14:39:49 kernel: May 23 14:39:49 freenas winbindd[67114]: [2014/05/23 14:39:49.923422,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:39:50	Daemon.Error	192.168.4.200	May 23 14:39:49 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:50	System4.Info	192.168.4.200	May 23 14:39:49 kernel: May 23 14:39:49 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:39:50	Daemon.Error	192.168.4.200	May 23 14:39:49 winbindd[67114]: [2014/05/23 14:39:49.940706,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:50	System4.Info	192.168.4.200	May 23 14:39:49 kernel: May 23 14:39:49 freenas winbindd[67114]: [2014/05/23 14:39:49.940706,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:39:50	Daemon.Error	192.168.4.200	May 23 14:39:49 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:39:50	System4.Info	192.168.4.200	May 23 14:39:49 kernel: May 23 14:39:49 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:40:01	Cron.Info	192.168.4.200	May 23 14:40:00 /usr/sbin/cron[67133]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:40:01	Cron.Info	192.168.4.200	May 23 14:40:00 /usr/sbin/cron[67134]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:40:01	Cron.Info	192.168.4.200	May 23 14:40:00 /usr/sbin/cron[67135]: (root) CMD (/usr/libexec/atrun)
2014-05-23 14:40:02	User.Debug	192.168.4.200	May 23 14:40:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:40:02	User.Debug	192.168.4.200	May 23 14:40:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:40:04	User.Debug	192.168.4.200	May 23 14:40:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:40:04	User.Debug	192.168.4.200	May 23 14:40:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:40:04	User.Debug	192.168.4.200	May 23 14:40:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67133
2014-05-23 14:40:04	User.Debug	192.168.4.200	May 23 14:40:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:41:01	Cron.Info	192.168.4.200	May 23 14:41:00 /usr/sbin/cron[67185]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:41:01	Cron.Info	192.168.4.200	May 23 14:41:00 /usr/sbin/cron[67186]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:41:02	User.Debug	192.168.4.200	May 23 14:41:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:41:02	User.Debug	192.168.4.200	May 23 14:41:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:41:04	User.Debug	192.168.4.200	May 23 14:41:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:41:04	User.Debug	192.168.4.200	May 23 14:41:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:41:04	User.Debug	192.168.4.200	May 23 14:41:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67185
2014-05-23 14:41:04	User.Debug	192.168.4.200	May 23 14:41:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:41:55	User.Debug	192.168.4.200	May 23 14:41:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:42:00	User.Debug	192.168.4.200	May 23 14:41:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:42:00	Cron.Info	192.168.4.200	May 23 14:42:00 /usr/sbin/cron[67245]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:42:00	Cron.Info	192.168.4.200	May 23 14:42:00 /usr/sbin/cron[67246]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:42:01	User.Debug	192.168.4.200	May 23 14:42:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:42:02	User.Debug	192.168.4.200	May 23 14:42:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:42:04	User.Debug	192.168.4.200	May 23 14:42:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:42:04	User.Debug	192.168.4.200	May 23 14:42:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:42:04	User.Debug	192.168.4.200	May 23 14:42:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67245
2014-05-23 14:42:04	User.Debug	192.168.4.200	May 23 14:42:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:43:01	Cron.Info	192.168.4.200	May 23 14:43:00 /usr/sbin/cron[67298]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:43:01	Cron.Info	192.168.4.200	May 23 14:43:00 /usr/sbin/cron[67299]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:43:02	User.Debug	192.168.4.200	May 23 14:43:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:43:02	User.Debug	192.168.4.200	May 23 14:43:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:43:04	User.Debug	192.168.4.200	May 23 14:43:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:43:04	User.Debug	192.168.4.200	May 23 14:43:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:43:04	User.Debug	192.168.4.200	May 23 14:43:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67298
2014-05-23 14:43:04	User.Debug	192.168.4.200	May 23 14:43:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:44:01	Cron.Info	192.168.4.200	May 23 14:44:00 /usr/sbin/cron[67344]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:44:01	Cron.Info	192.168.4.200	May 23 14:44:00 /usr/sbin/cron[67345]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:44:01	Cron.Info	192.168.4.200	May 23 14:44:00 /usr/sbin/cron[67346]: (operator) CMD (/usr/libexec/save-entropy)
2014-05-23 14:44:02	User.Debug	192.168.4.200	May 23 14:44:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:44:02	User.Debug	192.168.4.200	May 23 14:44:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:44:05	User.Debug	192.168.4.200	May 23 14:44:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:44:05	User.Debug	192.168.4.200	May 23 14:44:04 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:44:05	User.Debug	192.168.4.200	May 23 14:44:04 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67344
2014-05-23 14:44:05	User.Debug	192.168.4.200	May 23 14:44:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:45:01	Cron.Info	192.168.4.200	May 23 14:45:00 /usr/sbin/cron[67421]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:45:01	Cron.Info	192.168.4.200	May 23 14:45:00 /usr/sbin/cron[67422]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:45:01	Cron.Info	192.168.4.200	May 23 14:45:00 /usr/sbin/cron[67423]: (root) CMD (/usr/libexec/atrun)
2014-05-23 14:45:02	User.Debug	192.168.4.200	May 23 14:45:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:45:02	User.Debug	192.168.4.200	May 23 14:45:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:45:04	User.Debug	192.168.4.200	May 23 14:45:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:45:04	User.Debug	192.168.4.200	May 23 14:45:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:45:04	User.Debug	192.168.4.200	May 23 14:45:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67421
2014-05-23 14:45:04	User.Debug	192.168.4.200	May 23 14:45:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:46:01	Cron.Info	192.168.4.200	May 23 14:46:00 /usr/sbin/cron[67476]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:46:01	Cron.Info	192.168.4.200	May 23 14:46:00 /usr/sbin/cron[67477]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:46:02	User.Debug	192.168.4.200	May 23 14:46:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:46:02	User.Debug	192.168.4.200	May 23 14:46:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:46:04	User.Debug	192.168.4.200	May 23 14:46:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:46:04	User.Debug	192.168.4.200	May 23 14:46:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:46:04	User.Debug	192.168.4.200	May 23 14:46:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67476
2014-05-23 14:46:04	User.Debug	192.168.4.200	May 23 14:46:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]: [2014/05/23 14:46:51.716302,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]: [2014/05/23 14:46:51.716302,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]: [2014/05/23 14:46:51.831348,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]: [2014/05/23 14:46:51.831348,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]: [2014/05/23 14:46:51.851984,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]: [2014/05/23 14:46:51.851984,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]: [2014/05/23 14:46:51.852225,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]: [2014/05/23 14:46:51.852225,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]: [2014/05/23 14:46:51.852439,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]: [2014/05/23 14:46:51.852439,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]: [2014/05/23 14:46:51.852646,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]: [2014/05/23 14:46:51.852646,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:46:52	Daemon.Error	192.168.4.200	May 23 14:46:51 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:46:52	System4.Info	192.168.4.200	May 23 14:46:51 kernel: May 23 14:46:51 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:46:55	User.Debug	192.168.4.200	May 23 14:46:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:47:01	User.Debug	192.168.4.200	May 23 14:46:54 manage.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:47:01	Cron.Info	192.168.4.200	May 23 14:47:00 /usr/sbin/cron[67530]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:47:01	Cron.Info	192.168.4.200	May 23 14:47:00 /usr/sbin/cron[67531]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:47:02	User.Debug	192.168.4.200	May 23 14:47:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:47:02	User.Debug	192.168.4.200	May 23 14:47:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:47:05	User.Debug	192.168.4.200	May 23 14:47:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:47:05	User.Debug	192.168.4.200	May 23 14:47:04 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:47:05	User.Debug	192.168.4.200	May 23 14:47:04 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67530
2014-05-23 14:47:05	User.Debug	192.168.4.200	May 23 14:47:04 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:48:01	Cron.Info	192.168.4.200	May 23 14:48:00 /usr/sbin/cron[67584]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:48:01	Cron.Info	192.168.4.200	May 23 14:48:00 /usr/sbin/cron[67585]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:48:02	User.Debug	192.168.4.200	May 23 14:48:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:48:02	User.Debug	192.168.4.200	May 23 14:48:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:48:04	User.Debug	192.168.4.200	May 23 14:48:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:48:04	User.Debug	192.168.4.200	May 23 14:48:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:48:04	User.Debug	192.168.4.200	May 23 14:48:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67584
2014-05-23 14:48:04	User.Debug	192.168.4.200	May 23 14:48:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:49:01	Cron.Info	192.168.4.200	May 23 14:49:00 /usr/sbin/cron[67629]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:49:01	Cron.Info	192.168.4.200	May 23 14:49:00 /usr/sbin/cron[67630]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:49:02	User.Debug	192.168.4.200	May 23 14:49:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:49:02	User.Debug	192.168.4.200	May 23 14:49:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:49:04	User.Debug	192.168.4.200	May 23 14:49:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:49:04	User.Debug	192.168.4.200	May 23 14:49:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:49:04	User.Debug	192.168.4.200	May 23 14:49:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67629
2014-05-23 14:49:04	User.Debug	192.168.4.200	May 23 14:49:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.600741,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.600741,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.666583,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.666583,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.700497,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.700497,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1002
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.700807,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.700807,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1008
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.701035,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.701035,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1004
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.701247,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.701247,  0] ../source3/winbindd/winbindd_samr.c:694(sam_sid_to_name)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249-1009
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]: [2014/05/23 14:49:06.787104,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]: [2014/05/23 14:49:06.787104,  0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
2014-05-23 14:49:07	Daemon.Error	192.168.4.200	May 23 14:49:06 winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:49:07	System4.Info	192.168.4.200	May 23 14:49:06 kernel: May 23 14:49:06 freenas winbindd[67114]:   sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2803706266-3940992467-408568249
2014-05-23 14:50:00	Cron.Info	192.168.4.200	May 23 14:50:00 /usr/sbin/cron[67704]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/autosnap.py > /dev/null 2>&1)
2014-05-23 14:50:00	Cron.Info	192.168.4.200	May 23 14:50:00 /usr/sbin/cron[67705]: (root) CMD (/usr/local/bin/python /usr/local/www/freenasUI/tools/alert.py > /dev/null 2>&1)
2014-05-23 14:50:00	Cron.Info	192.168.4.200	May 23 14:50:00 /usr/sbin/cron[67706]: (root) CMD (/usr/libexec/atrun)
2014-05-23 14:50:02	User.Debug	192.168.4.200	May 23 14:50:01 autosnap.py: [tools.autosnap:58] Popen()ing: /sbin/zfs list -t snapshot -H
2014-05-23 14:50:02	User.Debug	192.168.4.200	May 23 14:50:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:50:04	User.Debug	192.168.4.200	May 23 14:50:01 alert.py: [middleware.notifier:220] Popen()ing: zpool list -H -o health New_Tampa
2014-05-23 14:50:04	User.Debug	192.168.4.200	May 23 14:50:03 autorepl.py: [tools.autorepl:117] Autosnap replication started
2014-05-23 14:50:04	User.Debug	192.168.4.200	May 23 14:50:03 autorepl.py: [tools.autorepl:118] temp log file: /tmp/repl-67704
2014-05-23 14:50:04	User.Debug	192.168.4.200	May 23 14:50:03 autorepl.py: [tools.autorepl:393] Autosnap replication finished