Problems with establishing PPP

[tux43]

Hi,

I have read all of the FAQ's and followed the instructions regarding
bringing up a PPP session on my TiVo however it is failing.

To me it seems like logging in to Telstra BP is working fine.

I am using Telstra's BigPond Lite Plan as specified at
http://minnie.tuhs.org/twiki/bin/view/Network/AustralianISPs

Here is the output of /var/log/tclient

Feb 2 14:58:49 (none) comm[110]:
ATW2S6=8S7=50S11=100%C0&D2+MS=11,1,300,33600,0
,0
Feb 2 14:58:49 (none) comm[110]: OK
Feb 2 14:58:49 (none) comm[110]: Modem set up and onhook.
Feb 2 14:58:49 (none) comm[110]: Turning off dial tone detection
Feb 2 14:58:49 (none) comm[110]: ATX3
Feb 2 14:58:49 (none) comm[110]: OK
Feb 2 14:58:49 (none) comm[110]: Trying to connect
Feb 2 14:59:15 (none) comm[110]: ATDT0198308888
Feb 2 14:59:15 (none) comm[110]: CONNECT 33600
Feb 2 14:59:15 (none) comm[110]: Got a connection.
Feb 2 14:59:15 (none) comm[110]: Trying to login
Feb 2 14:59:15 (none) comm[110]: ** Dial IP **
Feb 2 14:59:15 (none) comm[110]: Username:
Feb 2 14:59:15 (none) comm[110]: <username displayed>
Feb 2 14:59:15 (none) comm[110]: Password:
Feb 2 14:59:16 (none) comm[110]:
Feb 2 14:59:16 (none) comm[110]: Entering PPP Session.
Feb 2 14:59:16 (none) comm[110]:
Feb 2 14:59:16 (none) comm[110]: Login is complete.
Feb 2 14:59:16 (none) comm[110]: Modem dialcode is 1
Feb 2 14:59:16 (none) comm[110]: CallStatusReporter: Phase: Calling,
Status In
Progress
Feb 2 14:59:16 (none) comm[110]: CallStatusReporter: sending message
"CL|31"
Feb 2 14:59:16 (none) comm[110]: CallStatusReporter: Phase: Start_PPP,
Status I
n Progress
Feb 2 14:59:16 (none) comm[110]: CallStatusReporter: sending message
"ST|33"
Feb 2 14:59:16 (none) comm[110]: Going to start pppd.
Feb 2 14:59:16 (none) comm[110]: Pppd:Start: enter
Feb 2 14:59:16 (none) comm[110]: Pppd::Start: fork()ed (pid = 180)
Feb 2 14:59:16 (none) comm[110]: Pppd::Start: storing child pid as 180
Feb 2 14:59:16 (none) comm[110]: Pppd::Start: waiting for PPP - 1
Feb 2 14:59:16 (none) comm[180]: Pppd::Start: fork()ed (pid = 0)
Feb 2 14:59:16 (none) comm[180]: Pppd::Start: spawning pppd
Feb 2 14:59:16 (none) comm[110]: Pppd child handler invoked
Feb 2 14:59:16 (none) comm[110]: Pppd::Start: waiting for PPP - 2
Feb 2 14:59:19 (none) comm[110]: Pppd::Start: waiting for PPP - 3
Feb 2 14:59:22 (none) comm[110]: Pppd::Start: waiting for PPP - 4
Feb 2 14:59:25 (none) comm[110]: Pppd::Start: waiting for PPP - 5
Feb 2 14:59:28 (none) comm[110]: Pppd::Start: waiting for PPP - 6
Feb 2 14:59:31 (none) comm[110]: Pppd::Start: waiting for PPP - 7
Feb 2 14:59:34 (none) comm[110]: Pppd::Start: waiting for PPP - 8
Feb 2 14:59:37 (none) comm[110]: Pppd::Start: waiting for PPP - 9
Feb 2 14:59:40 (none) comm[110]: Pppd::Start: waiting for PPP - 10
Feb 2 14:59:43 (none) comm[110]: Pppd::Stop: enter
Feb 2 14:59:43 (none) comm[110]: Pppd::Stop: killed (pid = 180, status
= -1)
Feb 2 14:59:43 (none) comm[110]: Pppd::Stop: waited (pid = 180, status
= -1)
Feb 2 14:59:43 (none) comm[110]: Pppd::Stop: exit
Feb 2 14:59:43 (none) comm[110]: Pppd::Start: exit (failure)
Feb 2 14:59:43 (none) comm[110]: CallStatusReporter: Turning off-hook
LED off
Feb 2 14:59:44 (none) comm[110]: Recover private logs
Feb 2 14:59:44 (none) comm[110]: Opening old private log files
Feb 2 14:59:44 (none) comm[110]: Reverting private logs
Feb 2 14:59:46 (none) tcphonehome[110]: CallService return status
0xffff
Feb 2 14:59:48 (none) comm[110]: OK
Feb 2 14:59:48 (none) comm[110]: ATZ
Feb 2 14:59:48 (none) comm[110]: OK
Feb 2 14:59:51 (none) comm[110]: CallStatusReporter: Phase: Start_PPP,
Status F
ailed
Feb 2 14:59:51 (none) comm[110]: CallStatusReporter: sending message
"EX|13"
Feb 2 14:59:51 (none) tcphonehome[110]: TClient failed: EX|13
Feb 2 14:59:51 (none) tcphonehome[110]: Sending dialup event, subtype
8, msg EX
|13
Feb 2 14:59:51 (none) tcphonehome[110]: setCallStatus called with:
Failed state
0
Feb 2 14:59:58 (none) tcphonehome[110]: LastCallAttempt at 1107356290
Feb 2 14:59:58 (none) tcphonehome[110]: setting call status to: Failed
Feb 2 14:59:58 (none) tcphonehome[110]: setting call in progress to 0
(second s
uccessCount)
Feb 2 14:59:58 (none) tcphonehome[110]: Setting callActive to 0
Feb 2 14:59:58 (none) comm[110]: CallStatusReporter: sending data
changed event
20
Feb 2 14:59:58 (none) tcphonehome[110]: destroying CommGlobals!
Feb 2 14:59:58 (none) tcphonehome[110]: pCommMempool empty
Feb 2 14:59:58 (none) tcphonehome[110]: Setting MCP PhoneHome action:
ENABLED