PDA

View Full Version : Issue with Metadata in Properties Files

White94Cobra
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
07-02-2011, 05:51 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.

If you when you imported the files, you have an existing .properties, then BMT will not process the file.
White94Cobra
07-02-2011, 06:51 PM
I made sure to delete all .properties files before adding the import paths back in.

Thanks!
stuckless
07-02-2011, 07:13 PM
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
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
07-03-2011, 04:56 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!

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
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.

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:
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.

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
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
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
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).


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
07-03-2011, 05:32 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).


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
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
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
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.



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
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
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
07-03-2011, 08:59 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.

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 :D

Thank you!!
stuckless
07-03-2011, 09:15 PM
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 :D

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
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
07-04-2011, 06:35 AM
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.
White94Cobra
07-07-2011, 05:39 PM
It looks like the latest update fixed my auto-scan issues. The last two movies I added have all metadata populated and Fanart Title is populated.

Thank you!!