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

    Upgrade from 12/13 to 12/18 -> PPPoE dead…

    2.0-RC Snapshot Feedback and Problems - RETIRED
    20
    193
    73.3k
    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.
    • J
      jlepthien
      last edited by

      Ok. I'll wait for the next snapshot to appear and try it…

      | apple fanboy | music lover | network and security specialist | in love with cisco systems |

      1 Reply Last reply Reply Quote 0
      • C
        clarknova
        last edited by

        2.0-BETA4 (amd64)
        built on Tue Dec 21 15:13:51 EST 2010

        This 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.

        db

        1 Reply Last reply Reply Quote 0
        • jimpJ
          jimp Rebel Alliance Developer Netgate
          last edited by

          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.

          Remember: Upvote with the 👍 button for any user/post you find to be helpful, informative, or deserving of recognition!

          Need help fast? Netgate Global Support!

          Do not Chat/PM for help!

          1 Reply Last reply Reply Quote 0
          • J
            jlepthien
            last edited by

            Still not working with latest snap…

            Still have to kill mpd5 and then click save on WAN interface again...

            | apple fanboy | music lover | network and security specialist | in love with cisco systems |

            1 Reply Last reply Reply Quote 0
            • J
              jlepthien
              last edited by

              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…

              | apple fanboy | music lover | network and security specialist | in love with cisco systems |

              1 Reply Last reply Reply Quote 0
              • C
                clarknova
                last edited by

                2.0-BETA4 (amd64)
                built on Thu Dec 23 12:13:59 EST 2010

                I updated 8 hours ago and still had to hit the Connect button to get a WAN.

                db

                1 Reply Last reply Reply Quote 0
                • J
                  jlepthien
                  last edited by

                  Same problem here with newest snap of 12/26…
                  But if I wait long enough pfSense connects by itself after 3-4 minutes...

                  | apple fanboy | music lover | network and security specialist | in love with cisco systems |

                  1 Reply Last reply Reply Quote 0
                  • C
                    cmb
                    last edited by

                    Post your mpd logs.

                    1 Reply Last reply Reply Quote 0
                    • J
                      jlepthien
                      last edited by

                      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…

                      | apple fanboy | music lover | network and security specialist | in love with cisco systems |

                      1 Reply Last reply Reply Quote 0
                      • G
                        grandrivers
                        last edited by

                        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

                        pfsense 2.4 super micro A1SRM-2558F
                        C2558 8gig ECC  60gig SSD
                        tripple Wan dual pppoe

                        1 Reply Last reply Reply Quote 0
                        • jimpJ
                          jimp Rebel Alliance Developer Netgate
                          last edited by

                          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

                          Remember: Upvote with the 👍 button for any user/post you find to be helpful, informative, or deserving of recognition!

                          Need help fast? Netgate Global Support!

                          Do not Chat/PM for help!

                          1 Reply Last reply Reply Quote 0
                          • J
                            jlepthien
                            last edited by

                            /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)

                            | apple fanboy | music lover | network and security specialist | in love with cisco systems |

                            1 Reply Last reply Reply Quote 0
                            • jimpJ
                              jimp Rebel Alliance Developer Netgate
                              last edited by

                              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.

                              Remember: Upvote with the 👍 button for any user/post you find to be helpful, informative, or deserving of recognition!

                              Need help fast? Netgate Global Support!

                              Do not Chat/PM for help!

                              1 Reply Last reply Reply Quote 0
                              • J
                                jlepthien
                                last edited by

                                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…

                                | apple fanboy | music lover | network and security specialist | in love with cisco systems |

                                1 Reply Last reply Reply Quote 0
                                • E
                                  eri--
                                  last edited by

                                  I just committed a patch which hopefully will resolve this.
                                  Please test with a new snapshot because you need a new mpd binary.

                                  1 Reply Last reply Reply Quote 0
                                  • jimpJ
                                    jimp Rebel Alliance Developer Netgate
                                    last edited by

                                    FYI- I just rebuilt it on the builder after Ermal's patch, and restarted the build, so the next new snapshot that uploads should include the fix.

                                    Remember: Upvote with the 👍 button for any user/post you find to be helpful, informative, or deserving of recognition!

                                    Need help fast? Netgate Global Support!

                                    Do not Chat/PM for help!

                                    1 Reply Last reply Reply Quote 0
                                    • J
                                      jlepthien
                                      last edited by

                                      It got even worse over here…
                                      After a reboot the ppp.log showed this:

                                      Dec 29 16:34:25 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:25 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:25 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:25 voldemort ppp: [wan_link0] Link: reconnection attempt 2 in 1 seconds
