Issue with Metadata in Properties Files

White94Cobra · #1 07-02-2011, 02:29 PM

Not sure if this is Phoenix or BMT related, so I figured I would start here.

I just started using Phoenix and have built several views that filter on the MediaTitle value. I restructured my import folders, so I removed them from Sage, rescanned/restarted, then added them back in and rescanned/restarted. Only a few fields are populating on the Properties Files in BMT, and MediaTitle is not one of them. "ScrappedBy" indicates Phoenix.

If I manually go into each movie and select "Find Metadata" then it populates several additional fields, including MediaTitle. Of course I don't want to have to do this for each of the 700 titles in my collection.

Thanks for any assistance!

Update: It appears that Fanart is not auto downloading either. Once I go into a movie and use "Find Metadata" it downloads immediately.

stuckless · #2 07-02-2011, 05:51 PM

Quote:

Originally Posted by White94Cobra

Not sure if this is Phoenix or BMT related, so I figured I would start here.

I just started using Phoenix and have built several views that filter on the MediaTitle value. I restructured my import folders, so I removed them from Sage, rescanned/restarted, then added them back in and rescanned/restarted. Only a few fields are populating on the Properties Files in BMT, and MediaTitle is not one of them. "ScrappedBy" indicates Phoenix.

If I manually go into each movie and select "Find Metadata" then it populates several additional fields, including MediaTitle. Of course I don't want to have to do this for each of the 700 titles in my collection.

Thanks for any assistance!

Update: It appears that Fanart is not auto downloading either. Once I go into a movie and use "Find Metadata" it downloads immediately.

If you when you imported the files, you have an existing .properties, then BMT will not process the file.

White94Cobra · #3 07-02-2011, 06:51 PM

I made sure to delete all .properties files before adding the import paths back in.

Thanks!

stuckless · #4 07-02-2011, 07:13 PM

Quote:

Originally Posted by White94Cobra

I made sure to delete all .properties files before adding the import paths back in.

Thanks!

Your issue (ie the automatic updating not working) looks like one of 2 issues...
1. The updater can't find a good match and it errors out
2. You have existing metadata so bmt skips it.

You can check the logs/phoenix-metadata.log and search for a file that didn't work.. you'll either see ERROR or SKIP as the the first part of the message (or UPDATED if bmt did actually update it)

White94Cobra · #5 07-03-2011, 12:34 AM

I'm confident my files are named well. I used TMDb to name everything. If I copy/paste the filenames into imdb or TMDb it finds an exact match every time.

That leaves existing metadata. How does one remove existing metadata from Sage?

One thing that is interesting is the movie title that Phoenix comes up with often doesn't match the filename. For example, "Iron Man 2 (2010).mkv" imports as "IRONMAN2.Title1.DVDRIP". I never saw that using Diamond or MyMovies. Could either of those somehow be breaking Phoenix?

Thanks much!

stuckless · #6 07-03-2011, 04:56 AM

Quote:

Originally Posted by White94Cobra

I'm confident my files are named well. I used TMDb to name everything. If I copy/paste the filenames into imdb or TMDb it finds an exact match every time.

That leaves existing metadata. How does one remove existing metadata from Sage?

One thing that is interesting is the movie title that Phoenix comes up with often doesn't match the filename. For example, "Iron Man 2 (2010).mkv" imports as "IRONMAN2.Title1.DVDRIP". I never saw that using Diamond or MyMovies. Could either of those somehow be breaking Phoenix?

Thanks much!

I think you are not seeing the right title because the file hasn't been "scraped" by phoenix/bmt. I'm guessing when you manually update the file from the web ui, then it looks good again, which would be another indication that phoenix/bmt is not handling the file initially.

The first place to start is the logs/phoenix-metadata.log (which is human readable log - mostly

) and search for the filename, in this case, Ironman (2010).mkv, and see if it has SKIP or ERROR.

There should not be an issue with having Diamond installed alongside Phoenix. But if you have some other process installed that also fetches metadata, then it may prevent bmt/phoenix from doing it's metadata/fanart job.

White94Cobra · #7 07-03-2011, 12:13 PM

OK... So I removed all import paths again, cleared the log file with Sage shutdown, rescanned, rebooted, removed all properties files, added the import paths back and rescanned.

In the phoenix-metadata.log I see the following:

5-6 movies show an error due to a mispelling in the filename (my bad).

