Hello,
I'm having similar issues to some of the post I read on here about PPP and ADSL. However my issue isn't fixed by any of the resolution I see. The following is my output log from debug ppp
488578: Sep 5 19:22:47.408: %DIALER-6-BIND: Interface Vi2 bound to profile Di1
488579: Sep 5 19:22:47.412: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
488580: Sep 5 19:22:47.412: Vi2 DDR: Dialer statechange to up
488581: Sep 5 19:22:47.412: Vi2 PPP: Sending cstate UP notification
488582: Sep 5 19:22:47.412: Vi2 PPP: Processing CstateUp message
488583: Sep 5 19:22:47.412: PPP: Alloc Context [21FE5BFC]
488584: Sep 5 19:22:47.412: ppp61 PPP: Phase is ESTABLISHING
488585: Sep 5 19:22:47.412: Vi2 PPP: Using dialer call direction
488586: Sep 5 19:22:47.412: Vi2 PPP: Treating connection as a callout
488587: Sep 5 19:22:47.412: Vi2 PPP: Session handle[390000FE] Session id[61]
488588: Sep 5 19:22:47.412: Vi2 LCP: Event[OPEN] State[Initial to Starting]
488589: Sep 5 19:22:47.412: Vi2 PPP: No remote authentication for call-out
488590: Sep 5 19:22:47.412: Vi2 LCP: O CONFREQ [Starting] id 1 len 14
488591: Sep 5 19:22:47.412: Vi2 LCP: MRU 1492 (0x010405D4)
488592: Sep 5 19:22:47.412: Vi2 LCP: MagicNumber 0x6BB36944 (0x05066BB36944)
488593: Sep 5 19:22:47.412: Vi2 LCP: Event[UP] State[Starting to REQsent]
488594: Sep 5 19:22:47.424: Vi2 PPP: I pkt type 0xC021, datagramsize 16 link[ppp]
488595: Sep 5 19:22:47.424: Vi2 LCP: I CONFACK [REQsent] id 1 len 14
488596: Sep 5 19:22:47.424: Vi2 LCP: MRU 1492 (0x010405D4)
488597: Sep 5 19:22:47.428: Vi2 LCP: MagicNumber 0x6BB36944 (0x05066BB36944)
488598: Sep 5 19:22:47.428: Vi2 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
488599: Sep 5 19:22:49.400: Vi2 LCP: O CONFREQ [ACKrcvd] id 2 len 14
488600: Sep 5 19:22:49.400: Vi2 LCP: MRU 1492 (0x010405D4)
488601: Sep 5 19:22:49.400: Vi2 LCP: MagicNumber 0x6BB36944 (0x05066BB36944)
488602: Sep 5 19:22:49.400: Vi2 LCP: Event[Timeout+] State[ACKrcvd to REQsent]
488603: Sep 5 19:22:49.408: Vi2 PPP: I pkt type 0xC021, datagramsize 16 link[ppp]
488604: Sep 5 19:22:49.408: Vi2 LCP: I CONFACK [REQsent] id 2 len 14
488605: Sep 5 19:22:49.408: Vi2 LCP: MRU 1492 (0x010405D4)
488606: Sep 5 19:22:49.408: Vi2 LCP: MagicNumber 0x6BB36944 (0x05066BB36944)
488607: Sep 5 19:22:49.408: Vi2 LCP: Event[Receive ConfAck] State[REQsent to ACKrcvd]
488609: Sep 5 19:22:50.472: Vi2 PPP: I pkt type 0xC021, datagramsize 20 link[ppp]
488610: Sep 5 19:22:50.472: Vi2 LCP: I CONFREQ [ACKrcvd] id 2 len 18
488611: Sep 5 19:22:50.472: Vi2 LCP: MRU 1492 (0x010405D4)
488612: Sep 5 19:22:50.472: Vi2 LCP: AuthProto PAP (0x0304C023)
488613: Sep 5 19:22:50.472: Vi2 LCP: MagicNumber 0xE1C3723B (0x0506E1C3723B)
488614: Sep 5 19:22:50.472: Vi2 LCP: O CONFACK [ACKrcvd] id 2 len 18
488615: Sep 5 19:22:50.472: Vi2 LCP: MRU 1492 (0x010405D4)
488616: Sep 5 19:22:50.472: Vi2 LCP: AuthProto PAP (0x0304C023)
488617: Sep 5 19:22:50.472: Vi2 LCP: MagicNumber 0xE1C3723B (0x0506E1C3723B)
488618: Sep 5 19:22:50.472: Vi2 LCP: Event[Receive ConfReq+] State[ACKrcvd to Open]
488619: Sep 5 19:22:50.488: Vi2 PPP: Phase is AUTHENTICATING, by the peer
488620: Sep 5 19:22:50.488: Vi2 PAP: Using hostname from interface PAP
488621: Sep 5 19:22:50.488: Vi2 PAP: Using password from interface PAP
488622: Sep 5 19:22:50.488: Vi2 PAP: O AUTH-REQ id 1 len 22 from "oiWi2639"
488623: Sep 5 19:22:50.488: Vi2 LCP: State is Open
488624: Sep 5 19:22:50.720: Vi2 PPP: I pkt type 0xC023, datagramsize 38 link[ppp]
488625: Sep 5 19:22:50.720: Vi2 PPP: I pkt type 0x8021, datagramsize 12 link[ip]
488626: Sep 5 19:22:50.720: Vi2 PAP: I AUTH-ACK id 1 len 36 msg is "Authentication success,Welcome!"
488627: Sep 5 19:22:50.720: Vi2 PPP: Phase is FORWARDING, Attempting Forward
488628: Sep 5 19:22:50.720: Vi2 PPP: Queue IPCP code[1] id[1]
488629: Sep 5 19:22:50.720: Vi2 PPP: Phase is ESTABLISHING, Finish LCP
488630: Sep 5 19:22:50.720: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access2, changed state to up
488631: Sep 5 19:22:50.720: Vi2 PPP: No AAA accounting method list
488632: Sep 5 19:22:50.720: Vi2 PPP: Phase is UP
488633: Sep 5 19:22:50.724: Vi2 IPCP: Protocol configured, start CP. state[Initial]
488634: Sep 5 19:22:50.724: Vi2 IPCP: Event[OPEN] State[Initial to Starting]
488635: Sep 5 19:22:50.724: Vi2 IPCP: O CONFREQ [Starting] id 1 len 10
488636: Sep 5 19:22:50.724: Vi2 IPCP: Address 77.X.X.X (0x03064D45BEB0)
488637: Sep 5 19:22:50.724: Vi2 IPCP: Event[UP] State[Starting to REQsent]
488638: Sep 5 19:22:50.724: Vi2 PPP: Process pending ncp packets
488639: Sep 5 19:22:50.724: Vi2 IPCP: Redirect packet to Vi2
488640: Sep 5 19:22:50.724: Vi2 IPCP: I CONFREQ [REQsent] id 1 len 10
488641: Sep 5 19:22:50.724: Vi2 IPCP: Address 77.X.X.X (0x03064D45BE81)
488642: Sep 5 19:22:50.724: Vi2 IPCP: O CONFACK [REQsent] id 1 len 10
488643: Sep 5 19:22:50.724: Vi2 IPCP: Address 77.X.X.X (0x03064D45BE81)
488644: Sep 5 19:22:50.724: Vi2 IPCP: Event[Receive ConfReq+] State[REQsent to ACKsent]
488645: Sep 5 19:22:50.732: Vi2 PPP: I pkt type 0x8021, datagramsize 12 link[ip]
488646: Sep 5 19:22:50.732: Vi2 IPCP: I CONFACK [ACKsent] id 1 len 10
488647: Sep 5 19:22:50.732: Vi2 IPCP: Address 77.X.X.X (0x03064D45BEB0)
488648: Sep 5 19:22:50.732: Vi2 IPCP: Event[Receive ConfAck] State[ACKsent to Open]
488649: Sep 5 19:22:50.740: Vi2 PPP: I pkt type 0xC021, datagramsize 6 link[ppp]
488650: Sep 5 19:22:50.740: Vi2 LCP: I TERMREQ [Open] id 3 len 4
488651: Sep 5 19:22:50.740: Vi2 PPP DISC: Received LCP TERMREQ from peer
488652: Sep 5 19:22:50.740: Vi2 PPP: Sending Acct Event[Down] id[6290]
488653: Sep 5 19:22:50.740: PPP: NET STOP send to AAA.
488654: Sep 5 19:22:50.740: Vi2 PPP: Phase is TERMINATING
488655: Sep 5 19:22:50.744: Vi2 IPCP: Event[DOWN] State[Open to Starting]
488656: Sep 5 19:22:50.744: Vi2 IPCP: Event[CLOSE] State[Starting to Initial]
488657: Sep 5 19:22:50.744: Vi2 LCP: O TERMACK [Open] id 3 len 4
488658: Sep 5 19:22:50.744: Vi2 LCP: Event[Receive TermReq] State[Open to Stopping]
488659: Sep 5 19:22:50.744: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di1
488660: Sep 5 19:22:50.744: Vi2 DDR: disconnecting call
488661: Sep 5 19:22:50.744: Vi2 PPP: Block vaccess from being freed [0x10]
488662: Sep 5 19:22:50.748: Vi2 LCP: Event[DOWN] State[Stopping to Starting]
488663: Sep 5 19:22:50.748: Vi2 PPP: Unlocked by [0x10] Still Locked by [0x0]
488664: Sep 5 19:22:50.748: Vi2 PPP: Free previously blocked vaccess
488665: Sep 5 19:22:50.748: Vi2 PPP: Phase is DOWN
488666: Sep 5 19:22:50.748: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access2, changed state to down
488667: Sep 5 19:22:50.748: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
488668: Sep 5 19:22:50.748: Vi2 PPP: Sending cstate DOWN notification
488669: Sep 5 19:22:50.748: Vi2 PPP: Processing CstateDown message
I'm not sure why we're receiving a termination request from the service provider. Everything seems ok with authentication. I'm not sure what else to check. If any can help greatly appreciate it.
