Jul 1 15:41:47 (none) comm[144]: sourceParameterList: st=3,con=5,drm=1,ekr=1,ccn=,brn=,lin=;st=2,con=1,d rm=,ekr=1,ccn=,brn=,lin=
Jul 1 15:41:47 (none) comm[144]: configParameterList: zip=07000,dar=0,rcq=100,tz=1,as=1,tun=,suc=0,sta=0 ,exp=12917
Jul 1 15:41:47 (none) comm[144]: iceboxinfo:
Jul 1 15:41:47 (none) comm[144]: timeStamp: ...
Jul 1 15:41:47 (none) comm[144]: End Ident ==================
Jul 1 15:41:47 (none) comm[144]: CallService: Start err 0
Jul 1 15:41:47 (none) comm[144]: CAM_ID not found.
Jul 1 15:41:47 (none) comm[144]: CallStatusReporter: Phase: Dial, Status In Progress
Jul 1 15:41:47 (none) comm[144]: CallStatusReporter: sending message "CL|30"
Jul 1 15:41:47 (none) comm[144]: CallStatusReporter: Turning off-hook LED on
Jul 1 15:41:47 (none) comm[144]: Opening device
Jul 1 15:41:47 (none) comm[144]: Initializing modem
Jul 1 15:41:47 (none) comm[144]: ATZ
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: Determining modem type
Jul 1 15:41:47 (none) comm[144]: ATI3
Jul 1 15:41:47 (none) comm[144]: V2.210K1-V34_DLP
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: Modem is OLD
Jul 1 15:41:47 (none) comm[144]: Set up modem and check for availability
Jul 1 15:41:47 (none) comm[144]: Setting up old modem
Jul 1 15:41:47 (none) comm[144]: 0007: FB
Jul 1 15:41:47 (none) comm[144]: 02
Jul 1 15:41:47 (none) comm[144]: 0008: E0
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: ATQ0
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: ATS0=0
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: ATW2S6=8S7=50S11=100%C0&D2+MS=11,1,300,33600,0,0
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: Modem set up and onhook.
Jul 1 15:41:47 (none) comm[144]: Turning off dial tone detection
Jul 1 15:41:47 (none) comm[144]: ATX3
Jul 1 15:41:47 (none) comm[144]: OK
Jul 1 15:41:47 (none) comm[144]: Trying to connect
Jul 1 15:42:14 (none) comm[144]: ATDT0198308100
Jul 1 15:42:14 (none) comm[144]: CONNECT 33600
Jul 1 15:42:14 (none) comm[144]: Got a connection.
Jul 1 15:42:14 (none) comm[144]: Trying to login
Jul 1 15:42:14 (none) comm[144]: ** Dial IP **
Jul 1 15:42:14 (none) comm[144]: Username:
Jul 1 15:42:14 (none) comm[144]: myusername
Jul 1 15:42:14 (none) comm[144]: Password:
Jul 1 15:42:14 (none) comm[144]:
Jul 1 15:42:14 (none) comm[144]: Entering PPP Session
Jul 1 15:42:14 (none) comm[144]: Login is complete.
Jul 1 15:42:14 (none) comm[144]: Modem dialcode is 1
Jul 1 15:42:14 (none) comm[144]: CallStatusReporter: Phase: Calling, Status In Progress
Jul 1 15:42:14 (none) comm[144]: CallStatusReporter: sending message "CL|31"
Jul 1 15:42:14 (none) comm[144]: CallStatusReporter: Phase: Start_PPP, Status In Progress
Jul 1 15:42:14 (none) comm[144]: CallStatusReporter: sending message "ST|33"
Jul 1 15:42:14 (none) comm[144]: Going to start pppd.
Jul 1 15:42:14 (none) comm[144]: Pppd:Start: enter
Jul 1 15:42:14 (none) comm[144]: Pppd::Start: fork()ed (pid = 490)
Jul 1 15:42:14 (none) comm[144]: Pppd::Start: storing child pid as 490
Jul 1 15:42:14 (none) comm[144]: Pppd::Start: waiting for PPP - 1
Jul 1 15:42:14 (none) comm[490]: Pppd::Start: fork()ed (pid = 0)
Jul 1 15:42:14 (none) comm[490]: Pppd::Start: spawning pppd
Jul 1 15:42:17 (none) comm[144]: Pppd::Start: waiting for PPP - 2
Jul 1 15:42:17 (none) comm[144]: Pppd::Start: exit (succeeded)
Jul 1 15:42:17 (none) comm[144]: ModemImpl::enableLineStatusInterrupt old modem, set status 0.
Jul 1 15:42:17 (none) comm[144]: CallStatusReporter: Phase: Start_Auth, Status In Progress
Jul 1 15:42:23 (none) comm[144]: CallStatusReporter: sending message "ST|33"
Jul 1 15:45:23 (none) comm[144]: CommUtil: connection to host 65.49.60.197, port 9090, err 0x50005
Jul 1 15:45:23 (none) comm[144]: tmk tcp connect failed, reason = Operation now in progress
Jul 1 15:45:23 (none) comm[144]: drainGetPostQ: doXfer failed err=327685 (0x50005)
Jul 1 15:45:23 (none) comm[144]: NetAgent: drainPostQ failed, err = 0x50005
Jul 1 15:45:23 (none) comm[144]: CallService: NetAgent Process err 327685
Jul 1 15:45:23 (none) comm[144]: CallService: NetAgent failed, status 0x50005
Jul 1 15:45:23 (none) comm[144]: CallService: EndCall start
Jul 1 15:45:23 (none) comm[144]: Stopping pppd.
Jul 1 15:45:23 (none) comm[144]: Pppd::Stop: enter
Jul 1 15:45:23 (none) comm[144]: Pppd::Stop: killed (pid = 490, status = 0)
Jul 1 15:45:24 (none) comm[144]: Pppd::Stop: waited (pid = 490, status = 490)
Jul 1 15:45:24 (none) comm[144]: Pppd::Stop: exit
Jul 1 15:45:26 (none) comm[144]: CallStatusReporter: Turning off-hook LED off
Jul 1 15:45:26 (none) comm[144]: Recover private logs
Jul 1 15:45:26 (none) comm[144]: Opening old private log files
Jul 1 15:45:26 (none) comm[144]: Reverting private logs
Jul 1 15:45:26 (none) tcphonehome[144]: CallService return status 0x50005
Jul 1 15:45:26 (none) comm[144]: AT&V1&V2
Jul 1 15:45:26 (none) comm[144]: TERMINATION REASON.......... LOCAL REQUEST
Jul 1 15:45:26 (none) comm[144]: LAST TX rate................ 28800 BPS
Jul 1 15:45:26 (none) comm[144]: HIGHEST TX rate............. 31200 BPS
Jul 1 15:45:26 (none) comm[144]: LAST RX rate................ 33600 BPS
Jul 1 15:45:26 (none) comm[144]: HIGHEST RX rate............. 33600 BPS
Jul 1 15:45:26 (none) comm[144]: PROTOCOL.................... LAPM
Jul 1 15:45:26 (none) comm[144]: COMPRESSION................. NONE
Jul 1 15:45:26 (none) comm[144]: Line QUALITY................ 027
Jul 1 15:45:26 (none) comm[144]: Rx LEVEL.................... 026
Jul 1 15:45:26 (none) comm[144]: Highest Rx State............ 85
Jul 1 15:45:26 (none) comm[144]: Highest TX State............ 87
Jul 1 15:45:26 (none) comm[144]: EQM Sum..................... 0022
Jul 1 15:45:26 (none) comm[144]: RBS Pattern................. FF
Jul 1 15:45:26 (none) comm[144]: Rate Drop................... FF
Jul 1 15:45:26 (none) comm[144]: Digital Loss................ None
Jul 1 15:45:26 (none) comm[144]: Local Rtrn Count............ 00
Jul 1 15:45:26 (none) comm[144]: Remote Rtrn Count........... 00
Jul 1 15:45:26 (none) comm[144]: BEGINaa14ab14ac15ad15ba16bb16bc16bd16ca77cb69cc206 da5ea0eb0fa75fb75fc75ga10gb0ha27hb24hc34hd0he50hf3 4hg0hh0hi0hj29hk28hl28hm27hn33ho27hp29hq30hr26hs31 ia26ib26ic26ja0jb0jc0jd1je0ka1kb1kc1kd1ke16kf16kg0 kh5ki38kj0kk0kl41km33kn255la135lb133lc135ld133ma0m b3mc11na0nb0oa4ob255oc255od0oe225of0og56pa1pb1qa0q b1qc255ra255rb255rc255END
Jul 1 15:45:26 (none) comm[144]: OK
Jul 1 15:45:26 (none) comm[144]: ATZ
Jul 1 15:45:26 (none) comm[144]: OK
Jul 1 15:45:31 (none) comm[144]: CallStatusReporter: Phase: Start_Auth, Status Failed
Jul 1 15:45:31 (none) comm[144]: CallStatusReporter: sending message "EX|14"
Jul 1 15:45:31 (none) tcphonehome[144]: TClient failed: EX|14
Jul 1 15:45:31 (none) tcphonehome[144]: Sending dialup event, subtype 8, msg EX|14
Jul 1 15:45:31 (none) tcphonehome[144]: setCallStatus called with: Failed state 0
Jul 1 15:45:39 (none) tcphonehome[144]: LastCallAttempt at 1214926878
Jul 1 15:45:39 (none) tcphonehome[144]: setting call status to: Failed
Jul 1 15:45:39 (none) tcphonehome[144]: setting call in progress to 0 (second successCount)
Jul 1 15:45:39 (none) tcphonehome[144]: Setting callActive to 0
Jul 1 15:45:39 (none) comm[144]: CallStatusReporter: sending data changed event 20
Jul 1 15:45:39 (none) tcphonehome[144]: destroying CommGlobals!
Jul 1 15:45:39 (none) tcphonehome[144]: pCommMempool empty
Jul 1 15:45:39 (none) tcphonehome[144]: Setting MCP PhoneHome action: ENABLED
[TiVo [S3] ~]#
----------------------------------------------
"operation now in progress" I wonder if this means that because my dsl is still connected to dodo, they don't allow me an internet connection over dial up??
Thanks for your help and continued patience, pete.
Chris.