Dec 29 16:34:26 voldemort ppp: [wan_link0] Link: reconnection attempt 2
Dec 29 16:34:26 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory
Dec 29 16:34:26 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:26 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:26 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:26 voldemort ppp: [wan_link0] Link: reconnection attempt 3 in 2 seconds
Dec 29 16:34:28 voldemort ppp: [wan_link0] Link: reconnection attempt 3
Dec 29 16:34:28 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory
Dec 29 16:34:28 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:28 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:28 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:28 voldemort ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds
Dec 29 16:34:30 voldemort ppp: [wan_link0] Link: reconnection attempt 4
Dec 29 16:34:30 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory
Dec 29 16:34:30 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:30 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:30 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:30 voldemort ppp: [wan_link0] Link: reconnection attempt 5 in 4 seconds
Dec 29 16:34:34 voldemort ppp: [wan_link0] Link: reconnection attempt 5
Dec 29 16:34:34 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory
Dec 29 16:34:34 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:34 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:34 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:34 voldemort ppp: [wan_link0] Link: reconnection attempt 6 in 4 seconds
Dec 29 16:34:38 voldemort ppp: [wan_link0] Link: reconnection attempt 6
Dec 29 16:34:38 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory
Dec 29 16:34:38 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:38 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:38 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:38 voldemort ppp: [wan_link0] Link: reconnection attempt 7 in 1 seconds
Dec 29 16:34:39 voldemort ppp: [wan_link0] Link: reconnection attempt 7
Dec 29 16:34:39 voldemort ppp: [wan_link0] PPPoE: can't connect "[12]:"->"mpd10506-0" and "[f]:"->"left": No such file or directory
Dec 29 16:34:39 voldemort ppp: [wan_link0] can't remove hook mpd10506-0 from node "[12]:": No such file or directory
Dec 29 16:34:39 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:39 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:39 voldemort ppp: [wan_link0] Link: reconnection attempt 8 in 3 seconds
Dec 29 16:34:41 voldemort ppp: caught fatal signal term
Dec 29 16:34:41 voldemort ppp: [wan] IFACE: Close event
Dec 29 16:34:41 voldemort ppp: [wan] IPCP: Close event
Dec 29 16:34:42 voldemort ppp: [wan_link0] LCP: Close event
Dec 29 16:34:42 voldemort ppp: [wan_link0] LCP: state change Starting --> Initial
Dec 29 16:34:42 voldemort ppp: [wan_link0] LCP: LayerFinish
Dec 29 16:34:43 voldemort ppp: [wan] Bundle: Shutdown
Dec 29 16:34:43 voldemort ppp: [wan_link0] Link: Shutdown
Dec 29 16:34:43 voldemort ppp: process 10506 terminated
Dec 29 16:34:46 voldemort ppp: Multi-link PPP daemon for FreeBSD
Dec 29 16:34:46 voldemort ppp:  
Dec 29 16:34:46 voldemort ppp: process 25103 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 16:48 28-Dec-2010)
Dec 29 16:34:46 voldemort ppp: web: web is not running
Dec 29 16:34:46 voldemort ppp: [wan] Bundle: Interface ng0 created
Dec 29 16:34:46 voldemort ppp: [wan_link0] Link: OPEN event
Dec 29 16:34:46 voldemort ppp: [wan_link0] LCP: Open event
Dec 29 16:34:46 voldemort ppp: [wan_link0] LCP: state change Initial --> Starting
Dec 29 16:34:46 voldemort ppp: [wan_link0] LCP: LayerStart
Dec 29 16:34:46 voldemort ppp: [wan_link0] PPPoE: Connecting to '*'
Dec 29 16:34:46 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"
Dec 29 16:34:55 voldemort ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Dec 29 16:34:55 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:34:55 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:34:55 voldemort ppp: [wan_link0] Link: reconnection attempt 1 in 1 seconds
Dec 29 16:34:56 voldemort ppp: [wan_link0] Link: reconnection attempt 1
Dec 29 16:34:56 voldemort ppp: [wan_link0] PPPoE: Connecting to '*'
Dec 29 16:34:57 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"
Dec 29 16:35:06 voldemort ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Dec 29 16:35:06 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:35:06 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:35:06 voldemort ppp: [wan_link0] Link: reconnection attempt 2 in 1 seconds
Dec 29 16:35:07 voldemort ppp: [wan_link0] Link: reconnection attempt 2
Dec 29 16:35:07 voldemort ppp: [wan_link0] PPPoE: Connecting to '*'
Dec 29 16:35:07 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"
Dec 29 16:35:16 voldemort ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Dec 29 16:35:16 voldemort ppp: [wan_link0] Link: DOWN event
Dec 29 16:35:16 voldemort ppp: [wan_link0] LCP: Down event
Dec 29 16:35:16 voldemort ppp: [wan_link0] Link: reconnection attempt 3 in 3 seconds
Dec 29 16:35:19 voldemort ppp: [wan_link0] Link: reconnection attempt 3
Dec 29 16:35:19 voldemort ppp: [wan_link0] PPPoE: Connecting to '*'
Dec 29 16:35:19 voldemort ppp: PPPoE: rec'd ACNAME "KIER71-se800-B2234310702478"

                                      Then suddenly it stopped and I checked if mpd5 was running which was not. Also the WebGUI wasn't accessable…

                                      system.log showed the following:

                                      Dec 29 16:37:35 voldemort kernel: pid 14143 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 14463 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 14739 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 14314 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 14793 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 15107 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 15387 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 15807 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16146 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16160 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 15704 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16285 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16331 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16761 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16748 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 17055 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 17089 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 17394 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16638 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 16853 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 17664 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18136 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 17773 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18389 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18687 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18083 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18495 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18919 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 18798 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 19008 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 19298 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 19625 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 19589 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 19925 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 20132 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 20418 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 20714 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 20804 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 20829 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 21317 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 21104 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 21314 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 21511 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 21839 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 22209 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 22150 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 22368 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 22644 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 22831 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 22645 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 23037 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 23171 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 23461 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24007 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 23702 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24061 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24346 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24312 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24044 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24669 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24820 (php), uid 0: exited on signal 11
