Upgrade from 12/13 to 12/18 -> PPPoE dead…
-
Hmm. Tried to restore part of the config but nothing changed…I tried to restore interfaces first, rebooted but no change at all. Stays on default IP on LAN etc...
Tried to restore aliases and nothing....
-
Tried again with latest snap from 12/19. Blank config with just PPPoE enabled so that my internet connection works. Tried just restoring the aliases from my config files but they do not appear on aliases. Nothing there. Did a reboot then but still nothing. I can see that it was imported under Config History though…
-
BlueMatt, I'm using pppoe as the WAN interface, it is an ADSL actually did an update for version 1.2.3, but the initial boot camp the username and password were blank being filled to function normally again :)
-
Can anyone from the dev team try to import my config? Just to see if it will work?
Thanks…
-
Try this manually
https://rcs.pfsense.org/projects/pfsense/repos/mainline/commits/92a1c8e6caca910ae1f8c54751bffebd45d87682or wait for a snapshot with the patch applied.
-
Thanks ermal, but the PPPoE is working now. I meant if someone could check why my config is not importable not even just some parts like firewall rules or aliases. Tried with older configs as well…
Would hate to start from scratch... -
Tried again to restore just the aliases and the following shows up in my system.log:
Dec 21 17:17:46 pfSense php: /diag_backup.php: XML error: no aliases object found!
I have the following in my config.xml though:
<aliases><alias><name>AppleServers</name> <address>17.155.0.0/16 79.223.0.0/16 80.149.0.0/16 87.154.0.0/16</address> <type>network</type> <detail>Entry added Thu, 21 Oct 2010 00:20:31 +0200||Entry added Thu, 21 Oct 2010 00:20:31 +0200||Entry added Thu, 21 Oct 2010 20:35:29 +0200||Entry added Thu, 21 Oct 2010 20:38:41 +0200</detail></alias> <alias><name>Dumbledore</name> <address>172.16.100.5</address> <type>host</type> <detail>Entry added Thu, 21 Oct 2010 14:46:01 +0200</detail></alias> <alias><name>DynDNS_Hostname</name> <address>voldemort.endofinternet.org</address> <type>network</type> <detail>Entry added Fri, 08 Oct 2010 15:05:25 +0200</detail></alias> <alias><name>FaceTimePorts</name> <address>3478:3497 16384:16386 16393:16402</address> <type>port</type> <detail>Entry added Mon, 29 Nov 2010 23:04:17 +0100||Entry added Mon, 29 Nov 2010 23:04:17 +0100||Entry added Mon, 29 Nov 2010 23:04:17 +0100</detail></alias> <alias><name>FiletransferPorts</name> <address>21 22</address> <type>port</type> <detail>Entry added Wed, 24 Nov 2010 23:14:01 +0100||Entry added Wed, 24 Nov 2010 23:14:01 +0100</detail></alias> <alias><name>Hermione</name> <address>172.16.100.50</address> <type>host</type> <detail>Entry added Wed, 20 Oct 2010 22:09:04 +0200</detail></alias> <alias><name>LocalAreaNetwork</name> <address>10.0.100.0/24</address> <type>network</type> <detail>Entry added Tue, 19 Oct 2010 17:02:57 +0200</detail></alias> <alias><name>LocalNetworks</name> <address>10.0.100.0/24 172.16.100.0/24 192.168.100.0/24</address> <type>network</type> <detail>Entry added Tue, 19 Oct 20109:38:15 +0200||Entry added Thu, 21 Oct 2010 13:28:29 +0200||Entry added Sun, 05 Dec 2010 20:28:57 +0100</detail></alias> <alias><name>Luna</name> <address>172.16.100.10</address> <type>host</type> <detail>Entry added Thu, 21 Oct 2010 14:46:21 +0200</detail></alias> <alias><name>MailPorts</name> <address>25 110 143 465 587 993 995</address> <type>port</type> <detail>Entry added Sun, 21 Nov 2010 16:23:51 +0100||Entry added Sun, 21 Nov 2010 16:23:51 +0100||Entry added Sun, 21 Nov 2010 16:23:51 +0100||Entry added Sun, 21 Nov 2010 16:23:51 +0100||Entry added Sun, 21 Nov 2010 16:23:51 +0100||Entry added Sun, 21 Nov 2010 16:23:51 +0100||Entry added Sun, 21 Nov 2010 16:23:51 +0100</detail></alias> <alias><name>ManagementPorts</name> <address>22 8443</address> <type>port</type> <detail>SSH||WebGUI</detail></alias> <alias><name>MessagingPorts</name> <address>1863 5222 5223 5190</address> <type>port</type> <detail>MSN||Jabber||Jabber/WhatsApp/Apple Push/FaceTime||ICQ/AIM</detail></alias> <alias><name>PenaltyBox</name> <address>10.0.100.100/30 10.0.100.104/29 10.0.100.112/29 10.0.100.120/32 172.16.100.100/30 172.16.100.104/29 172.16.100.112/29 172.16.100.120/32 192.168.100.100/30 192.168.100.104/29 192.168.100.112/29 192.168.100.120/32</address> <type>network</type> <detail>Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100||Entry added Sun, 05 Dec 2010 20:29:45 +0100</detail></alias> <alias><name>pfSense</name> <address>10.0.100.254 172.16.100.254 192.168.100.254</address> <type>host</type> <detail>LAN||WLAN||WLAN-Guest</detail></alias> <alias><name>Speedport</name> <address>192.168.2.1</address> <type>host</type> <detail>Entry added Thu, 21 Oct 2010 22:50:26 +0200</detail></alias> <alias><name>StarCraft2Ports</name> <address>1119 3724</address> <type>port</type> <detail>Battle.net||Entry added Wed, 15 Dec 2010 13:40:47 +0100</detail></alias> <alias><name>SteamPorts</name> <address>27000:27015 27015:27030 27014:27050 4380 27015 3478 4379 4380 1500 3005 3101 28960</address> <type>port</type> <detail>Steam Client UDP 27000 to 27015 inclusive (Game client traffic)||Steam Client UDP 27015 to 27030 inclusive (Typically Matchmaking and HLTV)||Steam Client TCP 27014 to 27050 inclusive (Steam downloads)||Steam Client UDP 4380||Dedicated or Listen Servers TCP 27015 (SRCDS Rcon port)||Steamworks P2P Networking and Steam Voice Chat UDP 3478 (Outbound)||Steamworks P2P Networking and Steam Voice Chat UDP 4379 (Outbound)||Steamworks P2P Networking and Steam Voice Chat UDP 4380 (Outbound)||Additional Ports for Call of Duty: Modern Warfare 2 Multiplayer UDP 1500 (outbound)||Additional Ports for Call of Duty: Modern Warfare 2 Multiplayer UDP 3005 (outbound)||Additional Ports for Call of Duty: Modern Warfare 2 Multiplayer UDP 3101 (outbound)||Additional Ports for Call of Duty: Modern Warfare 2 Multiplayer UDP 28960</detail></alias> <alias><name>TeamviewerPorts</name> <address>5938 60179</address> <type>port</type> <detail>Entry added Wed, 24 Nov 2010 23:15:04 +0100||Entry added Wed, 24 Nov 2010 23:15:04 +0100</detail></alias> <alias><name>WebPorts</name> <address>80 443</address> <type>port</type> <detail>HTTP||HTTPS</detail></alias> <alias><name>WirelessLocalAreaNetwork</name> <address>172.16.100.0/24</address> <type>network</type> <detail>Entry added Thu, 21 Oct 2010 13:27:38 +0200</detail></alias></aliases>
So there definitely is a aliases part…
-
The errors you show are more indicative of a filesystem that has been corrupted. On embedded, you may try switching back to the other boot slice. If neither one works, you may have to reflash.
On a full install, you'd need to reinstall from CD but you should be able to use the "Rescue install" option to pull your config from the hard drive (Or just do a fresh install and restore your config as you mentioned)
-
Aha. So you think my config would work on a new flashed install?
-
It might, but if your filesystem is corrupt there is always a chance that something in the config could be corrupt as well.
So while it is more likely to work with a freshly flashed CF, I can't say for certain it would.
-
So what should you do so that this type of thing does not happen? Test a config restore every now and then?
-
Normally it's not a problem over time, just keeping a few backups around may be enough. It may just be that it was corrupted by chance, but the only real test is to restore the config (perhaps in a VM) to see if it works OK.
Testing backups is a good task for any backup plan. Backups can't help you if they aren't usable. :)
-
True ;-)
Actually I have a VM around with a 2.0 full version…
Will try to restore that one now...
-
You can make embedded VMs in either VMWare or VirtualBox as well, though it is a little trickier to setup since you either have to convert the nanobsd image to a virtual disk directly, or attach the target virtual disk to another VM where you can download and use dd inside the VM to pull it off. Both VMWare and VirtualBox support named pipe serial ports you can connect to with putty or another program. It's pretty slick once it's all setup.
-
Hm. No, that config wouldn't restore in a vm as well :(
I also tested older configs from october. Same error, everytime…
Tried with aliases and nat section. Does not work. Does this restore of some actually work for you in newer builds? Perhaps nobody tried to restore just a part of the config? -
The partial restore doesn't seek out portions of a whole config, you have to edit out the part you want. For example, to restore DHCP settings, you need to cut the <dhcpd>…</dhcpd> section out into a separate file, and then pick that file along with choosing the DHCP server settings from the drop-down.
-
Aha. So THAT could be the problem….
-
Thanks jimp! That works! Man I could have searched a long time. Perhaps you should add a comment on the restore page so that everyone knows about this…
Or am I the only one who didn't know how it's suppose to work? ;)
-
@ermal:
Try this manually
https://rcs.pfsense.org/projects/pfsense/repos/mainline/commits/92a1c8e6caca910ae1f8c54751bffebd45d87682or wait for a snapshot with the patch applied.
I just had this error while updating my config. Applied this manually and it works. Will check if it reached the newest snap yet…
-
Ok the fix is in the newest snap, but there seems to be a problem. Each time I reboot my Alix I get the same error as I posted in the beginning of this thread. I then need to ssh into my box do a killall mpd5. Then I have to go into the WebUI to my WAN interface, set a 0 into the idle timeout field and click save. Is this actually also a bug that I have to put in the zero everytime again? It does not get saved somehow…
After these steps my connections stays online until the next reboot...
-
So, are you saying that you have the "Dial on Demand" checkbox checked? Do you really need Dial on Demand?
Refs for devs:
http://redmine.pfsense.org/issues/757http://redmine.pfsense.org/issues/927
-
Yes, that box is checked. I want my line to be up and running all the time…
-
Well, two points.
First if you you want your line up and running all the time, you need to uncheck that box. That box is supposed to bring the line down when no outgoing traffic is detected after "Idle timeout" time has elapsed.
Second, checking that box is likely what has caused all your problems. That options invokes some pretty complex code that apparently still isn't stable. (re: linked redmine issues)
-
If thats is the intended function of dial on demand the the wording of setting it to zero should be remove and replace with untick for always on connection would clear up some confusion
-
Can you please test with latest snapshots?
-
Ok. I'll wait for the next snapshot to appear and try it…
-
2.0-BETA4 (amd64)
built on Tue Dec 21 15:13:51 EST 2010This is a clean install using a config that was running on a nanobsd snapshot from about a week ago. I'm getting errors similar to the OP. I don't have, and never did have the dial on demand box checked. If I click the Connect button on Status: Interfaces, it connects within seconds, but never automatically after a reboot.
-
Try with the most current snapshot, the one just uploaded. IIRC some fixes happened after the snapshot you're on but a new snap hadn't been generated since last night/this morning.
-
Still not working with latest snap…
Still have to kill mpd5 and then click save on WAN interface again...
-
Actually I tried again, because I needed to reboot. (Still the Internal Server Error after installing avahi package)
I do not need to kill mpd5. I just need to click save on WAN… -
2.0-BETA4 (amd64)
built on Thu Dec 23 12:13:59 EST 2010I updated 8 hours ago and still had to hit the Connect button to get a WAN.
-
Same problem here with newest snap of 12/26…
But if I wait long enough pfSense connects by itself after 3-4 minutes... -
Post your mpd logs.
-
Here is some output of my ppp.log:
Dec 28 00:16:03 ppp: [wan_link0] Link: reconnection attempt 2 Dec 28 00:16:03 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:03 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:03 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:03 ppp: [wan_link0] LCP: Down event Dec 28 00:16:03 ppp: [wan_link0] Link: reconnection attempt 3 in 4 seconds Dec 28 00:16:07 ppp: [wan_link0] Link: reconnection attempt 3 Dec 28 00:16:07 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:07 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:07 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:07 ppp: [wan_link0] LCP: Down event Dec 28 00:16:07 ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds Dec 28 00:16:09 ppp: [wan_link0] Link: reconnection attempt 4 Dec 28 00:16:09 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:09 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:09 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:09 ppp: [wan_link0] LCP: Down event Dec 28 00:16:09 ppp: [wan_link0] Link: reconnection attempt 5 in 2 seconds Dec 28 00:16:11 ppp: [wan_link0] Link: reconnection attempt 5 Dec 28 00:16:11 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:11 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:11 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:11 ppp: [wan_link0] LCP: Down event Dec 28 00:16:11 ppp: [wan_link0] Link: reconnection attempt 6 in 2 seconds Dec 28 00:16:13 ppp: [wan_link0] Link: reconnection attempt 6 Dec 28 00:16:13 ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd8264-0" and "[f]:"->"left": No such file or directory Dec 28 00:16:13 ppp: [wan_link0] can't remove hook mpd8264-0 from node "[12]:": No such file or directory Dec 28 00:16:13 ppp: [wan_link0] Link: DOWN event Dec 28 00:16:13 ppp: [wan_link0] LCP: Down event Dec 28 00:16:13 ppp: [wan_link0] Link: reconnection attempt 7 in 4 seconds Dec 28 00:16:17 ppp: caught fatal signal term Dec 28 00:16:17 ppp: [wan] IFACE: Close event Dec 28 00:16:17 ppp: [wan] IPCP: Close event Dec 28 00:16:17 ppp: [wan_link0] LCP: Close event Dec 28 00:16:17 ppp: [wan_link0] LCP: state change Starting --> Initial Dec 28 00:16:17 ppp: [wan_link0] LCP: LayerFinish Dec 28 00:16:19 ppp: [wan] Bundle: Shutdown Dec 28 00:16:19 ppp: [wan_link0] Link: Shutdown Dec 28 00:16:19 ppp: process 8264 terminated Dec 28 00:16:25 ppp: Multi-link PPP daemon for FreeBSD Dec 28 00:16:25 ppp: Dec 28 00:16:25 ppp: process 38608 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 21:35 22-Dec-2010) Dec 28 00:16:25 ppp: web: web is not running Dec 28 00:16:25 ppp: [wan] Bundle: Interface ng0 created Dec 28 00:16:25 ppp: [wan_link0] Link: OPEN event Dec 28 00:16:25 ppp: [wan_link0] LCP: Open event Dec 28 00:16:25 ppp: [wan_link0] LCP: state change Initial --> Starting Dec 28 00:16:25 ppp: [wan_link0] LCP: LayerStart Dec 28 00:16:25 ppp: [wan_link0] PPPoE: Connecting to '*' Dec 28 00:16:25 ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"
That goes on for like 2-3 minutes and then suddenly it works…
Dec 28 00:17:44 ppp: [wan_link0] Link: reconnection attempt 7 in 2 seconds Dec 28 00:17:46 ppp: [wan_link0] Link: reconnection attempt 7 Dec 28 00:17:46 ppp: [wan_link0] PPPoE: Connecting to '*' Dec 28 00:17:46 ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 28 00:17:55 ppp: [wan_link0] PPPoE connection timeout after 9 seconds Dec 28 00:17:55 ppp: [wan_link0] Link: DOWN event Dec 28 00:17:55 ppp: [wan_link0] LCP: Down event Dec 28 00:17:55 ppp: [wan_link0] Link: reconnection attempt 8 in 3 seconds Dec 28 00:17:58 ppp: [wan_link0] Link: reconnection attempt 8 Dec 28 00:17:58 ppp: [wan_link0] PPPoE: Connecting to '*' Dec 28 00:17:58 ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478" Dec 28 00:18:04 ppp: [wan_link0] PPPoE: connection successful Dec 28 00:18:04 ppp: [wan_link0] Link: UP event Dec 28 00:18:04 ppp: [wan_link0] LCP: Up event Dec 28 00:18:04 ppp: [wan_link0] LCP: state change Starting --> Req-Sent Dec 28 00:18:04 ppp: [wan_link0] LCP: SendConfigReq #1 Dec 28 00:18:04 ppp: [wan_link0] PROTOCOMP Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM a8eef870 Dec 28 00:18:04 ppp: [wan_link0] LCP: rec'd Configure Request #52 (Req-Sent) Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] AUTHPROTO PAP Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM 49a2d6c8 Dec 28 00:18:04 ppp: [wan_link0] LCP: SendConfigAck #52 Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] AUTHPROTO PAP Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM 49a2d6c8 Dec 28 00:18:04 ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent Dec 28 00:18:04 ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent) Dec 28 00:18:04 ppp: [wan_link0] PROTOCOMP Dec 28 00:18:04 ppp: [wan_link0] LCP: SendConfigReq #2 Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM a8eef870 Dec 28 00:18:04 ppp: [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent) Dec 28 00:18:04 ppp: [wan_link0] MRU 1492 Dec 28 00:18:04 ppp: [wan_link0] MAGICNUM a8eef870 Dec 28 00:18:04 ppp: [wan_link0] LCP: state change Ack-Sent --> Opened Dec 28 00:18:04 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing Dec 28 00:18:04 ppp: [wan_link0] PAP: using authname "xxxx@t-online.de" Dec 28 00:18:04 ppp: [wan_link0] PAP: sending REQUEST #1 len: 54 Dec 28 00:18:04 ppp: [wan_link0] LCP: LayerUp Dec 28 00:18:05 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5 Dec 28 00:18:05 ppp: [wan_link0] LCP: authorization successful Dec 28 00:18:05 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""' Dec 28 00:18:05 ppp: [wan_link0] Link: Join bundle "wan" Dec 28 00:18:05 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps Dec 28 00:18:05 ppp: [wan] IPCP: Open event Dec 28 00:18:05 ppp: [wan] IPCP: state change Initial --> Starting Dec 28 00:18:05 ppp: [wan] IPCP: LayerStart Dec 28 00:18:05 ppp: [wan] IPCP: Up event Dec 28 00:18:05 ppp: [wan] IPCP: state change Starting --> Req-Sent Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigReq #1 Dec 28 00:18:05 ppp: [wan] IPADDR 0.0.0.0 Dec 28 00:18:05 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Request #229 (Req-Sent) Dec 28 00:18:05 ppp: [wan] IPADDR 87.186.224.38 Dec 28 00:18:05 ppp: [wan] 87.186.224.38 is OK Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigAck #229 Dec 28 00:18:05 ppp: [wan] IPADDR 87.186.224.38 Dec 28 00:18:05 ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent) Dec 28 00:18:05 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigReq #2 Dec 28 00:18:05 ppp: [wan] IPADDR 0.0.0.0 Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent) Dec 28 00:18:05 ppp: [wan] IPADDR xxxx Dec 28 00:18:05 ppp: [wan] xxxx is OK Dec 28 00:18:05 ppp: [wan] IPCP: SendConfigReq #3 Dec 28 00:18:05 ppp: [wan] IPADDR xxxx Dec 28 00:18:05 ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent) Dec 28 00:18:05 ppp: [wan] IPADDR xxxx Dec 28 00:18:05 ppp: [wan] IPCP: state change Ack-Sent --> Opened Dec 28 00:18:05 ppp: [wan] IPCP: LayerUp Dec 28 00:18:05 ppp: [wan] xxxx -> 87.186.224.38 Dec 28 00:18:05 ppp: [wan] IFACE: Up event
This whole thing was working fine for me before…
-
ppp logs if it helps
Dec 27 21:37:47 ppp: [wan] IFACE: Up event
Dec 27 21:37:47 ppp: [wan] 173.191.242.233 -> 151.213.8.5
Dec 27 21:37:47 ppp: [wan] IPCP: LayerUp
Dec 27 21:37:47 ppp: [wan] IPCP: state change Ack-Sent –> Opened
Dec 27 21:37:47 ppp: [wan] IPADDR 173.191.242.233
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
Dec 27 21:37:47 ppp: [wan] IPADDR 173.191.242.233
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigReq #3
Dec 27 21:37:47 ppp: [wan] 173.191.242.233 is OK
Dec 27 21:37:47 ppp: [wan] IPADDR 173.191.242.233
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
Dec 27 21:37:47 ppp: [wan] IPADDR 0.0.0.0
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigReq #2
Dec 27 21:37:47 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
Dec 27 21:37:47 ppp: [wan] IPCP: state change Req-Sent –> Ack-Sent
Dec 27 21:37:47 ppp: [wan] IPADDR 151.213.8.5
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigAck #1
Dec 27 21:37:47 ppp: [wan] 151.213.8.5 is OK
Dec 27 21:37:47 ppp: [wan] IPADDR 151.213.8.5
Dec 27 21:37:47 ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Dec 27 21:37:47 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Dec 27 21:37:47 ppp: [wan] IPADDR 0.0.0.0
Dec 27 21:37:47 ppp: [wan] IPCP: SendConfigReq #1
Dec 27 21:37:47 ppp: [wan] IPCP: state change Starting –> Req-Sent
Dec 27 21:37:47 ppp: [wan] IPCP: Up event
Dec 27 21:37:47 ppp: [wan] IPCP: LayerStart
Dec 27 21:37:47 ppp: [wan] IPCP: state change Initial –> Starting
Dec 27 21:37:47 ppp: [wan] IPCP: Open event
Dec 27 21:37:47 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Dec 27 21:37:47 ppp: [wan_link0] Link: Join bundle "wan"
Dec 27 21:37:47 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Dec 27 21:37:47 ppp: [wan_link0] LCP: authorization successful
Dec 27 21:37:47 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Dec 27 21:37:46 ppp: [wan_link0] LCP: LayerUp
Dec 27 21:37:46 ppp: [wan_link0] PAP: sending REQUEST #1 len: 26
Dec 27 21:37:46 ppp: [wan_link0] PAP: using authname "grandrivers"
Dec 27 21:37:46 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM 6f07b4dc
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:46 ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM c9e5f758
Dec 27 21:37:46 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] LCP: SendConfigAck #1
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM c9e5f758
Dec 27 21:37:46 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Dec 27 21:37:46 ppp: [wan_link0] MAGICNUM 6f07b4dc
Dec 27 21:37:46 ppp: [wan_link0] MRU 1492
Dec 27 21:37:46 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:46 ppp: [wan_link0] LCP: SendConfigReq #1
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Dec 27 21:37:46 ppp: [wan_link0] LCP: Up event
Dec 27 21:37:46 ppp: [wan_link0] Link: UP event
Dec 27 21:37:46 ppp: [wan_link0] PPPoE: connection successful
Dec 27 21:37:46 ppp: PPPoE: rec'd ACNAME "ashtohlcs03-7200"
Dec 27 21:37:46 ppp: [wan_link0] PPPoE: Connecting to ''
Dec 27 21:37:46 ppp: [wan_link0] LCP: LayerStart
Dec 27 21:37:46 ppp: [wan_link0] LCP: state change Initial –> Starting
Dec 27 21:37:46 ppp: [wan_link0] LCP: Open event
Dec 27 21:37:46 ppp: [wan_link0] Link: OPEN event
Dec 27 21:37:46 ppp: [wan] Bundle: Interface ng0 created
Dec 27 21:37:46 ppp: web: web is not running
Dec 27 21:37:46 ppp: process 8957 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 21:35 22-Dec-2010)
Dec 27 21:37:46 ppp:
Dec 27 21:37:46 ppp: Multi-link PPP daemon for FreeBSD
Dec 27 21:37:43 ppp: process 4206 terminated
Dec 27 21:37:43 ppp: [wan_link0] Link: Shutdown
Dec 27 21:37:43 ppp: [wan] Bundle: Shutdown
Dec 27 21:37:42 ppp: [wan_link0] LCP: state change Closed –> Initial
Dec 27 21:37:42 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:42 ppp: [wan_link0] LCP: state change Stopped –> Closed
Dec 27 21:37:42 ppp: [wan_link0] LCP: Close event
Dec 27 21:37:42 ppp: [wan_link0] Link: giving up after 0 reconnection attempts
Dec 27 21:37:42 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:42 ppp: [wan_link0] PPPoE: connection closed
Dec 27 21:37:42 ppp: [wan_link0] LCP: LayerFinish
Dec 27 21:37:42 ppp: [wan_link0] LCP: state change Stopping –> Stopped
Dec 27 21:37:42 ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Stopping)
Dec 27 21:37:41 ppp: [wan_link0] rec'd proto IPCP during terminate phase
Dec 27 21:37:41 ppp: [wan_link0] LCP: LayerDown
Dec 27 21:37:41 ppp: [wan_link0] LCP: SendTerminateReq #2
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Opened –> Stopping
Dec 27 21:37:41 ppp: [wan_link0] LCP: parameter negotiation failed
Dec 27 21:37:41 ppp: [wan_link0] link did not validate in bundle
Dec 27 21:37:41 ppp: Shutdown sequence in progress, BundJoin() denied
Dec 27 21:37:41 ppp: [wan_link0] LCP: authorization successful
Dec 27 21:37:41 ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
Dec 27 21:37:41 ppp: [wan_link0] LCP: LayerUp
Dec 27 21:37:41 ppp: [wan_link0] PAP: sending REQUEST #1 len: 26
Dec 27 21:37:41 ppp: [wan_link0] PAP: using authname "grandrivers"
Dec 27 21:37:41 ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Ack-Sent –> Opened
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM 29185664
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:41 ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Req-Sent –> Ack-Sent
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM c9e5e352
Dec 27 21:37:41 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] LCP: SendConfigAck #1
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM c9e5e352
Dec 27 21:37:41 ppp: [wan_link0] AUTHPROTO PAP
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] LCP: rec'd Configure Request #1 (Req-Sent)
Dec 27 21:37:41 ppp: [wan_link0] MAGICNUM 29185664
Dec 27 21:37:41 ppp: [wan_link0] MRU 1492
Dec 27 21:37:41 ppp: [wan_link0] PROTOCOMP
Dec 27 21:37:41 ppp: [wan_link0] LCP: SendConfigReq #1
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Starting –> Req-Sent
Dec 27 21:37:41 ppp: [wan_link0] LCP: Up event
Dec 27 21:37:41 ppp: [wan_link0] Link: UP event
Dec 27 21:37:41 ppp: [wan_link0] PPPoE: connection successful
Dec 27 21:37:41 ppp: PPPoE: rec'd ACNAME "ashtohlcs03-7200"
Dec 27 21:37:41 ppp: [wan] IPCP: Close event
Dec 27 21:37:41 ppp: [wan] IFACE: Close event
Dec 27 21:37:41 ppp: caught fatal signal term
Dec 27 21:37:41 ppp: [wan_link0] PPPoE: Connecting to ''
Dec 27 21:37:41 ppp: [wan_link0] LCP: LayerStart
Dec 27 21:37:41 ppp: [wan_link0] LCP: state change Initial –> Starting
Dec 27 21:37:41 ppp: [wan_link0] LCP: Open event
Dec 27 21:37:41 ppp: [wan_link0] Link: OPEN event
Dec 27 21:37:41 ppp: [wan] Bundle: Interface ng0 created
Dec 27 21:37:41 ppp: web: web is not running
Dec 27 21:37:41 ppp: process 4206 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 21:35 22-Dec-2010)
Dec 27 21:37:41 ppp:
Dec 27 21:37:41 ppp: Multi-link PPP daemon for FreeBSD
Dec 27 21:37:38 ppp: process 8816 terminated
Dec 27 21:37:38 ppp: [wan_link0] Link: Shutdown
Dec 27 21:37:38 ppp: [wan] Bundle: Shutdown
Dec 27 21:37:37 ppp: [wan_link0] LCP: LayerFinish
Dec 27 21:37:37 ppp: [wan_link0] LCP: state change Starting –> Initial
Dec 27 21:37:37 ppp: [wan_link0] LCP: Close event
Dec 27 21:37:36 ppp: [wan] IPCP: Close event
Dec 27 21:37:36 ppp: [wan] IFACE: Close event
Dec 27 21:37:36 ppp: caught fatal signal term
Dec 27 21:37:35 ppp: [wan_link0] Link: reconnection attempt 103 in 2 seconds
Dec 27 21:37:35 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:35 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:35 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:35 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:35 ppp: [wan_link0] Link: reconnection attempt 102
Dec 27 21:37:34 ppp: [wan_link0] Link: reconnection attempt 102 in 1 seconds
Dec 27 21:37:34 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:34 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:34 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:34 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:34 ppp: [wan_link0] Link: reconnection attempt 101
Dec 27 21:37:33 ppp: [wan_link0] Link: reconnection attempt 101 in 1 seconds
Dec 27 21:37:33 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:33 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:33 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:33 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:33 ppp: [wan_link0] Link: reconnection attempt 100
Dec 27 21:37:31 ppp: [wan_link0] Link: reconnection attempt 100 in 2 seconds
Dec 27 21:37:31 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:31 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:31 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:31 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:31 ppp: [wan_link0] Link: reconnection attempt 99
Dec 27 21:37:27 ppp: [wan_link0] Link: reconnection attempt 99 in 4 seconds
Dec 27 21:37:27 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:27 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:27 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:27 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:27 ppp: [wan_link0] Link: reconnection attempt 98
Dec 27 21:37:24 ppp: [wan_link0] Link: reconnection attempt 98 in 3 seconds
Dec 27 21:37:24 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:24 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:24 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:24 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:24 ppp: [wan_link0] Link: reconnection attempt 97
Dec 27 21:37:21 ppp: [wan_link0] Link: reconnection attempt 97 in 3 seconds
Dec 27 21:37:21 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:21 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:21 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:21 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:21 ppp: [wan_link0] Link: reconnection attempt 96
Dec 27 21:37:20 ppp: [wan_link0] Link: reconnection attempt 96 in 1 seconds
Dec 27 21:37:20 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:20 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:20 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:20 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:20 ppp: [wan_link0] Link: reconnection attempt 95
Dec 27 21:37:19 ppp: [wan_link0] Link: reconnection attempt 95 in 1 seconds
Dec 27 21:37:19 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:19 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:19 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:19 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:19 ppp: [wan_link0] Link: reconnection attempt 94
Dec 27 21:37:15 ppp: [wan_link0] Link: reconnection attempt 94 in 4 seconds
Dec 27 21:37:15 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:15 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:15 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:15 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:15 ppp: [wan_link0] Link: reconnection attempt 93
Dec 27 21:37:13 ppp: [wan_link0] Link: reconnection attempt 93 in 2 seconds
Dec 27 21:37:13 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:13 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:13 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:13 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:13 ppp: [wan_link0] Link: reconnection attempt 92
Dec 27 21:37:12 ppp: [wan_link0] Link: reconnection attempt 92 in 1 seconds
Dec 27 21:37:12 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:12 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:12 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:12 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:12 ppp: [wan_link0] Link: reconnection attempt 91
Dec 27 21:37:10 ppp: [wan_link0] Link: reconnection attempt 91 in 2 seconds
Dec 27 21:37:10 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:10 ppp: [wan_link0] Link: DOWN event
Dec 27 21:37:10 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory
Dec 27 21:37:10 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
Dec 27 21:37:10 ppp: [wan_link0] Link: reconnection attempt 90
Dec 27 21:37:06 ppp: [wan_link0] Link: reconnection attempt 90 in 4 seconds
Dec 27 21:37:06 ppp: [wan_link0] LCP: Down event
Dec 27 21:37:06 ppp: [wan_link0] Link: DOWN event -
At the exact moment it's having the problem, grab the mpd_wan.conf (or whichever interface it is) from /var/etc/ and post it here - make sure to edit out your username and password though, assuming they are really your username and password.
Also the output of this may help:
ps uxawww | grep mpd5
-
/var/etc/mpd_wan.conf:
startup: # configure the console set console close # configure the web server set web close default: pppoeclient: create bundle static wan set iface name pppoe0 set iface route default set iface disable on-demand set iface idle 0 set iface enable tcpmssfix set iface up-script /usr/local/sbin/ppp-linkup set iface down-script /usr/local/sbin/ppp-linkdown set ipcp ranges 0.0.0.0/0 0.0.0.0/0 #log -bund -ccp -chat -iface -ipcp -lcp -link create link static wan_link0 pppoe set link action bundle wan set link disable multilink set link keep-alive 10 60 set link max-redial 0 set link disable chap pap set link accept chap pap eap set link disable incoming set link mtu 1492 set auth authname "xxxx@t-online.de" set auth password xxxx set pppoe iface vr1 open
ps uxawww | grep mpd5:
root 31262 0.0 1.6 7440 3940 ?? SNs 2:22PM 0:00.02 /usr/local/sbin/mpd5 -b -k -d /var/etc -f mpd_wan.conf -p /var/run/pppoe_wan.pid -s ppp pppoeclient
Also check out the screenshot attached. If I open up mpd_wan.conf with vi there is some sort of control character at the end of the file…
![Bildschirmfoto 2010-12-28 um 14.24.10.png](/public/imported_attachments/1/Bildschirmfoto 2010-12-28 um 14.24.10.png)
-
So those are the files from the same time you are getting the errors in the logs?
Dec 27 21:37:35 ppp: [wan_link0] can't remove hook mpd8816-0 from node "[11]:": No such file or directory Dec 27 21:37:35 ppp: [wan_link0] PPPoE: can't connect "[11]:"->"mpd8816-0" and "[f]:"->"left": No such file or directory
That ^M is just a windows carriage return, usually programs are smart enough to ignore those. I've got one on my home router running a working snap from late November, so it's probably not the cause here.
-
I think so. I took this as soon as I could ssh into the box. But these errors go away and for some time you have the other stuff I posted above going on until it finally connects…
-
I just committed a patch which hopefully will resolve this.
Please test with a new snapshot because you need a new mpd binary.