New image from dial-in not posting to MFS

[smokey75]

I've successfully upgraded a DVR40 and an HR10-260 to the 6.3e code base and was trying to upgrade my HDVR2 (currently on 6.2-01-2-101), but I keep running into a problem. I've searched based on the errors in my logs and just can't find anything like it out there.

The 6.3e is not in MFS on this box so I force it to dial into Tivo. After hours of downloads, the tivo gets all the files and attempts to do it's load into MFS of the .gz files that it temporarily downloaded to /var/packages/. Unfortunately, the load into MFS seems to go awry each time which then wipes the /var/packages directory and I have to start all over again w/ the download, etc.

Here's the pertinent section from my tclient log:


Nov 15 21:02:41 (none) Decryptor[573]: BuildKeyRing 9/9 keys loaded
Nov 15 21:02:41 (none) BndStreamReader[573]: fileName=SWE_FLY-3.skey
Nov 15 21:02:41 (none) AvalKeyRing[573]: ElgamalFound
Nov 15 21:02:53 (none) aval_cryptolib[573]: MASTER=TIVOKEY_ELGAMAL_MASTER_KEY|SESSION=TIVOKEY_BLOWFISH_DISTRIBUTION_MESSAGE
Nov 15 21:02:53 (none) BndStreamReader[573]: fileName=swsystem-105665525-2.slice.gz.bf
Nov 15 21:02:53 (none) BndStreamReader[573]: .bf matched swsystem-105665525-2.slice.gz.bf
Nov 15 21:02:53 (none) BndStreamReader[573]: readyReader=bf
Nov 15 21:02:53 (none) avalPP[573]: BuildChainFromIndex=5 returned x0
Nov 15 21:02:53 (none) AvalDbLoader[573]: Trying to disable gc
Nov 15 21:02:54 (none) AvalBlockFileIo[573]: ctor: fileno=22
Nov 15 21:02:54 (none) comm[573]: Phase=Expand
Nov 15 21:02:54 (none) AvalPercentReporter[573]: SetTrueFileSize(100)
Nov 15 21:02:54 (none) AvalPercentReporter[573]: pct=89 nSec=143 nSecLeft=19
Nov 15 21:02:54 (none) AvalBlockIoPacker[573]: Dtor: sBlock=16368 nByteWrite=0 nWriteBlock=0 nWrite=0
Nov 15 21:02:54 (none) AvalKeyRing[573]: Looking for key=SWE_FLY-3 file=SWE_FLY-3.skey|DecryptedSize=18
Nov 15 21:02:54 (none) GzStreamReader[573]: Could not read magic header: nRead=10/10 ch0=d9 ch1=7a
Nov 15 21:02:54 (none) comm[573]: Phase=Load
Nov 15 21:02:54 (none) AvalBlockIoUnpacker[573]: sFile=184570 sBlock=16368 nCallRead=30453 nByteRead=19376 curBlockHit=19364 prevBlockHit=0 blockRead=13
Nov 15 21:02:54 (none) db[573]: Loader UnpackerCtorError
Nov 15 21:02:54 (none) avalPP[573]: DBLoad Failed with errcode xffff!
Nov 15 21:02:54 (none) AvalDbLoader[573]: Enabling gc
Nov 15 21:02:54 (none) comm[573]: SoftwareLoaderAvalTraits: reportError=xffff
Nov 15 21:02:54 (none) avalPP[573]: file=/var/packages/swsystem-105665525-2.slice.bnd (5) PostProc returns FAILURE_0000FFFF (ffff)
Nov 15 21:02:54 (none) avalPP[573]: ProcessFile=5: /var/packages/swsystem-105665525-2.slice.bnd return=xffff
Nov 15 21:02:54 (none) avalPP[573]: Failed on required file
Nov 15 21:02:54 (none) avalPP[573]: Process and log file returned <1e0011>
Nov 15 21:02:54 (none) AvalBlockIoUnpacker[573]: sFile=-559038737 sBlock=-559038737 nCallRead=0 nByteRead=0 curBlockHit=0 prevBlockHit=0 blockRead=0
Nov 15 21:02:54 (none) avalPP[573]: AvalancheManager::StartDirectory returns <1e0011>
Nov 15 21:02:54 (none) avalPP[573]: DONE Starting Directory <0x5ed316c8> = 0
Nov 15 21:02:54 (none) avalPP[573]: StartDirectory puked, returned <0x1e0011>
Nov 15 21:02:54 (none) avalPP[573]: CLEARING OUT ANY TROUBLED DIRECTORIES
Nov 15 21:02:54 (none) avalPP[573]: Nuking dead directory <0x5ed316c8>
Nov 15 21:02:54 (none) avalPP[573]: NukeFromDisk returns <0>
Nov 15 21:02:54 (none) avalPP[573]: DONE CLEARING OUT TROUBLED DIRECTORIES: bool retval=0
Nov 15 21:02:54 (none) comm[573]: There went Avalanche->Start! (err = 0x1e0011)
Nov 15 21:02:54 (none) comm[573]: RemovePackages: unlinked /var/packages/GZcore-105664607-2.slice.gz
Nov 15 21:02:54 (none) comm[573]: RemovePackages: unlinked /var/packages/GZhpk-Series2-105664885-2.slice.gz
Nov 15 21:02:54 (none) comm[573]: RemovePackages: unlinked /var/packages/GZkernel-Series2-105664862-2.slice.gz
Nov 15 21:02:54 (none) comm[573]: RemovePackages: unlinked /var/packages/swsystem-105665525-2.slice.bnd
Nov 15 21:02:54 (none) comm[573]: RemovePackages: unlinked /var/packages/utils-105664588-2.slice.gz
Nov 15 21:02:54 (none) comm[573]: RemovePackages: unlinked /var/packages/IR-standard-v251.slice.gz
Nov 15 21:02:54 (none) comm[573]: Call: ProcessQueue failed, status 0x1e0011
Nov 15 21:02:54 (none) comm[573]: CallService: Postprocessing failed, status 0x1e0011
Nov 15 21:02:55 (none) comm[573]: Recover private logs
Nov 15 21:02:55 (none) comm[573]: Opening old private log files
Nov 15 21:02:55 (none) comm[573]: Reverting private logs
Nov 15 21:02:55 (none) tcphonehome[573]: CallService return status 0x1e0011
Nov 15 21:02:56 (none) tcphonehome[573]: ReschedCall: reason = 3, numFails = 2
Nov 15 21:02:56 (none) tcphonehome[573]: No failed server callback available
Nov 15 21:02:56 (none) tcphonehome[573]: picked backoff time using numFails = 3
Nov 15 21:02:56 (none) tcphonehome[573]: setting next attempt at 1195204016 (Fri Nov 16 09:06:56 2007 )
Nov 15 21:02:56 (none) tcphonehome[573]: ReschedCall(3) in 724 min (Fri Nov 16 09:06:56 2007 )
Nov 15 21:02:56 (none) tcphonehome[573]: Rotating log files