8-10 movies show an error like the one below.

Code:

2011-07-03 10:39:11,165 - ERROR; \\media-pc\Movies2\DVD Rentals\The Assassination of Jesse James\The Assassination of Jesse James.mkv; The Assassination of Jesse James.Title1.DVDRip; 3950562; Metadata lookup failed for The Assassination of Jesse James

About 99% of the movies show this:

Code:

2011-07-03 11:00:01,164 - UPDATE; \\main-pc\Movies0\HD Movies\Casino Royale (2006)\Casino Royale (2006).m2ts; ; Movie; MF3958040; 3958040
2011-07-03 11:00:01,164 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Conviction (2010)\Conviction (2010).m2ts; Conviction (2010); 3958046
2011-07-03 11:00:01,320 - UPDATE; \\main-pc\Movies0\HD Movies\Conviction (2010)\Conviction (2010).m2ts; ; Movie; MF3958046; 3958046
2011-07-03 11:00:01,320 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Crash (2004)\Crash (2004).mkv; Crash (2004); 3958067
2011-07-03 11:00:01,554 - UPDATE; \\main-pc\Movies0\HD Movies\Crash (2004)\Crash (2004).mkv; ; Movie; MF3958067; 3958067
2011-07-03 11:00:01,554 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Date Night (2010)\Date Night (2010).m2ts; Date Night (2010); 3958085
2011-07-03 11:00:01,726 - UPDATE; \\main-pc\Movies0\HD Movies\Date Night (2010)\Date Night (2010).m2ts; ; Movie; MF3958085; 3958085
2011-07-03 11:00:01,726 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Due Date (2010)\Due Date (2010).m2ts; Due Date (2010); 3958091
2011-07-03 11:00:01,897 - UPDATE; \\main-pc\Movies0\HD Movies\Due Date (2010)\Due Date (2010).m2ts; ; Movie; MF3958091; 3958091
2011-07-03 11:00:01,897 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Eagle Eye (2008)\Eagle Eye (2008).mkv; Eagle Eye (2008); 3958109
2011-07-03 11:00:02,069 - UPDATE; \\main-pc\Movies0\HD Movies\Eagle Eye (2008)\Eagle Eye (2008).mkv; ; Movie; MF3958109; 3958109

About 75% of the metadata is being filled in. "Fanart Title" is not being filled, and only the following values are being populated on the properties tab:
Actor
Description
Director
Extended Ratings
Genere
IMDBID
MediaProviderDataID
MediaProviderID
MediaType
OriginalAirDate
Rated
Running Time
ScrapedBy (This shows Phoenix)
ScrapedDate
Title (This is always blank)
TrailerURL

If I manually click "Find Metadata..." then click Save, it populates "Fanart Title" and adds/populates 5-6 additional fields under the Properties tab, including MediaTitle and Title. Fanart then immediately downloads.

I am seeing a few errors/warnings in the phoenix log.

Code:

2011-07-03 10:56:30,454 [SageTV] WARN  sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key
2011-07-03 10:56:30,454 [SageTV] WARN  sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key
2011-07-03 10:56:30,454 [SageTV] WARN  sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key
2011-07-03 10:56:30,454 [SageTV] WARN  sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key


2011-07-03 10:56:30,766 [SageTV] ERROR sagex.phoenix.profiles.ProfileManager - GetUIContextName() returned Null!! Creating a default context: default
2011-07-03 10:56:30,766 [SageTV] ERROR sagex.phoenix.profiles.ProfileManager - GetUIContextName() returned Null!! Creating a default context: default
2011-07-03 10:56:30,782 [SageTV] WARN  sagex.phoenix.profiles.ProfileManager - Current Profile: sagex.phoenix.profiles.Profile@157fec5[user=default,context=default,lastAccessed=0] was previously loaded.

Thanks for your assistance!

stuckless · #8 07-03-2011, 12:23 PM

It's certainly odd that MediaTitle (ie, fanart title) is not being set, yet, MediaProviderDataID
MediaProviderID, and MediaType are being set.

What is your providers set to... and do you have fanart enabled?

White94Cobra · #9 07-03-2011, 01:20 PM

Providers are set to imdb,tmdb. I have fanart enabled. I am going through right now and removing every single plugin in Sage and will try installing only BMT and Phoenix.

