Friday the 15th was the last time my Tivo was able to get guide data from the emulator.

I have forced the call with "forcedailycall.tcl" but it fails with

Failed. Service unavailable

Aug 15 18:54:13 (none) comm[162]: CallService: Start err 0
Aug 15 18:54:13 (none) comm[162]: CAM_ID not found.
Aug 15 18:54:13 (none) comm[162]: CallStatusReporter: Phase: Dial, Status In Progress
Aug 15 18:54:13 (none) comm[162]: CallStatusReporter: sending message "CL|30"
Aug 15 18:54:13 (none) comm[162]: Backdoor code 4, enable calls via broadband
Aug 15 18:54:13 (none) comm[162]: Using Ethernet. Not starting modem/pppd.
Aug 15 18:54:13 (none) comm[162]: CallStatusReporter: Phase: Start_Auth, Status In Progress
Aug 15 18:54:13 (none) comm[162]: CallStatusReporter: sending message "ST|33"
Aug 15 18:54:14 (none) comm[162]: CommUtil: connection to host 64.151.65.200, port 80, err 0x0
Aug 15 18:54:14 (none) comm[162]: Uploading HTTP Header for modLog of /var/log/svclog: POST /tivo-service/mlog.cgi HTTP/1.0^M Content-Length: 358^M ^M
Aug 15 18:54:17 (none) comm[162]: read HTTP Header: HTTP/1.0 200 OK^M Date: Mon, 15 Aug 2005 06:54:15 GMT^M Server: Apache/1.3.33 (Unix) (Gentoo/Linux) PHP/4.3.11 mod_gzip/1.3.26.1a^M Content-Type: text/plain; ch****t=ISO-8859-1^M X-Cache: MISS from cache1.qsi.net.nz^M Connection: close
Aug 15 18:54:17 (none) comm[162]: SvcLogRqst::verify: enter
Aug 15 18:54:17 (none) comm[162]: SvcLogRqst::verify: unlink(/var/log/svclog.upload)ed (status = 0)
Aug 15 18:54:17 (none) comm[162]: couldn't stat input file, reason = No such file or directory
Aug 15 18:54:17 (none) comm[162]: NetAgent::doXfer: open failed on Rqst : No such file or directory
Aug 15 18:54:17 (none) comm[162]: drainGetPostQ: doXfer failed err=65535 (0xffff)
Aug 15 18:54:17 (none) comm[162]: Xfer Performance Log says errors are Ok!
Aug 15 18:54:17 (none) comm[162]: CommUtil: connection to host 64.151.65.200, port 80, err 0x0
Aug 15 18:54:17 (none) comm[162]: read 384 bytes of upload data for FourOneOneRqst
Aug 15 18:54:18 (none) comm[162]: CommUtil: connection to host 64.151.65.200, port 80, err 0x0
Aug 15 18:54:18 (none) comm[162]: read 2002 bytes of upload data for HServerRqst
Aug 15 18:54:18 (none) comm[162]: HTTP header: HTTP/1.0 200 OK^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Date: Mon, 15 Aug 2005 06:54:15 GMT^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Server: Apache/1.3.33 (Unix) (Gentoo/Linux) PHP/4.3.11 mod_gzip/1.3.26.1a^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Content-Type: text/plain; ch****t=ISO-8859-1^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: X-Cache: MISS from cache1.qsi.net.nz^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Connection: close^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: ^M
Aug 15 18:54:18 (none) comm[162]: Start TCD411Resp =====================
Aug 15 18:54:18 (none) comm[162]: errMsg:
Aug 15 18:54:18 (none) comm[162]: areaCodeObj: OK
Aug 15 18:54:18 (none) comm[162]: tollFreeAuth: 0
Aug 15 18:54:18 (none) comm[162]: tollFreeNum:
Aug 15 18:54:18 (none) comm[162]: End TCD411Resp =====================
Aug 15 18:54:18 (none) comm[162]: current AreaCode object is OK
Aug 15 18:54:18 (none) comm[162]: HServerRqst::evaluate starting
Aug 15 18:54:18 (none) comm[162]: HTTP header: HTTP/1.0 411 Length Required^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Server: squid/2.5.STABLE3^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Mime-Version: 1.0^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Date: Mon, 15 Aug 2005 06:54:16 GMT^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Content-Type: text/html^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Content-Length: 3263^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Expires: Mon, 15 Aug 2005 06:54:16 GMT^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: X-Squid-Error: ERR_INVALID_REQ 0^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: X-Cache: MISS from cache1.qsi.net.nz^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: Connection: close^M
Aug 15 18:54:18 (none) comm[162]: HTTP header: ^M
Aug 15 18:54:18 (none) comm[162]: Required Parameter ERR_MSG not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter VERSION not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter CODE not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter SW_LIST not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter BACK_CH_PRV not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter BACK_CH_PUB not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter BACK_CH_LOG not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter TIME_SVC not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter SEQ_COOKIE not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter INV_FILE not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter NO_PRV_BACKHAUL not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter SERVICE_STATE not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter STATE_EXPIRE not present
Aug 15 18:54:18 (none) comm[162]: Required Parameter SW_SYSTEM_NAME not present
Aug 15 18:54:18 (none) comm[162]: HServerRqst: readBlock failed, err = 0xffff
Aug 15 18:54:18 (none) comm[162]: NetAgent: drainEvalQ failed, err = 0xffff
Aug 15 18:54:18 (none) comm[162]: CallService: NetAgent Process err 65535
Aug 15 18:54:18 (none) comm[162]: CallService: NetAgent failed, status 0xffff
Aug 15 18:54:18 (none) comm[162]: CallService: EndCall start
Aug 15 18:54:20 (none) comm[162]: CallStatusReporter: Turning off-hook LED off
Aug 15 18:54:21 (none) comm[162]: Recover private logs
Aug 15 18:54:21 (none) comm[162]: Opening old private log files
Aug 15 18:54:21 (none) comm[162]: Reverting private logs
Aug 15 18:54:21 (none) comm[162]: Cleanup: GetAddDiskBasedDirectories returned <70001> "0x70001"...skipping cleanup
Aug 15 18:54:21 (none) tcphonehome[162]: CallService return status 0xffff
Aug 15 18:54:21 (none) tcphonehome[162]: GetPostalCodeVersion - /PostalCode/02113 ServerVersion=27
Aug 15 18:54:21 (none) tcphonehome[162]: debug: sumServerVersion=27
Aug 15 18:54:21 (none) comm[162]: CallStatusReporter: Phase: Start_Auth, Status Failed
Aug 15 18:54:21 (none) comm[162]: CallStatusReporter: sending message "EX|14"
Aug 15 18:54:21 (none) tcphonehome[162]: TClient failed: EX|14
Aug 15 18:54:21 (none) tcphonehome[162]: Sending dialup event, subtype 8, msg EX|14
Aug 15 18:54:21 (none) tcphonehome[162]: setCallStatus called with: Failed state 0
Aug 15 18:54:21 (none) tcphonehome[162]: LastCallAttempt at 1124132019
Aug 15 18:54:21 (none) tcphonehome[162]: setting call status to: Failed
Aug 15 18:54:25 (none) tcphonehome[162]: setting call in progress to 0 (second successCount)
Aug 15 18:54:25 (none) tcphonehome[162]: Setting callActive to 0
Aug 15 18:54:25 (none) comm[162]: CallStatusReporter: sending data changed event 20
Aug 15 18:54:25 (none) tcphonehome[162]: destroying CommGlobals!
Aug 15 18:54:25 (none) tcphonehome[162]: pCommMempool empty
Aug 15 18:54:35 (none) tcphonehome[162]: Setting MCP PhoneHome action: ENABLED