Hello Derek,
luckily it is a test server. There is almost nothing going on... Only
one iPhone, unregistered.
dmesg shows nothing particular since booting:
[Mon Apr 25 03:17:42 2016] eth0: no IPv6 routers present
[Mon Apr 25 03:17:42 2016] vboxsf: Successfully loaded version 5.0.16
(interface 0x00010004)
[Mon Apr 25 03:17:42 2016] eth1: no IPv6 routers present
[Mon Apr 25 03:17:59 2016] Netfilter messages via NETLINK v0.30.
[Mon Apr 25 03:17:59 2016] ip_set: protocol 6
[Mon Apr 25 03:18:04 2016] ip_tables: (C) 2000-2006 Netfilter Core Team
[Mon Apr 25 03:18:51 2016] nf_conntrack version 0.5.0 (16384 buckets,
65536 max)
[Mon Apr 25 03:19:14 2016] device eth1 entered promiscuous mode
[Mon Apr 25 03:19:17 2016] device eth0 entered promiscuous mode
The jump in CPU load was at appox. Apr 25 19:15. Packetfence and message
log is attached.
Count(ip) from iplog_history is 219.
How can I strace the dhcpd in a meaningful manor?
Cheers,
Till
On 04/25/2016 08:59 PM, Derek Wuelfrath wrote:
> Hello Till,
>
> Is there any “evidence” of what could be happening either in
> /var/log/messages (or syslog), dmesg ?
> Any way you can strace the dhcpd process and see if you can find something ?
>
> Is the PacketFence server in production or only in tests ? Any large
> network(s) provisionned by the PacketFence dhcpd service ? (registration,
> isolation)
>
> Any “evident” errors / warnings in the packetfence.log ?
>
> What is the result of select count(ip) from iplog_history on the PacketFence
> database ?
>
> Cheers!
> -dw.
>
> —
> Derek Wuelfrath
> dwuelfr...@inverse.ca :: +1.514.447.4918 (x110) :: +1.866.353.6153 (x110)
> Inverse inc. :: Leaders behind SOGo (www.sogo.nu) and PacketFence
> (www.packetfence.org)
>
>> On Apr 25, 2016, at 14:51, Till Wimmer <t.wim...@tonarchiv.ch> wrote:
>>
>> Hello Derek,
>>
>> sadly, it was not the timezone setting. After runing for almost a day,
>> the problem did come back. Average sytem load is 1.34, Dhcpd is running
>> 100% of one CPU.
>>
>> Cheers,
>> Till
>>
>> On 04/25/2016 03:17 PM, Derek Wuelfrath wrote:
>>> Hello Till,
>>>
>>>> But meanwhile I think that I have found the real reason: The timezone in
>>>> PF was set wrong.
>>>> So maybe there was some confusion with the lease times because of this.
>>>> Now, after setting it right, the lease file also looks fine to me.
>>> Have you reenable OMAPI after changing the timezone ? :)
>>>
>>> It seems a bit strange to me since [general.timezone] PacketFence
>>> configuration parameter is not really used in “that workflow”.
>>>
>>> Cheers!
>>> -dw.
>>>
>>> —
>>> Derek Wuelfrath
>>> dwuelfr...@inverse.ca :: +1.514.447.4918 (x110) :: +1.866.353.6153 (x110)
>>> Inverse inc. :: Leaders behind SOGo (www.sogo.nu) and PacketFence
>>> (www.packetfence.org)
>>>
>>>> On Apr 25, 2016, at 05:16, g4-l...@tonarchiv.ch wrote:
>>>>
>>>> Hi MJ,
>>>>
>>>> yes, diasbling OMAPI solved the issue...
>>>>
>>>> But meanwhile I think that I have found the real reason: The timezone in
>>>> PF was set wrong.
>>>> So maybe there was some confusion with the lease times because of this.
>>>> Now, after setting it right, the lease file also looks fine to me.
>>>>
>>>> Cheers,
>>>> Till
>>>>
>>>> On 04/24/2016 10:41 PM, mj wrote:
>>>>> try disabling omapi for dhcp.
>>>>>
>>>>> MJ
>>>>>
>>>>> On 04/24/2016 08:12 PM, g4-l...@tonarchiv.ch wrote:
>>>>>> Hi there!
>>>>>>
>>>>>> I set up a PF 6 in inline configuration for testing on Debian 7.
>>>>>> There is nothing going on on the WLAN - no registered nodes yet.
>>>>>> But after a few hours dhcpd took 100% of one CPU. After restarting dhcpd
>>>>>> the load went back to normal. Then, a few hours later, the same story...
>>>>>>
>>>>>> I had a look in the leases file... and there something looks strange to
>>>>>> me. There are overlapping leases:
>>>>>>
>>>>>> lease 192.168.3.10 {
>>>>>> starts 0 2016/04/24 17:26:23;
>>>>>> ends 1 2016/04/25 17:26:23;
>>>>>> cltt 0 2016/04/24 17:26:23;
>>>>>> binding state active;
>>>>>> next binding state free;
>>>>>> rewind binding state free;
>>>>>> hardware ethernet e0:f8:47:aa:bb:cc;
>>>>>> uid "\001\340\370G\212\0112";
>>>>>> client-hostname "iPhone";
>>>>>> }
>>>>>> server-duid "\000\001\000\001\036\254\321$\010\000'\377ds";
>>>>>>
>>>>>> lease 192.168.3.10 {
>>>>>> starts 0 2016/04/24 17:41:23;
>>>>>> ends 1 2016/04/25 17:41:23;
>>>>>> cltt 0 2016/04/24 17:41:23;
>>>>>> binding state active;
>>>>>> next binding state free;
>>>>>> rewind binding state free;
>>>>>> hardware ethernet e0:f8:47:aa:bb:cc;
>>>>>> uid "\001\340\370G\212\0112";
>>>>>> client-hostname "iPhone";
>>>>>> }
>>>>>> lease 192.168.3.10 {
>>>>>> starts 0 2016/04/24 17:56:19;
>>>>>> ends 1 2016/04/25 17:56:19;
>>>>>> cltt 0 2016/04/24 17:56:19;
>>>>>> binding state active;
>>>>>> next binding state free;
>>>>>> rewind binding state free;
>>>>>> hardware ethernet e0:f8:47:aa:bb:cc;
>>>>>> uid "\001\340\370G\212\0112";
>>>>>> client-hostname "iPhone";
>>>>>> }
>>>>>>
>>>>>> Is there maybe a bug in the PF OMAPI part?
>>>>>>
>>>>>> Cheers,
>>>>>> Till
>>
>> ------------------------------------------------------------------------------
>> Find and fix application performance issues faster with Applications Manager
>> Applications Manager provides deep performance insights into multiple tiers
>> of
>> your business applications. It resolves application problems quickly and
>> reduces your MTTR. Get your free trial!
>> https://ad.doubleclick.net/ddm/clk/302982198;130105516;z
>> _______________________________________________
>> PacketFence-users mailing list
>> PacketFence-users@lists.sourceforge.net
>> https://lists.sourceforge.net/lists/listinfo/packetfence-users
>
> ------------------------------------------------------------------------------
> Find and fix application performance issues faster with Applications Manager
> Applications Manager provides deep performance insights into multiple tiers of
> your business applications. It resolves application problems quickly and
> reduces your MTTR. Get your free trial!
> https://ad.doubleclick.net/ddm/clk/302982198;130105516;z
> _______________________________________________
> PacketFence-users mailing list
> PacketFence-users@lists.sourceforge.net
> https://lists.sourceforge.net/lists/listinfo/packetfence-users
Apr 25 03:17:39 packetfence kernel: [ 35.134205] ADDRCONF(NETDEV_CHANGE):
eth1: link becomes ready
Apr 25 03:17:39 packetfence kernel: [ 36.311373] RPC: Registered named UNIX
socket transport module.
Apr 25 03:17:39 packetfence kernel: [ 36.311376] RPC: Registered udp
transport module.
Apr 25 03:17:39 packetfence kernel: [ 36.311377] RPC: Registered tcp
transport module.
Apr 25 03:17:39 packetfence kernel: [ 36.311378] RPC: Registered tcp NFSv4.1
backchannel transport module.
Apr 25 03:17:39 packetfence kernel: [ 36.376980] FS-Cache: Loaded
Apr 25 03:17:39 packetfence kernel: [ 36.593677] FS-Cache: Netfs 'nfs'
registered for caching
Apr 25 03:17:39 packetfence kernel: [ 36.619694] Installing knfsd (copyright
(C) 1996 o...@monad.swb.de).
Apr 25 03:17:39 packetfence kernel: [ 41.162866] VBoxService 5.0.16 r105871
(verbosity: 0) linux.amd64 (Mar 4 2016 17:38:55) release log
Apr 25 03:17:39 packetfence kernel: [ 41.162868] 00:00:00.009269 main Log
opened 2016-04-25T01:17:38.894239000Z
Apr 25 03:17:39 packetfence kernel: [ 41.177085] 00:00:00.023831 main OS
Product: Linux
Apr 25 03:17:39 packetfence kernel: [ 41.177270] 00:00:00.024042 main OS
Release: 3.2.0-4-amd64
Apr 25 03:17:39 packetfence kernel: [ 41.177469] 00:00:00.024230 main OS
Version: #1 SMP Debian 3.2.78-1
Apr 25 03:17:39 packetfence kernel: [ 41.178424] 00:00:00.025187 main
Executable: /opt/VBoxGuestAdditions-5.0.16/sbin/VBoxService
Apr 25 03:17:39 packetfence kernel: [ 41.178425] 00:00:00.025189 main
Process ID: 2107
Apr 25 03:17:39 packetfence kernel: [ 41.178426] 00:00:00.025189 main
Package type: LINUX_64BITS_GENERIC
Apr 25 03:17:39 packetfence kernel: [ 41.185465] 00:00:00.032245 main
5.0.16 r105871 started. Verbose level = 0
Apr 25 03:17:39 packetfence kernel: [ 41.799777] 00:00:00.646208 vminfo
Error: Unable to connect to system D-Bus (1/3): Failed to connect to socket
/var/run/dbus/system_bus_socket: No such file or directory
Apr 25 03:18:00 packetfence kernel: [ 62.464200] Netfilter messages via
NETLINK v0.30.
Apr 25 03:18:00 packetfence kernel: [ 62.486521] ip_set: protocol 6
Apr 25 03:18:05 packetfence kernel: [ 67.914690] ip_tables: (C) 2000-2006
Netfilter Core Team
Apr 25 03:18:52 packetfence kernel: [ 114.461310] nf_conntrack version 0.5.0
(16384 buckets, 65536 max)
Apr 25 03:19:14 packetfence kernel: [ 137.040141] device eth1 entered
promiscuous mode
Apr 25 03:19:18 packetfence kernel: [ 140.560117] device eth0 entered
promiscuous mode
Apr 25 03:19:30 packetfence dhcpd: Internet Systems Consortium DHCP Server 4.2.2
Apr 25 03:19:30 packetfence dhcpd: Copyright 2004-2011 Internet Systems
Consortium.
Apr 25 03:19:30 packetfence dhcpd: All rights reserved.
Apr 25 03:19:30 packetfence dhcpd: For info, please visit
https://www.isc.org/software/dhcp/
Apr 25 03:19:30 packetfence dhcpd: Wrote 0 group decls to leases file.
Apr 25 03:19:30 packetfence dhcpd: Wrote 1 leases to leases file.
Apr 25 06:25:04 packetfence rsyslogd: [origin software="rsyslogd"
swVersion="5.8.11" x-pid="2125" x-info="http://www.rsyslog.com";] rsyslogd was
HUPed
Apr 25 10:52:09 packetfence dhcpd: Wrote 0 group decls to leases file.
Apr 25 10:52:09 packetfence dhcpd: Wrote 1 leases to leases file.
Apr 25 10:52:09 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 10:52:09 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 14:50:00 packetfence dhcpd: Wrote 0 group decls to leases file.
Apr 25 14:50:00 packetfence dhcpd: Wrote 1 leases to leases file.
Apr 25 14:50:00 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 14:50:00 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 15:26:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 15:26:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 15:37:44 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 15:37:44 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 18:05:21 packetfence dhcpd: DHCPDISCOVER from e0:f8:47:8a:09:32 (iPhone)
via eth1
Apr 25 18:05:23 packetfence dhcpd: DHCPOFFER on 192.168.3.10 to
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 18:05:23 packetfence dhcpd: DHCPDISCOVER from e0:f8:47:8a:09:32 (iPhone)
via eth1
Apr 25 18:05:23 packetfence dhcpd: DHCPOFFER on 192.168.3.10 to
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 18:05:24 packetfence dhcpd: Wrote 0 group decls to leases file.
Apr 25 18:05:24 packetfence dhcpd: Wrote 1 leases to leases file.
Apr 25 18:05:24 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 (192.168.3.1)
from e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 18:05:24 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 18:11:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 18:11:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:04:51 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:04:51 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:20:10 packetfence dhcpd: Wrote 0 group decls to leases file.
Apr 25 19:20:10 packetfence dhcpd: Wrote 1 leases to leases file.
Apr 25 19:20:10 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:20:10 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:26:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:26:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:34:35 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:34:35 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:41:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:41:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:49:34 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:49:34 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 19:56:40 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 19:56:40 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:04:34 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:04:34 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:11:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:11:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:19:34 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:19:34 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:26:39 packetfence dhcpd: Wrote 0 group decls to leases file.
Apr 25 20:26:39 packetfence dhcpd: Wrote 1 leases to leases file.
Apr 25 20:26:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:26:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:34:34 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:34:34 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:41:39 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:41:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 20:49:34 packetfence dhcpd: DHCPREQUEST for 192.168.3.10 from
e0:f8:47:8a:09:32 (iPhone) via eth1
Apr 25 20:49:34 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 21:26:39 packetfence dhcpd: DHCPACK on 192.168.3.10 to e0:f8:47:8a:09:32
(iPhone) via eth1
Apr 25 14:18:56 httpd.portal(3785) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 14:18:56 httpd.portal(3554) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 14:19:13 httpd.portal(3552) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 14:19:13 httpd.portal(3553) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 14:19:46 httpd.portal(3556) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 14:19:46 httpd.portal(3552) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 19:20:35 httpd.portal(4589) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: verifying process 3561 (pf::services::manager::removeStalePid)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: Sending TERM signal to dhcpd with pid 3561 (pf::services::manager::stopService)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: verifying process 3561 (pf::services::manager::removeStalePid)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: verifying process 3561 (pf::services::manager::removeStalePid)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: pidof -x dhcpd returned 3561 (pf::services::manager::pidFromFile)
Apr 25 20:51:50 pfcmd.pl(4915) INFO: removing stale pid file /usr/local/pf/var/run/dhcpd.pid (pf::services::manager::removeStalePid)
Apr 25 20:51:51 pfcmd.pl(4915) INFO: pidof -x httpd.admin returned 3397 (pf::services::manager::pidFromFile)
Apr 25 20:51:51 pfcmd.pl(4915) INFO: verifying process 3397 (pf::services::manager::removeStalePid)
Apr 25 20:51:51 pfcmd.pl(4915) INFO: pidof -x httpd.admin returned 3397 (pf::services::manager::pidFromFile)
Apr 25 20:51:51 pfcmd.pl(4915) INFO: pidof -x httpd.admin returned 3397 (pf::services::manager::pidFromFile)
Apr 25 20:51:51 pfcmd.pl(4915) INFO: Instantiate profile default (pf::Portal::ProfileFactory::_from_profile)
Apr 25 20:52:01 pfcmd.pl(4915) INFO: pf::services::manager, /usr/local/pf/lib/pf/services/manager.pm, 199 (pf::services::manager::dhcpd::generateConfig)
Apr 25 20:52:02 pfcmd.pl(4915) INFO: Daemon dhcpd took 0.553 seconds to start. (pf::services::manager::launchService)
Apr 25 21:12:20 httpd.portal(4692) INFO: [mac:[undef]] Instantiate a new iptables modification method. pf::ipset (pf::inline::get_technique)
------------------------------------------------------------------------------
Find and fix application performance issues faster with Applications Manager
Applications Manager provides deep performance insights into multiple tiers of
your business applications. It resolves application problems quickly and
reduces your MTTR. Get your free trial!
https://ad.doubleclick.net/ddm/clk/302982198;130105516;z
_______________________________________________
PacketFence-users mailing list
PacketFence-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/packetfence-users