White94Cobra · #10 07-03-2011, 04:55 PM

This is driving me insane. I just removed every single plugin from Sage and went through the properties file and removed anything related to a plugin. Rebooted, removed/added back the video import folders. Installed Phoenix and BMT. It STILL doesn't populate the Fanart Title.

The auto-scan correctly populates everything on the Metadata tab correctly except Fanart Title, Year and User Rating. I am not seeing any errors in the phoenix-metadata.log file (other than a couple of titles it cannot match).

Code:

2011-07-03 14:30:34,695 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts; Avatar (2009); 3975874
2011-07-03 14:30:37,871 - UPDATE; \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts; ; Movie; MF3975874; 3975874

If I use the "Find Metadata..." button it populates Fanart Title, Year and User Rating. It also changes the Metadata Id value.

What is the difference between when BMT auto-scans and when one uses the "Find Metadata..." button (except one takes a few minutes and the other takes hours

)?

stuckless · #11 07-03-2011, 05:32 PM

Quote:

Originally Posted by White94Cobra

This is driving me insane. I just removed every single plugin from Sage and went through the properties file and removed anything related to a plugin. Rebooted, removed/added back the video import folders. Installed Phoenix and BMT. It STILL doesn't populate the Fanart Title.

The auto-scan correctly populates everything on the Metadata tab correctly except Fanart Title, Year and User Rating. I am not seeing any errors in the phoenix-metadata.log file (other than a couple of titles it cannot match).

Code:

2011-07-03 14:30:34,695 - AUTO; MEDIA; \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts; Avatar (2009); 3975874
2011-07-03 14:30:37,871 - UPDATE; \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts; ; Movie; MF3975874; 3975874

If I use the "Find Metadata..." button it populates Fanart Title, Year and User Rating. It also changes the Metadata Id value.

What is the difference between when BMT auto-scans and when one uses the "Find Metadata..." button (except one takes a few minutes and the other takes hours

)?

That;s the strangest part... because they both use the same code to do the lookups

I can't explain why the fanart title would be blank when doing an automatic update... just doesn't make sense.

White94Cobra · #12 07-03-2011, 05:58 PM

Does BMT or Phoenix handle populating the "Fanart Title" field? Is the "Fanart Title" field handled any differently than the "Movie Title" field?

White94Cobra · #13 07-03-2011, 06:03 PM

Here's the info from my BMT Status screen. Is anything an incorrect version?

Phoenix

Version 2.3.34
sagex.api Version 7.1.8
Fanart Enabled true
Fanart Folder \\media-pc\fanart
Automatic Metadata/Fanart Lookups Enabled true

SageTV

Version 7.1.9.256
Required Version 7.0.23
Java Version 1.6.0_26
Server true
Recordings Used Diskspace 1,799.89 G
Library Import Scan in Progress false
JVM Max Memory 1,013,632 M
JVM Allocated Memory 555,732 M
JVM Total Free Memory 563,996 M

White94Cobra · #14 07-03-2011, 06:22 PM

Here's a excerpt from the phoenix.log file. I included a couple of lookups where it finds and populates data, but doesn't populate "Fanart Title" etc...

If you have a chance to take a look that would be great. Thanks.

Code:

