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