Entware ISC DHCP Server 4.4.1 стартует через раз.

[dexter]

Не могу понять в чем дело. На Кинетик Ультра поднят ISC DHCP Server 4.4.1. В итоге при перезагрузке он может стартануть нормально, а может не запуститься с такой ошибкой 

Скрытый текст

 

[I] Jun  8 12:51:45 root: Started dhcpd from .
[I] Jun  8 12:51:46 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
[I] Jun  8 12:51:46 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
[I] Jun  8 12:51:46 dhcpd: All rights reserved.
[I] Jun  8 12:51:46 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
[I] Jun  8 12:51:46 ndm: Http::Manager: new Web server configuration was applied.
[I] Jun  8 12:51:46 root: Started openvpn from .
[I] Jun  8 12:51:46 dhcpd: Config file: /opt/etc/dhcpd.conf
[I] Jun  8 12:51:46 dhcpd: Database file: /opt/var/db/dhcpd.leases
[I] Jun  8 12:51:46 dhcpd: PID file: /opt/var/run/dhcpd.pid
[I] Jun  8 12:51:46 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
[I] Jun  8 12:51:46 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
[I] Jun  8 12:51:46 dhcpd: All rights reserved.
[I] Jun  8 12:51:46 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
[E] Jun  8 12:51:46 dhcpd: There's already a DHCP server running.
[E] Jun  8 12:51:46 dhcpd: 
[E] Jun  8 12:51:46 dhcpd: If you think you have received this message due to a bug rather
[W] Jun  8 12:51:46 openvpn[464]: WARNING: file 'keys/bikovo.key' is group or others accessible
[W] Jun  8 12:51:46 openvpn[464]: WARNING: file 'keys/ta.key' is group or others accessible
[I] Jun  8 12:51:46 openvpn[464]: OpenVPN 2.4.5 mipsel-openwrt-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD]
[I] Jun  8 12:51:46 openvpn[464]: library versions: OpenSSL 1.0.2o  27 Mar 2018, LZO 2.10
[E] Jun  8 12:51:46 dhcpd: than a configuration issue please read the section on submitting
[E] Jun  8 12:51:46 dhcpd: bugs on either our web page at www.isc.org or in the README file
[E] Jun  8 12:51:46 dhcpd: before submitting a bug.  These pages explain the proper
[E] Jun  8 12:51:46 dhcpd: process and the information we find helpful for debugging.
[E] Jun  8 12:51:46 dhcpd: 
[E] Jun  8 12:51:46 dhcpd: exiting.

Тут я вижу "[E] Jun  8 12:51:46 dhcpd: There's already a DHCP server running.", но он не запущен выше по логу загрузки нет процесса "dhcpd".

Вот кусок лога нормальной загрузки

Скрытый текст

[I] Jun  8 12:51:46 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
[I] Jun  8 12:51:46 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
[I] Jun  8 12:51:46 dhcpd: All rights reserved.
[I] Jun  8 12:51:46 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
[I] Jun  8 12:51:46 root: Started openvpn from .
[I] Jun  8 12:51:46 dhcpd: Config file: /opt/etc/dhcpd.conf
[I] Jun  8 12:51:46 dhcpd: Database file: /opt/var/db/dhcpd.leases
[I] Jun  8 12:51:46 dhcpd: PID file: /opt/var/run/dhcpd.pid
[I] Jun  8 12:51:46 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
[I] Jun  8 12:51:46 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
[I] Jun  8 12:51:46 dhcpd: All rights reserved.
[I] Jun  8 12:51:46 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
[I] Jun  8 12:51:46 dhcpd: Wrote 0 class decls to leases file.
[I] Jun  8 12:51:46 dhcpd: Wrote 0 deleted host decls to leases file.
[I] Jun  8 12:51:46 dhcpd: Wrote 0 new dynamic host decls to leases file.
[I] Jun  8 12:51:46 dhcpd: Wrote 18 leases to leases file.
[I] Jun  8 12:51:46 root: Started snmpd from .
[I] Jun  8 12:51:46 dhcpd: Listening on LPF/br2/ec:43:f6:72:a4:18/L2-VLAN-253
[I] Jun  8 12:51:46 dhcpd: Sending on   LPF/br2/ec:43:f6:72:a4:18/L2-VLAN-253
[I] Jun  8 12:51:46 dhcpd: Listening on LPF/br1/ec:43:f6:72:a4:18/VLAN-33-Guest
[I] Jun  8 12:51:46 dhcpd: Sending on   LPF/br1/ec:43:f6:72:a4:18/VLAN-33-Guest
[W] Jun  8 12:51:46 openvpn[461]: WARNING: file 'keys/bikovo.key' is group or others accessible
[W] Jun  8 12:51:46 openvpn[461]: WARNING: file 'keys/ta.key' is group or others accessible
[I] Jun  8 12:51:46 openvpn[461]: OpenVPN 2.4.5 mipsel-openwrt-linux-gnu [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD]
[I] Jun  8 12:51:46 openvpn[461]: library versions: OpenSSL 1.0.2o  27 Mar 2018, LZO 2.10
[I] Jun  8 12:51:46 dhcpd: Listening on LPF/eth2.32/ec:43:f6:72:a4:32/VLAN-32
[I] Jun  8 12:51:46 dhcpd: Sending on   LPF/eth2.32/ec:43:f6:72:a4:32/VLAN-32
[I] Jun  8 12:51:46 dhcpd: Listening on LPF/eth2.31/ec:43:f6:72:a4:31/VLAN-31
[I] Jun  8 12:51:46 dhcpd: Sending on   LPF/eth2.31/ec:43:f6:72:a4:31/VLAN-31
[I] Jun  8 12:51:46 dhcpd: Listening on LPF/br0/ec:43:f6:72:a4:18/home
[I] Jun  8 12:51:46 dhcpd: Sending on   LPF/br0/ec:43:f6:72:a4:18/home
[I] Jun  8 12:51:46 dhcpd: Sending on   Socket/fallback/fallback-net
[I] Jun  8 12:51:46 dhcpd: Server starting service.

 

