Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
I followed the same procedure described by DNA and I am optimistic that the fix is good. Frontview shows ReadyDLNA archiving from my Premiere XL, and I see a ".nfo" file for a KUID show has been created on the ReadyNAS, something that has not happened since about 12/15/11.
Unfortunately, the first show archiving is a 4.5 hour HD recording of last night's Australian Open Tennis Quarterfinal, so I won't be able to confirm success for awhile, as I believe the companion ".TiVO" file doesn't appear until it is finished being written.
But indications of success are strong. Thank you Skywalker, and all those who assisted!
[Update] That 35.5G transfer took 140 minutes and has completed. Now other files have been added. Looks good to me.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
I installed ReadyTiVo 1.3.8 and now my ReadyNAS is busy archiving from my TiVo Premiere XL running 20.2-01-2-748. It has a lot of work to do, but the first few transfers have completed successfully.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
OK, so it works, mostly.
I installed and let it run and it archived for about 14 hours. However, it has a gap in the log where it still carried on archiving, as you can see in the paste below. [2012/01/25 05:11:24] tivo_utils.c:321: info: Archiving to "/c/Media/TiVo/TiVo - Media/The Simpsons/The Simpsons - 2218 - Holidays of Future Passed.TiVo"... [2012/01/25 05:28:37] tivo_utils.c:406: info: Finished archiving "The Simpsons - 2218 - Holidays of Future Passed.TiVo" [2012/01/25 05:29:08] tivo_utils.c:321: info: Archiving to "/c/Media/TiVo/TiVo - Media/The Mentalist/The Mentalist - The Redshirt.TiVo"... [2012/01/25 05:55:33] tivo_utils.c:406: info: Finished archiving "The Mentalist - The Redshirt.TiVo" [2012/01/25 05:56:04] tivo_utils.c:321: info: Archiving to "/c/Media/TiVo/TiVo - Media/Cold Case/Cold Case - 28 - Who's Your Daddy_.TiVo"... [2012/01/25 10:23:30] tivo_utils.c:857: info: Finished archiving from TiVo - Media [4841]. [2012/01/25 10:23:30] main.c:250: info: Finished archiving
It archived 4 shows during that period with no log entries.
The good news is that it has archived a number of shows and I can free up some space on my TiVos.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
DNA wrote:
It archived 4 shows during that period with no log entries.
You shouldn't run into this very often. What happened is the log file got truncated (usually ~6:25am), which requires the log to be closed and re-opened. However, that doesn't get done for the child archiver processes. Basically, only in cases where archival is running at 6:25am, and your log file is very large (> 10,000 lines), will you ever miss any log entries. In any case, this issue will be fixed in 4.2.20.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
Skywalker
That's great. Thanks for the fix and the background on the log issue. I've just noticed this in the log that I missed before. [2012/01/24 17:59:57] database.c:36: error: SQL ERROR 19 [column program_id is not unique] INSERT INTO archives (show_title, episode_title, duration, recorded_time, episode_no, program_id, series_id, archival_time, source_tivo) VALUES ('American Chopper', '2011-11-29', 60, 1322596800, 0, 5657830000, 565783, 1327449597, 'TiVo - Family')
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
That could be okay. If you've recorded the same show+episode already in the past, and it's been archived by the ReadyNAS, then it's fine. If the ReadyNAS sees a show/episode on your TiVo marked KUID, it checks to see if it's already been archived. If it's a duplicate, then we see if the recorded time is newer than the last time it was archived. If it a newer recording, and marked KUID again, we assume the last recording was a bad one, and we replace it with the newer one.
We'll make this message go away in cases like this in 4.2.20 as well.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
This seems to be working for me, thanks folks!
One additional issue, of lesser importance, suggested by Skywalker's last posting. Before the present problem began, I noticed that my readynas would often rearchive shows from my tivo to my readynas, even if there had been no change to the show on the tivo. This caused two issues - it used up bandwidth on my home network, and sometimes so many shows were reacrhived, that the system would not get around to newly added shows unless I manually stopped and restarted the tivo process in frontview. I never did figure this out. However, I wonder if the problem could be related to the feature Skywalker described, wherein the readynas will rearchive shows if it thinks there's a newer version with a more recent record date. I wonder if this is working properly.
In any case, I'm grateful to have the archiving back, and thank the folks at netgear for the patch. rearchive
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
Hmm, I'm not seeing that. Unless the TiVo changes the timestamp, I don't see any way for this to happen. You could use this addon to enable more verbose logging, to get a better idea of what's going on there.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
This is not KUID related but is the same re-archiving issue mentioned earlier in the thread.
Over two weeks after logging a support case I have no success in getting Support to even find someone who understands the question so am posting here. Below are log fragments that demonstrate the issue. This happens for many files.
2012/02/18 04:24:32] tivo_utils.c:212: debug: Downloading details for Doctor Who - 42 (https://DVR-0029.local:443/TiVoVideoDetails?id=1737696)... [2012/02/18 04:24:32] database.c:207: debug: Re-archiving item Doctor Who (42) because it is newer than the original archive (Thu Feb 2 22:21:14 2012 )[2012/02/18 04:24:32] tivo_utils.c:321: info: Archiving to "/c/Tivo/DVR-0029/Doctor Who/Doctor Who - 42.TiVo"... [2012/02/18 04:24:32] tivo_utils.c:339: debug: Fetching from "http://DVR-0029.local:80/download/Doctor%20Who.TiVo?Container=%2FNowPlaying&id=1737696" [2012/02/18 04:44:22] tivo_utils.c:406: info: Finished archiving "Doctor Who - 42.TiVo" [2012/02/18 04:44:22] database.c:36: error: SQL ERROR 19 [column program_id is not unique] INSERT INTO archives (show_title, episode_title, duration, recorded_time, episode_no, program_id, series_id, archival_time, source_tivo) VALUES ('Doctor Who', '42', 61, 1329467460, 0, 179127, 179127, 1329500662, 'DVR-0029') [2012/02/18 04:44:22] database.c:160: error: Adding info for Doctor Who to db failed!
Then it tries to rearchive every time archiving is scheduled:
fgrep 1737696 readytivo.log [2012/02/18 04:24:32] tivo_utils.c:212: debug: Downloading details for Doctor Who - 42 (https://DVR-0029.local:443/TiVoVideoDetails?id=1737696)... [2012/02/18 04:24:32] tivo_utils.c:339: debug: Fetching from "http://DVR-0029.local:80/download/Doctor%20Who.TiVo?Container=%2FNowPlaying&id=1737696" [2012/02/19 04:24:03] tivo_utils.c:212: debug: Downloading details for Doctor Who - 42 (https://DVR-0029.local:443/TiVoVideoDetails?id=1737696)... [2012/02/19 04:24:03] tivo_utils.c:339: debug: Fetching from "http://DVR-0029.local:80/download/Doctor%20Who.TiVo?Container=%2FNowPlaying&id=1737696" [2012/02/20 05:27:56] tivo_utils.c:212: debug: Downloading details for Doctor Who - 42 (https://DVR-0029.local:443/TiVoVideoDetails?id=1737696)... [2012/02/20 05:27:57] tivo_utils.c:339: debug: Fetching from "http://DVR-0029.local:80/download/Doctor%20Who.TiVo?Container=%2FNowPlaying&id=1737696" [2012/02/20 19:48:22] tivo_utils.c:212: debug: Downloading details for Doctor Who - 42 (https://DVR-0029.local:443/TiVoVideoDetails?id=1737696)... [2012/02/20 19:48:22] tivo_utils.c:339: debug: Fetching from "http://DVR-0029.local:80/download/Doctor%20Who.TiVo?Container=%2FNowPlaying&id=1737696"
RAIDiator-x86 4.2.19 ReadyTiVo v1.3.8 with debug enabled
Tivo: Series 3 Model: TCD663320 Software Version 11.3b4=01-2-663
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
I was having the same issue and my newly purchased Ultra 6 Plus was not transferring any TIVO shows. After following the directions found is this thread (updated the ReadyDLNA to 1.0.24 and the ReadyTIVO to the one linked here), all issues have resolved and it works perfect. Thanks!! Both my TIVO's Premier and Elite!!
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
deepbluesea wrote:
Over two weeks after logging a support case I have no success in getting Support to even find someone who understands the question so am posting here. Below are log fragments that demonstrate the issue. This happens for many files.
2012/02/18 04:24:32] tivo_utils.c:212: debug: Downloading details for Doctor Who - 42 (https://DVR-0029.local:443/TiVoVideoDetails?id=1737696)... [2012/02/18 04:24:32] database.c:207: debug: Re-archiving item Doctor Who (42) because it is newer than the original archive (Thu Feb 2 22:21:14 2012 )[2012/02/18 04:24:32] tivo_utils.c:321: info: Archiving to "/c/Tivo/DVR-0029/Doctor Who/Doctor Who - 42.TiVo"... [2012/02/18 04:24:32] tivo_utils.c:339: debug: Fetching from "http://DVR-0029.local:80/download/Doctor%20Who.TiVo?Container=%2FNowPlaying&id=1737696" [2012/02/18 04:44:22] tivo_utils.c:406: info: Finished archiving "Doctor Who - 42.TiVo" [2012/02/18 04:44:22] database.c:36: error: SQL ERROR 19 [column program_id is not unique] INSERT INTO archives (show_title, episode_title, duration, recorded_time, episode_no, program_id, series_id, archival_time, source_tivo) VALUES ('Doctor Who', '42', 61, 1329467460, 0, 179127, 179127, 1329500662, 'DVR-0029') [2012/02/18 04:44:22] database.c:160: error: Adding info for Doctor Who to db failed!
You're right, that can happen because the database record never gets updated with the new recording time. I've just updated the link for the ReadyTiVo 1.3.8 addon to include this fix -- it's already in the 4.2.20 beta releases. If you re-download and install that addon package, it should show up with a date stamp from today, and should take care of this issue for you.
Something else that's happening (not new) is that files that have never been downloaded are skipped with this kind of log message:
The archive timestamps for this group are impossible and the files are not present in the download folder:
[2012/02/22 21:23:19] tivo_utils.c:212: debug: Downloading details for Doctor Who - The Time Of Angels (https://DVR-0029.local:443/TiVoVideoDetails?id=787598)... [2012/02/22 21:23:19] database.c:199: debug: Skipping item Doctor Who (The Time Of Angels) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:19] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:19] tivo_utils.c:212: debug: Downloading details for Doctor Who - The End of Time (2) (https://DVR-0029.local:443/TiVoVideoDetails?id=727945)... [2012/02/22 21:23:20] database.c:199: debug: Skipping item Doctor Who (The End of Time (2)) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:20] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:20] tivo_utils.c:212: debug: Downloading details for Doctor Who - The End of Time (1) (https://DVR-0029.local:443/TiVoVideoDetails?id=713861)... [2012/02/22 21:23:20] database.c:199: debug: Skipping item Doctor Who (The End of Time (1)) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:20] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:20] tivo_utils.c:212: debug: Downloading details for Doctor Who - The Pandorica Opens (https://DVR-0029.local:443/TiVoVideoDetails?id=354172)... [2012/02/22 21:23:20] database.c:199: debug: Skipping item Doctor Who (The Pandorica Opens) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:20] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:20] tivo_utils.c:212: debug: Downloading details for Doctor Who - The Lodger (https://DVR-0029.local:443/TiVoVideoDetails?id=343161)... [2012/02/22 21:23:21] database.c:199: debug: Skipping item Doctor Who (The Lodger) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:21] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:21] tivo_utils.c:212: debug: Downloading details for Doctor Who - (null) (https://DVR-0029.local:443/TiVoVideoDetails?id=332038)... [2012/02/22 21:23:21] database.c:199: debug: Skipping item Doctor Who (2010-06-20) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:21] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:21] tivo_utils.c:212: debug: Downloading details for Doctor Who - Cold Blood (2) (https://DVR-0029.local:443/TiVoVideoDetails?id=320609)... [2012/02/22 21:23:21] database.c:199: debug: Skipping item Doctor Who (Cold Blood (2)) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:21] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:21] tivo_utils.c:212: debug: Downloading details for Doctor Who - (null) (https://DVR-0029.local:443/TiVoVideoDetails?id=308924)... [2012/02/22 21:23:22] database.c:199: debug: Skipping item Doctor Who (2010-06-06) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:22] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:22] tivo_utils.c:212: debug: Downloading details for Doctor Who - The Vampires Of Venice (https://DVR-0029.local:443/TiVoVideoDetails?id=286148)... [2012/02/22 21:23:22] database.c:199: debug: Skipping item Doctor Who (The Vampires Of Venice) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:22] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:22] tivo_utils.c:212: debug: Downloading details for Doctor Who - Flesh And Stone (https://DVR-0029.local:443/TiVoVideoDetails?id=274054)... [2012/02/22 21:23:22] database.c:199: debug: Skipping item Doctor Who (Flesh And Stone) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:22] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:22] tivo_utils.c:212: debug: Downloading details for Doctor Who - The Time Of Angels (https://DVR-0029.local:443/TiVoVideoDetails?id=262443)... [2012/02/22 21:23:23] database.c:199: debug: Skipping item Doctor Who (The Time Of Angels) because it was already archived on Wed Feb 22 21:22:21 2012 [2012/02/22 21:23:23] tivo_utils.c:691: debug: Processing item Doctor Who from DVR-0029. [2012/02/22 21:23:23] tivo_utils.c:212: debug: Downloading details for Doctor Who - Victory of the Daleks (https://DVR-0029.local:443/TiVoVideoDetails?id=250053)... [2012/02/22 21:23:23] database.c:199: debug: Skipping item Doctor Who (Victory of the Daleks) because it was already archived on Wed Feb 22 21:22:21 2012
It might be better to start from scratch so that there's a clean test with the updated software. Is that as simple as stopping readytivod, cleaning out /var/lib/readytivo and the download destination directory, then starting readytivod again?
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
I can perhaps add a little to the behavior.
I just got an Ultra 6 and configured TivoPlus. Not knowing how it worked, I simply entered the media key and discovered the next day that I had 3 shows on the NAS. I the realized that I had to mark shows as KUID to get them to archive, and marked a bunch. They are NOT showing up on the NAS.
I have no suggestions, but it seemed like a piece of data that hasn't been mentioned before.
BTW, I wouldn't hold your breath on bug fixes, us Mac users (and my clients) have been waiting for a fix to Remote for many a month (over year?) now and still no progress that I'm aware of.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
Have you tried the latest update addon posted in this thread? You could also use this Toggle_ReadyTiVo_Debug addon and send in your logs to see what the cause is.
Re: ReadyNAS Ultra 6 Tivo Not Archiving -- KUID problem?
It enabled verbose logging. You can see the logs by downloading the .zip file as described in my last post. That link is in the Frontview management console, under Status->Logs.