It seems that it's processing this file, swsystem-105665525-2.slice.bnd, each time it dies. Multiple re-downloads, etc. don't seem to make a difference.

Any ideas what I should be doing to troubleshoot further?

Thanks in advance.

[labbie48]

Did you set up MRV on your S2 SD? I remember reading (somewhere) that if you have this enabled, you are downloading the wrong "swsystem" slice for your unit and of course you then have the wrong decrypt key stored on your unit so the .bnd file won't decrypt and the load into mfs fails. Don't know if there is a work around for this other then finding the slices elsewhere or finding a 6.3e image for a SD Tivo.

Somebody correct me if I'm wrong because I have no clue as to where or when I read this.....

[smokey75]

Thanks for the response back.

I did have MRV and HMO on this at one time. Since those are just tivoapp patches, would putting my original tivoapp back fix this?

I did see one thread where there was an issue w/ the superpatch or something and they had a similar error, but not exactly the same. I believe that thread indicated an issue with superpatch that was then fixed. So earlier, I did reapply my original tivoapp, but immediately repatched it with the latest scripts.

Since it takes over 3 hours per try (since it has to re-download everything ...), I want to make sure I have all my ducks in a row before I try the next time.

[labbie48]

I would think it's worth a shot. If that doesn't work, check this thread ->

http://dealdatabase.com/forum/showpost.php?p=290749&postcount=1171

[smokey75]

Unfortunately, using the original Tivoapp (and rebooting) and redownloading everything gives the same error as before. It is definitely related to the encrypted slice .bnd file.

I've been playing around w/ the unbundler.tcl script but I can't seem to get it to recognize the updated MFS path ( State\Keyring ) when I modify the tcl file. Anyone have one of these files that works on the newer code?

On another related thought, what would happen if I blew out everything that was in my State\Keyring directory? I'm afraid this would make the box unbootable, but I also thought that if it was missing the keys, it might redownload the appropriate ones.

[labbie48]

IIRC "set_mrv_name" changes the encryption keys not the tivoapp. So when the slices are downloaded the swsystem.slice.gz won't be decrypted because you don't have the correct key in your keyring. I have no clue what would happen if you wiped your State\Keyring directory.

Maybe someone with A LOT more knowledge than me can chime in.

[smokey75]

I wanted to close this out and thank everyone that helped out. I just completed the upgarde to 6.3e for the last DTivo I had.

It was the encrypted .bnd file that was the issue. After 'obtaining' the unencrypted slice file and doing a manual dbload, the new image finally showed correctly in MFS. From there it was just a standard upgrade to reapply hacks, etc.

This site is the best for those needing help.

Thanks,
Smokey

[crashHD]

I've got the same situation here. I've done a C&DE on this box, and restored an original tivoapp. That allowed the 6.3e slice to download and dbload successfully, however, that's only part of what I'm trying to accomplish. I'm wanting to capture the 6.3e slices for later use. I've got all of them but the swsystem, which is "swsystem-blahblahblah-slice.bnd". I'm trying to get "unbundler.tcl" to process it, but having little luck, and I'm not even sure if I'm headed in the right direction with this. Any help would be appreciated.

[labbie48]

I've uploaded the 6.3e slices to here if you want them..... -->

http://rapidshare.com/files/71392542/6.3e-0.slices.tgz

[crashHD]

those are for the hr10, right? I snagged those a few days ago. Now I'm just trying to "collect the whole set".

[tivo4mevo]

These aren't the droids you're looking for (http://rapidshare.com/files/70935546/6.3e-01-2_slices.zip.html). You can be on your way now.

[crashHD]

excellent. Thanks.

[k c dias]

These aren't the droids you're looking for (http://rapidshare.com/files/70935546/6.3e-01-2_slices.zip.html). You can be on your way now.

Please to post on /mediafire/

kcd