2013-03-01 16:37:55,515 [SageTV] INFO sagex.phoenix.Phoenix - Initializing Phoenix - Version: 2.3.61 2013-03-01 16:37:55,515 [SageTV] INFO sagex.phoenix.Phoenix - Java classpath: C:\Program Files (x86)\SageTV\SageTV\Sage.jar;C:\Program Files (x86)\SageTV\SageTV\xerces.jar;C:\Program Files (x86)\SageTV\SageTV\plugin.jar;C:\Program Files (x86)\SageTV\SageTV\;;JARs\activation.jar;JARs\ADM.jar;JARs\ant.jar;JARs\bsh-2.0b4.jar;JARs\cling-core-2.0.jar;JARs\cling-support-2.0.jar;JARs\commons-cli-1.0.jar;JARs\commons-codec-1.3.jar;JARs\commons-collections-3.2.jar;JARs\commons-dbutils-1.3.jar;JARs\commons-email-1.2.jar;JARs\commons-httpclient-3.1.jar;JARs\commons-io-1.4.jar;JARs\commons-jxpath-1.1.jar;JARs\commons-lang-2.4.jar;JARs\commons-logging-1.1.1.jar;JARs\commons-net-1.4.1.jar;JARs\core-3.1.1.jar;JARs\df_sageutils.jar;JARs\dom4j-1.6.1.jar;JARs\Gemstone.jar;JARs\gkusnick.sagetv.jar;JARs\GoogleWeather.jar;JARs\groovy-all-2.0.5.jar;JARs\gson-1.7.1.jar;JARs\gtools.jar;JARs\htmlcleaner.jar;JARs\htmlparser-1.6.jar;JARs\jcifs-1.1.6.jar;JARs\jdic.jar;JARs\jetty-6.1.19.jar;JARs\jetty-ajp-6.1.19.jar;JARs\jetty-rewrite-handler-6.1.19.jar;JARs\jetty-sslengine-6.1.19.jar;JARs\jetty-starter.jar;JARs\jetty-util-6.1.19.jar;JARs\jflac-1.0.jar;JARs\jl1.0.jar;JARs\jnlp.jar;JARs\jogg-0.0.7.jar;JARs\jorbis-0.0.15.jar;JARs\jsch-20040429.jar;JARs\json.jar;JARs\jsoup.jar;JARs\jsp-2.1-jetty-6.1.19.jar;JARs\jsp-2.1.jar;JARs\jsp-api-2.1.jar;JARs\log4j-1.2.15.jar;JARs\lucene-core-3.0.1.jar;JARs\mail.jar;JARs\mina-core-1.1.7.jar;JARs\mp3spi1.9.2.jar;JARs\nielm_sageimdb.jar;JARs\nielm_sageutls.jar;JARs\nielm_sagewebserver.jar;JARs\nielm_sagexmlinfo.jar;JARs\phoenix.jar;JARs\SageDiamond.jar;JARs\sagex-api.jar;JARs\salicense.jar;JARs\saxon9he.jar;JARs\sbbi-upnplib-1.0.3.jar;JARs\seamless-http-1.0.jar;JARs\seamless-util-1.0.jar;JARs\seamless-xml-1.0.jar;JARs\servlet-api-2.5-6.1.19.jar;JARs\shadeblue-infopopup.jar;JARs\slf4j-api-1.6.0.jar;JARs\slf4j-log4j12-1.6.0.jar;JARs\slimplayer.jar;JARs\SoftSqueeze.jar;JARs\tritonus_share.jar;JARs\vecmath.jar;JARs\vorbisspi1.0.2.jar;JARs\webharvest_2.jar;JARs\wfesageplugin.jar;JARs\WIcons.jar 2013-03-01 16:37:55,515 [SageTV] INFO sagex.phoenix.Phoenix - Java Impl: Oracle Corporation - 1.7.0_15 2013-03-01 16:37:55,515 [SageTV] INFO sagex.phoenix.Phoenix - OS: Windows 7 - x86 - 6.1 2013-03-01 16:37:55,515 [SageTV] INFO sagex.phoenix.Phoenix - User: HORIZON$ 2013-03-01 16:37:55,515 [SageTV] INFO sagex.phoenix.Phoenix - Registering System Message Handler to the Event Bus 2013-03-01 16:37:55,516 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.SageSystemMessageListener for Event Type: phoenix.SystemMessage 2013-03-01 16:37:55,523 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Begin Loading Configuration Metadata 2013-03-01 16:37:55,525 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\log4j.xml 2013-03-01 16:37:55,549 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Phoenix.xml 2013-03-01 16:37:55,574 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:55,575 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:55,575 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:55,575 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:55,581 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Sage.xml 2013-03-01 16:37:55,584 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Streaming.xml 2013-03-01 16:37:55,591 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - End Loading Configuration Metadata 2013-03-01 16:37:55,593 [SageTV] INFO sagex.phoenix.Phoenix - Configuration Metadata Initialized 2013-03-01 16:37:55,626 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: PlaybackStopped 2013-03-01 16:37:55,626 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: PlaybackFinished 2013-03-01 16:37:55,635 [SageTV] INFO sagex.phoenix.upnp.PhoenixUPNPServer - UPnP Services are not online 2013-03-01 16:37:55,635 [SageTV] INFO sagex.phoenix.Phoenix - Phoenix base system initialized. 2013-03-01 16:37:55,635 [SageTV] INFO sagex.phoenix.Phoenix - Initializing Phoenix Services 2013-03-01 16:37:55,635 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2013-03-01 16:37:55,635 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Found core Phoenix Views file (no need to download update).\STVs\Phoenix\vfs\x-vfs.xml 2013-03-01 16:37:55,636 [SageTV] INFO sagex.phoenix.vfs.VFSManager - VFS Cache is not modified so, we'll reload the cache. 2013-03-01 16:37:55,636 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2013-03-01 16:37:55,812 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.music 2013-03-01 16:37:55,813 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.pictures 2013-03-01 16:37:55,813 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagerecordings 2013-03-01 16:37:55,814 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagearchivedrecordings 2013-03-01 16:37:55,815 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.videos 2013-03-01 16:37:55,815 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.dvd 2013-03-01 16:37:55,816 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.bluray 2013-03-01 16:37:55,816 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.allimportedvideo 2013-03-01 16:37:55,816 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.mediafiles 2013-03-01 16:37:55,817 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.scheduledrecordings 2013-03-01 16:37:55,818 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedmovies 2013-03-01 16:37:55,819 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedmovies 2013-03-01 16:37:55,820 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtv 2013-03-01 16:37:55,820 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtvarchived 2013-03-01 16:37:55,824 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedtv 2013-03-01 16:37:55,824 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.homevideos 2013-03-01 16:37:55,826 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.scheduledrecordings 2013-03-01 16:37:55,826 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.upcomingairings 2013-03-01 16:37:55,827 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.currentlyairing 2013-03-01 16:37:55,827 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.todaysrecordings 2013-03-01 16:37:55,828 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.todaysrecordings 2013-03-01 16:37:55,830 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentrecordings 2013-03-01 16:37:55,831 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentrecordings 2013-03-01 16:37:55,832 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentmusic 2013-03-01 16:37:55,832 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentpictures 2013-03-01 16:37:55,833 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedtv 2013-03-01 16:37:55,837 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedtv 2013-03-01 16:37:55,840 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedmovies 2013-03-01 16:37:55,843 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedmovies 2013-03-01 16:37:55,844 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.currentlyrecording 2013-03-01 16:37:55,844 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.currentlyrecording 2013-03-01 16:37:55,845 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.featuredfavorite 2013-03-01 16:37:55,846 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentimports 2013-03-01 16:37:55,847 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentimports 2013-03-01 16:37:55,847 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.conflicts 2013-03-01 16:37:55,848 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.allsagefanart 2013-03-01 16:37:55,849 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.upcomingmovies 2013-03-01 16:37:55,849 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.scheduledrecordings 2013-03-01 16:37:55,850 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.nowshowing 2013-03-01 16:37:55,851 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.videofolders 2013-03-01 16:37:55,851 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.archivedTV 2013-03-01 16:37:55,852 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTV 2013-03-01 16:37:55,853 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTVseasons 2013-03-01 16:37:55,854 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.TV 2013-03-01 16:37:55,854 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allMovies 2013-03-01 16:37:55,855 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.bluray 2013-03-01 16:37:55,855 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.dvd 2013-03-01 16:37:55,857 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.recentlywatched 2013-03-01 16:37:55,858 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.homevideos 2013-03-01 16:37:55,858 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.playlists 2013-03-01 16:37:55,859 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.album 2013-03-01 16:37:55,859 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.artist 2013-03-01 16:37:55,861 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.genre 2013-03-01 16:37:55,862 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.online.videos 2013-03-01 16:37:55,863 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.all 2013-03-01 16:37:55,863 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tv 2013-03-01 16:37:55,864 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.movies 2013-03-01 16:37:55,865 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.allforcache 2013-03-01 16:37:55,866 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tvforusercats 2013-03-01 16:37:55,866 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videofolders 2013-03-01 16:37:55,867 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTV 2013-03-01 16:37:55,868 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVbyrecent 2013-03-01 16:37:55,869 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasons 2013-03-01 16:37:55,871 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasonsbyrecent 2013-03-01 16:37:55,872 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.firstletter 2013-03-01 16:37:55,873 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allVideos 2013-03-01 16:37:55,874 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allMovies 2013-03-01 16:37:55,875 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.recentMovies 2013-03-01 16:37:55,876 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.recentlywatched 2013-03-01 16:37:55,876 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videosbygenre 2013-03-01 16:37:55,876 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.scheduledrecordings 2013-03-01 16:37:55,877 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.scheduledrecordings 2013-03-01 16:37:55,877 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.todaysrecordings 2013-03-01 16:37:55,877 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: scraped.allMovies 2013-03-01 16:37:55,878 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: scraped.allTVseasons 2013-03-01 16:37:55,879 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: phoenix.vfs.reload 2013-03-01 16:37:55,879 [SageTV] INFO sagex.phoenix.Phoenix - VFS Initialized 2013-03-01 16:37:55,879 [SageTV] INFO sagex.phoenix.menu.MenuManager - Begin Loading Menus 2013-03-01 16:37:55,879 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adjusting menu item visibility based on stored settings 2013-03-01 16:37:55,879 [SageTV] INFO sagex.phoenix.menu.MenuManager - Processing Menu Fragments... 2013-03-01 16:37:55,879 [SageTV] INFO sagex.phoenix.menu.MenuManager - Ordering Menu Items... 2013-03-01 16:37:55,880 [SageTV] INFO sagex.phoenix.menu.MenuManager - End Loading Menus 2013-03-01 16:37:55,880 [SageTV] INFO sagex.phoenix.Phoenix - Menus Initialized 2013-03-01 16:37:55,880 [SageTV] INFO sagex.phoenix.skins.SkinManager - Begin Loading Skins 2013-03-01 16:37:55,880 [SageTV] INFO sagex.phoenix.skins.SkinManager - End Loading Skins 2013-03-01 16:37:55,880 [SageTV] INFO sagex.phoenix.Phoenix - Skins Initialized 2013-03-01 16:37:55,880 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Begin Loading Metadata Providers 2013-03-01 16:37:55,881 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\dvdprofiler.xml 2013-03-01 16:37:55,887 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\htbackdrops.xml 2013-03-01 16:37:55,889 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\imdb.xml 2013-03-01 16:37:55,891 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\mymovies.xml 2013-03-01 16:37:55,894 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb.xml 2013-03-01 16:37:55,897 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tvdb.xml 2013-03-01 16:37:55,900 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Begin Loading Xbmc Metadata Providers 2013-03-01 16:37:55,900 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Loading XBMC Metadata Scraper: .\STVs\Phoenix\scrapers\xbmc\video\imdb.xml 2013-03-01 16:37:55,920 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - End Loading Xbmc Metadata Providers 2013-03-01 16:37:55,920 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - End Loading Metadata Providers 2013-03-01 16:37:55,920 [SageTV] INFO sagex.phoenix.Phoenix - Metadata Scrapers Initialized 2013-03-01 16:37:55,920 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Begin Loading Movie Filename Scrapers 2013-03-01 16:37:55,924 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear1 with priority 116 2013-03-01 16:37:55,925 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear2 with priority 116 2013-03-01 16:37:55,927 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-dot-year with priority 116 2013-03-01 16:37:55,928 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - End Loading Movie Filename Scrapers 2013-03-01 16:37:55,929 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: defaultmovie with priority 2147483647 2013-03-01 16:37:55,929 [SageTV] INFO sagex.phoenix.Phoenix - Movie Filename Scrapers Initialized 2013-03-01 16:37:55,929 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Begin Loading TV Filename Scrapers 2013-03-01 16:37:55,933 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecordingNoEpisode with priority 116 2013-03-01 16:37:55,934 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecording with priority 116 2013-03-01 16:37:55,938 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-show-episode with priority 116 2013-03-01 16:37:55,940 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-00x00 with priority 116 2013-03-01 16:37:55,941 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-airedDate with priority 116 2013-03-01 16:37:55,943 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00d00 with priority 116 2013-03-01 16:37:55,945 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00e00 with priority 116 2013-03-01 16:37:55,947 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00-Disc# with priority 116 2013-03-01 16:37:55,949 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-Season00Episode00 with priority 116 2013-03-01 16:37:55,949 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - End Loading TV Filename Scrapers 2013-03-01 16:37:55,949 [SageTV] INFO sagex.phoenix.Phoenix - TV Filename Scrapers Initialized 2013-03-01 16:37:55,949 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - Begin Loading Ratings Map 2013-03-01 16:37:55,949 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - Loading Ratings file .\STVs\Phoenix\metadata\ratings.properties 2013-03-01 16:37:55,950 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - End Loading Ratings Map 2013-03-01 16:37:55,950 [SageTV] INFO sagex.phoenix.Phoenix - Ratings Manager Initialized 2013-03-01 16:37:55,950 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - Begin loading Title Matchers 2013-03-01 16:37:55,951 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - End loading Title Matchers 2013-03-01 16:37:55,951 [SageTV] INFO sagex.phoenix.Phoenix - MediaTitles.xml Initialized 2013-03-01 16:37:55,951 [SageTV] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - Being Loading URL Resolvers 2013-03-01 16:37:55,952 [SageTV] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - End Loading URL Resolvers 2013-03-01 16:37:55,952 [SageTV] INFO sagex.phoenix.Phoenix - URL Resolvers has been loaded 2013-03-01 16:37:55,953 [SageTV] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Url Cleanup Task Created monitoring .\userdata\Phoenix\cache\url 2013-03-01 16:37:55,954 [SageTV] INFO sagex.phoenix.util.TaskManager - Adding Scheduled Task: urlcachecleaner; Date: Fri Mar 01 16:37:55 PST 2013; Repeat: 86400000 2013-03-01 16:37:55,954 [SageTV] INFO sagex.phoenix.Phoenix - Core Scheduled Tasks Initialized 2013-03-01 16:37:55,960 [SageTV] INFO sagex.phoenix.Phoenix - Phoenix Services initialized. 2013-03-01 16:37:55,960 [Timer-5] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Begin URL Cache Cleanup 2013-03-01 16:37:55,969 [SageTV] INFO sagex.phoenix.plugin.PhoenixPlugin - Phoenix Plugin starting... 2013-03-01 16:37:55,969 [Timer-5] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Finished URL Cache Cleanup; Cleaned 0 items. 2013-03-01 16:37:55,971 [SageTV] INFO sagex.phoenix.plugin.PhoenixPlugin - Registering SageTV EventListener in Phoenix... 2013-03-01 16:37:55,972 [SageTV] INFO sagex.phoenix.event.EventBus - Setting the Event bus to sagex.phoenix.event.SageEventBus@7c48ad 2013-03-01 16:37:55,972 [SageTV] INFO phoenix.log - Adding Event Listener for phoenix.SystemMessage routing to sagex.phoenix.event.SageSystemMessageListener@139ad4 2013-03-01 16:37:55,972 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener phoenix.SystemMessage; sagex.phoenix.event.SageSystemMessageListener@139ad4; to new Event bus 2013-03-01 16:37:55,972 [SageTV] INFO phoenix.log - Adding Event Listener for phoenix.vfs.reload routing to sagex.phoenix.event.ReflectionEventListener@11a0bba 2013-03-01 16:37:55,972 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener phoenix.vfs.reload; sagex.phoenix.event.ReflectionEventListener@11a0bba; to new Event bus 2013-03-01 16:37:55,972 [SageTV] INFO phoenix.log - Adding Event Listener for PlaybackFinished routing to sagex.phoenix.event.ReflectionEventListener@f81f79 2013-03-01 16:37:55,972 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener PlaybackFinished; sagex.phoenix.event.ReflectionEventListener@f81f79; to new Event bus 2013-03-01 16:37:55,972 [SageTV] INFO phoenix.log - Adding Event Listener for PlaybackStopped routing to sagex.phoenix.event.ReflectionEventListener@1da77cc 2013-03-01 16:37:55,973 [2013-03-01 16:37:58,042 [Fork-JUSJOKEN-8522289] INFO sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport - Getting WorldWeather Weather for http://free.worldweatheronline.com/feed/weather.ashx?q=94801&format=xml&num_of_days=5&includeLocation=yes&extra=localObsTime&key=b18c521658003002121007 2013-03-01 16:37:58,463 [Fork-BASE-71232] INFO sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport - Getting WorldWeather Weather for http://free.worldweatheronline.com/feed/weather.ashx?q=94801&format=xml&num_of_days=5&includeLocation=yes&extra=localObsTime&key=b18c521658003002121007 2013-03-01 16:37:59,920 [AWT-EventQueue-0] INFO sagex.phoenix.image.ImageUtil - Created Image Cache Dir: .\userdata\Phoenix\cache\imagetrans; exists: true 2013-03-01 17:12:58,186 [Fork-JUSJOKEN-8522289] INFO sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport - Getting WorldWeather Weather for http://free.worldweatheronline.com/feed/weather.ashx?q=94801&format=xml&num_of_days=5&includeLocation=yes&extra=localObsTime&key=b18c521658003002121007 2013-03-01 17:47:58,308 [Fork-JUSJOKEN-8522289] INFO sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport - Getting WorldWeather Weather for http://free.worldweatheronline.com/feed/weather.ashx?q=94801&format=xml&num_of_days=5&includeLocation=yes&extra=localObsTime&key=b18c521658003002121007 adata in .\STVs\Phoenix\Configuration\Phoenix.xml 2013-03-01 16:37:56,080 [Timer-7] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:56,080 [Timer-7] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:56,080 [Timer-7] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:56,081 [Timer-7] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-03-01 16:37:56,083 [Timer-7] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Sage.xml 2013-03-01 16:37:56,085 [Timer-7] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Streaming.xml 2013-03-01 16:37:56,088 [Timer-7] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in plugins\bmtweb\Phoenix\Configuration\bmt.xml 2013-03-01 16:37:56,092 [Timer-7] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - End Loading Configuration Metadata 2013-03-01 16:37:56,092 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2013-03-01 16:37:56,093 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - Found core Phoenix Views file (no need to download update).\STVs\Phoenix\vfs\x-vfs.xml 2013-03-01 16:37:56,094 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - VFS Cache is not modified so, we'll reload the cache. 2013-03-01 16:37:56,094 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2013-03-01 16:37:56,125 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.music 2013-03-01 16:37:56,126 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.pictures 2013-03-01 16:37:56,126 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagerecordings 2013-03-01 16:37:56,126 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagearchivedrecordings 2013-03-01 16:37:56,127 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.videos 2013-03-01 16:37:56,127 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.dvd 2013-03-01 16:37:56,127 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.bluray 2013-03-01 16:37:56,127 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.allimportedvideo 2013-03-01 16:37:56,127 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.mediafiles 2013-03-01 16:37:56,144 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.scheduledrecordings 2013-03-01 16:37:56,145 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedmovies 2013-03-01 16:37:56,145 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedmovies 2013-03-01 16:37:56,146 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtv 2013-03-01 16:37:56,146 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtvarchived 2013-03-01 16:37:56,146 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedtv 2013-03-01 16:37:56,146 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.homevideos 2013-03-01 16:37:56,147 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.scheduledrecordings 2013-03-01 16:37:56,147 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.upcomingairings 2013-03-01 16:37:56,147 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.currentlyairing 2013-03-01 16:37:56,147 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.todaysrecordings 2013-03-01 16:37:56,148 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.todaysrecordings 2013-03-01 16:37:56,148 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentrecordings 2013-03-01 16:37:56,149 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentrecordings 2013-03-01 16:37:56,149 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentmusic 2013-03-01 16:37:56,149 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentpictures 2013-03-01 16:37:56,150 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedtv 2013-03-01 16:37:56,151 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedtv 2013-03-01 16:37:56,151 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedmovies 2013-03-01 16:37:56,152 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedmovies 2013-03-01 16:37:56,152 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.currentlyrecording 2013-03-01 16:37:56,153 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.currentlyrecording 2013-03-01 16:37:56,153 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.featuredfavorite 2013-03-01 16:37:56,154 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentimports 2013-03-01 16:37:56,154 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentimports 2013-03-01 16:37:56,155 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.conflicts 2013-03-01 16:37:56,156 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.allsagefanart 2013-03-01 16:37:56,156 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.upcomingmovies 2013-03-01 16:37:56,156 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.scheduledrecordings 2013-03-01 16:37:56,157 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.nowshowing 2013-03-01 16:37:56,157 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.videofolders 2013-03-01 16:37:56,158 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.archivedTV 2013-03-01 16:37:56,159 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTV 2013-03-01 16:37:56,159 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTVseasons 2013-03-01 16:37:56,160 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.TV 2013-03-01 16:37:56,160 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allMovies 2013-03-01 16:37:56,161 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.bluray 2013-03-01 16:37:56,161 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.dvd 2013-03-01 16:37:56,162 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.recentlywatched 2013-03-01 16:37:56,163 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.homevideos 2013-03-01 16:37:56,163 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.playlists 2013-03-01 16:37:56,163 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.album 2013-03-01 16:37:56,164 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.artist 2013-03-01 16:37:56,164 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.genre 2013-03-01 16:37:56,164 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.online.videos 2013-03-01 16:37:56,165 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.all 2013-03-01 16:37:56,166 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tv 2013-03-01 16:37:56,166 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.movies 2013-03-01 16:37:56,167 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.allforcache 2013-03-01 16:37:56,168 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tvforusercats 2013-03-01 16:37:56,168 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videofolders 2013-03-01 16:37:56,169 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTV 2013-03-01 16:37:56,169 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVbyrecent 2013-03-01 16:37:56,170 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasons 2013-03-01 16:37:56,171 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasonsbyrecent 2013-03-01 16:37:56,172 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.firstletter 2013-03-01 16:37:56,173 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allVideos 2013-03-01 16:37:56,174 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allMovies 2013-03-01 16:37:56,174 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.recentMovies 2013-03-01 16:37:56,175 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.recentlywatched 2013-03-01 16:37:56,176 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videosbygenre 2013-03-01 16:37:56,176 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.scheduledrecordings 2013-03-01 16:37:56,176 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.scheduledrecordings 2013-03-01 16:37:56,176 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.todaysrecordings 2013-03-01 16:37:56,177 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: scraped.allMovies 2013-03-01 16:37:56,178 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: scraped.allTVseasons 2013-03-01 16:37:56,179 [Timer-7] INFO sagex.phoenix.plugin.PhoenixPlugin - End Reloading some configurations because of plugin contributions 2013-03-01 16:37:56,179 [Timer-7] INFO sagex.phoenix.plugin.PhoenixPlugin - Reloading Phoenix Services in case Plugins have contributed. 2013-03-01 16:37:56,179 [Timer-7] INFO sagex.phoenix.Phoenix - Initializing Phoenix Services 2013-03-01 16:37:56,179 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2013-03-01 16:37:56,179 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - Found core Phoenix Views file (no need to download update).\STVs\Phoenix\vfs\x-vfs.xml 2013-03-01 16:37:56,181 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - VFS Cache is not modified so, we'll reload the cache. 2013-03-01 16:37:56,181 [Timer-7] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2013-03-01 16:37:56,208 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.music 2013-03-01 16:37:56,208 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.pictures 2013-03-01 16:37:56,208 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagerecordings 2013-03-01 16:37:56,208 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagearchivedrecordings 2013-03-01 16:37:56,209 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.videos 2013-03-01 16:37:56,209 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.dvd 2013-03-01 16:37:56,209 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.bluray 2013-03-01 16:37:56,209 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.allimportedvideo 2013-03-01 16:37:56,209 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.mediafiles 2013-03-01 16:37:56,210 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.scheduledrecordings 2013-03-01 16:37:56,210 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedmovies 2013-03-01 16:37:56,210 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedmovies 2013-03-01 16:37:56,211 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtv 2013-03-01 16:37:56,211 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtvarchived 2013-03-01 16:37:56,211 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedtv 2013-03-01 16:37:56,211 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.homevideos 2013-03-01 16:37:56,212 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.scheduledrecordings 2013-03-01 16:37:56,212 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.upcomingairings 2013-03-01 16:37:56,212 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.currentlyairing 2013-03-01 16:37:56,212 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.todaysrecordings 2013-03-01 16:37:56,213 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.todaysrecordings 2013-03-01 16:37:56,214 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentrecordings 2013-03-01 16:37:56,214 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentrecordings 2013-03-01 16:37:56,214 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentmusic 2013-03-01 16:37:56,215 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentpictures 2013-03-01 16:37:56,215 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedtv 2013-03-01 16:37:56,216 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedtv 2013-03-01 16:37:56,216 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedmovies 2013-03-01 16:37:56,217 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedmovies 2013-03-01 16:37:56,217 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.currentlyrecording 2013-03-01 16:37:56,218 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.currentlyrecording 2013-03-01 16:37:56,218 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.featuredfavorite 2013-03-01 16:37:56,219 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentimports 2013-03-01 16:37:56,219 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentimports 2013-03-01 16:37:56,219 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.conflicts 2013-03-01 16:37:56,220 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.allsagefanart 2013-03-01 16:37:56,220 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.upcomingmovies 2013-03-01 16:37:56,221 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.scheduledrecordings 2013-03-01 16:37:56,221 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.nowshowing 2013-03-01 16:37:56,221 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.videofolders 2013-03-01 16:37:56,222 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.archivedTV 2013-03-01 16:37:56,222 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTV 2013-03-01 16:37:56,224 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTVseasons 2013-03-01 16:37:56,224 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.TV 2013-03-01 16:37:56,225 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allMovies 2013-03-01 16:37:56,225 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.bluray 2013-03-01 16:37:56,225 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.dvd 2013-03-01 16:37:56,226 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.recentlywatched 2013-03-01 16:37:56,227 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.homevideos 2013-03-01 16:37:56,227 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.playlists 2013-03-01 16:37:56,227 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.album 2013-03-01 16:37:56,228 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.artist 2013-03-01 16:37:56,228 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.genre 2013-03-01 16:37:56,229 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.online.videos 2013-03-01 16:37:56,230 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.all 2013-03-01 16:37:56,230 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tv 2013-03-01 16:37:56,231 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.movies 2013-03-01 16:37:56,232 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.allforcache 2013-03-01 16:37:56,232 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tvforusercats 2013-03-01 16:37:56,232 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videofolders 2013-03-01 16:37:56,233 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTV 2013-03-01 16:37:56,234 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVbyrecent 2013-03-01 16:37:56,234 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasons 2013-03-01 16:37:56,235 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasonsbyrecent 2013-03-01 16:37:56,236 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.firstletter 2013-03-01 16:37:56,237 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allVideos 2013-03-01 16:37:56,237 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allMovies 2013-03-01 16:37:56,238 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.recentMovies 2013-03-01 16:37:56,239 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.recentlywatched 2013-03-01 16:37:56,239 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videosbygenre 2013-03-01 16:37:56,239 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.scheduledrecordings 2013-03-01 16:37:56,240 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.scheduledrecordings 2013-03-01 16:37:56,240 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.todaysrecordings 2013-03-01 16:37:56,241 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: scraped.allMovies 2013-03-01 16:37:56,241 [Timer-7] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: scraped.allTVseasons 2013-03-01 16:37:56,242 [Timer-7] INFO phoenix.log - Adding Event Listener for phoenix.vfs.reload routing to sagex.phoenix.event.ReflectionEventListener@192eaad 2013-03-01 16:37:56,242 [Timer-7] INFO sagex.phoenix.Phoenix - VFS Initialized 2013-03-01 16:37:56,242 [Timer-7] INFO sagex.phoenix.menu.MenuManager - Begin Loading Menus 2013-03-01 16:37:56,243 [Timer-7] INFO sagex.phoenix.menu.MenuManager - Adjusting menu item visibility based on stored settings 2013-03-01 16:37:56,243 [Timer-7] INFO sagex.phoenix.menu.MenuManager - Processing Menu Fragments... 2013-03-01 16:37:56,243 [Timer-7] INFO sagex.phoenix.menu.MenuManager - Ordering Menu Items... 2013-03-01 16:37:56,243 [Timer-7] INFO sagex.phoenix.menu.MenuManager - End Loading Menus 2013-03-01 16:37:56,243 [Timer-7] INFO sagex.phoenix.Phoenix - Menus Initialized 2013-03-01 16:37:56,243 [Timer-7] INFO sagex.phoenix.skins.SkinManager - Begin Loading Skins 2013-03-01 16:37:56,244 [Timer-7] INFO sagex.phoenix.skins.SkinManager - End Loading Skins 2013-03-01 16:37:56,244 [Timer-7] INFO sagex.phoenix.Phoenix - Skins Initialized 2013-03-01 16:37:56,244 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Begin Loading Metadata Providers 2013-03-01 16:37:56,244 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\dvdprofiler.xml 2013-03-01 16:37:56,247 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\htbackdrops.xml 2013-03-01 16:37:56,248 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\imdb.xml 2013-03-01 16:37:56,250 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\mymovies.xml 2013-03-01 16:37:56,252 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb.xml 2013-03-01 16:37:56,254 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tvdb.xml 2013-03-01 16:37:56,256 [Timer-7] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Begin Loading Xbmc Metadata Providers 2013-03-01 16:37:56,256 [Timer-7] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Loading XBMC Metadata Scraper: .\STVs\Phoenix\scrapers\xbmc\video\imdb.xml 2013-03-01 16:37:56,264 [Timer-7] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - End Loading Xbmc Metadata Providers 2013-03-01 16:37:56,264 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - End Loading Metadata Providers 2013-03-01 16:37:56,264 [Timer-7] INFO sagex.phoenix.Phoenix - Metadata Scrapers Initialized 2013-03-01 16:37:56,264 [Timer-7] INFO sagex.phoenix.metadata.search.MovieScraperManager - Begin Loading Movie Filename Scrapers 2013-03-01 16:37:56,269 [Timer-7] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear1 with priority 116 2013-03-01 16:37:56,271 [Timer-7] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear2 with priority 116 2013-03-01 16:37:56,273 [Timer-7] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-dot-year with priority 116 2013-03-01 16:37:56,273 [Timer-7] INFO sagex.phoenix.metadata.search.MovieScraperManager - End Loading Movie Filename Scrapers 2013-03-01 16:37:56,273 [Timer-7] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: defaultmovie with priority 2147483647 2013-03-01 16:37:56,273 [Timer-7] INFO sagex.phoenix.Phoenix - Movie Filename Scrapers Initialized 2013-03-01 16:37:56,273 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Begin Loading TV Filename Scrapers 2013-03-01 16:37:56,276 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecordingNoEpisode with priority 116 2013-03-01 16:37:56,278 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecording with priority 116 2013-03-01 16:37:56,279 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-show-episode with priority 116 2013-03-01 16:37:56,281 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-00x00 with priority 116 2013-03-01 16:37:56,283 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-airedDate with priority 116 2013-03-01 16:37:56,285 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00d00 with priority 116 2013-03-01 16:37:56,286 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00e00 with priority 116 2013-03-01 16:37:56,288 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00-Disc# with priority 116 2013-03-01 16:37:56,289 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-Season00Episode00 with priority 116 2013-03-01 16:37:56,290 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - End Loading TV Filename Scrapers 2013-03-01 16:37:56,290 [Timer-7] INFO sagex.phoenix.Phoenix - TV Filename Scrapers Initialized 2013-03-01 16:37:56,290 [Timer-7] INFO sagex.phoenix.metadata.RatingsManager - Begin Loading Ratings Map 2013-03-01 16:37:56,291 [Timer-7] INFO sagex.phoenix.metadata.RatingsManager - Loading Ratings file .\STVs\Phoenix\metadata\ratings.properties 2013-03-01 16:37:56,291 [Timer-7] INFO sagex.phoenix.metadata.RatingsManager - End Loading Ratings Map 2013-03-01 16:37:56,291 [Timer-7] INFO sagex.phoenix.Phoenix - Ratings Manager Initialized 2013-03-01 16:37:56,291 [Timer-7] INFO sagex.phoenix.metadata.search.FileMatcherManager - Begin loading Title Matchers 2013-03-01 16:37:56,292 [Timer-7] INFO sagex.phoenix.metadata.search.FileMatcherManager - End loading Title Matchers 2013-03-01 16:37:56,292 [Timer-7] INFO sagex.phoenix.Phoenix - MediaTitles.xml Initialized 2013-03-01 16:37:56,293 [Timer-7] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - Being Loading URL Resolvers 2013-03-01 16:37:56,293 [Timer-7] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - End Loading URL Resolvers 2013-03-01 16:37:56,293 [Timer-7] INFO sagex.phoenix.Phoenix - URL Resolvers has been loaded 2013-03-01 16:37:56,294 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Url Cleanup Task Created monitoring .\userdata\Phoenix\cache\url 2013-03-01 16:37:56,294 [Timer-7] INFO sagex.phoenix.util.TaskManager - Cancelling scheduled task since it's being updated for: urlcachecleaner 2013-03-01 16:37:56,294 [Timer-7] INFO sagex.phoenix.util.TaskManager - Adding Scheduled Task: urlcachecleaner; Date: Fri Mar 01 16:37:56 PST 2013; Repeat: 86400000 2013-03-01 16:37:56,295 [Timer-7] INFO sagex.phoenix.Phoenix - Core Scheduled Tasks Initialized 2013-03-01 16:37:56,295 [Timer-7] INFO sagex.phoenix.Phoenix - Phoenix Services initialized. 2013-03-01 16:37:56,295 [Timer-7] INFO sagex.phoenix.plugin.PhoenixPlugin - End: Phoenix looking for plugins that contribute to the Phoenix Core 2013-03-01 16:37:56,295 [Timer-5] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Begin URL Cache Cleanup 2013-03-01 16:37:56,296 [Timer-5] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Finished URL Cache Cleanup; Cleaned 0 items. 2013-03-01 16:38:28,779 [31975997@qtp-2143-6] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initializing 2013-03-01 16:38:28,779 [31975997@qtp-2143-6] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initialized 2013-03-01 16:40:24,621 [7613230@qtp-2143-9] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initializing 2013-03-01 16:40:24,621 [7613230@qtp-2143-9] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initialized 2013-03-01 16:41:16,927 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 16:41:16,927 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 16:41:16,927 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 16:41:16,927 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 16:41:16,929 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 2ms 2013-03-01 16:41:16,929 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 2ms 2013-03-01 16:41:16,942 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 16:41:16,943 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 16:41:16,944 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 16:41:16,944 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 16:41:16,972 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 45ms 2013-03-01 16:41:16,974 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 16:41:16,974 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:41:16,980 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 53ms 2013-03-01 16:41:16,980 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:41:16,980 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 16:41:16,980 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 16:41:16,980 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 16:41:16,981 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 1ms 2013-03-01 16:41:16,981 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 16:41:16,984 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:41:16,984 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:41:16,994 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 14ms 2013-03-01 16:41:17,007 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 16:41:17,008 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:41:17,011 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 31ms 2013-03-01 16:41:17,012 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:41:17,012 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 86ms 2013-03-01 16:41:17,020 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,021 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,021 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,022 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,022 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,022 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,022 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,023 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,023 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,023 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,024 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,024 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,024 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,024 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,025 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,025 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,025 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,026 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,026 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,027 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,027 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,027 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,028 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,028 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,028 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,029 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,029 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,029 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,030 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,030 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,030 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,031 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,031 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,031 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,032 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,032 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,032 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:17,247 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:17,422 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:17,432 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:17,656 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:17,768 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:20,069 [17464945@qtp-2143-14] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:49,293 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 16:41:49,293 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 16:41:49,293 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 16:41:49,294 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 16:41:49,294 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 0ms 2013-03-01 16:41:49,294 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 0ms 2013-03-01 16:41:49,294 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 16:41:49,295 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 16:41:49,295 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 16:41:49,295 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 16:41:49,297 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 4ms 2013-03-01 16:41:49,297 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 16:41:49,297 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:41:49,301 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 8ms 2013-03-01 16:41:49,301 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:41:49,301 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 16:41:49,301 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 16:41:49,301 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 16:41:49,302 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 1ms 2013-03-01 16:41:49,302 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 16:41:49,304 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:41:49,304 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:41:49,312 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 11ms 2013-03-01 16:41:49,315 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 16:41:49,315 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:41:49,319 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 18ms 2013-03-01 16:41:49,319 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:41:49,320 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 27ms 2013-03-01 16:41:49,325 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,326 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,327 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,327 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,327 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,328 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,328 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,329 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,329 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,330 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,330 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,330 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,331 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,331 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,331 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,332 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,332 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,333 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,333 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,334 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,334 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,334 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,335 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,335 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,335 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,336 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,336 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,336 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,337 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,337 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,337 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,338 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,338 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,338 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,338 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,339 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,339 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:41:49,725 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:49,738 [10751504@qtp-2143-8] INFO sagex.phoenix.image.ImageUtil - Created Image Cache Dir: .\userdata\Phoenix\cache\imagetrans; exists: true 2013-03-01 16:41:50,650 [10751504@qtp-2143-8] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:50,905 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:50,943 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:41:58,195 [7613230@qtp-2143-9] WARN sagex.phoenix.image.ImageUtil - Failed to load sage image using sage apis using ImageIO for file:/Z:/SageTV/Fanart/TV/Late%20Show%20with%20David%20Letterman/Season%2020/Episodes/0036.png 2013-03-01 16:44:25,943 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 16:44:25,943 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 16:44:25,943 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 16:44:25,943 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 16:44:25,943 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 0ms 2013-03-01 16:44:25,943 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 0ms 2013-03-01 16:44:25,944 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 16:44:25,944 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 16:44:25,944 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 16:44:25,945 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 16:44:25,946 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 3ms 2013-03-01 16:44:25,946 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 16:44:25,947 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:44:25,950 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 7ms 2013-03-01 16:44:25,950 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:44:25,950 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 16:44:25,950 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 16:44:25,950 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 16:44:25,951 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 1ms 2013-03-01 16:44:25,951 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 16:44:25,953 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:44:25,953 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:44:25,960 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 10ms 2013-03-01 16:44:25,962 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 16:44:25,963 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:44:25,966 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 16ms 2013-03-01 16:44:25,966 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:44:25,966 [10751504@qtp-2143-8] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 23ms 2013-03-01 16:44:25,969 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,970 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,970 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,970 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,970 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,971 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,971 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,971 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,971 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,972 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,972 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,972 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,972 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,973 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,973 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,973 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,973 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,974 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,974 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,974 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,975 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,975 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,975 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,975 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,976 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,976 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,976 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,977 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,977 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,977 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,977 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,978 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,978 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,978 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,979 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,979 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:25,979 [10751504@qtp-2143-8] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:26,670 [10751504@qtp-2143-8] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:44:27,555 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 16:44:27,555 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 16:44:27,555 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 16:44:27,555 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 16:44:27,556 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 1ms 2013-03-01 16:44:27,556 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 16:44:27,556 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 16:44:27,557 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 16:44:27,557 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 16:44:27,557 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 16:44:27,559 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 3ms 2013-03-01 16:44:27,559 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 16:44:27,559 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:44:27,562 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 7ms 2013-03-01 16:44:27,562 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:44:27,562 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 16:44:27,562 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 16:44:27,562 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 16:44:27,563 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 1ms 2013-03-01 16:44:27,563 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 16:44:27,564 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:44:27,565 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 16:44:27,571 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 9ms 2013-03-01 16:44:27,574 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 16:44:27,574 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 16:44:27,577 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 15ms 2013-03-01 16:44:27,577 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 16:44:27,578 [31975997@qtp-2143-6] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 23ms 2013-03-01 16:44:27,583 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,584 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,584 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,585 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,585 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,585 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,585 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,586 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,586 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,586 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,586 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,587 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,587 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,587 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,587 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,587 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,588 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,588 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,588 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,589 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,589 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,589 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,589 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,590 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,590 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,590 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,591 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,591 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,591 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,591 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,592 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,592 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,592 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,593 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,593 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,593 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:27,593 [31975997@qtp-2143-6] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 16:44:28,144 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:44:28,516 [10751504@qtp-2143-8] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:44:28,617 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:44:28,818 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:44:29,077 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:44:31,124 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:49:35,280 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:49:35,284 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:59:07,532 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 16:59:07,559 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 17:08:08,880 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 17:08:08,884 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 17:13:03,409 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 17:13:03,447 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 17:44:42,995 [Fork-BASE-71232] INFO sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport - Getting WorldWeather Weather for http://free.worldweatheronline.com/feed/weather.ashx?q=94801&format=xml&num_of_days=5&includeLocation=yes&extra=localObsTime&key=b18c521658003002121007 2013-03-01 17:44:43,038 [Fork-JUSJOKEN-8522289] INFO sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport - Getting WorldWeather Weather for http://free.worldweatheronline.com/feed/weather.ashx?q=94801&format=xml&num_of_days=5&includeLocation=yes&extra=localObsTime&key=b18c521658003002121007 2013-03-01 18:00:01,001 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - search(): tvdb; SearchQuery [type=TV, fields={RAW_TITLE: CBS Evening News With Scott Pelley,YEAR: 2011,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: CBS Evening News With Scott Pelley,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]] 2013-03-01 18:00:01,001 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Searching: CBS Evening News With Scott Pelley using TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]] 2013-03-01 18:00:01,006 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - TVDB Search: http://www.thetvdb.com/api/GetSeries.php?seriesname=CBS+Evening+News+With+Scott+Pelley 2013-03-01 18:00:01,006 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlFactory - Caching URL Factory in use. 2013-03-01 18:00:01,154 [Timer-7] WARN sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - Could not find any results for: CachedUrl: http://www.thetvdb.com/api/GetSeries.php?seriesname=CBS+Evening+News+With+Scott+Pelley; UrlId: 09c058847ff96322f6de3367799703be; CachedFile: .\userdata\Phoenix\cache\url\09c058847ff96322f6de3367799703be.properties 2013-03-01 18:00:01,156 [Timer-7] WARN sagex.phoenix.metadata.MetadataManager - Search Failed for: SearchQuery [type=TV, fields={RAW_TITLE: CBS Evening News With Scott Pelley,YEAR: 2011,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: CBS Evening News With Scott Pelley,QUERY: CBS Evening News With Scott Pelley,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]] using provider TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]]; Message: null java.lang.NullPointerException at java.util.ArrayList.addAll(Unknown Source) at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:276) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:538) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:506) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:155) at sagex.phoenix.plugin.PhoenixPlugin.recordingCompleted(PhoenixPlugin.java:144) 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 sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2013-03-01 18:00:01,157 [Timer-7] WARN phoenix.log - ERROR; T:\TV Recordings\CBSEveningNewsWithScottPelley-15893882-0.mpg; CBS Evening News With Scott Pelley; 16029843; Search Failed for SearchQuery [type=TV, fields={RAW_TITLE: CBS Evening News With Scott Pelley,YEAR: 2011,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: CBS Evening News With Scott Pelley,QUERY: CBS Evening News With Scott Pelley,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]] MetadataException [query=SearchQuery [type=TV, fields={RAW_TITLE: CBS Evening News With Scott Pelley,YEAR: 2011,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: CBS Evening News With Scott Pelley,QUERY: CBS Evening News With Scott Pelley,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]], ] at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:315) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:538) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:506) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:155) at sagex.phoenix.plugin.PhoenixPlugin.recordingCompleted(PhoenixPlugin.java:144) 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 sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2013-03-01 18:00:01,320 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - search(): tvdb; SearchQuery [type=TV, fields={RAW_TITLE: ABC World News With Diane Sawyer,YEAR: 2009,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: ABC World News With Diane Sawyer,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]] 2013-03-01 18:00:01,320 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Searching: ABC World News With Diane Sawyer using TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]] 2013-03-01 18:00:01,320 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - TVDB Search: http://www.thetvdb.com/api/GetSeries.php?seriesname=ABC+World+News+With+Diane+Sawyer 2013-03-01 18:00:01,417 [Timer-7] WARN sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - Could not find any results for: CachedUrl: http://www.thetvdb.com/api/GetSeries.php?seriesname=ABC+World+News+With+Diane+Sawyer; UrlId: e50d30372a7407bc58011a5401d02300; CachedFile: .\userdata\Phoenix\cache\url\e50d30372a7407bc58011a5401d02300.properties 2013-03-01 18:00:01,417 [Timer-7] WARN sagex.phoenix.metadata.MetadataManager - Search Failed for: SearchQuery [type=TV, fields={RAW_TITLE: ABC World News With Diane Sawyer,YEAR: 2009,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: ABC World News With Diane Sawyer,QUERY: ABC World News With Diane Sawyer,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]] using provider TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]]; Message: null java.lang.NullPointerException at java.util.ArrayList.addAll(Unknown Source) at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:276) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:538) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:506) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:155) at sagex.phoenix.plugin.PhoenixPlugin.recordingCompleted(PhoenixPlugin.java:144) 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 sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2013-03-01 18:00:01,417 [Timer-7] WARN phoenix.log - ERROR; T:\TV Recordings\ABCWorldNewsWithDianeSawyer-15893673-0.mpg; ABC World News With Diane Sawyer; 16029844; Search Failed for SearchQuery [type=TV, fields={RAW_TITLE: ABC World News With Diane Sawyer,YEAR: 2009,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: ABC World News With Diane Sawyer,QUERY: ABC World News With Diane Sawyer,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]] MetadataException [query=SearchQuery [type=TV, fields={RAW_TITLE: ABC World News With Diane Sawyer,YEAR: 2009,EPISODE_DATE: 2013-03-01,EPISODE_TITLE: ,CLEAN_TITLE: ABC World News With Diane Sawyer,QUERY: ABC World News With Diane Sawyer,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: true,import_tv_as_recording: true,auto: true,}]], ] at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:315) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:538) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:506) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:155) at sagex.phoenix.plugin.PhoenixPlugin.recordingCompleted(PhoenixPlugin.java:144) 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 sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2013-03-01 18:13:59,200 [Timer-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Scraper title-s00e00 created a query: SearchQuery [type=TV, fields={RAW_TITLE: Eureka -,SEASON: 05,EPISODE: 09,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: false,import_tv_as_recording: true,auto: true,}]] for file SageMediaFile [sageId=16029862, sageObject=MediaFile[id=16029862 A[16029865,16029863,"Eureka - S05E09 - Smarter Carter",0@0301.18:13,0] mask=V host=Horizon encodedBy= format=MATROSKA 0:00:00 0 kbps [] E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv, Seg0[Fri 3/1 18:13:59.090-Fri 3/1 18:13:59.091]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,known_recording: false,scan_subfolders: true,import_tv_as_recording: true,auto: true,}] 2013-03-01 18:13:59,202 [Timer-7] INFO sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=TV, fields={RAW_TITLE: Eureka,SEASON: 05,EPISODE_DATE: null,CLEAN_TITLE: Eureka,EPISODE: 09,FILE: E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: false,import_tv_as_recording: true,auto: true,}]] 2013-03-01 18:13:59,203 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - search(): tvdb; SearchQuery [type=TV, fields={RAW_TITLE: Eureka,SEASON: 05,EPISODE_DATE: null,CLEAN_TITLE: Eureka,EPISODE: 09,FILE: E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: false,import_tv_as_recording: true,auto: true,}]] 2013-03-01 18:13:59,203 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Searching: Eureka using TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]] 2013-03-01 18:13:59,203 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - TVDB Search: http://www.thetvdb.com/api/GetSeries.php?seriesname=Eureka 2013-03-01 18:13:59,353 [Timer-7] WARN sagex.phoenix.metadata.search.MetadataSearchUtil - The year was not passed in the query: SearchQuery [type=TV, fields={RAW_TITLE: Eureka,SEASON: 05,EPISODE_DATE: null,CLEAN_TITLE: Eureka,EPISODE: 09,QUERY: Eureka,FILE: E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,known_recording: false,import_tv_as_recording: true,auto: true,}]] we are returning the first good result. Consider adding the year to the query for better matches. 2013-03-01 18:13:59,353 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Fetching Metadata for MediaSearchResult [extraArgs={CLEAN_TITLE: Eureka,SEASON: 05,EPISODE: 09,RAW_TITLE: Eureka,FILE: E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv,}, id=79334, imdbId=null, metadata=null, providerId=tvdb, score=1.0, title=Eureka, type=TV, url=79334, year=2006] 2013-03-01 18:13:59,364 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Series: http://www.thetvdb.com/api/5645B594A3F32D27/series/79334/en.xml 2013-03-01 18:13:59,496 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Actors: http://www.thetvdb.com/api/5645B594A3F32D27/series/79334/actors.xml 2013-03-01 18:13:59,579 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - TVDB Episode: http://www.thetvdb.com/api/5645B594A3F32D27/series/79334/default/5/9/en.xml 2013-03-01 18:13:59,662 [Timer-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - Parsing TVDB Banners url: http://www.thetvdb.com/api/5645B594A3F32D27/series/79334/banners.xml 2013-03-01 18:13:59,807 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Skipping Fanart since Fanart Provider is empty 2013-03-01 18:13:59,807 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Saving Metadata for SageMediaFile [sageId=16029862, sageObject=MediaFile[id=16029862 A[16029865,16029863,"Eureka - S05E09 - Smarter Carter",0@0301.18:13,0] mask=V host=Horizon encodedBy= format=MATROSKA 0:00:00 0 kbps [] E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv, Seg0[Fri 3/1 18:13:59.090-Fri 3/1 18:13:59.091]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,known_recording: false,scan_subfolders: true,import_tv_as_recording: true,auto: true,}] 2013-03-01 18:13:59,811 [Timer-7] INFO sagex.phoenix.metadata.persistence.Sage7Persistence - Storing updated metadata for item SageMediaFile [sageId=16029862, sageObject=MediaFile[id=16029862 A[16029865,16029863,"Eureka - S05E09 - Smarter Carter",0@0301.18:13,0] mask=V host=Horizon encodedBy= format=MATROSKA 0:00:00 0 kbps [] E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv, Seg0[Fri 3/1 18:13:59.090-Fri 3/1 18:13:59.091]]] 2013-03-01 18:13:59,989 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Imported MediaFile as Recording: SageMediaFile [sageId=16029862, sageObject=MediaFile[id=16029862 A[16029868,16029867,"Eureka",0@0301.18:13,60] mask=TV host=Horizon encodedBy= format=MATROSKA 0:00:00 0 kbps [{IMDBID=tt1998658, MediaType=TV, UserRating=77, MediaProviderDataID=79334, MediaTitle=Eureka, MediaProviderID=tvdb}] E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv, Seg0[Fri 3/1 18:13:59.090-Fri 3/1 19:13:59.090]]] 2013-03-01 18:14:00,010 [Timer-7] INFO sagex.phoenix.metadata.MetadataManager - Saving Phoenix Fanart for SageMediaFile [sageId=16029862, sageObject=MediaFile[id=16029862 A[16029869,16029867,"Eureka",0@0301.18:13,60] mask=TV host=Horizon encodedBy= format=MATROSKA 0:00:00 0 kbps [{ScrapedBy=Phoenix, IMDBID=tt1998658, MediaType=TV, ScrapedDate=1362190440008, MediaProviderDataID=79334, MediaTitle=Eureka, SeriesInfoID=840179, UserRating=77, MediaProviderID=tvdb}] E:\Downloads\Eureka (1982)\Eureka - S05E09 - Smarter Carter.mkv, Seg0[Fri 3/1 18:13:59.090-Fri 3/1 19:13:59.090]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,known_recording: false,scan_subfolders: true,import_tv_as_recording: true,auto: true,}] 2013-03-01 18:14:00,017 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/79334-18.jpg because it's in the image skip file. 2013-03-01 18:14:00,017 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/79334-5.jpg because it's in the image skip file. 2013-03-01 18:14:00,017 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/79334-1.jpg because it's in the image skip file. 2013-03-01 18:14:00,018 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/79334-12.jpg because it's in the image skip file. 2013-03-01 18:14:00,018 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/79334-4.jpg because it's in the image skip file. 2013-03-01 18:14:00,018 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No BACKGROUND for Z:\SageTV\Fanart\TV\Eureka\Season 5\Backgrounds in the metadata. 2013-03-01 18:14:00,020 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/79334-g6.jpg because it's in the image skip file. 2013-03-01 18:14:00,020 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/79334-g4.jpg because it's in the image skip file. 2013-03-01 18:14:00,020 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/79334-g8.jpg because it's in the image skip file. 2013-03-01 18:14:00,020 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/58448-g.jpg because it's in the image skip file. 2013-03-01 18:14:00,021 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/79334-g.jpg because it's in the image skip file. 2013-03-01 18:14:00,021 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No BANNER for Z:\SageTV\Fanart\TV\Eureka\Season 5\Banners in the metadata. 2013-03-01 18:14:00,023 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/posters/79334-2.jpg because it's in the image skip file. 2013-03-01 18:14:00,023 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/posters/79334-1.jpg because it's in the image skip file. 2013-03-01 18:14:00,023 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/posters/79334-3.jpg because it's in the image skip file. 2013-03-01 18:14:00,024 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/posters/79334-4.jpg because it's in the image skip file. 2013-03-01 18:14:00,026 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/seasons/79334-5-6.jpg because it's in the image skip file. 2013-03-01 18:14:00,026 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/seasons/79334-5-5.jpg because it's in the image skip file. 2013-03-01 18:14:00,026 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/seasons/79334-5.jpg because it's in the image skip file. 2013-03-01 18:14:00,026 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/seasons/79334-5-4.jpg because it's in the image skip file. 2013-03-01 18:14:00,027 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No ACTOR for Z:\SageTV\Fanart\TV\Eureka\Actors in the metadata. 2013-03-01 18:14:00,027 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No ACTOR for Z:\SageTV\Fanart\TV\Eureka\Season 5\Actors in the metadata. 2013-03-01 18:14:00,027 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No EPISODE for Z:\SageTV\Fanart\TV\Eureka\Episodes in the metadata. 2013-03-01 18:14:00,029 [Timer-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/episodes/79334/4232280.jpg because it's in the image skip file. 2013-03-01 18:14:00,029 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No ALBUM for Z:\SageTV\Fanart\TV\Eureka\Albums in the metadata. 2013-03-01 18:14:00,029 [Timer-7] WARN sagex.phoenix.fanart.FanartStorage - No ALBUM for Z:\SageTV\Fanart\TV\Eureka\Season 5\Albums in the metadata. 2013-03-01 18:15:38,870 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 18:15:38,870 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 18:15:38,870 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 18:15:38,870 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 18:15:38,871 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 1ms 2013-03-01 18:15:38,871 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 18:15:38,871 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 18:15:38,871 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 18:15:38,871 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 18:15:38,871 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 18:15:38,873 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 3ms 2013-03-01 18:15:38,873 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 18:15:38,873 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 18:15:38,875 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 5ms 2013-03-01 18:15:38,875 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 18:15:38,875 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 18:15:38,875 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 18:15:38,876 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 18:15:38,876 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 0ms 2013-03-01 18:15:38,876 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 0ms 2013-03-01 18:15:38,877 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 18:15:38,877 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 18:15:38,879 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 4ms 2013-03-01 18:15:38,881 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 18:15:38,881 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 18:15:38,883 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 8ms 2013-03-01 18:15:38,883 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 18:15:38,883 [10848188@qtp-2143-3] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 14ms 2013-03-01 18:15:38,886 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,887 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,887 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,887 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,888 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,888 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,888 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,888 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,889 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,889 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,889 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,889 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,889 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,890 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,890 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,890 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,890 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,891 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,891 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,891 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,891 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,891 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,892 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,892 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,892 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,892 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,893 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,893 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,893 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,893 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,894 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,894 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,894 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,894 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,895 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,895 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:38,895 [10848188@qtp-2143-3] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:15:39,777 [10848188@qtp-2143-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:15:40,091 [10751504@qtp-2143-8] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:15:40,185 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:15:40,319 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:15:40,597 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:16:48,512 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:17:11,795 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 18:17:11,795 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 18:17:11,795 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 18:17:11,795 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 18:17:11,796 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 1ms 2013-03-01 18:17:11,796 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 18:17:11,796 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 18:17:11,796 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 18:17:11,796 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 18:17:11,796 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 18:17:11,797 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 2ms 2013-03-01 18:17:11,797 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 18:17:11,797 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 18:17:11,799 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 4ms 2013-03-01 18:17:11,799 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 18:17:11,799 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 18:17:11,799 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 18:17:11,799 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 18:17:11,800 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 1ms 2013-03-01 18:17:11,800 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 18:17:11,800 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 18:17:11,800 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 18:17:11,804 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 5ms 2013-03-01 18:17:11,805 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 18:17:11,806 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 18:17:11,808 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 9ms 2013-03-01 18:17:11,808 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 18:17:11,808 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 13ms 2013-03-01 18:17:11,810 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,811 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,811 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,811 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,812 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,812 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,812 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,813 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,813 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,814 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,814 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,814 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,814 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,815 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,815 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,815 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,816 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,816 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,816 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,816 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,817 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,817 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,817 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,818 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,818 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,818 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,818 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,819 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,819 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,819 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,819 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,820 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,820 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,820 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,821 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,821 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:11,821 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:12,640 [7613230@qtp-2143-9] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:17:12,648 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:17:13,081 [31975997@qtp-2143-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:17:13,256 [10657040@qtp-2143-12] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-03-01 18:17:29,038 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: TV Shows; id: phoenix.view.default.TV; Options: null 2013-03-01 18:17:29,038 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Recorded TV; id: phoenix.view.primary.recordedtv; Options: null 2013-03-01 18:17:29,038 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Sage Recordings; id: phoenix.view.source.sagerecordings; Options: null 2013-03-01 18:17:29,038 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) 2013-03-01 18:17:29,039 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("T"), GetMediaFiles("TL"))) resulted in 41 files in 1ms 2013-03-01 18:17:29,039 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 1ms 2013-03-01 18:17:29,039 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Sofia the First: Once Upon a Princess 2013-03-01 18:17:29,039 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: November Christmas 2013-03-01 18:17:29,039 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: Disney's A Christmas Carol 2013-03-01 18:17:29,040 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: The Bourne Supremacy 2013-03-01 18:17:29,040 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Sage Recordings in 2ms 2013-03-01 18:17:29,040 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.sagerecordings to view: phoenix.view.primary.recordedtv; Will flatten. 2013-03-01 18:17:29,040 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 18:17:29,042 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Recorded TV in 4ms 2013-03-01 18:17:29,042 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.recordedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 18:17:29,043 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Imported TV; id: phoenix.view.primary.importedtv; Options: null 2013-03-01 18:17:29,043 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: All Imported Video; id: phoenix.view.source.allimportedvideo; Options: null 2013-03-01 18:17:29,043 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Evaluating Expression for media items: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) 2013-03-01 18:17:29,043 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.sources.SageExpressionSourceFactory - Expression: phoenix_util_ToArray(phoenix_util_RemoveAll(GetMediaFiles("VDBL"), GetMediaFiles("T"))) resulted in 456 files in 0ms 2013-03-01 18:17:29,043 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage Expression in 0ms 2013-03-01 18:17:29,044 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 18:17:29,044 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - Found Movie Title is wrong place for: 2013-03-01 18:17:29,047 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: All Imported Video in 4ms 2013-03-01 18:17:29,048 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.source.allimportedvideo to view: phoenix.view.primary.importedtv; Will flatten. 2013-03-01 18:17:29,048 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - applying root filters... 2013-03-01 18:17:29,050 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Imported TV in 7ms 2013-03-01 18:17:29,050 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Adding View Source: phoenix.view.primary.importedtv to view: phoenix.view.default.TV; Will flatten. 2013-03-01 18:17:29,050 [7613230@qtp-2143-9] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: TV Shows in 12ms 2013-03-01 18:17:29,053 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,053 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,053 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,053 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,054 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,054 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,054 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,054 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,055 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,055 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,055 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,055 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,055 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,056 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,056 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,056 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,056 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,057 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,057 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,057 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,057 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,057 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,058 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,058 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,059 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,059 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,059 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,059 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,060 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,060 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,060 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,060 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,061 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,061 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,061 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,061 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:29,062 [7613230@qtp-2143-9] WARN sagex.phoenix.vfs.sage.SageMediaFile - isType(ONLINE[13]) is unhandled. 2013-03-01 18:17:36,594 [10751504@qtp-2143-8] WARN sagex.phoenix.image.ImageUtil - Failed to load sage image using sage apis using ImageIO for file:/Z:/SageTV/Fanart/TV/Late%20Show%20with%20David%20Letterman/Season%2020/Episodes/0031.png