BMT fails to fetch tvdb metadata

[scanner95]

Hello,

Recently BMT failed to fetch information for some TV series. I'm using BMT with great success for a long time now, being on Sage v6. Recently I upgraded to v7, following all guidelines for upgrading BMT. Since the upgrade, I had BMT working fine for some new titles, but this one failed.
There are some interesting errors in the log files:

Phoenix-metadata:

2011-02-14 18:48:20,158 - AUTO; MEDIA; G:\Movies\_Serials\Mentalist\The Mentalist.s03e14.avi; The Mentalist.s03e14; 6948688
2011-02-14 18:48:20,189 - ERROR; G:\Movies\_Serials\Mentalist\The Mentalist.s03e14.avi; The Mentalist.s03e14; 6948688; Failed while parsing series: MediaSearchResult [extraArgs={CLEAN_TITLE: The Mentalist,SEASON: 03,EPISODE: 14,RAW_TITLE: The Mentalist,FILE: G:\Movies\_Serials\Mentalist\The Mentalist.s03e14.avi,EPISODE_DATE: 2011-02-14,}, id=82459, imdbId=null, metadata=null, providerId=tvdb, score=1.0, title=The Mentalist, type=TV, url=82459, year=2008]

Sagex-api:

2011-02-14 18:24:29,626 [26733444@qtp-22265879-6] WARN  sagex.remote.media.MediaHandler - 503 - Internal Error - /bmt/media/poster/5395272
java.lang.IllegalArgumentException: im == null!
	at javax.imageio.ImageIO.write(Unknown Source)
	at javax.imageio.ImageIO.write(Unknown Source)
	at sagex.phoenix.fanart.FanartMediaRequestHandler.writeImage(FanartMediaRequestHandler.java:101)
	at sagex.phoenix.fanart.FanartMediaRequestHandler.processRequest(FanartMediaRequestHandler.java:84)
	at sagex.remote.media.ProxySageMediaRequestHandler.processRequest(ProxySageMediaRequestHandler.java:28)
	at sagex.remote.media.MediaHandler.handleRequest(MediaHandler.java:81)
	at org.jdna.bmt.web.server.MediaHandlerServlet.doGet(MediaHandlerServlet.java:82)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
	at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:502)
	at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:389)
	at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
	at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
	at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
	at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:417)
	at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
	at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.handler.rewrite.RewriteHandler.handle(RewriteHandler.java:230)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.Server.handle(Server.java:326)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:534)
	at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:864)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:539)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:409)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:520)

phoenix.log:

[sageId=6948688, sageObject=MediaFile[id=6948688 A[6948691,6948689,"_Serials/Mentalist/The Mentalist.s03e14",0@0214.12:59,42] mask=V host=yuly-home-new encodedBy= format=AVI 0:42:36 1148 kbps [#0 Video[MPEG4-Video 23.98 fps 624x352 39:22 progressive]#1 Audio[MP3 48000 Hz 2 channels 112 kbps idx=1]] G:\Movies\_Serials\Mentalist\The Mentalist.s03e14.avi, Seg0[Ïí 2/14 12:59:58.824-Ïí 2/14 13:42:35.564]]]
2011-02-14 18:50:23,064 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.search.TVScraperManager - Scraper title-s00e00 created a query: SearchQuery [type=TV, fields={EPISODE: 14,DISC: ,RAW_TITLE: The Mentalist.,SEASON: 03,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: false,}]] for file SageMediaFile [sageId=6948688, sageObject=MediaFile[id=6948688 A[6948691,6948689,"_Serials/Mentalist/The Mentalist.s03e14",0@0214.12:59,42] mask=V host=yuly-home-new encodedBy= format=AVI 0:42:36 1148 kbps [#0 Video[MPEG4-Video 23.98 fps 624x352 39:22 progressive]#1 Audio[MP3 48000 Hz 2 channels 112 kbps idx=1]] G:\Movies\_Serials\Mentalist\The Mentalist.s03e14.avi, Seg0[Ïí 2/14 12:59:58.824-Ïí 2/14 13:42:35.564]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: false,}]
2011-02-14 18:50:23,064 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=TV, fields={EPISODE_DATE: 2011-02-14,EPISODE: 14,DISC: ,RAW_TITLE: The Mentalist,SEASON: 03,FILE: G:\Movies\_Serials\Mentalist\The Mentalist.s03e14.avi,CLEAN_TITLE: The Mentalist,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: false,}]]
2011-02-14 18:50:24,814 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.MetadataManager - search(): tvdb; SearchQuery [type=TV, fields={EPISODE_TITLE: ,YEAR: null,EPISODE_DATE: 2011-02-14,EPISODE: 14,SEASON: 3,QUERY: The Mentalist,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: false,}]]
2011-02-14 18:50:24,814 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.MetadataManager - Searching: The Mentalist using TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]]
2011-02-14 18:50:24,814 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - TVDB Search: http://www.thetvdb.com/api/GetSeries...=The+Mentalist
2011-02-14 18:50:26,251 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.MetadataManager - Fetching Metadata for MediaSearchResult [extraArgs={SEASON: 3,EPISODE: 14,EPISODE_DATE: 2011-02-14,}, id=82459, imdbId=null, metadata=null, providerId=tvdb, score=1.0, title=The Mentalist, type=TV, url=82459, year=2008]
2011-02-14 18:50:26,251 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Series: http://www.thetvdb.com/api/5645B594A...s/82459/en.xml
2011-02-14 18:50:26,251 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Actors: http://www.thetvdb.com/api/5645B594A...459/actors.xml
2011-02-14 18:50:26,267 [26733444@qtp-22265879-6] INFO  sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - TVDB Episode: http://www.thetvdb.com/api/5645B594A...lt/3/14/en.xml
2011-02-14 18:50:26,267 [26733444@qtp-22265879-6] WARN  sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - Failed to get season/episode specific information for 82459; Season: 3; episode: 14
com.sun.org.apache.xerces.internal.impl.io.MalformedByteSequenceException: Invalid byte 1 of 1-byte UTF-8 sequence.
	at com.sun.org.apache.xerces.internal.impl.io.UTF8Reader.invalidByte(Unknown Source)
	at com.sun.org.apache.xerces.internal.impl.io.UTF8Reader.read(Unknown Source)
	at com.sun.org.apache.xerces.internal.impl.XMLEntityScanner.load(Unknown Source)
	at com.sun.org.apache.xerces.internal.impl.XMLEntityScanner.arrangeCapacity(Unknown Source)
	at com.sun.org.apache.xerces.internal.impl.XMLEntityScanner.skipString(Unknown Source)
	at com.sun.org.apache.xerces.internal.impl.XMLVersionDetector.determineDocVersion(Unknown Source)
	at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source)
	at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source)
	at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source)
	at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(Unknown Source)
	at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(Unknown Source)
	at javax.xml.parsers.DocumentBuilder.parse(Unknown Source)
	at sagex.phoenix.metadata.provider.tvdb.TVDBItemParser.updateMetadataFromUrl(TVDBItemParser.java:143)
	at sagex.phoenix.metadata.provider.tvdb.TVDBItemParser.addSeasonEpisodeInfo(TVDBItemParser.java:262)
	at sagex.phoenix.metadata.provider.tvdb.TVDBItemParser.getMetadata(TVDBItemParser.java:87)
	at sagex.phoenix.metadata.provider.tvdb.TVDBMetadataProvider.getMetaData(TVDBMetadataProvider.java:33)
	at sagex.phoenix.metadata.MetadataManager.getMetdata(MetadataManager.java:318)
	at org.jdna.bmt.web.server.BrowsingServicesImpl.getMetadata(BrowsingServicesImpl.java:444)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at com.google.gwt.user.server.rpc.RPC.invokeAndEncodeResponse(RPC.java:562)
	at com.google.gwt.user.server.rpc.RemoteServiceServlet.processCall(RemoteServiceServlet.java:207)
	at com.google.gwt.user.server.rpc.RemoteServiceServlet.processPost(RemoteServiceServlet.java:243)
	at com.google.gwt.user.server.rpc.AbstractRemoteServiceServlet.doPost(AbstractRemoteServiceServlet.java:62)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
	at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:502)
	at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:389)
	at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
	at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
	at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
	at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:417)
	at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:230)
	at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.handler.rewrite.RewriteHandler.handle(RewriteHandler.java:230)
	at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
	at org.mortbay.jetty.Server.handle(Server.java:326)
	at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:534)
	at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:879)
	at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:747)
	at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:218)
	at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
	at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:409)
	at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:520)

Any ideas?

Thanks

[stuckless]

I've seen this happen when the tvdb site is overloaded... basically they are sending me back crap... or nothing at all. I have an open issue, and I'll implementing an automatic retry when automatic metadata lookups fail.