2011-07-03 15:40:12,034 [27677339@qtp-19875864-0] WARN  phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for DecoratedItem: [SageMediaFile [sageId=3975902, sageObject=MediaFile[id=3975902 A[3975905,3975903,"",0@1213.16:23,117] mask=V host=media-pc encodedBy= format=MATROSKA 1:57:35 0 kbps [#0 Video[H.264 23.98 fps 1920x1080 16:9 progressive]#1 Audio[DolbyTrueHD 48000 Hz 6 channels  idx=1 eng]{TrailerUrl=http://www.imdb.com/video/imdb/vi3270115609/, IMDBID=tt1059786, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728658064, MediaProviderDataID=tt1059786, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Eagle Eye (2008)\Eagle Eye (2008).mkv, Seg0[Mon 12/13 16:23:18.637-Mon 12/13 18:20:53.727]]]]
2011-07-03 15:40:12,034 [27677339@qtp-19875864-0] WARN  sagex.phoenix.metadata.persistence.TVSeriesUtil - Unable to create a SeriesInfoID for imdb:tt0964517
2011-07-03 15:40:12,036 [27677339@qtp-19875864-0] WARN  phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for DecoratedItem: [SageMediaFile [sageId=3976090, sageObject=MediaFile[id=3976090 A[3976093,3976091,"",0@0612.20:29,115] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:55:38 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi2330986777/, IMDBID=tt0964517, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728779581, MediaProviderDataID=tt0964517, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\The Fighter (2010)\The Fighter (2010).m2ts, Seg0[Sun 6/12 20:29:44.218-Sun 6/12 22:25:22.766]]]]
2011-07-03 15:40:12,036 [27677339@qtp-19875864-0] WARN  sagex.phoenix.metadata.persistence.TVSeriesUtil - Unable to create a SeriesInfoID for imdb:tt0408345
2011-07-03 15:40:12,038 [27677339@qtp-19875864-0] WARN  phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for DecoratedItem: [SageMediaFile [sageId=3975906, sageObject=MediaFile[id=3975906 A[3975909,3975907,"",0@0418.12:16,104] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:44:52 154354 kbps [#0 Video[VC1 0.023976 fps 1920x1080 16:9 progressive id=1011]#1 Audio[AC3 48000 Hz 6 channels 640 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/screenplay/vi1336344857/, IMDBID=tt0408345, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728661070, MediaProviderDataID=tt0408345, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Firewall (2006)\Firewall (2006).m2ts, Seg0[Mon 4/18 12:16:58.197-Mon 4/18 14:01:50.302]]]]
2011-07-03 15:40:12,056 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,064 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,074 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,084 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,095 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,105 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,117 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,127 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,137 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,148 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,159 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,180 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,190 [27677339@qtp-19875864-0] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,201 [7239850@qtp-19875864-4] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:12,212 [7239850@qtp-19875864-4] WARN  sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found
2011-07-03 15:40:16,206 [Thread-47] INFO  sagex.phoenix.metadata.PhoenixMetadataSupport - Auto update in progress: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:16,211 [Thread-47] WARN  sagex.phoenix.metadata.search.TVScraperManager - No TV Scraper could handle file: DecoratedItem: [SageMediaFile [sageId=3975870, sageObject=MediaFile[id=3975870 A[3975873,3975871,"",0@0109.10:30,108] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:48:34 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi975438873/, IMDBID=tt1014759, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728634690, MediaProviderDataID=tt1014759, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts, Seg0[Sun 1/9 10:30:27.156-Sun 1/9 12:19:01.992]]]]
2011-07-03 15:40:16,212 [Thread-47] INFO  sagex.phoenix.metadata.search.MovieScraperManager - Scraper title-withyear1 created a query: SearchQuery [type=MOVIE, fields={YEAR: 2010,RAW_TITLE: Alice in Wonderland,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]] for file DecoratedItem: [SageMediaFile [sageId=3975870, sageObject=MediaFile[id=3975870 A[3975873,3975871,"",0@0109.10:30,108] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:48:34 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi975438873/, IMDBID=tt1014759, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728634690, MediaProviderDataID=tt1014759, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts, Seg0[Sun 1/9 10:30:27.156-Sun 1/9 12:19:01.992]]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:16,212 [Thread-47] INFO  sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=MOVIE, fields={FILE: \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts,EPISODE_DATE: 2011-01-09,YEAR: 2010,CLEAN_TITLE: Alice in Wonderland,RAW_TITLE: Alice in Wonderland,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]]
2011-07-03 15:40:16,212 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - search(): imdb,tmdb; SearchQuery [type=MOVIE, fields={FILE: \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts,EPISODE_DATE: 2011-01-09,YEAR: 2010,CLEAN_TITLE: Alice in Wonderland,RAW_TITLE: Alice in Wonderland,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]]
2011-07-03 15:40:16,212 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Searching: Alice in Wonderland using IMDBMetadataProvider[MetadataProviderInfo [id=imdb, fanartId=tmdb, mediaTypes=[MOVIE], name=IMDb]]
2011-07-03 15:40:16,212 [Thread-47] INFO  sagex.phoenix.util.url.URLSaxParser - Parsing Url: http://www.imdb.com/find?s=tt&q=Alice+in+Wonderland&x=0&y=0
2011-07-03 15:40:17,161 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Fetching Metadata for MediaSearchResult [extraArgs=empty, id=tt1014759, imdbId=tt1014759, metadata=null, providerId=imdb, score=1.0, title=Alice in Wonderland, type=MOVIE, url=http://www.imdb.com/title/tt1014759/, year=2010]
2011-07-03 15:40:18,483 [Thread-47] WARN  phoenix.log - Failed to parse date string: 5 March 2010
(USA); Attempting again using: 5 March 2010
2011-07-03 15:40:18,484 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Fetching Fanart from alternate source: TheMovieDBMetadataProvider[MetadataProviderInfo [id=tmdb, fanartId=tmdb, mediaTypes=[MOVIE], name=themoviedb.org]] for result: MediaSearchResult [extraArgs=empty, id=tt1014759, imdbId=tt1014759, metadata=null, providerId=imdb, score=1.0, title=Alice in Wonderland, type=MOVIE, url=http://www.imdb.com/title/tt1014759/, year=2010]
2011-07-03 15:40:18,484 [Thread-47] INFO  sagex.phoenix.metadata.provider.tmdb.TheMovieDBItemParser - Fetching TMDB Metadata: http://api.themoviedb.org/2.1/Movie.imdbLookup/en/xml/d4ad46ee51d364386b6cf3b580fb5d8c/tt1014759
2011-07-03 15:40:18,485 [Thread-47] INFO  sagex.phoenix.metadata.provider.tmdb.TheMovieDBItemParser - Parsing TheMovieDB url: CachedUrl: http://api.themoviedb.org/2.1/Movie.imdbLookup/en/xml/d4ad46ee51d364386b6cf3b580fb5d8c/tt1014759; UrlId: 1fe35eb8df44daf8368cc5b54b17ee15
2011-07-03 15:40:19,325 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Saving Metadata for DecoratedItem: [SageMediaFile [sageId=3975870, sageObject=MediaFile[id=3975870 A[3975873,3975871,"",0@0109.10:30,108] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:48:34 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi975438873/, IMDBID=tt1014759, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728634690, MediaProviderDataID=tt1014759, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts, Seg0[Sun 1/9 10:30:27.156-Sun 1/9 12:19:01.992]]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:19,326 [Thread-47] INFO  sagex.phoenix.metadata.persistence.Sage7Persistence - Storing updated metadata for item DecoratedItem: [SageMediaFile [sageId=3975870, sageObject=MediaFile[id=3975870 A[3975873,3975871,"",0@0109.10:30,108] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:48:34 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi975438873/, IMDBID=tt1014759, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728634690, MediaProviderDataID=tt1014759, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts, Seg0[Sun 1/9 10:30:27.156-Sun 1/9 12:19:01.992]]]]
2011-07-03 15:40:19,502 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Saving Phoenix Fanart for DecoratedItem: [SageMediaFile [sageId=3975870, sageObject=MediaFile[id=3975870 A[3975873,3975871,"",0@0109.10:30,108] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:48:34 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi975438873/, IMDBID=tt1014759, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309732819499, MediaProviderDataID=tt1014759, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts, Seg0[Sun 1/9 10:30:27.156-Sun 1/9 12:19:01.992]]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:19,503 [Thread-47] WARN  sagex.phoenix.metadata.MetadataManager - Some or all fanart was not saved due to some unkown problem
MetadataException [file=DecoratedItem: [SageMediaFile [sageId=3975870, sageObject=MediaFile[id=3975870 A[3975873,3975871,"",0@0109.10:30,108] mask=V host=media-pc encodedBy= format=MPEG2-TS 1:48:34 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi975438873/, IMDBID=tt1014759, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309732819499, MediaProviderDataID=tt1014759, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Alice in Wonderland (2010)\Alice in Wonderland (2010).m2ts, Seg0[Sun 1/9 10:30:27.156-Sun 1/9 12:19:01.992]]]], metadata=sagex.phoenix.metadata.proxy.MetadataProxy, ]
	at sagex.phoenix.fanart.FanartStorage.saveFanart(FanartStorage.java:44)
	at sagex.phoenix.metadata.MetadataManager.updateMetadata(MetadataManager.java:468)
	at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:528)
	at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:496)
	at sagex.phoenix.metadata.AutomaticMetadataVisitor.visit(AutomaticMetadataVisitor.java:27)
	at sagex.phoenix.vfs.DecoratedMediaFile.accept(DecoratedMediaFile.java:49)
	at sagex.phoenix.vfs.DecoratedMediaFolder.accept(DecoratedMediaFolder.java:63)
	at sagex.phoenix.metadata.PhoenixMetadataSupport$1.run(PhoenixMetadataSupport.java:124)
	at sagex.phoenix.progress.ProgressTrackerManager$1.run(ProgressTrackerManager.java:48)
2011-07-03 15:40:19,506 [Thread-47] WARN  sagex.phoenix.metadata.search.TVScraperManager - No TV Scraper could handle file: DecoratedItem: [SageMediaFile [sageId=3975874, sageObject=MediaFile[id=3975874 A[3975877,3975875,"",0@0413.16:31,161] mask=V host=media-pc encodedBy= format=MPEG2-TS 2:41:41 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi531039513/, IMDBID=tt0499549, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728637868, MediaProviderDataID=tt0499549, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts, Seg0[Wed 4/13 16:31:13.812-Wed 4/13 19:12:55.540]]]]
2011-07-03 15:40:19,506 [Thread-47] INFO  sagex.phoenix.metadata.search.MovieScraperManager - Scraper title-withyear1 created a query: SearchQuery [type=MOVIE, fields={YEAR: 2009,RAW_TITLE: Avatar,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]] for file DecoratedItem: [SageMediaFile [sageId=3975874, sageObject=MediaFile[id=3975874 A[3975877,3975875,"",0@0413.16:31,161] mask=V host=media-pc encodedBy= format=MPEG2-TS 2:41:41 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi531039513/, IMDBID=tt0499549, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728637868, MediaProviderDataID=tt0499549, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts, Seg0[Wed 4/13 16:31:13.812-Wed 4/13 19:12:55.540]]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:19,506 [Thread-47] INFO  sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=MOVIE, fields={FILE: \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts,EPISODE_DATE: 2011-04-13,YEAR: 2009,CLEAN_TITLE: Avatar,RAW_TITLE: Avatar,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]]
2011-07-03 15:40:19,506 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - search(): imdb,tmdb; SearchQuery [type=MOVIE, fields={FILE: \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts,EPISODE_DATE: 2011-04-13,YEAR: 2009,CLEAN_TITLE: Avatar,RAW_TITLE: Avatar,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]]
2011-07-03 15:40:19,506 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Searching: Avatar using IMDBMetadataProvider[MetadataProviderInfo [id=imdb, fanartId=tmdb, mediaTypes=[MOVIE], name=IMDb]]
2011-07-03 15:40:19,507 [Thread-47] INFO  sagex.phoenix.util.url.URLSaxParser - Parsing Url: http://www.imdb.com/find?s=tt&q=Avatar&x=0&y=0
2011-07-03 15:40:20,229 [Thread-47] WARN  sagex.phoenix.util.Similarity - Adjusted the perfect score to 0.9 for Avatar and 8 Avatar because they are not equal.
2011-07-03 15:40:20,229 [Thread-47] WARN  sagex.phoenix.util.Similarity - Adjusted the perfect score to 0.9 for Avatar and 8 Avatar because they are not equal.
2011-07-03 15:40:20,229 [Thread-47] WARN  sagex.phoenix.util.Similarity - Adjusted the perfect score to 0.9 for Avatar and Avatar 2 because they are not equal.
2011-07-03 15:40:20,229 [Thread-47] WARN  sagex.phoenix.util.Similarity - Adjusted the perfect score to 0.9 for Avatar and Avatar 2 because they are not equal.
2011-07-03 15:40:20,229 [Thread-47] WARN  sagex.phoenix.util.Similarity - Adjusted the perfect score to 0.9 for Avatar and Avatar 3 because they are not equal.
2011-07-03 15:40:20,229 [Thread-47] WARN  sagex.phoenix.util.Similarity - Adjusted the perfect score to 0.9 for Avatar and Avatar 3 because they are not equal.
2011-07-03 15:40:20,231 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Fetching Metadata for MediaSearchResult [extraArgs=empty, id=tt0499549, imdbId=tt0499549, metadata=null, providerId=imdb, score=1.0, title=Avatar, type=MOVIE, url=http://www.imdb.com/title/tt0499549/, year=2009]
2011-07-03 15:40:21,075 [7239850@qtp-19875864-4] INFO  sagex.phoenix.metadata.PhoenixMetadataSupport - Cancelling Media Scan: 10001
2011-07-03 15:40:21,314 [Thread-47] WARN  phoenix.log - Failed to parse date string: 18 December 2009
(USA); Attempting again using: 18 December 2009
2011-07-03 15:40:21,315 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Fetching Fanart from alternate source: TheMovieDBMetadataProvider[MetadataProviderInfo [id=tmdb, fanartId=tmdb, mediaTypes=[MOVIE], name=themoviedb.org]] for result: MediaSearchResult [extraArgs=empty, id=tt0499549, imdbId=tt0499549, metadata=null, providerId=imdb, score=1.0, title=Avatar, type=MOVIE, url=http://www.imdb.com/title/tt0499549/, year=2009]
2011-07-03 15:40:21,316 [Thread-47] INFO  sagex.phoenix.metadata.provider.tmdb.TheMovieDBItemParser - Fetching TMDB Metadata: http://api.themoviedb.org/2.1/Movie.imdbLookup/en/xml/d4ad46ee51d364386b6cf3b580fb5d8c/tt0499549
2011-07-03 15:40:21,316 [Thread-47] INFO  sagex.phoenix.metadata.provider.tmdb.TheMovieDBItemParser - Parsing TheMovieDB url: CachedUrl: http://api.themoviedb.org/2.1/Movie.imdbLookup/en/xml/d4ad46ee51d364386b6cf3b580fb5d8c/tt0499549; UrlId: 066ae5ee75df920e73cd47dba166e87d
2011-07-03 15:40:22,023 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Saving Metadata for DecoratedItem: [SageMediaFile [sageId=3975874, sageObject=MediaFile[id=3975874 A[3975877,3975875,"",0@0413.16:31,161] mask=V host=media-pc encodedBy= format=MPEG2-TS 2:41:41 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi531039513/, IMDBID=tt0499549, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728637868, MediaProviderDataID=tt0499549, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts, Seg0[Wed 4/13 16:31:13.812-Wed 4/13 19:12:55.540]]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:22,023 [Thread-47] INFO  sagex.phoenix.metadata.persistence.Sage7Persistence - Storing updated metadata for item DecoratedItem: [SageMediaFile [sageId=3975874, sageObject=MediaFile[id=3975874 A[3975877,3975875,"",0@0413.16:31,161] mask=V host=media-pc encodedBy= format=MPEG2-TS 2:41:41 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi531039513/, IMDBID=tt0499549, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309728637868, MediaProviderDataID=tt0499549, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts, Seg0[Wed 4/13 16:31:13.812-Wed 4/13 19:12:55.540]]]]
2011-07-03 15:40:22,572 [7239850@qtp-19875864-4] INFO  sagex.phoenix.metadata.PhoenixMetadataSupport - Cancelling Media Scan: 10002
2011-07-03 15:40:29,512 [Thread-47] INFO  sagex.phoenix.metadata.MetadataManager - Saving Phoenix Fanart for DecoratedItem: [SageMediaFile [sageId=3975874, sageObject=MediaFile[id=3975874 A[3975877,3975875,"",0@0413.16:31,161] mask=V host=media-pc encodedBy= format=MPEG2-TS 2:41:41 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi531039513/, IMDBID=tt0499549, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309732829509, MediaProviderDataID=tt0499549, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts, Seg0[Wed 4/13 16:31:13.812-Wed 4/13 19:12:55.540]]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: false,scan_subfolders: true,import_tv_as_recording: false,refresh: false,}]
2011-07-03 15:40:29,513 [Thread-47] WARN  sagex.phoenix.metadata.MetadataManager - Some or all fanart was not saved due to some unkown problem
MetadataException [file=DecoratedItem: [SageMediaFile [sageId=3975874, sageObject=MediaFile[id=3975874 A[3975877,3975875,"",0@0413.16:31,161] mask=V host=media-pc encodedBy= format=MPEG2-TS 2:41:41 6250 kbps [#0 Video[H.264 23.976025 fps 1920x1080 16:9 progressive id=1011]#1 Audio[DTS-MA 48000 Hz 6 channels 192 kbps MAIN idx=1 id=1100]#2 Subpic[pgssub id=1200]{TrailerUrl=http://www.imdb.com/video/imdb/vi531039513/, IMDBID=tt0499549, ScrapedBy=Phoenix, MediaType=Movie, ScrapedDate=1309732829509, MediaProviderDataID=tt0499549, MediaProviderID=imdb}] \\main-pc\Movies0\HD Movies\Avatar (2009)\Avatar (2009).m2ts, Seg0[Wed 4/13 16:31:13.812-Wed 4/13 19:12:55.540]]]], metadata=sagex.phoenix.metadata.proxy.MetadataProxy, ]
	at sagex.phoenix.fanart.FanartStorage.saveFanart(FanartStorage.java:44)
	at sagex.phoenix.metadata.MetadataManager.updateMetadata(MetadataManager.java:468)
	at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:528)
	at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:496)
	at sagex.phoenix.metadata.AutomaticMetadataVisitor.visit(AutomaticMetadataVisitor.java:27)
	at sagex.phoenix.vfs.DecoratedMediaFile.accept(DecoratedMediaFile.java:49)
	at sagex.phoenix.vfs.DecoratedMediaFolder.accept(DecoratedMediaFolder.java:63)
	at sagex.phoenix.metadata.PhoenixMetadataSupport$1.run(PhoenixMetadataSupport.java:124)
	at sagex.phoenix.progress.ProgressTrackerManager$1.run(ProgressTrackerManager.java:48)

stuckless · #15 07-03-2011, 08:09 PM

The interesting thing there is that when I traced the error, it was dying because the "Title" (which would also feed the MediaTitle as well) was empty, and it should not be empty.

I'm wondering if there is something up with the imdb lookup. Maybe change your providers to be tmdb,imdb (instead of imdb, tmdb), and see if that makes a difference.

stuckless · #16 07-03-2011, 08:53 PM

I just re-ran the unit tests for imdb, and the good news is that you are not crazy

It would appear the the imdb provider is broken, but the code gracefully allowed it slip by. I'm in the process of getting a new build out for tomorrow night and it will have the updated imdb processor and better error checking around the imdb provider as well.

White94Cobra · #17 07-03-2011, 08:59 PM

Quote:

Originally Posted by stuckless

I just re-ran the unit tests for imdb, and the good news is that you are not crazy

It would appear the the imdb provider is broken, but the code gracefully allowed it slip by. I'm in the process of getting a new build out for tomorrow night and it will have the updated imdb processor and better error checking around the imdb provider as well.

That's good to know! I am on the "I's" for manually updating the metadata, so that will save me quite a bit of time

Thank you!!

stuckless · #18 07-03-2011, 09:15 PM

Quote:

Originally Posted by White94Cobra

That's good to know! I am on the "I's" for manually updating the metadata, so that will save me quite a bit of time

Thank you!!

If you change the order of the providers so that tmdb is first, it should work as well. The issue is that while the imdb provider failed, it didn't register as a failure at the time of the lookup, so the engine didn't move on to tmdb.

White94Cobra · #19 07-03-2011, 10:22 PM

I actually tried that early on but I don't recall that it worked. I'll give it a try again.

I changed Phoenix -> Fanart/Metadata Options -> Movie Metadata Provider(s) to only be tmdb and it is still not populating Fanart Title (although it does go 100x faster). Is there somewhere else I need make the change?

stuckless · #20 07-04-2011, 06:35 AM

Quote:

Originally Posted by White94Cobra

I actually tried that early on but I don't recall that it worked. I'll give it a try again.

I changed Phoenix -> Fanart/Metadata Options -> Movie Metadata Provider(s) to only be tmdb and it is still not populating Fanart Title (although it does go 100x faster). Is there somewhere else I need make the change?

No, that should be it. I guess we'll wait for the update tonight and see if that fixes it.

Similar Threads
Thread	Thread Starter	Forum	Replies	Last Post
Details about metadata properties files	Opus4	SageTV Customizations	37	11-06-2011 05:00 PM
Metadata/properties for manual recordings?	Slugger	SageTV Studio	3	10-20-2010 07:29 AM
Additional Metadata properties discussion	PLUCKYHD	SageTV Customizations	44	07-29-2009 10:25 AM
.properties, .my, and TV metadata in SageMC	himay	SageMC Custom Interface	1	05-26-2008 09:43 PM
6.1.4 Metadata Properties Parsing	JREkiwi	SageTV Beta Test Software	35	03-10-2007 08:39 PM

Currently Active Users Viewing This Thread: 1 (0 members and 1 guests)