Failed. Service Unavailable Part 2
Like Sadrake, I have noticed that I can not complete a daily call from Tivo...
Unlike Sadrake I have not changed ISP's, and instead connect to the dev emulator (port 80). My ISP assures me that they have not begun using any form of proxy (and provided this rather handy link to verify it (which it does)).
Having been away for the last week I know it's not something I have done :) - it was working before and after I left, but failed while I was away... and as is usually the way when you go away, my internet connection dropped early on the 16th and did not come back up until I reset the router on the weekend - therefore the last successful call was August 16 @ 2:20am.
I'm running the 1.3 image and did note the HTTP/1.0 comment in the log (below) which is why I went first to my ISP.
FYI The entry in tclient.conf is:
127::210.48.107.133:80:::
If I try to wget the file from Orac, I get the following:
[TiVo [p1] /tmp]# wget 210.48.107.133/tivo/slice/nzdev.slice
--11:25:39-- http://210.48.107.133/tivo/slice/nzdev.slice
=> `nzdev.slice'
Connecting to 210.48.107.133:80... connected.
HTTP request sent, awaiting response... 404 Not Found
11:25:42 ERROR 404: Not Found.
However I can FTP (from PC) to the IP and find the file in the correct location (and download it to the PC). Tivo can ping internet IP addresses with no issues.
Any idea how to resolve this?
Cheers
Neil
Log file contents:
Aug 22 11:03:15 (none) comm[114]: CallService: Start err 0
Aug 22 11:03:15 (none) comm[114]: CAM_ID not found.
Aug 22 11:03:15 (none) comm[114]: CallStatusReporter: Phase: Dial, Status In Progress
Aug 22 11:03:15 (none) comm[114]: CallStatusReporter: sending message "CL|30"
Aug 22 11:03:15 (none) comm[114]: Backdoor code 4, enable calls via broadband
Aug 22 11:03:15 (none) comm[114]: Using Ethernet. Not starting modem/pppd.
Aug 22 11:03:15 (none) comm[114]: CallStatusReporter: Phase: Start_Auth, Status In Progress
Aug 22 11:03:15 (none) comm[114]: CallStatusReporter: sending message "ST|33"
Aug 22 11:03:17 (none) comm[114]: CommUtil: connection to host 210.48.107.133, port 80, err 0x0
Aug 22 11:03:17 (none) comm[114]: Uploading Header for modLog of /var/log/svclog: POST /tivo-service/mlog.cgi HTTP/1.0^M Content-Length: 9942^M ^M
Aug 22 11:06:14 (none) comm[114]: XferRqst timeout waiting to read
Aug 22 11:06:16 (none) comm[114]: drainGetPostQ: doXfer failed err=65535 (0xffff)
Aug 22 11:06:16 (none) comm[114]: NetAgent: drainPostQ failed, err = 0xffff
Aug 22 11:06:16 (none) comm[114]: CallService: NetAgent Process err 65535
Aug 22 11:06:16 (none) comm[114]: CallService: NetAgent failed, status 0xffff
Aug 22 11:06:16 (none) comm[114]: CallService: EndCall start
Aug 22 11:06:18 (none) comm[114]: CallStatusReporter: Turning off-hook LED off
Aug 22 11:06:18 (none) comm[114]: Recover private logs
Aug 22 11:06:18 (none) comm[114]: Opening old private log files
Aug 22 11:06:18 (none) comm[114]: Reverting private logs
Aug 22 11:07:39 (none) comm[114]: Cleanup: GetAddDiskBasedDirectories returned <70001> "0x70001"...skipping cleanup
Aug 22 11:07:39 (none) tcphonehome[114]: CallService return status 0xffff
Aug 22 11:07:39 (none) tcphonehome[114]: GetPostalCodeVersion - /PostalCode/02113 ServerVersion=27
Aug 22 11:07:39 (none) tcphonehome[114]: debug: sumServerVersion=27
Aug 22 11:07:39 (none) comm[114]: CallStatusReporter: Phase: Start_Auth, Status Failed
Aug 22 11:07:39 (none) comm[114]: CallStatusReporter: sending message "EX|14"
Aug 22 11:07:39 (none) tcphonehome[114]: TClient failed: EX|14
Aug 22 11:07:39 (none) tcphonehome[114]: Sending dialup event, subtype 8, msg EX|14
Aug 22 11:07:39 (none) tcphonehome[114]: setCallStatus called with: Failed state 0
Aug 22 11:08:15 (none) tcphonehome[114]: LastCallAttempt at 1124708190
Aug 22 11:08:15 (none) tcphonehome[114]: setting call status to: Failed
Aug 22 11:08:15 (none) tcphonehome[114]: setting call in progress to 0 (second successCount)
Aug 22 11:08:15 (none) tcphonehome[114]: Setting callActive to 0
Aug 22 11:08:15 (none) comm[114]: CallStatusReporter: sending data changed event 20
Aug 22 11:08:15 (none) tcphonehome[114]: destroying CommGlobals!
Aug 22 11:08:15 (none) tcphonehome[114]: pCommMempool empty
Aug 22 11:09:49 (none) tcphonehome[114]: Setting MCP PhoneHome action: ENABLED