Apinger: sendto: Operation not permitted
-
Appears what started that is a WAN renewal or reconnection on DSL. I presume that interface is DHCP judging by the DSL_DHCP gateway? If so, what do your dhclient logs show from that time? Status>System logs, DHCP tab, anything with dhclient in the log.
-
its pppoe dhcp
only thing out of that log that doesnt look lan relatedNov 19 18:00:37 dhclient[20383]: accepting packet with data after udp payload.
Nov 19 18:00:37 dhclient[20383]: ip length 332 disagrees with bytes received 350. -
What kind of system is that, hardware, NICs?
-
windstream adsl modem in bridge mode with pfsense doing the ppp connection pfsense is a 4 port intel nic
-
On the bare metal, not in a VM of some sort?
-
yes on bare metal asus atom board atom d525
Copyright
1992-2014 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 10.1-RELEASE #0 29f4af5(releng/10.1)-dirty: Tue Nov 18 06:22:48 CST 2014
root@pfsense-22-amd64-builder:/usr/obj.amd64/usr/pfSensesrc/src/sys/pfSense_SMP.10 amd64
FreeBSD clang version 3.4.1 (tags/RELEASE_34/dot1-final 208032) 20140512
CPU: Intel(R) Atom(TM) CPU D525 @ 1.80GHz (1800.03-MHz K8-class CPU)
Origin = "GenuineIntel" Id = 0x106ca Family = 0x6 Model = 0x1c Stepping = 10
Features=0xbfebfbff <fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,htt,tm,pbe>Features2=0x40e31d <sse3,dtes64,mon,ds_cpl,tm2,ssse3,cx16,xtpr,pdcm,movbe>AMD Features=0x20100800 <syscall,nx,lm>AMD Features2=0x1 <lahf>TSC: P-state invariant, performance statistics
real memory = 4294967296 (4096 MB)
avail memory = 4088594432 (3899 MB)
Event timer "LAPIC" quality 400
ACPI APIC Table: <082212 APIC1603>
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 2 core(s)
cpu0 (BSP): APIC ID: 0
cpu1 (AP): APIC ID: 2
ioapic0 <version 2.0="">irqs 0-23 on motherboard
wlan: mac acl policy registered
ipw_bss: You need to read the LICENSE file in /usr/share/doc/legal/intel_ipw/.
ipw_bss: If you agree with the license, set legal.intel_ipw.license_ack=1 in /boot/loader.conf.
module_register_init: MOD_LOAD (ipw_bss_fw, 0xffffffff80606680, 0) error 1
ipw_ibss: You need to read the LICENSE file in /usr/share/doc/legal/intel_ipw/.
ipw_ibss: If you agree with the license, set legal.intel_ipw.license_ack=1 in /boot/loader.conf.
module_register_init: MOD_LOAD (ipw_ibss_fw, 0xffffffff80606730, 0) error 1
ipw_monitor: You need to read the LICENSE file in /usr/share/doc/legal/intel_ipw/.
ipw_monitor: If you agree with the license, set legal.intel_ipw.license_ack=1 in /boot/loader.conf.
module_register_init: MOD_LOAD (ipw_monitor_fw, 0xffffffff806067e0, 0) error 1
iwi_bss: You need to read the LICENSE file in /usr/share/doc/legal/intel_iwi/.
iwi_bss: If you agree with the license, set legal.intel_iwi.license_ack=1 in /boot/loader.conf.
module_register_init: MOD_LOAD (iwi_bss_fw, 0xffffffff8062de50, 0) error 1
iwi_ibss: You need to read the LICENSE file in /usr/share/doc/legal/intel_iwi/.
iwi_ibss: If you agree with the license, set legal.intel_iwi.license_ack=1 in /boot/loader.conf.
module_register_init: MOD_LOAD (iwi_ibss_fw, 0xffffffff8062df00, 0) error 1
iwi_monitor: You need to read the LICENSE file in /usr/share/doc/legal/intel_iwi/.
iwi_monitor: If you agree with the license, set legal.intel_iwi.license_ack=1 in /boot/loader.conf.
module_register_init: MOD_LOAD (iwi_monitor_fw, 0xffffffff8062dfb0, 0) error 1
random: <software, yarrow="">initialized
kbd1 at kbdmux0
cryptosoft0: <software crypto="">on motherboard
padlock0: No ACE support.
acpi0: <082212 XSDT1603> on motherboard
acpi0: Power Button (fixed)
acpi0: reservation of fee00000, 1000 (3) failed
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, bf600000 (3) failed
cpu0: <acpi cpu="">on acpi0
cpu1: <acpi cpu="">on acpi0
attimer0: <at timer="">port 0x40-0x43 irq 0 on acpi0
Timecounter "i8254" frequency 1193182 Hz quality 0
Event timer "i8254" frequency 1193182 Hz quality 100
atrtc0: <at realtime="" clock="">port 0x70-0x71 irq 8 on acpi0
Event timer "RTC" frequency 32768 Hz quality 0
hpet0: <high precision="" event="" timer="">iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 14318180 Hz quality 950
Event timer "HPET" frequency 14318180 Hz quality 450
Event timer "HPET1" frequency 14318180 Hz quality 440
Event timer "HPET2" frequency 14318180 Hz quality 440
Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0
pcib0: <acpi host-pci="" bridge="">port 0xcf8-0xcff on acpi0
pci0: <acpi pci="" bus="">on pcib0
vgapci0: <vga-compatible display="">port 0xb080-0xb087 mem 0xfbb80000-0xfbbfffff,0xd0000000-0xdfffffff,0xfba00000-0xfbafffff irq 16 at device 2.0 on pci0
agp0: <intel pineview="" svga="" controller="">on vgapci0
agp0: aperture size is 256M, detected 8188k stolen memory
vgapci0: Boot video device
vgapci1: <vga-compatible display="">mem 0xfbc00000-0xfbc7ffff at device 2.1 on pci0
pcib1: <acpi pci-pci="" bridge="">irq 16 at device 28.0 on pci0
pci3: <acpi pci="" bus="">on pcib1
pcib2: <pci-pci bridge="">at device 0.0 on pci3
pci4: <pci bus="">on pcib2
pcib3: <pci-pci bridge="">at device 0.0 on pci4
pci6: <pci bus="">on pcib3
em0: <intel(r) 1000="" pro="" network="" connection="" 7.4.2="">port 0xe880-0xe89f mem 0xfbf80000-0xfbf9ffff,0xfbf60000-0xfbf7ffff irq 16 at device 0.0 on pci6
em0: Using an MSI interrupt
em1: <intel(r) 1000="" pro="" network="" connection="" 7.4.2="">port 0xec00-0xec1f mem 0xfbfe0000-0xfbffffff,0xfbfc0000-0xfbfdffff irq 17 at device 0.1 on pci6
em1: Using an MSI interrupt
pcib4: <pci-pci bridge="">at device 1.0 on pci4
pci5: <pci bus="">on pcib4
em2: <intel(r) 1000="" pro="" network="" connection="" 7.4.2="">port 0xd880-0xd89f mem 0xfbe80000-0xfbe9ffff,0xfbe60000-0xfbe7ffff irq 17 at device 0.0 on pci5
em2: Using an MSI interrupt
em3: <intel(r) 1000="" pro="" network="" connection="" 7.4.2="">port 0xdc00-0xdc1f mem 0xfbee0000-0xfbefffff,0xfbec0000-0xfbedffff irq 18 at device 0.1 on pci5
em3: Using an MSI interrupt
pcib5: <acpi pci-pci="" bridge="">irq 19 at device 28.3 on pci0
pci2: <acpi pci="" bus="">on pcib5
atapci0: <jmicron jmb362="" sata300="" controller="">port 0xcc00-0xcc07,0xc880-0xc883,0xc800-0xc807,0xc480-0xc483,0xc400-0xc40f mem 0xfbdffc00-0xfbdffdff irq 19 at device 0.0 on pci2
ahci0: <jmicron jmb362="" ahci="" sata="" controller="">at channel -1 on atapci0
ahci0: AHCI v1.10 with 2 3Gbps ports, Port Multiplier supported
ahcich0: <ahci channel="">at channel 0 on ahci0
ahcich1: <ahci channel="">at channel 1 on ahci0
pcib6: <acpi pci-pci="" bridge="">at device 30.0 on pci0
pci1: <acpi pci="" bus="">on pcib6
isab0: <pci-isa bridge="">at device 31.0 on pci0
isa0: <isa bus="">on isab0
ahci1: <intel ich7="" ahci="" sata="" controller="">port 0xbc00-0xbc07,0xb880-0xb883,0xb800-0xb807,0xb480-0xb483,0xb400-0xb41f mem 0xfbcffc00-0xfbcfffff irq 19 at device 31.2 on pci0
ahci1: AHCI v1.10 with 4 3Gbps ports, Port Multiplier not supported
ahcich2: <ahci channel="">at channel 0 on ahci1
ahcich3: <ahci channel="">at channel 1 on ahci1
acpi_button0: <power button="">on acpi0
atkbdc0: <keyboard controller="" (i8042)="">port 0x60,0x64 irq 1 on acpi0
atkbd0: <at keyboard="">irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (115200,n,8,1)
uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
sc0: <system console="">at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <generic isa="" vga="">at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ppc0: cannot reserve I/O port range
p4tcc0: <cpu frequency="" thermal="" control="">on cpu0
p4tcc1: <cpu frequency="" thermal="" control="">on cpu1
Timecounters tick every 1.000 msec
IPsec: Initialized Security Association Processing.
random: unblocking device.
ada0 at ahcich2 bus 0 scbus2 target 0 lun 0
ada0: <ocz-agility3 2.15="">ATA-8 SATA 3.x device
ada0: Serial Number OCZ-L916D2AWD3G98838
ada0: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
ada0: Command Queueing enabled
ada0: 57241MB (117231408 512 byte sectors: 16H 63S/T 16383C)
ada0: quirks=0x1<4K>
ada0: Previously was known as ad8
SMP: AP CPU #1 Launched!
Timecounter "TSC" frequency 1800034281 Hz quality 1000
Trying to mount root from ufs:/dev/ad8s1a [rw]…
WARNING: / was not properly dismounted</ocz-agility3></cpu></cpu></generic></system></at></keyboard></power></ahci></ahci></intel></isa></pci-isa></acpi></acpi></ahci></ahci></jmicron></jmicron></acpi></acpi></intel(r)></intel(r)></pci></pci-pci></intel(r)></intel(r)></pci></pci-pci></pci></pci-pci></acpi></acpi></vga-compatible></intel></vga-compatible></acpi></acpi></high></at></at></acpi></acpi></software></software,></version></lahf></syscall,nx,lm></sse3,dtes64,mon,ds_cpl,tm2,ssse3,cx16,xtpr,pdcm,movbe></fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,htt,tm,pbe> -
Did it come back on its own, or what'd you do to get it back?
-
no at 17 mintues I logged in to remote power switch and power cycled dsl modem
-
So power cycled the DSL modem only, everything else stayed up, and that brought everything back online?
-
Yes opt1 was fine just a power cycle of DSL modem as to make sure it was windstream having issues
-
If you just unplug the network cable from the DSL modem (leave it powered on) and plug it back in, does that have the same end result?
I think part of the issue is that "IP length disagrees", but it's not clear whether that's from the modem flaking out, or if a link cycle of the NIC is fixing it (which could be a driver issue or an issue in our code somewhere or just a problematic NIC port). That may still not determine definitively which is more likely.
Still, the "operation not permitted" from apinger probably shouldn't be happening, and that's not a situation I can replicate. Is that a system you could get me access to? If so, PM me and we can work out the details. I'd like to check it out while it's showing that behavior, if it's still somehow reachable over the Internet and the circumstance is replicable.
There is some basic protection against mail flooding, in that it generally won't send duplicate alerts, but it only checks the previously-sent alert and it's flapping in this case. Opened a ticket on that.
https://redmine.pfsense.org/issues/4031 -
The operation not permitted is because the system does nto have anymore the ip configured and apinger has not catched up with the change in its config.
-
At this point it has not happened regular enough to even guess when it might happen again. the box is in a location i don't physically access often which is why i put a remote 8 outlet power switch in to handle this offsite. the opt1 stays up and if i had direction i should be able to open it up to get you access when it happened box was extremely slow. i will try and keep current with beta versions
-
is this how this should be set for intel card pro/1000
-
@ermal:
The operation not permitted is because the system does nto have anymore the ip configured and apinger has not catched up with the change in its config.
That's what I was figuring it'd be, though it'd be nice to actually see it happen.
is this how this should be set for intel card pro/1000
That's fine. Shouldn't be necessary to disable hardware checksum offloading, but won't likely hurt anything to do so.
-
php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:15 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 16:28:15 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:13 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 16:28:13 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:12 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 16:28:12 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:10 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 16:28:10 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:09 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 16:28:09 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:07 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 16:28:07 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:06 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 16:28:06 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:04 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 16:28:04 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:03 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 16:28:03 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:01 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 16:28:01 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:28:00 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 16:28:00 php-fpm[22482]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 16:27:58 php-fpm[22482]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 16:01:54 ntopng: [HTTPserver.cpp:379] ERROR: Unable to start HTTP server (IPv4) on port 3000
Nov 24 16:29:45 apinger: Polling, timeout: 0.926s
Nov 24 16:29:45 apinger: (avg. loss: 0.0%)
Nov 24 16:29:45 apinger: (avg: 39.183ms)
Nov 24 16:29:45 apinger: #4337 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.184ms/39.502ms/391.826ms received = 4331
Nov 24 16:29:45 apinger: Polling, timeout: 0.964s
Nov 24 16:29:45 apinger: Recently lost packets: 50
Nov 24 16:29:45 apinger: sendto: Operation not permitted
Nov 24 16:29:45 apinger: Sending ping #4337 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:45 apinger: Recently lost packets: 0
Nov 24 16:29:45 apinger: Sending ping #4337 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:44 apinger: Polling, timeout: 0.961s
Nov 24 16:29:44 apinger: (avg. loss: 0.0%)
Nov 24 16:29:44 apinger: (avg: 39.214ms)
Nov 24 16:29:44 apinger: #4336 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.626ms/40.919ms/392.144ms received = 4330
Nov 24 16:29:44 apinger: Polling, timeout: 0.998s
Nov 24 16:29:44 apinger: Recently lost packets: 50
Nov 24 16:29:44 apinger: sendto: Operation not permitted
Nov 24 16:29:44 apinger: Sending ping #4336 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:44 apinger: Recently lost packets: 0
Nov 24 16:29:44 apinger: Sending ping #4336 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:43 apinger: Polling, timeout: 0.938s
Nov 24 16:29:43 apinger: (avg. loss: 0.0%)
Nov 24 16:29:43 apinger: (avg: 39.444ms)
Nov 24 16:29:43 apinger: #4335 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.728ms/38.347ms/394.437ms received = 4329
Nov 24 16:29:43 apinger: Polling, timeout: 0.977s
Nov 24 16:29:43 apinger: Recently lost packets: 50
Nov 24 16:29:43 apinger: sendto: Operation not permitted
Nov 24 16:29:43 apinger: Sending ping #4335 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:43 apinger: Recently lost packets: 0
Nov 24 16:29:43 apinger: Sending ping #4335 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:43 apinger: Polling, timeout: 0.000s
Nov 24 16:29:43 apinger: Polling, timeout: 0.000s
Nov 24 16:29:42 apinger: Polling, timeout: 0.961s
Nov 24 16:29:42 apinger: (avg. loss: 0.0%)
Nov 24 16:29:42 apinger: (avg: 39.406ms)
Nov 24 16:29:42 apinger: #4334 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.499ms/42.217ms/394.056ms received = 4328
Nov 24 16:29:42 apinger: Polling, timeout: 0.999s
Nov 24 16:29:42 apinger: Recently lost packets: 50
Nov 24 16:29:42 apinger: sendto: Operation not permitted
Nov 24 16:29:42 apinger: Sending ping #4334 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:42 apinger: Recently lost packets: 0
Nov 24 16:29:42 apinger: Sending ping #4334 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:42 apinger: Polling, timeout: 0.000s
Nov 24 16:29:41 apinger: Polling, timeout: 0.959s
Nov 24 16:29:41 apinger: (avg. loss: 0.0%)
Nov 24 16:29:41 apinger: (avg: 39.777ms)
Nov 24 16:29:41 apinger: #4333 from CABLEMODEM_DHCP(8.8.8.8) delay: 40.363ms/38.496ms/397.774ms received = 4327
Nov 24 16:29:41 apinger: Polling, timeout: 0.999s
Nov 24 16:29:41 apinger: Recently lost packets: 50
Nov 24 16:29:41 apinger: sendto: Operation not permitted
Nov 24 16:29:41 apinger: Sending ping #4333 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:41 apinger: Recently lost packets: 0
Nov 24 16:29:41 apinger: Sending ping #4333 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:40 apinger: Polling, timeout: 0.961s
Nov 24 16:29:40 apinger: (avg. loss: 0.0%)
Nov 24 16:29:40 apinger: (avg: 39.591ms)
Nov 24 16:29:40 apinger: #4332 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.342ms/42.885ms/395.907ms received = 4326
Nov 24 16:29:40 apinger: Polling, timeout: 0.999s
Nov 24 16:29:40 apinger: Recently lost packets: 50
Nov 24 16:29:40 apinger: sendto: Operation not permitted
Nov 24 16:29:40 apinger: Sending ping #4332 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:40 apinger: Recently lost packets: 0
Nov 24 16:29:40 apinger: Sending ping #4332 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:39 apinger: Polling, timeout: 0.960s
Nov 24 16:29:39 apinger: (avg. loss: 0.0%)
Nov 24 16:29:39 apinger: (avg: 40.045ms)
Nov 24 16:29:39 apinger: #4331 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.474ms/39.133ms/400.450ms received = 4325
Nov 24 16:29:39 apinger: Polling, timeout: 0.999s
Nov 24 16:29:39 apinger: Recently lost packets: 50
Nov 24 16:29:39 apinger: sendto: Operation not permitted
Nov 24 16:29:39 apinger: Sending ping #4331 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:39 apinger: Recently lost packets: 0
Nov 24 16:29:39 apinger: Sending ping #4331 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:39 apinger: Polling, timeout: 0.010s
Nov 24 16:29:38 apinger: Polling, timeout: 0.946s
Nov 24 16:29:38 apinger: (avg. loss: 0.0%)
Nov 24 16:29:38 apinger: (avg: 40.011ms)
Nov 24 16:29:38 apinger: #4330 from CABLEMODEM_DHCP(8.8.8.8) delay: 40.329ms/38.429ms/400.109ms received = 4324
Nov 24 16:29:38 apinger: Polling, timeout: 0.986s
Nov 24 16:29:38 apinger: Recently lost packets: 50
Nov 24 16:29:38 apinger: sendto: Operation not permitted
Nov 24 16:29:38 apinger: Sending ping #4330 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:38 apinger: Recently lost packets: 0
Nov 24 16:29:38 apinger: Sending ping #4330 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:37 apinger: Polling, timeout: 0.960s
Nov 24 16:29:37 apinger: (avg. loss: 0.0%)
Nov 24 16:29:37 apinger: (avg: 39.821ms)
Nov 24 16:29:37 apinger: #4329 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.885ms/38.300ms/398.209ms received = 4323
Nov 24 16:29:37 apinger: Polling, timeout: 0.999s
Nov 24 16:29:37 apinger: Recently lost packets: 50
Nov 24 16:29:37 apinger: sendto: Operation not permitted
Nov 24 16:29:37 apinger: Sending ping #4329 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:37 apinger: Recently lost packets: 0
Nov 24 16:29:37 apinger: Sending ping #4329 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:36 apinger: Polling, timeout: 0.960s
Nov 24 16:29:36 apinger: (avg. loss: 0.0%)
Nov 24 16:29:36 apinger: (avg: 39.762ms)
Nov 24 16:29:36 apinger: #4328 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.396ms/38.148ms/397.624ms received = 4322
Nov 24 16:29:36 apinger: Polling, timeout: 0.999s
Nov 24 16:29:36 apinger: Recently lost packets: 50
Nov 24 16:29:36 apinger: sendto: Operation not permitted
Nov 24 16:29:36 apinger: Sending ping #4328 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:36 apinger: Recently lost packets: 0
Nov 24 16:29:36 apinger: Sending ping #4328 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:36 apinger: Polling, timeout: 0.000s
Nov 24 16:29:36 apinger: Polling, timeout: 0.000s
Nov 24 16:29:36 apinger: Polling, timeout: 0.000s
Nov 24 16:29:36 apinger: Polling, timeout: 0.000s
Nov 24 16:29:35 apinger: Polling, timeout: 0.960s
Nov 24 16:29:35 apinger: (avg. loss: 0.0%)
Nov 24 16:29:35 apinger: (avg: 39.638ms)
Nov 24 16:29:35 apinger: #4327 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.502ms/38.250ms/396.376ms received = 4321
Nov 24 16:29:35 apinger: Polling, timeout: 0.999s
Nov 24 16:29:35 apinger: Recently lost packets: 50
Nov 24 16:29:35 apinger: sendto: Operation not permitted
Nov 24 16:29:35 apinger: Sending ping #4327 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:35 apinger: Recently lost packets: 0
Nov 24 16:29:35 apinger: Sending ping #4327 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:34 apinger: Polling, timeout: 0.958s
Nov 24 16:29:34 apinger: (avg. loss: 0.0%)
Nov 24 16:29:34 apinger: (avg: 39.512ms)
Nov 24 16:29:34 apinger: #4326 from CABLEMODEM_DHCP(8.8.8.8) delay: 40.919ms/40.349ms/395.124ms received = 4320
Nov 24 16:29:34 apinger: Polling, timeout: 0.999s
Nov 24 16:29:34 apinger: Recently lost packets: 50
Nov 24 16:29:34 apinger: sendto: Operation not permitted
Nov 24 16:29:34 apinger: Sending ping #4326 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:34 apinger: Recently lost packets: 0
Nov 24 16:29:34 apinger: Sending ping #4326 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:33 apinger: Polling, timeout: 0.959s
Nov 24 16:29:33 apinger: (avg. loss: 0.0%)
Nov 24 16:29:33 apinger: (avg: 39.455ms)
Nov 24 16:29:33 apinger: #4325 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.347ms/38.822ms/394.554ms received = 4319
Nov 24 16:29:33 apinger: Polling, timeout: 0.997s
Nov 24 16:29:33 apinger: Recently lost packets: 50
Nov 24 16:29:33 apinger: sendto: Operation not permitted
Nov 24 16:29:33 apinger: Sending ping #4325 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:33 apinger: Recently lost packets: 0
Nov 24 16:29:33 apinger: Sending ping #4325 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:32 apinger: Polling, timeout: 0.957s
Nov 24 16:29:32 apinger: (avg. loss: 0.0%)
Nov 24 16:29:32 apinger: (avg: 39.503ms)
Nov 24 16:29:32 apinger: #4324 from CABLEMODEM_DHCP(8.8.8.8) delay: 42.217ms/39.070ms/395.029ms received = 4318
Nov 24 16:29:32 apinger: Polling, timeout: 0.999s
Nov 24 16:29:32 apinger: Recently lost packets: 50
Nov 24 16:29:32 apinger: sendto: Operation not permitted
Nov 24 16:29:32 apinger: Sending ping #4324 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:32 apinger: Recently lost packets: 0
Nov 24 16:29:32 apinger: Sending ping #4324 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:31 apinger: Polling, timeout: 0.961s
Nov 24 16:29:31 apinger: (avg. loss: 0.0%)
Nov 24 16:29:31 apinger: (avg: 39.188ms)
Nov 24 16:29:31 apinger: #4323 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.496ms/38.306ms/391.882ms received = 4317
Nov 24 16:29:31 apinger: Polling, timeout: 0.999s
Nov 24 16:29:31 apinger: Recently lost packets: 50
Nov 24 16:29:31 apinger: sendto: Operation not permitted
Nov 24 16:29:31 apinger: Sending ping #4323 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:31 apinger: Recently lost packets: 0
Nov 24 16:29:31 apinger: Sending ping #4323 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:30 apinger: Polling, timeout: 0.956s
Nov 24 16:29:30 apinger: (avg. loss: 0.0%)
Nov 24 16:29:30 apinger: (avg: 39.169ms)
Nov 24 16:29:30 apinger: #4322 from CABLEMODEM_DHCP(8.8.8.8) delay: 42.885ms/40.464ms/391.692ms received = 4316
Nov 24 16:29:30 apinger: Polling, timeout: 0.999s
Nov 24 16:29:30 apinger: Recently lost packets: 50
Nov 24 16:29:30 apinger: sendto: Operation not permitted
Nov 24 16:29:30 apinger: Sending ping #4322 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:30 apinger: Recently lost packets: 0
Nov 24 16:29:30 apinger: Sending ping #4322 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:29 apinger: Polling, timeout: 0.960s
Nov 24 16:29:29 apinger: (avg. loss: 0.0%)
Nov 24 16:29:29 apinger: (avg: 38.927ms)
Nov 24 16:29:29 apinger: #4321 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.133ms/39.556ms/389.271ms received = 4315
Nov 24 16:29:29 apinger: Polling, timeout: 0.978s
Nov 24 16:29:29 apinger: Polling, timeout: 0.019s
Nov 24 16:29:29 apinger: Recently lost packets: 50
Nov 24 16:29:29 apinger: sendto: Operation not permitted
Nov 24 16:29:29 apinger: Sending ping #4321 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:29 apinger: Recently lost packets: 0
Nov 24 16:29:29 apinger: Sending ping #4321 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:28 apinger: Polling, timeout: 0.961s
Nov 24 16:29:28 apinger: (avg. loss: 0.0%)
Nov 24 16:29:28 apinger: (avg: 38.969ms)
Nov 24 16:29:28 apinger: #4320 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.429ms/40.464ms/389.694ms received = 4314
Nov 24 16:29:28 apinger: Polling, timeout: 0.999s
Nov 24 16:29:28 apinger: Recently lost packets: 50
Nov 24 16:29:28 apinger: sendto: Operation not permitted
Nov 24 16:29:28 apinger: Sending ping #4320 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:28 apinger: Recently lost packets: 0
Nov 24 16:29:28 apinger: Sending ping #4320 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:27 apinger: Polling, timeout: 0.961s
Nov 24 16:29:27 apinger: (avg. loss: 0.0%)
Nov 24 16:29:27 apinger: (avg: 39.173ms)
Nov 24 16:29:27 apinger: #4319 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.300ms/42.801ms/391.729ms received = 4313
Nov 24 16:29:27 apinger: Polling, timeout: 0.999s
Nov 24 16:29:27 apinger: Recently lost packets: 50
Nov 24 16:29:27 apinger: sendto: Operation not permitted
Nov 24 16:29:27 apinger: Sending ping #4319 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:27 apinger: Recently lost packets: 0
Nov 24 16:29:27 apinger: Sending ping #4319 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:26 apinger: Polling, timeout: 0.961s
Nov 24 16:29:26 apinger: (avg. loss: 0.0%)
Nov 24 16:29:26 apinger: (avg: 39.623ms)
Nov 24 16:29:26 apinger: #4318 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.148ms/38.641ms/396.230ms received = 4312
Nov 24 16:29:26 apinger: Polling, timeout: 0.999s
Nov 24 16:29:26 apinger: Recently lost packets: 50
Nov 24 16:29:26 apinger: sendto: Operation not permitted
Nov 24 16:29:26 apinger: Sending ping #4318 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:26 apinger: Recently lost packets: 0
Nov 24 16:29:26 apinger: Sending ping #4318 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:25 apinger: Polling, timeout: 0.961s
Nov 24 16:29:25 apinger: (avg. loss: 0.0%)
Nov 24 16:29:25 apinger: (avg: 39.672ms)
Nov 24 16:29:25 apinger: #4317 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.250ms/39.397ms/396.723ms received = 4311
Nov 24 16:29:25 apinger: Polling, timeout: 0.999s
Nov 24 16:29:25 apinger: Recently lost packets: 50
Nov 24 16:29:25 apinger: sendto: Operation not permitted
Nov 24 16:29:25 apinger: Sending ping #4317 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:25 apinger: Recently lost packets: 0
Nov 24 16:29:25 apinger: Sending ping #4317 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:24 apinger: Polling, timeout: 0.959s
Nov 24 16:29:24 apinger: (avg. loss: 0.0%)
Nov 24 16:29:24 apinger: (avg: 39.787ms)
Nov 24 16:29:24 apinger: #4316 from CABLEMODEM_DHCP(8.8.8.8) delay: 40.349ms/45.186ms/397.870ms received = 4310
Nov 24 16:29:24 apinger: Polling, timeout: 0.971s
Nov 24 16:29:24 apinger: Polling, timeout: 0.026s
Nov 24 16:29:24 apinger: Recently lost packets: 50
Nov 24 16:29:24 apinger: sendto: Operation not permitted
Nov 24 16:29:24 apinger: Sending ping #4316 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:24 apinger: Recently lost packets: 0
Nov 24 16:29:24 apinger: Sending ping #4316 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:23 apinger: Polling, timeout: 0.960s
Nov 24 16:29:23 apinger: (avg. loss: 0.0%)
Nov 24 16:29:23 apinger: (avg: 40.271ms)
Nov 24 16:29:23 apinger: #4315 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.822ms/44.797ms/402.707ms received = 4309
Nov 24 16:29:23 apinger: Polling, timeout: 0.999s
Nov 24 16:29:23 apinger: Recently lost packets: 50
Nov 24 16:29:23 apinger: sendto: Operation not permitted
Nov 24 16:29:23 apinger: Sending ping #4315 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:23 apinger: Recently lost packets: 0
Nov 24 16:29:23 apinger: Sending ping #4315 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:22 apinger: Polling, timeout: 0.960s
Nov 24 16:29:22 apinger: (avg. loss: 0.0%)
Nov 24 16:29:22 apinger: (avg: 40.868ms)
Nov 24 16:29:22 apinger: #4314 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.070ms/39.213ms/408.682ms received = 4308
Nov 24 16:29:22 apinger: Polling, timeout: 0.999s
Nov 24 16:29:22 apinger: Recently lost packets: 50
Nov 24 16:29:22 apinger: sendto: Operation not permitted
Nov 24 16:29:22 apinger: Sending ping #4314 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:22 apinger: Recently lost packets: 0
Nov 24 16:29:22 apinger: Sending ping #4314 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:21 apinger: Polling, timeout: 0.961s
Nov 24 16:29:21 apinger: (avg. loss: 0.0%)
Nov 24 16:29:21 apinger: (avg: 40.882ms)
Nov 24 16:29:21 apinger: #4313 from CABLEMODEM_DHCP(8.8.8.8) delay: 38.306ms/38.571ms/408.825ms received = 4307
Nov 24 16:29:21 apinger: Polling, timeout: 0.999s
Nov 24 16:29:21 apinger: Recently lost packets: 50
Nov 24 16:29:21 apinger: sendto: Operation not permitted
Nov 24 16:29:21 apinger: Sending ping #4313 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:21 apinger: Recently lost packets: 0
Nov 24 16:29:21 apinger: Sending ping #4313 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:20 apinger: Polling, timeout: 0.959s
Nov 24 16:29:20 apinger: (avg. loss: 0.0%)
Nov 24 16:29:20 apinger: (avg: 40.909ms)
Nov 24 16:29:20 apinger: #4312 from CABLEMODEM_DHCP(8.8.8.8) delay: 40.464ms/39.274ms/409.090ms received = 4306
Nov 24 16:29:20 apinger: Polling, timeout: 0.999s
Nov 24 16:29:20 apinger: Recently lost packets: 50
Nov 24 16:29:20 apinger: sendto: Operation not permitted
Nov 24 16:29:20 apinger: Sending ping #4312 to DSL_DHCP (8.8.4.4)
Nov 24 16:29:20 apinger: Recently lost packets: 0
Nov 24 16:29:20 apinger: Sending ping #4312 to CABLEMODEM_DHCP (8.8.8.8)
Nov 24 16:29:19 apinger: Polling, timeout: 0.951s
Nov 24 16:29:19 apinger: Polling, timeout: 0.008s
Nov 24 16:29:19 apinger: (avg. loss: 0.0%)
Nov 24 16:29:19 apinger: (avg: 40.790ms)
Nov 24 16:29:19 apinger: #4311 from CABLEMODEM_DHCP(8.8.8.8) delay: 39.556ms/38.594ms/407.900ms received = 4305 -
seems a login to webgui can trigger this
-
Last 1000 system log entries
Nov 24 22:42:36 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:35 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 22:42:35 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:34 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 22:42:34 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:31 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 22:42:31 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:30 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 22:42:30 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:28 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 22:42:28 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:26 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 22:42:26 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:25 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 22:42:25 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:24 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 22:42:24 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:23 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 22:42:23 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:22 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group loadbalance
Nov 24 22:42:22 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:20 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover2
Nov 24 22:42:20 php-fpm[98604]: /index.php: Message sent to grandrivers@gmail.com OK
Nov 24 22:42:18 php-fpm[98604]: /index.php: MONITOR: DSL_DHCP is down, omitting from routing group fallover
Nov 24 22:42:17 php-fpm[98604]: /index.php: Successful login for user 'admin' from: 192.168.35.211
Nov 24 22:42:17 php-fpm[98604]: /index.php: Successful login for user 'admin' from: 192.168.35.211 -
reloading dashboard can seem to cause this