Падает isc-dhcp44-server-4.4.1_3

[novik]

Примерно каждые 6 часов в логах:

Код: Выделить всё

Aug  8 14:57:20 FreeBSD dhcpd: from the dynamic address pool for 192.168.2.0/24
Aug  8 15:00:02 FreeBSD nmbd[1108]: [2018/08/08 15:00:02.430403,  0, pid=1108, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd.c:169(nmbd_sig_hup_handler)
Aug  8 15:00:02 FreeBSD nmbd[1108]:   Got SIGHUP dumping debug info.
Aug  8 15:00:02 FreeBSD nmbd[1108]: [2018/08/08 15:00:02.444437,  0, pid=1108, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:276(dump_workgroups)
Aug  8 15:00:02 FreeBSD nmbd[1108]:   dump_workgroups()
Aug  8 15:00:02 FreeBSD nmbd[1108]:    dump workgroup on subnet     192.168.0.1: netmask=  255.255.255.0:
Aug  8 15:00:02 FreeBSD nmbd[1108]:     WORKGROUP(1) current master browser = FREEBSD
Aug  8 15:00:02 FreeBSD nmbd[1108]:             FREEBSD 408c9a03 (Samba Server)
Aug  8 15:00:02 FreeBSD nmbd[1108]:             WINDOWS10 40011003 (Win10)
Aug  8 15:00:02 FreeBSD nmbd[1108]: [2018/08/08 15:00:02.447953,  0, pid=1108, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:276(dump_workgroups)
Aug  8 15:00:02 FreeBSD nmbd[1108]:   dump_workgroups()
Aug  8 15:00:02 FreeBSD nmbd[1108]:    dump workgroup on subnet     192.168.2.1: netmask=  255.255.255.0:
Aug  8 15:00:02 FreeBSD nmbd[1108]:     WORKGROUP(1) current master browser = FREEBSD
Aug  8 15:00:02 FreeBSD nmbd[1108]:             FREEBSD 408c9a03 (Samba Server)
Aug  8 15:00:02 FreeBSD nmbd[1108]:             MARY 40011003 (Work stantion)
Aug  8 15:00:02 FreeBSD nmbd[1108]: [2018/08/08 15:00:02.451247,  0, pid=1108, effective(0, 0), real(0, 0)] ../source3/nmbd/nmbd_workgroupdb.c:276(dump_workgroups)
Aug  8 15:00:02 FreeBSD nmbd[1108]:   dump_workgroups()
Aug  8 15:00:02 FreeBSD nmbd[1108]:    dump workgroup on subnet  UNICAST_SUBNET: netmask=    192.168.2.1:
Aug  8 15:00:02 FreeBSD nmbd[1108]:     WORKGROUP(1) current master browser = UNKNOWN
Aug  8 15:00:02 FreeBSD nmbd[1108]:             FREEBSD 40899a03 (Samba Server)
Aug  8 15:00:03 FreeBSD root: /usr/local/etc/rc.d/isc-dhcpd: WARNING: failed to start dhcpd
Aug  8 15:00:28 FreeBSD monit[37615]: 'dhcpd' process is not running
Aug  8 15:00:45 FreeBSD dhcpd: Dynamic and static leases present for 192.168.2.51.
Aug  8 15:00:45 FreeBSD dhcpd: Remove host declaration masha1 or remove 192.168.2.51
Aug  8 15:00:45 FreeBSD dhcpd: from the dynamic address pool for 192.168.2.0/24
Aug  8 15:00:54 FreeBSD dhcpd: Dynamic and static leases present for 192.168.0.196.
Aug  8 15:00:54 FreeBSD dhcpd: Remove host declaration igor_win10_lan or remove 192.168.0.196
Aug  8 15:00:54 FreeBSD dhcpd: from the dynamic address pool for 192.168.0.0/24
Aug  8 15:01:30 FreeBSD monit[37615]: 'dhcpd' failed to start (exit status -1) -- '/usr/local/etc/rc.d/isc-dhcpd start': Program timed out -- Internet Systems Consortium DHCP Server 4.4.1 Copy
Aug  8 15:02:20 FreeBSD dhcpd: Dynamic and static leases present for 192.168.2.12.

Прикрутил monit который его перезапускает, но это же костыль какой-то. Помогите выяснить почему он падает. Куда копать, что смотреть?

[Хостинг HostFood.ru]

Тарифы на хостинг в России, от 12 рублей: https://www.host-food.ru/tariffs/hosting/
Тарифы на виртуальные сервера (VPS/VDS/KVM) в РФ, от 189 руб.: https://www.host-food.ru/tariffs/virtualny-server-vps/
Выделенные сервера, Россия, Москва, от 2000 рублей (HP Proliant G5, Intel Xeon E5430 (2.66GHz, Quad-Core, 12Mb), 8Gb RAM, 2x300Gb SAS HDD, P400i, 512Mb, BBU):
https://www.host-food.ru/tariffs/vydelennyi-server-ds/
Недорогие домены в популярных зонах: https://www.host-food.ru/domains/

[novik]

/usr/local/etc/dhcpd.conf

Код: Выделить всё

option domain-name "freebsd.local";
default-lease-time 3600;
max-lease-time 86400;
authoritative;
log-facility local7;
option rfc3442-classless-static-routes code 121 = array of unsigned integer 8;
option ms-classless-static-routes code 249 = array of unsigned integer 8;

subnet 192.168.0.0 netmask 255.255.255.0 {
  range 192.168.0.10 192.168.0.200;
  option domain-name-servers 192.168.0.1;
  option netbios-name-servers 192.168.0.1;
  option domain-name "freebsd.local";
  option routers 192.168.0.1;
  option subnet-mask 255.255.255.0;
  option broadcast-address 192.168.0.255;
  option ntp-servers 192.168.0.1;
  default-lease-time 600;
  max-lease-time 7200;
  option rfc3442-classless-static-routes 24, 192,168,2, 192,168,0,1;
  option ms-classless-static-routes 24, 192,168,2, 192,168,0,1;
  deny unknown-clients;
}

subnet 192.168.2.0 netmask 255.255.255.0 {
  range 192.168.2.10 192.168.2.200;
  option domain-name-servers 192.168.0.1;
  option netbios-name-servers 192.168.2.1;
  option domain-name "freebsd.local";
  option routers 192.168.2.1;
  option subnet-mask 255.255.255.0;
  option broadcast-address 192.168.2.255;
  option ntp-servers 192.168.0.1;
  default-lease-time 600;
  max-lease-time 7200;
  option rfc3442-classless-static-routes 24, 192,168,0, 192,168,2,1;
  option ms-classless-static-routes 24, 192,168,0, 192,168,2,1;
  deny unknown-clients;
}

host slava {
  hardware ethernet 00:25:D3:C1:E6:8B;
  fixed-address 192.168.0.18;
}
host slavalan {
  hardware ethernet 90:E6:BA:9A:9A:55;
  fixed-address 192.168.0.19;
}
host stalker_lan {
  hardware ethernet E0:CB:4E:EA:A1:E7;
  fixed-address 192.168.0.68;
}
host masha {
  hardware ethernet 80:61:8F:AB:97:0D;
  fixed-address 192.168.2.50;
}
host masha1 {
  hardware ethernet 38:E6:0A:A9:1B:03;
  fixed-address 192.168.2.51;
}
host igor {
  hardware ethernet 48:88:CA:EF:2E:6F;
  fixed-address 192.168.2.60;
}
host planshet {
  hardware ethernet 70:72:0D:87:2B:71;
  fixed-address 192.168.2.58;
}
host nout {
  hardware ethernet E0:06:E6:64:1F:5D;
  fixed-address 192.168.2.12;
}

host igor_win10 {
  hardware ethernet B0:C0:90:BC:40:21;
  fixed-address 192.168.2.195;
}

host igor_win10_lan {
  hardware ethernet 1C:39:47:EC:E1:9B;
  fixed-address 192.168.0.196;
}

host win_xp {
  hardware ethernet 00:21:6B:9F:D0:2E;
  fixed-address 192.168.0.190;
}
host ipad {
  hardware ethernet C8:B5:B7:C7:2F:E0;
  fixed-address 192.168.0.191;
}

cat /etc/syslog.conf

Код: Выделить всё

!dhcpd
local7.*                                        /var/log/dhcpd.log

/etc/newsyslog.conf

Код: Выделить всё

/var/log/dhcpd.log      dhcpd:dhcpd     644     5       100     *       JC      /var/run/dhcpd/dhcpd.pid

/etc/rc.conf
dhcpd_enable="YES"
dhcpd_flags="-d"
#dhcpd_flags="-q"
dhcpd_ifaces="rl0 wlan0"
dhcpd_conf="/usr/local/etc/dhcpd.conf"

cat /var/db/ports/net_isc-dhcp4

Код: Выделить всё

net_isc-dhcp43-server/ net_isc-dhcp44-server/
root@FreeBSD:/home/oleg # cat /var/db/ports/net_isc-dhcp44-server/options
# This file is auto-generated by 'make config'.
# Options for isc-dhcp44-server-4.4.1_3
_OPTIONS_READ=isc-dhcp44-server-4.4.1_3
_FILE_COMPLETE_OPTIONS_LIST=BIND_SYMBOLS BINLEASES DHCP4O6 IPV6 LDAP LDAP_SSL PARANOIA
OPTIONS_FILE_UNSET+=BIND_SYMBOLS
OPTIONS_FILE_UNSET+=BINLEASES
OPTIONS_FILE_UNSET+=DHCP4O6
OPTIONS_FILE_UNSET+=IPV6
OPTIONS_FILE_UNSET+=LDAP
OPTIONS_FILE_UNSET+=LDAP_SSL
OPTIONS_FILE_SET+=PARANOIA

Вроде бы больше ничего про него нигде и нет...

[novik]

dhcpd -t

Код: Выделить всё

Internet Systems Consortium DHCP Server 4.4.1
Copyright 2004-2018 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/
Config file: /usr/local/etc/dhcpd.conf
Database file: /var/db/dhcpd.leases
PID file: /var/run/dhcpd.pid

[novik]

Демон падает в момент ротации логов.
ls -l /var/log/ | grep dhcpd.log

Код: Выделить всё

-rw-r--r--  1 dhcpd      dhcpd       49380  9 авг 07:31 dhcpd.log
-rw-r--r--  1 dhcpd      dhcpd        4937  9 авг 07:00 dhcpd.log.0.bz2
-rw-r--r--  1 dhcpd      dhcpd        3705  8 авг 23:00 dhcpd.log.1.bz2
-rw-r--r--  1 dhcpd      dhcpd        4563  8 авг 15:00 dhcpd.log.2.bz2
-rw-r--r--  1 dhcpd      dhcpd        3814  8 авг 07:00 dhcpd.log.3.bz2
-rw-r--r--  1 dhcpd      dhcpd        3080  7 авг 23:00 dhcpd.log.4.bz2

cat /var/log/monit.log | grep "process is not running"

Код: Выделить всё

Aug  8 07:00:04 FreeBSD monit[37615]: 'dhcpd' process is not running
Aug  8 15:00:28 FreeBSD monit[37615]: 'dhcpd' process is not running
Aug  8 23:00:05 FreeBSD monit[37615]: 'dhcpd' process is not running
Aug  9 07:00:13 FreeBSD monit[37615]: 'dhcpd' process is not running

[snorlov]

Да он наверное не падает, его просто останавливают, а потом не запускают...

[novik]

его просто останавливают, а потом не запускают...

Если запускать с отладкой из консоли, с отключенным monit'ом то во время ротации логов пишет

Код: Выделить всё

Hungup
/usr/local/etc/rc.d/isc-dhcpd: WARNING: failed to start dhcpd

И больше не работает. Но почему и как с этим бороться? Остальные демоны себя так не ведут...

[snorlov]

Попробуйте прошерстить все конфиги и скрипты isc-dhcpd на тему pid-файла, может можно указать принудительно где он находится, после чего попробовать.
P.S. А что опция paranoi что-нибудь существенное дает?

[novik]

А что опция paranoi что-нибудь существенное дает?

Возможность запуска в chroot. Пока не пробовал. Запустил логгирование и...

прошерстить все конфиги и скрипты isc-dhcpd на тему pid-файла

Должно в принципе меняться как и расположение Database file: /var/db/dhcpd.leases. Но вроде бы он на месте.
ls -l /var/run/dhcpd/

Код: Выделить всё

total 4
-rw-r--r--  1 root  dhcpd  6  9 авг 13:00 dhcpd.pid

Вот только почему у него владелец root , хотя демон работает от dhcpd???

Код: Выделить всё

  PID USERNAME         THR PRI NICE   SIZE    RES STATE    TIME    WCPU COMMAND
58166 dhcpd              1  20    0 17016K  8580K select   0:02   0.00% dhcpd

[novik]

можно указать принудительно где он находится

Принудительно в /etc/rc.conf задаётся. Опцией

Код: Выделить всё

dhcpd_flags="-q -pf /var/run/dhcpd/dhcpd.pid"

Однако не помогло. С смена его владельца тоже...
ls -l /var/run/dhcpd/ | grep dhcp

Код: Выделить всё

-rw-r--r--  1 dhcpd  dhcpd  6 10 авг 07:30 dhcpd.pid

[snorlov]

Я бы в скрипте запуска посмотрел бы, и везде включая конфиги, где бы встретился упоминание pid-файла, установил единое местоположение и перекомпилировал бы без chroot(paranoi), там могут быть свои опции. Как мне кажется вы dhcpd по существу ручками запускаете и скрипт не хавает, что надо запустить от другой учетки ну а потом, может прав нет на pid-файл или попытка создания pid-файла где нет прав этой учетки

[novik]

Пересобрал без опции Paranoia, оставил всё по дефолту.
ps axuww | grep dhcp

Код: Выделить всё

root            52286  0,0  0,8  17012  8756  -  S    13:00      0:00,28 /usr/local/sbin/dhcpd -d -cf /usr/local/etc/dhcpd.conf -lf /var/db/dhcpd.leases -pf /var/run/dhcpd.pid rl0 wlan0
root            52418  0,0  0,2   6292  1884  2  DL+  13:28      0:00,00 grep dhcp

Обыскал /etc/ и /usr/local/etc/ может где ещё поискать?
grep -r dhcpd.pid /etc/

Код: Выделить всё

/etc/newsyslog.conf:/var/log/dhcpd.log  root:wheel      644     5       1      *JC      /var/run/dhcpd.pid
/etc/rc.conf:#dhcpd_flags="-d -t -pf /var/run/dhcpd/dhcpd.pid -lf /var/db/dhcpd/dhcpd.leases"
/etc/rc.conf:#dhcpd_witpid="/var/run/dhcpd/dhcpd.pid"

grep -r dhcpd.pid /usr/local/etc/

Код: Выделить всё

/usr/local/etc/rc.d/isc-dhcpd:          eval "${name}_piddir=\${__dhcpd_piddir}/\${name}"
/usr/local/etc/rc.d/isc-dhcpd:          eval "${name}_piddir=\${__dhcpd_piddir}"
/usr/local/etc/rc.d/isc-dhcpd:  eval "_dhcpd_piddir=\${${name}_chrootdir}\${${name}_piddir}"
/usr/local/etc/rc.d/isc-dhcpd:  eval "_dhcpd_pidfile=\${${name}_chrootdir}\${${name}_pidfile}"
/usr/local/etc/rc.d/isc-dhcpd:          safe_mkdir ${_dhcpd_piddir} ${_dhcpd_leasesdir}
/usr/local/etc/rc.d/isc-dhcpd:__dhcpd_piddir=/var/run                   # pid file directory
/usr/local/etc/rc.d/isc-dhcpd:pidfile=${_dhcpd_pidfile}
/usr/local/etc/monitrc:  check process dhcpd with pidfile /var/run/dhcpd.pid
/usr/local/etc/webmin/dhcpd/config:pid_file=/var/run/dhcpd.pid

Вроде бы всё правильно. webmin точно ни при чём, он выключен /etc/rc.conf:webmin_enable="NO". Проверил права
ls -l /var/run/ | grep dhcpd.pid

Код: Выделить всё

-rw-r--r--  1 root       wheel         6 12 авг 01:00 dhcpd.pid

После newsyslog -v

Код: Выделить всё

/var/log/dhcpd.log <5J>: size (Kb): 1 [1] --> trimming log....
Signal all daemon process(es)...
Notified daemon pid 51675 = /var/run/dhcpd.pid
Pause 10 seconds to allow daemon(s) to close log file(s)
Compress all rotated log file(s)...

Всё равно

Код: Выделить всё

Разрыв связи
/usr/local/etc/rc.d/isc-dhcpd: WARNING: failed to start dhcpd

вы dhcpd по существу ручками запускаете

Если перезагрузится, то тоже нормально запустится и будет до ротации логов нормально работать.

[snorlov]

/etc/rc.conf:#dhcpd_witpid="/var/run/dhcpd/dhcpd.pid"

/usr/local/etc/rc.d/isc-dhcpd:__dhcpd_piddir=/var/run # pid file directory /usr/local/etc/rc.d/isc-dhcpd:pidfile=${_dhcpd_pidfile} /usr/local/etc/monitrc: check process dhcpd with pidfile /var/run/dhcpd.pid /usr/local/etc/webmin/dhcpd/config:pid_file=/var/run/dhcpd.pid

А вы говорите одинаково...

[novik]

А вы говорите одинаково...

Эта строчка в /etc/rc.conf является комментарием потому что начинается с символа #
#dhcpd_witpid="/var/run/dhcpd/dhcpd.pid"
и не обрабатывается операционной системой.
Осталась от прошлой конфигурации, когда isc-dhcp44-server был с опцией paranoia и работал от пользователя dhcpd.

[snorlov]

строчку ротации лога из newsyslog.conf приведите, может имеет смысл там указать dhcpd в качестве учетной записи да и pid файл тоже...

[novik]

строчку ротации лога из newsyslog.conf приведите

/etc/newsyslog.conf:/var/log/dhcpd.log root:wheel 644 5 1 *JC /var/run/dhcpd.pid

может имеет смысл там указать dhcpd в качестве учетной записи

Сервер собранный без опции paranoia не может работать иначе чем от root. Он больше не понимает опций -user и -group. man dhcpd:

Код: Выделить всё

-user user
              Setuid  to  user after completing privileged operations, such as
              creating sockets that listen on  privileged  ports.   This  also
              causes  the lease file to be owned by user.  This option is only
              available if the code  was  compiled  with  the  PARANOIA  patch
              (./configure --enable-paranoia).

       -group group
              Setgid  to group after completing privileged operations, such as
              creating sockets that listen on  privileged  ports.   This  also
              causes  the lease file to use group.  This option is only avail-
              able if the code was compiled with the PARANOIA patch (./config-
              ure --enable-paranoia).

Я конечно попробовал dhcpd:dhcpd в /etc/newsyslog.conf но это к сожалению ничего не изменило.

да и pid файл тоже...

Он там указан.

Если указать в /etc/newsyslog.conf флаг N то после ротации isc-dhcp44-server работает, но в новый лог файл (вполне ожидаемо) ничего не пишет.

[Alex Keda]

принудительно перезапускайте, при ротации.
кастыль, конечно, но вам шашечки - или ехать?

[novik]

принудительно перезапускайте, при ротации.

Пока так и делаю.

Прикрутил monit который его перезапускает

но вам шашечки - или ехать?

Мне всё вместе. А главное понять ситуацию...
У всех это работает, а я такой «Кривая рука»? Или это сломано в порте, но всем пофиг? (novik ты с ума сошёл? Кому вообще нужны эти dhcpd логи? )

[snorlov]

Было у меня подобное, вот только давно, я тогда где-то прописывал нахождение pid файла ну и заодно расположение dhcp.leases в /var/db/dhcpd...

[novik]

Прекращение работы isc-dhcp44-server-4.4.1_3 при ротации лога происходит вот по этой вот причине.
https://www.freebsd.org/doc/ru_RU.KOI8- ... -dhcp.html

посылка сигнала SIGHUP приложению dhcpd не приведёт к перезагрузке настроек, как это бывает для большинства даемонов

[novik]

Рабочий метод избавления от этой напасти - убрать /var/run/dhcpd/dhcpd.pid из /etc/newsyslog.conf!!!