Netgate Discussion Forum
    • Categories
    • Recent
    • Tags
    • Popular
    • Users
    • Search
    • Register
    • Login

    if_pppoe ping works but dns doesn't?

    Scheduled Pinned Locked Moved General pfSense Questions
    2 Posts 2 Posters 189 Views 2 Watching
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • E Offline
      exomic
      last edited by exomic

      Since the release of if_pppoe I'm always getting the same issue, no dns resolving but ping works.

      Sep 5 09:17:58	ppp	48942	process 48942 terminated
Sep 5 09:17:58	ppp	48942	[wan_link0] Link: Shutdown
Sep 5 09:17:58	ppp	48942	[wan] Bundle: Shutdown
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: Close event
Sep 5 09:17:56	ppp	48942	[wan_link0] Link: CLOSE event
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: state change Closing --> Initial
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: LayerFinish
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: Down event
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: state change Stopping --> Closing
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: Close event
Sep 5 09:17:56	ppp	48942	[wan_link0] Link: giving up after 0 reconnection attempts
Sep 5 09:17:56	ppp	48942	[wan_link0] Link: DOWN event
Sep 5 09:17:56	ppp	48942	[wan_link0] PPPoE: connection closed
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: LayerDown
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: SendTerminateAck #4
Sep 5 09:17:56	ppp	48942	[wan] IPV6CP: state change Closed --> Initial
Sep 5 09:17:56	ppp	48942	[wan] IPV6CP: Down event
Sep 5 09:17:56	ppp	48942	[wan] IPCP: state change Closed --> Initial
Sep 5 09:17:56	ppp	48942	[wan] IPCP: Down event
Sep 5 09:17:56	ppp	48942	[wan] IPV6CP: Close event
Sep 5 09:17:56	ppp	48942	[wan] IPCP: Close event
Sep 5 09:17:56	ppp	48942	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Sep 5 09:17:56	ppp	48942	[wan_link0] Link: Leave bundle "wan"
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: state change Opened --> Stopping
Sep 5 09:17:56	ppp	48942	[wan_link0] LCP: rec'd Terminate Request #70 (Opened)
Sep 5 09:17:56	ppp	48942	[wan] Bundle: closing link "wan_link0"...
Sep 5 09:17:56	ppp	48942	[wan] Bundle: No NCPs left. Closing links...
Sep 5 09:17:56	ppp	48942	[wan] IPV6CP: LayerFinish
Sep 5 09:17:56	ppp	48942	[wan] IPV6CP: state change Closing --> Closed
Sep 5 09:17:56	ppp	48942	[wan] IPV6CP: rec'd Terminate Ack #2 (Closing)
Sep 5 09:17:56	ppp	48942	[wan] IPCP: LayerFinish
Sep 5 09:17:56	ppp	48942	[wan] IPCP: state change Closing --> Closed
Sep 5 09:17:56	ppp	48942	[wan] IPCP: rec'd Terminate Ack #4 (Closing)
Sep 5 09:17:56	ppp	48942	[wan] IPCP: SendTerminateReq #5
Sep 5 09:17:56	ppp	48942	[wan] IFACE: Set description "WAN"
Sep 5 09:17:56	ppp	48942	[wan] IFACE: Rename interface pppoe0 to pppoe0
Sep 5 09:17:56	ppp	48942	[wan] IFACE: Down event
Sep 5 09:17:55	ppp	48942	[wan] IPV6CP: LayerDown
Sep 5 09:17:55	ppp	48942	[wan] IPV6CP: SendTerminateReq #2
Sep 5 09:17:55	ppp	48942	[wan] IPV6CP: state change Opened --> Closing
Sep 5 09:17:55	ppp	48942	[wan] IPV6CP: Close event
Sep 5 09:17:55	ppp	48942	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
Sep 5 09:17:54	ppp	48942	[wan] IPCP: LayerDown
Sep 5 09:17:54	ppp	48942	[wan] IPCP: SendTerminateReq #4
Sep 5 09:17:54	ppp	48942	[wan] IPCP: state change Opened --> Closing
Sep 5 09:17:54	ppp	48942	[wan] IPCP: Close event
Sep 5 09:17:54	ppp	48942	[wan] IFACE: Close event
Sep 5 09:17:54	ppp	48942	caught fatal signal TERM
Sep 4 18:00:34	ppp	48942	[wan] 020a:f7ff:fe49:8660 -> 0200:00ff:fe00:0000
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: LayerUp
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: state change Ack-Sent --> Opened
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: rec'd Configure Ack #1 (Ack-Sent)
Sep 4 18:00:34	ppp	48942	[wan] IFACE: Add description "WAN"
Sep 4 18:00:34	ppp	48942	[wan] IFACE: Rename interface ng0 to pppoe0
Sep 4 18:00:34	ppp	48942	[wan] IFACE: Up event
Sep 4 18:00:34	ppp	48942	[wan] 107.xxx.xxx.38 -> 198.xxx.xxx.33
Sep 4 18:00:34	ppp	48942	[wan] IPCP: LayerUp
Sep 4 18:00:34	ppp	48942	[wan] IPCP: state change Ack-Sent --> Opened
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 107.xxx.xxx.38
Sep 4 18:00:34	ppp	48942	[wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: state change Req-Sent --> Ack-Sent
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: SendConfigAck #202
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: rec'd Configure Request #202 (Req-Sent)
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 107.xxx.xxx.38
Sep 4 18:00:34	ppp	48942	[wan] IPCP: SendConfigReq #3
Sep 4 18:00:34	ppp	48942	[wan] 107.xxx.xxx.38 is OK
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 107.xxx.xxx.38
Sep 4 18:00:34	ppp	48942	[wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 0.0.0.0
Sep 4 18:00:34	ppp	48942	[wan] IPCP: SendConfigReq #2
Sep 4 18:00:34	ppp	48942	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 4 18:00:34	ppp	48942	[wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Sep 4 18:00:34	ppp	48942	[wan] IPCP: state change Req-Sent --> Ack-Sent
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 198.xxx.xxx.33
Sep 4 18:00:34	ppp	48942	[wan] IPCP: SendConfigAck #167
Sep 4 18:00:34	ppp	48942	[wan] 198.xxx.xxx.33 is OK
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 198.xxx.xxx.33
Sep 4 18:00:34	ppp	48942	[wan] IPCP: rec'd Configure Request #167 (Req-Sent)
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: SendConfigReq #1
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: state change Starting --> Req-Sent
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: Up event
Sep 4 18:00:34	ppp	48942	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 4 18:00:34	ppp	48942	[wan] IPADDR 0.0.0.0
Sep 4 18:00:34	ppp	48942	[wan] IPCP: SendConfigReq #1
Sep 4 18:00:34	ppp	48942	[wan] IPCP: state change Starting --> Req-Sent
Sep 4 18:00:34	ppp	48942	[wan] IPCP: Up event
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: LayerStart
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: state change Initial --> Starting
Sep 4 18:00:34	ppp	48942	[wan] IPV6CP: Open event
Sep 4 18:00:34	ppp	48942	[wan] IPCP: LayerStart
Sep 4 18:00:34	ppp	48942	[wan] IPCP: state change Initial --> Starting
Sep 4 18:00:34	ppp	48942	[wan] IPCP: Open event
Sep 4 18:00:34	ppp	48942	[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 4 18:00:34	ppp	48942	[wan_link0] Link: Join bundle "wan"
Sep 4 18:00:34	ppp	48942	[wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 4 18:00:34	ppp	48942	[wan_link0] LCP: authorization successful
Sep 4 18:00:34	ppp	48942	[wan_link0] CHAP: rec'd SUCCESS #51 len: 4
Sep 4 18:00:34	ppp	48942	[wan_link0] CHAP: sending RESPONSE #51 len: 40
Sep 4 18:00:34	ppp	48942	[wan_link0] CHAP: Using authname "xxx@pppoe.ebox.net"
Sep 4 18:00:34	ppp	48942	[wan_link0] Name: "JUNOS"

      Sep 5 09:29:45	nginx		2025/09/05 09:29:45 [error] 81461#100290: send() failed (54: Connection reset by peer) while logging to syslog, server: unix:/var/run/log
Sep 5 09:29:45	php-fpm	79579	/index.php: Successful login for user 'admin' from: 10.10.0.11 (Local Database)
Sep 5 09:20:26	login	64826	login on ttyv0 as root
Sep 5 09:20:25	vnstatd	46318	Interface "bce1.40" disabled.
Sep 5 09:20:25	vnstatd	46318	Interface "bxe0.30" disabled.
Sep 5 09:20:25	vnstatd	46318	Monitoring (17): pppoe0 (1000 Mbit) pfsync0 (1000 Mbit) pflog0 (1000 Mbit) ovpns3 (1000 Mbit) ovpns2 (1000 Mbit) ovpns1 (1000 Mbit) enc0 (1000 Mbit) bxe1 (10000 Mbit) bxe0.69 (10000 Mbit) bxe0.6 (10000 Mbit) bxe0.5 (10000 Mbit) bxe0.4 (10000 Mbit) bxe0.30 (1000 Mbit) bxe0 (10000 Mbit) bce1.40 (1000 Mbit) bce1 (1000 Mbit) bce0 (1000 Mbit)
Sep 5 09:20:25	vnstatd	46318	Data retention: 48 5MinuteHours, 4 HourlyDays, 62 DailyDays, 25 MonthlyMonths, -1 YearlyYears, 20 TopDayEntries
Sep 5 09:20:25	vnstatd	46318	vnStat daemon 2.12 (pid:46318 uid:0 gid:0, SQLite 3.46.1)
Sep 5 09:20:25	vnstatd	48885	Error: pidfile "/var/run/vnstat/vnstat.pid" lock failed (Resource temporarily unavailable), exiting.
Sep 5 09:20:25	root	46259	Bootup complete
Sep 5 09:20:25	vnstatd	85230	SIGTERM received, exiting.
Sep 5 09:20:18	arpwatch	870	listening on bxe0.4
Sep 5 09:20:18	arpwatch	529	listening on bxe0.5
Sep 5 09:20:18	arpwatch	67	listening on bxe0
Sep 5 09:20:15	vnstatd	85230	Interface "bce1.40" disabled.
Sep 5 09:20:15	vnstatd	85230	Interface "bxe0.30" disabled.
Sep 5 09:20:15	vnstatd	85230	Monitoring (17): pppoe0 (1000 Mbit) pfsync0 (1000 Mbit) pflog0 (1000 Mbit) ovpns3 (1000 Mbit) ovpns2 (1000 Mbit) ovpns1 (1000 Mbit) enc0 (1000 Mbit) bxe1 (10000 Mbit) bxe0.69 (10000 Mbit) bxe0.6 (10000 Mbit) bxe0.5 (10000 Mbit) bxe0.4 (10000 Mbit) bxe0.30 (1000 Mbit) bxe0 (10000 Mbit) bce1.40 (1000 Mbit) bce1 (1000 Mbit) bce0 (1000 Mbit)
Sep 5 09:20:15	vnstatd	85230	Data retention: 48 5MinuteHours, 4 HourlyDays, 62 DailyDays, 25 MonthlyMonths, -1 YearlyYears, 20 TopDayEntries
Sep 5 09:20:15	vnstatd	85230	vnStat daemon 2.12 (pid:85230 uid:0 gid:0, SQLite 3.46.1)
Sep 5 09:20:15	kernel		pppoe0: promiscuous mode enabled
Sep 5 09:20:15	kernel		bxe0.4: promiscuous mode enabled
Sep 5 09:20:15	kernel		bxe0.6: promiscuous mode enabled
Sep 5 09:20:15	kernel		bxe0.5: promiscuous mode enabled
Sep 5 09:20:15	kernel		bxe0: promiscuous mode enabled
Sep 5 09:20:15	php-cgi	21500	notify_monitor.php: Could not send the message to xxx@me.com -- Error: Failed to connect to smtp.gmail.com:587 [SMTP: Failed to connect socket: php_network_getaddresses: getaddrinfo for smtp.gmail.com failed: Name does not resolve (code: -1, response: )]
Sep 5 09:20:15	php-fpm	79579	/rc.start_packages: Skipping STARTing packages process because previous/another instance is already running
Sep 5 09:20:14	check_reload_status	495	Reloading filter
Sep 5 09:20:14	check_reload_status	495	Starting packages
Sep 5 09:20:14	php-fpm	436	/rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 107.xxx.xxx.38 -> 66.xxx.xxx.168 - Restarting packages.
Sep 5 09:20:13	php-fpm	79579	/rc.newwanip: Interface is unassigned, nothing to do.
Sep 5 09:20:13	php-fpm	79579	/rc.newwanip: rc.newwanip: Info: starting on ovpns3.
Sep 5 09:20:12	php-fpm	79579	/rc.newwanip: Interface is unassigned, nothing to do.
Sep 5 09:20:12	php-fpm	79579	/rc.newwanip: rc.newwanip: Info: starting on ovpns2.
Sep 5 09:20:12	php-fpm	79579	/rc.newwanip: Interface is unassigned, nothing to do.
Sep 5 09:20:12	php-fpm	79579	/rc.newwanip: rc.newwanip: Info: starting on ovpns1.
Sep 5 09:20:12	kernel		ovpns3: link state changed to UP
Sep 5 09:20:12	check_reload_status	495	rc.newwanip starting ovpns3
Sep 5 09:20:12	php-fpm	436	/rc.newwanip: Creating rrd update script
Sep 5 09:20:12	php-fpm	436	OpenVPN PID written: 3194
Sep 5 09:20:11	kernel		ovpns3: link state changed to DOWN
Sep 5 09:20:11	kernel		ovpns2: link state changed to UP
Sep 5 09:20:11	php-fpm	436	OpenVPN terminate old pid: 75580
Sep 5 09:20:11	check_reload_status	495	rc.newwanip starting ovpns2
Sep 5 09:20:11	php-fpm	436	OpenVPN PID written: 93147
Sep 5 09:20:11	kernel		ovpns2: link state changed to DOWN
Sep 5 09:20:11	php-fpm	436	OpenVPN terminate old pid: 65921
Sep 5 09:20:11	kernel		ovpns1: link state changed to UP
Sep 5 09:20:11	check_reload_status	495	rc.newwanip starting ovpns1
Sep 5 09:20:11	check_reload_status	495	Reloading filter
Sep 5 09:20:11	php-fpm	436	OpenVPN PID written: 84119
Sep 5 09:20:11	kernel		ovpns1: link state changed to DOWN
Sep 5 09:20:11	check_reload_status	495	Reloading filter
Sep 5 09:20:11	php-fpm	436	OpenVPN terminate old pid: 58145
Sep 5 09:20:10	php-fpm	436	/rc.newwanip: Resyncing OpenVPN instances for interface WAN.
Sep 5 09:20:09	php-fpm	58220	/rc.dyndns.update: Curl error occurred: Could not resolve host: api.cloudflare.com
Sep 5 09:20:09	php-fpm	436	/rc.newwanip: Curl error occurred: Could not resolve host: api.cloudflare.com
Sep 5 09:19:58	php-fpm	435	/rc.start_packages: Restarting/Starting all packages.
Sep 5 09:19:57	sshguard	11504	Now monitoring attacks.
Sep 5 09:19:57	syslogd		kernel boot file is /boot/kernel/kernel
Sep 5 09:19:57	syslogd		exiting on signal 15
Sep 5 09:19:57	sshguard	16030	Exiting on signal.
Sep 5 09:19:57	sshguard	19169	Exiting on signal.
Sep 5 09:19:57	kernel		done.
Sep 5 09:19:57	php-cgi	542	rc.bootup: Creating rrd update script
Sep 5 09:19:57	php-cgi	542	rc.bootup: The command '/usr/bin/nice -n20 /usr/local/bin/rrdtool update /var/db/rrd/system-sensors.rrd N:U:U:U:U:U' returned exit code '1', the output was 'ERROR: /var/db/rrd/system-sensors.rrd: found extra data on update argument: U:U:U:U'
Sep 5 09:19:53	kernel		done.
Sep 5 09:19:53	php-cgi	542	rc.bootup: The command '/usr/local/sbin/strongswanrc stop' returned exit code '1', the output was 'strongswan not running? (check /var/run/daemon-charon.pid).'
Sep 5 09:19:53	check_reload_status	495	Updating all dyndns
Sep 5 09:19:52	kernel		done.
Sep 5 09:19:52	php-cgi	542	rc.bootup: NTPD is starting up.
Sep 5 09:19:47	php-fpm	436	/rc.newwanip: sync unbound done.
Sep 5 09:19:47	php-fpm	436	/rc.newwanip: dhcp6 init complete. Continuing
Sep 5 09:19:47	rtsold	68251	RTSOLD Lock in place - sending SIGHUP to dhcp6c
Sep 5 09:19:47	rtsold	67556	Received RA specifying route fe80::200:ff:fe00:0 for interface wan(pppoe0)
Sep 5 09:19:47	php-fpm	435	/rc.newwanipv6: Gateway, NONE AVAILABLE
Sep 5 09:19:47	php-fpm	435	/rc.newwanipv6: Gateway, NONE AVAILABLE
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: IP Address has changed, killing states on former IP Address 107.xxx.xxx.38.
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: Gateway, none 'available' for inet6, use the first one configured. 'WAN_DHCP6'
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: Gateway, none 'available' for inet, use the first one configured. 'WAN_PPPOE'
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: dpinger: status socket /var/run/dpinger_WAN_PPPOE~66.xxx.xxx.168~198.xxx.xxx.33.sock not found
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: dpinger: status socket /var/run/dpinger_WAN_DHCP6~2606:xxx:xxx:xxx:xxx:xxx:xxx:8660~2606:4700:4700::1111.sock not found
Sep 5 09:19:46	php-fpm	435	/rc.newwanipv6: Removing static route for monitor 2606:4700:4700::1111 and adding a new route through pppoe0
Sep 5 09:19:46	php-fpm	435	/rc.newwanipv6: rc.newwanipv6: on (IP address: 2606:xxx:xxx:xxx:xxx:xxx:xxx:8660) (interface: wan) (real interface: pppoe0).
Sep 5 09:19:46	php-fpm	435	/rc.newwanipv6: rc.newwanipv6: Info: starting on pppoe0.
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: Removing static route for monitor 2606:4700:4700::1111 and adding a new route through pppoe0
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: rc.newwanip: on (IP address: 66.xxx.xxx.168) (interface: WAN[wan]) (real interface: pppoe0).
Sep 5 09:19:46	php-fpm	436	/rc.newwanip: rc.newwanip: Info: starting on pppoe0.
Sep 5 09:19:45	check_reload_status	495	rc.newwanipv6 starting pppoe0
Sep 5 09:19:45	php-cgi	542	rc.bootup: sync unbound done.
Sep 5 09:19:45	check_reload_status	495	rc.newwanip starting pppoe0
Sep 5 09:19:45	php	94545	/usr/local/sbin/ppp-ipv6: Starting rtsold process on wan(pppoe0)
Sep 5 09:19:45	php	94545	/usr/local/sbin/ppp-ipv6: Starting DHCP6 client for interfaces pppoe0
Sep 5 09:19:45	php	94545	/usr/local/sbin/ppp-ipv6: Accept router advertisements on interface pppoe0
Sep 5 09:19:44	kernel		pppoe0: link state changed to UP
Sep 5 09:19:44	kernel		bce0: Gigabit link up!
Sep 5 09:19:44	php-cgi	542	rc.bootup: Unbound start waiting on dhcp6c.
Sep 5 09:19:43	php-cgi	542	rc.bootup: Unbound start waiting on dhcp6c.
Sep 5 09:19:42	php-cgi	542	rc.bootup: Unbound start waiting on dhcp6c.
Sep 5 09:19:41	php-fpm	436	/rc.linkup: Ignoring link event during boot sequence.
Sep 5 09:19:41	php-fpm	435	/rc.linkup: Ignoring link event during boot sequence.
      1 Reply Last reply Reply Quote 0
      • stephenw10S Online
        stephenw10 Netgate Administrator
        last edited by

        If you're seeing that output in the ppp log then you're not using the if_pppoe module.

        Did you enable it between those two log entries?

        How are you testing? What error(s) are you seeing?

        1 Reply Last reply Reply Quote 0
        • First post
          Last post
        Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.