Failed. Service unavailable

[rdornhart]

Ok, so I have read through the forum and seen many post of people that have had a similar issue to me. Basically for the past 3 year TiVo has been working without issue via iinet. I have recently moved house and changed ISP's as I could not resist TPG's 150GB download. Unfortunately since moving to TPG my TiVo has not downloaded its guide data.

So I have tried many of the recommendation in the forum and even e-mail tpg-proxy@tpg.com.au asking them to remove 131.2449.101 from the proxy list. They have assured me that this has happened. I am now out of options and the wife is getting impatient.

Anyone have any ideas? I have also attached the tclient log as I have seen others do the same.

[rdornhart]

I have done a bit more digging and tried some of the suggestions I saw on another thread. I have confirmed that tpg is not proxing the traffic:

Tracing route to 131.244.9.101 [minnie.tuhs.org] on port 80
Over a maximum of 30 hops.
1 2 ms 1 ms 2 ms 192.168.10.1 [mygateway1.NB5Plus4W]
2 28 ms 26 ms 26 ms 10.20.20.214
3 26 ms 26 ms 26 ms 203.213.7.208 [bri-pow-ibo-cup-1.tpgi.com.au]
4 26 ms 30 ms 26 ms 203.213.7.131 [bri-pow-pr2.tpgi.com.au]
5 30 ms 28 ms 28 ms 203.213.7.129 [203-213-7-129-qld.tpgi.com.au]
6 28 ms 28 ms 28 ms 218.100.0.21 [AS9313.brisbane.pipenetworks.co
m]
7 32 ms 34 ms * 203.22.124.33 [briswi-lsr1.onthenet.net]
8 34 ms 32 ms 34 ms 203.13.70.21 [gcva-gw-fe4-0.OntheNet.net]
9 30 ms 35 ms 31 ms 203.13.70.226 [fast1-1-gcva-sr1-gw2.OntheNet.n
et]
10 31 ms 31 ms 29 ms 203.144.10.94 [bond-gw1.OntheNet.net]
11 31 ms 31 ms 29 ms 192.48.109.2 [bdr0-hssi0.mgt.bond.edu.au]
12 31 ms 31 ms 31 ms 131.244.4.1 [guardian-ext.bond.edu.au]
13 Destination Reached in 37 ms. Connection established to 131.244.9.1

I have also confirmed the date and time are correct:

Locally configured IP address: 132.163.4.103
Our postcode is 04000. Found matching timezone Australia/Brisbane
Offset 36000 for timezone EST, daylight saving is 0
Connecting to time server 132.163.4.103
Setting the local time to Thu Sep 27 16:01:47 2007

Date
Thu Sep 27 16:14:07 localtime 2007

This is what I see in the logs everytime anyone know what it means? It consistantly hangs at:

Sep 27 16:09:29 (none) comm[171]: Uploading HTTP Header for modLog of /var/log/svclog: POST /tivo-service/mlog.cgi HTTP/1.0^M Content-Length: 16979^M ^M and then fails

Sep 27 16:09:29 (none) comm[171]: CallStatusReporter: sending message "ST|33"
Sep 27 16:09:29 (none) comm[171]: CommUtil: connection to host 127.0.0.1, port 8000, err 0x0
Sep 27 16:09:29 (none) comm[171]: Uploading HTTP Header for modLog of /var/log/svclog: POST /tivo-service/mlog.cgi HTTP/1.0^M Content-Length: 16979^M ^M
Sep 27 16:10:29 (none) WatchdogAction[168]: WatchdogAction::Trigger: callActive for 900 interval-secs
Sep 27 16:12:26 (none) comm[171]: XferRqst timeout waiting to read
Sep 27 16:12:26 (none) comm[171]: drainGetPostQ: doXfer failed err=65535 (0xffff)
Sep 27 16:12:26 (none) comm[171]: NetAgent: drainPostQ failed, err = 0xffff
Sep 27 16:12:26 (none) comm[171]: CallService: NetAgent Process err 65535
Sep 27 16:12:26 (none) comm[171]: CallService: NetAgent failed, status 0xffff
Sep 27 16:12:26 (none) comm[171]: CallService: EndCall start
Sep 27 16:12:28 (none) comm[171]: CallStatusReporter: Turning off-hook LED off
Sep 27 16:12:28 (none) comm[171]: Recover private logs
Sep 27 16:12:28 (none) comm[171]: Opening old private log files
Sep 27 16:12:28 (none) comm[171]: Reverting private logs
Sep 27 16:12:28 (none) comm[171]: Cleanup: GetAddDiskBasedDirectories returned <70001> "0x70001"...skipping cleanup
Sep 27 16:12:28 (none) tcphonehome[171]: CallService return status 0xffff
Sep 27 16:12:28 (none) tcphonehome[171]: GetPostalCodeVersion - /PostalCode/04000 ServerVersion=67
Sep 27 16:12:28 (none) tcphonehome[171]: debug: sumServerVersion=67
Sep 27 16:12:28 (none) comm[171]: CallStatusReporter: Phase: Start_Auth, Status Failed
Sep 27 16:12:28 (none) comm[171]: CallStatusReporter: sending message "EX|14"
Sep 27 16:12:29 (none) tcphonehome[171]: TClient failed: EX|14
Sep 27 16:12:29 (none) tcphonehome[171]: Sending dialup event, subtype 8, msg EX|14
Sep 27 16:12:29 (none) tcphonehome[171]: setCallStatus called with: Failed state 0
Sep 27 16:12:35 (none) tcphonehome[171]: LastCallAttempt at 1190909343
Sep 27 16:12:35 (none) tcphonehome[171]: setting call status to: Failed
Sep 27 16:12:35 (none) tcphonehome[171]: setting call in progress to 0 (second successCount)
Sep 27 16:12:35 (none) tcphonehome[171]: Setting callActive to 0
Sep 27 16:12:35 (none) comm[171]: CallStatusReporter: sending data changed event 20
Sep 27 16:12:35 (none) tcphonehome[171]: destroying CommGlobals!
Sep 27 16:12:35 (none) tcphonehome[171]: pCommMempool empty
Sep 27 16:12:42 (none) tcphonehome[171]: Setting MCP PhoneHome action: ENABLED

[Darren King]

I have confirmed that tpg is not proxing the traffic:

What's this one?

4 26 ms 30 ms 26 ms 203.213.7.131 [bri-pow-pr2.tpgi.com.au]

I'd be suspicious of what "pr2" stands for. For instance I put bri-pow-pr2 into Google and it came back first hit with this site that showed bri-pow-pr2 being #2 in the top 20 proxy servers:

http://www.cqcounter.com/?sts,l20proxy,,,,SSSA

[petestrash]

You need to show the results of Tracing route to 131.244.9.101 [minnie.tuhs.org] on port 80 and also port 21 to see if there is a transparent proxy (even this is not 100%).

I suspect Darren is correct.

Peter