Dec 29 16:37:35 voldemort kernel: pid 24933 (php), uid 0: exited on signal 11

                                      So I had to do a 'killall php' and restart the Web Configurator. After that I could click save on the WAN interface so that mpd5 would start again. This time the ppp logs started again and it took some more minutes before the connection came up. Still not everything was right (see screenshot attached) - the IP on WAN was missing and I could not access website although the connection seemed to be up…

                                      After another reboot and waiting some minutes for the connection to come up everything looked fine. But the packages (at the moment only avahi installed) did not get reinstalled automatically, again... After manually reinstalling avahi that was the log:

                                      Dec 29 16:41:22 voldemort avahi-daemon[38866]: Found user 'avahi' (UID 558) and group 'avahi' (GID 558).
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Successfully dropped root privileges.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: avahi-daemon 0.6.24 starting up.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Dec 29 16:41:22 voldemort avahi-daemon[38866]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Loading service file /usr/local/etc/avahi/services/sftp-ssh.service.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Loading service file /usr/local/etc/avahi/services/ssh.service.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface ng1.IPv4 with address xxxx.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface ng1.IPv4 for mDNS.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface ath0_wlan1.IPv4 with address 192.168.100.254.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface ath0_wlan1.IPv4 for mDNS.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface ath0_wlan0.IPv4 with address 172.16.100.254.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface ath0_wlan0.IPv4 for mDNS.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Joining mDNS multicast group on interface vr0.IPv4 with address 10.0.100.254.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: New relevant interface vr0.IPv4 for mDNS.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Network interface enumeration completed.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::20d:b9ff:fe13:54a8 on ng1.*.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for xxxx on ng1.IPv4.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::480:48ff:fe62:fa57 on ath0_wlan1.*.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for 192.168.100.254 on ath0_wlan1.IPv4.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::280:48ff:fe62:fa57 on ath0_wlan0.*.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for 172.16.100.254 on ath0_wlan0.IPv4.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for fe80::20d:b9ff:fe13:54a8 on vr0.*.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering new address record for 10.0.100.254 on vr0.IPv4.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Registering HINFO record with values 'I386'/'FREEBSD'.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Short read() on signal pipe.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: Short read() on signal pipe.
Dec 29 16:41:22 voldemort avahi-daemon[38866]: daemon_signal_next() failed
Dec 29 16:41:22 voldemort avahi-daemon[38866]: daemon_signal_next() failed
Dec 29 16:41:22 voldemort check_reload_status: reloading filter
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: iface.c: avahi_server_add_service() failed: Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: Server startup complete. Host name is voldemort.hogwarts.lan. Local service cookie is 3244555418.
Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'voldemort' of type '_ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/ssh.service): Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'voldemort' of type '_ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/ssh.service): Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'SFTP File Transfer on voldemort' of type '_sftp-ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/sftp-ssh.service): Not supported
Dec 29 16:41:23 voldemort avahi-daemon[38866]: Failed to add service 'SFTP File Transfer on voldemort' of type '_sftp-ssh._tcp', ignoring service group (/usr/local/etc/avahi/services/sftp-ssh.service): Not supported

                                      So still some error messages. What are these. Are they 'normal'?

                                      ![Bildschirmfoto 2010-12-29 um 16.42.48.png](/public/imported_attachments/1/Bildschirmfoto 2010-12-29 um 16.42.48.png)
                                      ![Bildschirmfoto 2010-12-29 um 16.42.48.png_thumb](/public/imported_attachments/1/Bildschirmfoto 2010-12-29 um 16.42.48.png_thumb)

                                      | apple fanboy | music lover | network and security specialist | in love with cisco systems |

                                      1 Reply Last reply Reply Quote 0
                                      • E
                                        eri--
                                        last edited by

                                        I think your problems come from avahi package because it does some very bad things to base system.
                                        Please uninstall avahi and if you can upgrade to a latest snap.

                                        1 Reply Last reply Reply Quote 0
                                        • J
                                          jlepthien
                                          last edited by

                                          Ok. I can test this, but I rely heavily on avahi for the apple bonjour stuff…

                                          | apple fanboy | music lover | network and security specialist | in love with cisco systems |

                                          1 Reply Last reply Reply Quote 0
                                          • G
                                            gnhb
                                            last edited by

                                            Bonjour works without the avahi package on your router. I have lots of macs and mac products on my networks and no avahi package on my routers.

                                            I'm not really even sure of the purpose of a router running avahi. What are you trying to do?

                                            GB

                                            1 Reply Last reply Reply Quote 0
                                            • First post
                                              Last post