Я уже и "sleep" скрипт вставлял, но это не помогает.

При этом руками после этого запускается без всяких ошибок  предупреждений.

В итоге пришлось написать скрипт проверку и повесить его на cron, но это не правильно. т.к. должно работать без всяких костылей.

Ниже прилагаю 2 лог файла, может кто заметит то, что я упустил.

 

log.txt

log (1).txt

[vasek00]

42 минуты назад, dexter сказал:

Не могу понять в чем дело. На Кинетик Ультра поднят ISC DHCP Server 4.4.1. В итоге при перезагрузке он может стартануть нормально, а может не запуститься с такой ошибкой 

Тут я вижу "[E] Jun  8 12:51:46 dhcpd: There's already a DHCP server running.", но он не запущен выше по логу загрузки нет процесса "dhcpd".

Для начала в скрипте запуска поставьте проверку pid файла на его наличие в данном каталоге

/opt/var/run/dhcpd.pid

если он есть то удалить и поставить что-то например

PIDFILE="/opt/var/run/dhcpd.pid"
if [ -f $PIDFILE ] && [ -d /proc/`cat $PIDFILE` ] ;
    then
        kill `cat $PIDFILE`; rm -f $PIDFILE;
fi

"rm -f ...."  при необходимости

[dexter]

Я немного не могу связать этот код секции старт с кодом из файла rc.func, который запускает все процессы:

start() {
    [ "$CRITICAL" != "yes" -a "$CALLER" = "cron" ] && return 7
        [ "$ENABLED" != "yes" ] && return 8
    echo -e -n "$ansi_white Starting $DESC... $ansi_std"
    if [ -n "`pidof $PROC`" ]; then
        echo -e "            $ansi_yellow already running. $ansi_std"
        return 0
    fi
    $PRECMD > /dev/null 2>&1
    $PREARGS $PROC $ARGS > /dev/null 2>&1 &
    #echo $PREARGS $PROC $ARGS.
    COUNTER=0
    LIMIT=10
    while [ -z "`pidof $PROC`" -a "$COUNTER" -le "$LIMIT" ]; do
        sleep 1;
        COUNTER=`expr $COUNTER + 1`
    done
    $POSTCMD > /dev/null 2>&1

    if [ -z "`pidof $PROC`" ]; then
        echo -e "            $ansi_red failed. $ansi_std"
        logger "Failed to start $DESC from $CALLER."
        return 255
    else
        echo -e "            $ansi_green done. $ansi_std"
        logger "Started $DESC from $CALLER."
        return 0
    fi
}

 

[Александр Рыжов]

Подождите, разве он должен стартовать при наличии дублирующего прошивочного сервиса?

Напомню, что по opkg dns-override отключается DNS-проксик, а не DHCP-сервер.

[dexter]

Он стартует через раз и если не стартовал то отлично запускается руками без лишних манипуляций.

[Александр Рыжов]

Чего вы хотите добиться одновременной работой двух DHCP-служб?

[E] Jun  8 12:51:46 dhcpd: There's already a DHCP server running.

И почему считаете процитированное поведение ISC dhcpd неправильным? Он при запуске обнаружил работающего конкурента из состава прошивки и грациозно уступил ему руль.

[dexter]

Тогда вопрос, куда девается прошивочный сервер, который не использую? Если руками потом отлично запускается из entware.

[Александр Рыжов]

35 минут назад, dexter сказал:

Тогда вопрос, куда девается прошивочный сервер, который не использую?

Отличный вопрос:)) Разумеется, никуда он не девается, если вы его не отключали принудительно на нужном сегменте. Работает с прежней пролетарской яростью.

[dexter]

У меня не к одному интерфейсу не прибиндин пул прошивочного DHCP, хотя в конфиге строчки были. Сейчас их удалил, понаблюдаю, что будет дальше.