2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.Phoenix - Initializing Phoenix - Version: 2.5.1 2013-04-29 10:48:04,638 [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\ant-javamail.jar;JARs\ant-launcher.jar;JARs\ant.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-dbutils-1.3.jar;JARs\commons-exec-1.0.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\cron4j-2.2.1.jar;JARs\df_sageutils.jar;JARs\dom4j-1.6.1.jar;JARs\fluent-hc-4.2.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\h2-1.2.145.jar;JARs\htmlparser-1.6.jar;JARs\httpclient-4.2.1.jar;JARs\httpclient-cache-4.2.1.jar;JARs\httpcore-4.2.1.jar;JARs\httpmime-4.2.1.jar;JARs\jackson-annotations-2.1.4.jar;JARs\jackson-core-2.1.4.jar;JARs\jackson-databind-2.1.4.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\jpathwatch-0-93-rc1.jar;JARs\json.jar;JARs\jsoup-1.7.1.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\junit-4.8.2.jar;JARs\livepvrdata4j.jar;JARs\livepvrdata_shared.jar;JARs\log4j-1.2.15.jar;JARs\lucene-core-3.0.1.jar;JARs\mail.jar;JARs\mjlib.jar;JARs\mjxml.jar;JARs\nielm_sageimdb.jar;JARs\nielm_sageutls.jar;JARs\nielm_sagewebserver.jar;JARs\nielm_sagexmlinfo.jar;JARs\OneTimeFavorite.jar;JARs\phoenix.jar;JARs\SageTCPServer.jar;JARs\sagex-api.jar;JARs\sagex-h2.jar;JARs\salicense.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\sjq-agent.jar;JARs\sjq-common.jar;JARs\sjq-listener.jar;JARs\sjq.jar;JARs\slf4j-api-1.6.0.jar;JARs\snark.jar;JARs\somf.jar;JARs\squeezeslave.jar;JARs\sre.jar;JARs\telnet.jar;JARs\themoviedb-3.0.0.jar;JARs\vecmath.jar 2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.Phoenix - Java Impl: Oracle Corporation - 1.7.0_21 2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.Phoenix - OS: Windows 7 - x86 - 6.1 2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.Phoenix - User: Sage 2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.Phoenix - Registering System Message Handler to the Event Bus 2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.SageSystemMessageListener for Event Type: phoenix.SystemMessage 2013-04-29 10:48:04,638 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Begin Loading Configuration Metadata 2013-04-29 10:48:04,653 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\epg.xml 2013-04-29 10:48:04,669 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\log4j.xml 2013-04-29 10:48:04,669 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\lz.xml 2013-04-29 10:48:04,669 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\music.xml 2013-04-29 10:48:04,669 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\musicosd.xml 2013-04-29 10:48:04,669 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Phoenix.xml 2013-04-29 10:48:04,809 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:04,809 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:04,809 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:04,809 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:04,825 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Sage.xml 2013-04-29 10:48:04,825 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Streaming.xml 2013-04-29 10:48:04,825 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\ui.xml 2013-04-29 10:48:04,825 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\umb.xml 2013-04-29 10:48:04,825 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\vosd.xml 2013-04-29 10:48:04,840 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - End Loading Configuration Metadata 2013-04-29 10:48:04,840 [SageTV] INFO sagex.phoenix.Phoenix - Configuration Metadata Initialized 2013-04-29 10:48:04,856 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: PlaybackStopped 2013-04-29 10:48:04,856 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: PlaybackFinished 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.upnp.PhoenixUPNPServer - UPnP Services are not online 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.Phoenix - Phoenix base system initialized. 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.Phoenix - Initializing Phoenix Services 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Found core Phoenix Views file (no need to download update).\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Rebuilding VFS Cache .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:04,872 [SageTV] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:04,950 [SageTV] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\z-gemstone-vfs.xml 2013-04-29 10:48:04,950 [SageTV] INFO sagex.phoenix.vfs.VFSOrganizer - Replaced VFS Entry: item-group[latertoday] from C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\z-gemstone-vfs.xml 2013-04-29 10:48:04,981 [SageTV] INFO sagex.phoenix.vfs.VFSManager - New VFS Cache Written to .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:04,981 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Loading VFS entries from .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:04,981 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.music 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.pictures 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagerecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagearchivedrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.videos 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.dvd 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.bluray 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.allimportedvideo 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.mediafiles 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.scheduledrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedmovies 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedmovies 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtv 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtvarchived 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedtv 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.homevideos 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.scheduledrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.upcomingairings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.currentlyairing 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.todaysrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.todaysrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentmusic 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentpictures 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedtv 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedtv 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedmovies 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedmovies 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.currentlyrecording 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.currentlyrecording 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.featuredfavorite 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentimports 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentimports 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.conflicts 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.allsagefanart 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.upcomingmovies 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.scheduledrecordings 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.nowshowing 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.videofolders 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.archivedTV 2013-04-29 10:48:05,074 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTV 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTVseasons 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.TV 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allMovies 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.bluray 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.dvd 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.recentlywatched 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.homevideos 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.playlists 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.album 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.artist 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.genre 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.online.videos 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.all 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tv 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.movies 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.allforcache 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tvforusercats 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videofolders 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTV 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVbyrecent 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasons 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasonsbyrecent 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.firstletter 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allVideos 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allMovies 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.recentMovies 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.recentlywatched 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videosbygenre 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.scheduledrecordings 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.scheduledrecordings 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.todaysrecordings 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: phoenix.vfs.reload 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.Phoenix - VFS Initialized 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.menu.MenuManager - Begin Loading Menus 2013-04-29 10:48:05,090 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\ContextSensitiveMenus.xml 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-SingleList with new menu 0 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-DoubleList with new menu 0 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-HorizontalList with new menu 3 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-CoverFlow with new menu 0 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-ArtFlow with new menu 0 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixMusicBrowser with new menu 2 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixEPGFull with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixEPGNormal with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixOSDMusic with new menu 2 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixOSDVideo with new menu 4 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneVertical with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneCross with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneHorizontal with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneBox with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneSage with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneMetro with new menu 1 items 2013-04-29 10:48:05,121 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\GlobalMenu.xml 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: GlobalMenu with new menu 6 items 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\InstantStatus.xml 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.lz.box.status with new menu 6 items 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\KeyMenu.xml 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.keys with new menu 14 items 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\LandingMenu.xml 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.lz with new menu 7 items 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\NewStatusMenu.xml 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.status with new menu 10 items 2013-04-29 10:48:05,137 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\OptionsMenu.xml 2013-04-29 10:48:05,168 [SageTV] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initializing 2013-04-29 10:48:05,168 [SageTV] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initialized 2013-04-29 10:48:05,199 [SageTV] ERROR sagex.phoenix.profiles.ProfileManager - GetUIContextName() returned Null!! Creating a default context: default 2013-04-29 10:48:05,199 [SageTV] ERROR sagex.phoenix.profiles.ProfileManager - GetUIContextName() returned Null!! Creating a default context: default 2013-04-29 10:48:05,215 [SageTV] WARN sagex.phoenix.profiles.ProfileManager - Current Profile: sagex.phoenix.profiles.Profile@1863215[user=default,context=default,lastAccessed=0] not was previously loaded. 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.profiles.ProfileManager - Setting Current User Profile: sagex.phoenix.profiles.Profile@1863215[user=default,context=default,lastAccessed=0] 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.options with new menu 5 items 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\StatusMenu.xml 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: StatusMenu with new menu 9 items 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adjusting menu item visibility based on stored settings 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - Processing Menu Fragments... 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - Ordering Menu Items... 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.menu.MenuManager - End Loading Menus 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.Phoenix - Menus Initialized 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.skins.SkinManager - Begin Loading Skins 2013-04-29 10:48:05,215 [SageTV] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Backgrounds 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinBuilder - No Skin declaration file for Skin: .\STVs\Phoenix\Skins\Backgrounds 2013-04-29 10:48:05,230 [SageTV] WARN sagex.phoenix.skins.SkinManager - Skipping Skin: Backgrounds since it is not a valid skin 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Default 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Adding Phoenix Skin: skin.default 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Ice 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Adding Phoenix Skin: skin.ice 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Serenity 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Adding Phoenix Skin: skin.serenity 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Plugin Activated: skin.default 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Plugin Activated: skin.ice 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - Plugin Activated: skin.serenity 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.skins.SkinManager - End Loading Skins 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.Phoenix - Skins Initialized 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Begin Loading Metadata Providers 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\dvdprofiler.xml 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\htbackdrops.xml 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\imdb.xml 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\mymovies.xml 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\nielm_imdb.xml 2013-04-29 10:48:05,230 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb.xml 2013-04-29 10:48:05,886 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb3.xml 2013-04-29 10:48:06,026 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tvdb.xml 2013-04-29 10:48:06,026 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Begin Loading Xbmc Metadata Providers 2013-04-29 10:48:06,026 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Loading XBMC Metadata Scraper: .\STVs\Phoenix\scrapers\xbmc\video\imdb.xml 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - End Loading Xbmc Metadata Providers 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - End Loading Metadata Providers 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.Phoenix - Metadata Scrapers Initialized 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Begin Loading Movie Filename Scrapers 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear1 with priority 116 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear2 with priority 116 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-dot-year with priority 116 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - End Loading Movie Filename Scrapers 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: defaultmovie with priority 2147483647 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.Phoenix - Movie Filename Scrapers Initialized 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Begin Loading TV Filename Scrapers 2013-04-29 10:48:06,042 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecordingNoEpisode with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecording with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-show-episode with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-00x00 with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-airedDate with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00d00 with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00e00 with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00-Disc# with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-Season00Episode00 with priority 116 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - End Loading TV Filename Scrapers 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.Phoenix - TV Filename Scrapers Initialized 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - Begin Loading Ratings Map 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - Loading Ratings file .\STVs\Phoenix\metadata\ratings.properties 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - End Loading Ratings Map 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.Phoenix - Ratings Manager Initialized 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - Begin loading Title Matchers 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - Loading User media titles from .\userdata\Phoenix\scrapers\MediaTitles.xml 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - End loading Title Matchers 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.Phoenix - MediaTitles.xml Initialized 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - Being Loading URL Resolvers 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - End Loading URL Resolvers 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.Phoenix - URL Resolvers has been loaded 2013-04-29 10:48:06,057 [SageTV] INFO sagex.phoenix.Phoenix - Scripting Services Factory initialized 2013-04-29 10:48:06,073 [SageTV] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Url Cleanup Task Created monitoring .\userdata\Phoenix\cache\url 2013-04-29 10:48:06,073 [SageTV] INFO sagex.phoenix.util.TaskManager - Adding Scheduled Task: urlcachecleaner; Date: Mon Apr 29 10:48:06 PDT 2013; Repeat: 86400000 2013-04-29 10:48:06,073 [SageTV] INFO sagex.phoenix.Phoenix - Core Scheduled Tasks Initialized 2013-04-29 10:48:06,572 [SageTV] WARN sagex.phoenix.weather.yahoo.YahooWeatherSupport - Failed to convert null to woeid java.lang.NullPointerException at sagex.phoenix.weather.yahoo.YahooWeatherSupport.setLocation(YahooWeatherSupport.java:138) at phoenix.impl.WeatherAPI.SetLocation(WeatherAPI.java:62) at phoenix.weather.SetLocation(weather.java:62) at sagex.phoenix.Phoenix.initServices(Phoenix.java:381) at sagex.phoenix.Phoenix.(Phoenix.java:87) at sagex.phoenix.configuration.proxy.FieldProxy.getProperty(FieldProxy.java:29) at sagex.phoenix.configuration.proxy.FieldProxy.get(FieldProxy.java:33) at sagex.phoenix.weather.WeatherConfiguration.getUnits(WeatherConfiguration.java:34) at sagex.phoenix.weather.worldweather.WorldWeatherWeatherSupport.getUnits(WorldWeatherWeatherSupport.java:132) at phoenix.impl.WeatherAPI2.GetUnits(WeatherAPI2.java:315) at phoenix.weather2.GetUnits(weather2.java:207) at Gemstone.Weather.setUnits(Weather.java:89) at Gemstone.Weather.Init(Weather.java:48) at Gemstone.api.Load(api.java:57) at Gemstone.GemstonePlugin.start(GemstonePlugin.java:90) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2013-04-29 10:48:06,572 [SageTV] INFO sagex.phoenix.Phoenix - Configuring weather using EPG zip code 2013-04-29 10:48:06,572 [SageTV] INFO sagex.phoenix.Phoenix - Phoenix Services initialized. 2013-04-29 10:48:06,572 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Begin URL Cache Cleanup 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.plugin.PhoenixPlugin - Phoenix Plugin starting... 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.plugin.PhoenixPlugin - Registering SageTV EventListener in Phoenix... 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.event.EventBus - Setting the Event bus to sagex.phoenix.event.SageEventBus@52f8c1 2013-04-29 10:48:06,993 [SageTV] INFO phoenix.log - Adding Event Listener for phoenix.SystemMessage routing to sagex.phoenix.event.SageSystemMessageListener@1bcd5b7 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener phoenix.SystemMessage; sagex.phoenix.event.SageSystemMessageListener@1bcd5b7; to new Event bus 2013-04-29 10:48:06,993 [SageTV] INFO phoenix.log - Adding Event Listener for phoenix.vfs.reload routing to sagex.phoenix.event.ReflectionEventListener@730e61 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener phoenix.vfs.reload; sagex.phoenix.event.ReflectionEventListener@730e61; to new Event bus 2013-04-29 10:48:06,993 [SageTV] INFO phoenix.log - Adding Event Listener for PlaybackFinished routing to sagex.phoenix.event.ReflectionEventListener@1b51a1a 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener PlaybackFinished; sagex.phoenix.event.ReflectionEventListener@1b51a1a; to new Event bus 2013-04-29 10:48:06,993 [SageTV] INFO phoenix.log - Adding Event Listener for PlaybackStopped routing to sagex.phoenix.event.ReflectionEventListener@c4a2dc 2013-04-29 10:48:06,993 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener PlaybackStopped; sagex.phoenix.event.ReflectionEventListener@c4a2dc; to new Event bus 2013-04-29 10:48:07,009 [SageTV] INFO phoenix.log - Adding Event Listener for configuration.button.event routing to sagex.phoenix.plugin.PhoenixPlugin$1@dbc273 2013-04-29 10:48:07,009 [SageTV] INFO phoenix.log - Setting Custom Metadata Fields: DefaultBackground;DefaultBanner;DefaultPoster;DiscNumber;EpisodeNumber;Fanart;IMDBID;MediaProviderDataID;MediaProviderID;MediaTitle;MediaType;MediaUrl;Quotes;ScrapedBy;ScrapedDate;SeasonFinal;SeasonNumber;SeriesInfoID;TagLine;TrailerUrl;Trivia;UserRating 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0014d4a81fbe72f7076bd8d5893dd591.cache 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - Begin: Phoenix looking for plugins that contribute to the Phoenix Core... 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\00723e3d70f2cd585fcf768d62d57fc0.cache 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\04a512226561d7424f599655f44decae.cache 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0b60a9625f463d1f07edb332d6172c3b.cache 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0c7227e853f46c8a0580a492195f3f0b.cache 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0c7df0c94e8d60800cbba0e00e1cb986.cache 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - Begin Reloading some configurations because of plugin contributions 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Begin Loading Configuration Metadata 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\epg.xml 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0dc814385499b5f2a7aa360ea6906678.cache 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0e50973d7f03d39d7d75e90b43f8c470.cache 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\log4j.xml 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\lz.xml 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\10160139a2364e561b542639f08a9596.cache 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\music.xml 2013-04-29 10:48:07,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1146fe7fe3b189be8f21750fe93cb697.cache 2013-04-29 10:48:07,586 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\musicosd.xml 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Phoenix.xml 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\128f753abba3cbf786009649aa8b909d.cache 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\13a8c310bce758e5fab6e3ed829eb997.cache 2013-04-29 10:48:07,602 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:07,602 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:07,602 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:07,602 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Sage.xml 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Streaming.xml 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\161e7d0849135166b244d7402117c66b.cache 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\ui.xml 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\umb.xml 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\17edb0ffd087eb45fc3d6d3f1e76f3b6.cache 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\vosd.xml 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\19070dd23faed47df53404b5a82b1e2e.cache 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in plugins\bmtweb\Phoenix\Configuration\bmt.xml 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1c7adb4e2e52dd67c8eca2fdd67ea35a.cache 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - End Loading Configuration Metadata 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Found core Phoenix Views file (no need to download update).\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Rebuilding VFS Cache .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,602 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:07,602 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1c844c02a0c98a9e5e82963221cfd449.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\25f925766efd24f931101c1b18a92d56.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\27ea067ee5054b952608cb3cde9d1939.cache 2013-04-29 10:48:07,617 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\z-gemstone-vfs.xml 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\292056d686ce6e0587c7884d803f7e44.cache 2013-04-29 10:48:07,617 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Replaced VFS Entry: item-group[latertoday] from C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\z-gemstone-vfs.xml 2013-04-29 10:48:07,617 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\plugins\bmtweb\Phoenix\vfs\bmt.xml 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\30141ae2ae90ebe5ecef16e883debdde.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\316ecf98888e1bd87ff811fe499507f4.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\332a4d96caf3a22bc21dde7dae220138.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\3750c090deecf5f07e610bb4643f8b29.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\39619c0692256cd5201cd6e822e1b6fb.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\39c25ea1f9dcb5b84214130e0518e161.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\436d6de9331dad3d1316664d74d137b2.cache 2013-04-29 10:48:07,617 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\45bf222fc63176eed5ccc5b08b461925.cache 2013-04-29 10:48:07,617 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - New VFS Cache Written to .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,617 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Loading VFS entries from .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,617 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4878ca79e7a7ddf675dbfab902a5d1f4.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4913f9e821c5f905b1d4225f60e2c1c9.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4b424d058bcc905d3dde51991f10f386.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4ba7605584d7a0d8ad6beb0b9d3f2b63.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4dfcc63570fbb2fccfedaf234c47c326.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\53372b59d06934efed2fac51f95b9678.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\57cd7afe6731ca09580d968986e8f7ef.cache 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\585dff844f17f07caeb471cf5fc2dfc6.cache 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.music 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.pictures 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagerecordings 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagearchivedrecordings 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.videos 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.dvd 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.bluray 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5be801dd68f72913e9a273ceca5be462.cache 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.allimportedvideo 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.mediafiles 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.scheduledrecordings 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedmovies 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedmovies 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtv 2013-04-29 10:48:07,633 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5ccff57e823f1058738385e2f316c1e0.cache 2013-04-29 10:48:07,633 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtvarchived 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedtv 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.homevideos 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.scheduledrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.upcomingairings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.currentlyairing 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.todaysrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.todaysrecordings 2013-04-29 10:48:07,648 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5e2e596011c52487917e3cf05f9b5d4c.cache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentmusic 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentpictures 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedtv 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedtv 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedmovies 2013-04-29 10:48:07,648 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\6130dfb39f5d9fdc23fb849c837697f0.cache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedmovies 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.currentlyrecording 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.currentlyrecording 2013-04-29 10:48:07,648 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\6260bc2fd283a7fab18461aab7afadca.cache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.featuredfavorite 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentimports 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentimports 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.conflicts 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.allsagefanart 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.upcomingmovies 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.scheduledrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.nowshowing 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.videofolders 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.archivedTV 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTV 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTVseasons 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.TV 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allMovies 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.bluray 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.dvd 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.recentlywatched 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.homevideos 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.playlists 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.album 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.artist 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.genre 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.online.videos 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.all 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tv 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.movies 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.allforcache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tvforusercats 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videofolders 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTV 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVbyrecent 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasons 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasonsbyrecent 2013-04-29 10:48:07,648 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\75c5bf560c9e834bb9cbc7d437f28be0.cache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.firstletter 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allVideos 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allMovies 2013-04-29 10:48:07,648 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\7692fc3b24b6d055e02ba86e89b00621.cache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.recentMovies 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.recentlywatched 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videosbygenre 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.scheduledrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.scheduledrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.todaysrecordings 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.allvideos 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.videosbygenre 2013-04-29 10:48:07,648 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\7ef0e481777bb308a2fd3336927604d2.cache 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.missingmetadata 2013-04-29 10:48:07,648 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.videosbyparentalrating 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.sagevideoimports 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\7f7e09c0955437ae3bf963cb02f90384.cache 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - End Reloading some configurations because of plugin contributions 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - Reloading Phoenix Services in case Plugins have contributed. 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Initializing Phoenix Services 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Found core Phoenix Views file (no need to download update).\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Rebuilding VFS Cache .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\x-vfs.xml 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\8168b4f739a989d13244138d3dae69cb.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\83753bf0f46f076355bdc212565c1a8e.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\879c13e267d2371eac17c7417953e5f9.cache 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\z-gemstone-vfs.xml 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\88541c970ebe29f49313937dd55ccbf3.cache 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Replaced VFS Entry: item-group[latertoday] from C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\vfs\z-gemstone-vfs.xml 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\plugins\bmtweb\Phoenix\vfs\bmt.xml 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\91eb09bc547eeed1963539579e60ecc4.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\920e16dedde0d51ae737dbb5ec8e1f9b.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\92889f252962630d4e438030e97a38f9.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\95ef8350e65f2692693d146963db07ae.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9903c3aa92624ee29dc65dfdbac98d23.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9972a1ca7eed6e723b409da15f08f442.cache 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9c00ad12aa2f1ed28b7dfc7651518d2b.cache 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - New VFS Cache Written to .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Loading VFS entries from .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,664 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2013-04-29 10:48:07,664 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9ce1fc88fc4f31031bda299c024e87fd.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9cfdd7f0f18d859050b67502e806f670.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9ddf76ab61b8e11cf10ec04fe1684394.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9ea8053f92670128f7437bc1c4c145c0.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9f45163b0375102c4b566947cf1b83b9.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a26fcbd4a6896e0605540d87716e3537.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a2ef36f6f4b89ad554cc427a8744fd29.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a4e51034c5ddb0e9af7bc369779280f2.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a535b3935f828846eb033c3c2147ec4b.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a5fd5d4dde2036c58b92fbfbc4703bc8.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a7ab6714f816f2e4094396c34c416f16.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a990e2e932f89e208953d4b92853bca6.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\aa6fa9970c6e82489446439b22871f94.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\aa74c5d37ea15742e40369e3845593df.cache 2013-04-29 10:48:07,680 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ab19e1cdaf511e999acbd8495e1caf4c.cache 2013-04-29 10:48:07,680 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.music 2013-04-29 10:48:07,680 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.pictures 2013-04-29 10:48:07,680 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagerecordings 2013-04-29 10:48:07,680 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.sagearchivedrecordings 2013-04-29 10:48:07,695 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ad135c72d64bdccc1aaaf6e4bfc513b5.cache 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.videos 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.dvd 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.bluray 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.allimportedvideo 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.mediafiles 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.source.scheduledrecordings 2013-04-29 10:48:07,695 [Timer-7] WARN sagex.phoenix.util.url.CachedUrlCleanupTask - CachedUrlCleanupTask failed java.lang.NullPointerException at java.io.File.(Unknown Source) at sagex.phoenix.util.url.CachedUrl.getCachedFile(CachedUrl.java:121) at sagex.phoenix.util.url.CachedUrlCleanupTask$FileCleaner.handleFile(CachedUrlCleanupTask.java:43) at org.apache.commons.io.DirectoryWalker.walk(DirectoryWalker.java:367) at org.apache.commons.io.DirectoryWalker.walk(DirectoryWalker.java:335) at sagex.phoenix.util.url.CachedUrlCleanupTask$FileCleaner.clean(CachedUrlCleanupTask.java:34) at sagex.phoenix.util.url.CachedUrlCleanupTask.run(CachedUrlCleanupTask.java:69) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedmovies 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedmovies 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtv 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.recordedtvarchived 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.importedtv 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.homevideos 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.scheduledrecordings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.upcomingairings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.primary.currentlyairing 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.todaysrecordings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.todaysrecordings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentrecordings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentrecordings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentmusic 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentpictures 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedtv 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedtv 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentlywatchedmovies 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentlywatchedmovies 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.currentlyrecording 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.currentlyrecording 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.featuredfavorite 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.recentimports 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.instantstatus.recentimports 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.conflicts 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.util.allsagefanart 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.upcomingmovies 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.scheduledrecordings 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.nowshowing 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.videofolders 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.archivedTV 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTV 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allTVseasons 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.TV 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.allMovies 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.bluray 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.dvd 2013-04-29 10:48:07,695 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.recentlywatched 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.homevideos 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.playlists 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.album 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.artist 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.default.music.genre 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.online.videos 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.all 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tv 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.movies 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.allforcache 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.tvforusercats 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videofolders 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTV 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVbyrecent 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasons 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allTVseasonsbyrecent 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.firstletter 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allVideos 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.allMovies 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.recentMovies 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.recentlywatched 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.videosbygenre 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.base.scheduledrecordings 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.source.scheduledrecordings 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: gemstone.widget.todaysrecordings 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.allvideos 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.videosbygenre 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.missingmetadata 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.videosbyparentalrating 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.vfs.builder.ViewsBuilder - Loaded View: phoenix.view.bmt.sagevideoimports 2013-04-29 10:48:07,711 [PluginEventQueue] INFO phoenix.log - Adding Event Listener for phoenix.vfs.reload routing to sagex.phoenix.event.ReflectionEventListener@abcd7c 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.Phoenix - VFS Initialized 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Begin Loading Menus 2013-04-29 10:48:07,711 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\ContextSensitiveMenus.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-SingleList with new menu 0 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-DoubleList with new menu 0 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-HorizontalList with new menu 3 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-CoverFlow with new menu 0 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixUMB-ArtFlow with new menu 0 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixMusicBrowser with new menu 2 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixEPGFull with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixEPGNormal with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixOSDMusic with new menu 2 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixOSDVideo with new menu 4 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneVertical with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneCross with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneHorizontal with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneBox with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneSage with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: PhoenixLandingZoneMetro with new menu 1 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\GlobalMenu.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: GlobalMenu with new menu 6 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\InstantStatus.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.lz.box.status with new menu 6 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\KeyMenu.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.keys with new menu 14 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\LandingMenu.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.lz with new menu 7 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\NewStatusMenu.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.status with new menu 10 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\OptionsMenu.xml 2013-04-29 10:48:07,726 [PluginEventQueue] ERROR sagex.phoenix.profiles.ProfileManager - GetUIContextName() returned Null!! Creating a default context: default 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: phoenix.menu.options with new menu 5 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Loading Menu: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\Menus\StatusMenu.xml 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adding new System menu: StatusMenu with new menu 9 items 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adjusting menu item visibility based on stored settings 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Processing Menu Fragments... 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Ordering Menu Items... 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - End Loading Menus 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Menus Initialized 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Begin Loading Skins 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Backgrounds 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.skins.SkinBuilder - No Skin declaration file for Skin: .\STVs\Phoenix\Skins\Backgrounds 2013-04-29 10:48:07,726 [PluginEventQueue] WARN sagex.phoenix.skins.SkinManager - Skipping Skin: Backgrounds since it is not a valid skin 2013-04-29 10:48:07,726 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Default 2013-04-29 10:48:07,742 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Adding Phoenix Skin: skin.default 2013-04-29 10:48:07,742 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Ice 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Adding Phoenix Skin: skin.ice 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Loading System Skin: .\STVs\Phoenix\Skins\Serenity 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Adding Phoenix Skin: skin.serenity 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Plugin Activated: skin.default 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Plugin Activated: skin.ice 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Plugin Activated: skin.serenity 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - End Loading Skins 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Skins Initialized 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Begin Loading Metadata Providers 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\dvdprofiler.xml 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\htbackdrops.xml 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\imdb.xml 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\mymovies.xml 2013-04-29 10:48:07,758 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\nielm_imdb.xml 2013-04-29 10:48:07,773 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb.xml 2013-04-29 10:48:07,914 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb3.xml 2013-04-29 10:48:08,148 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tvdb.xml 2013-04-29 10:48:08,148 [PluginEventQueue] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Begin Loading Xbmc Metadata Providers 2013-04-29 10:48:08,163 [PluginEventQueue] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Loading XBMC Metadata Scraper: .\STVs\Phoenix\scrapers\xbmc\video\imdb.xml 2013-04-29 10:48:08,163 [PluginEventQueue] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - End Loading Xbmc Metadata Providers 2013-04-29 10:48:08,163 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - End Loading Metadata Providers 2013-04-29 10:48:08,163 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Metadata Scrapers Initialized 2013-04-29 10:48:08,163 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Begin Loading Movie Filename Scrapers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear1 with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear2 with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-dot-year with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - End Loading Movie Filename Scrapers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: defaultmovie with priority 2147483647 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Movie Filename Scrapers Initialized 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Begin Loading TV Filename Scrapers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecordingNoEpisode with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecording with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-show-episode with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-00x00 with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-airedDate with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00d00 with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00e00 with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00-Disc# with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-Season00Episode00 with priority 116 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - End Loading TV Filename Scrapers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - TV Filename Scrapers Initialized 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.RatingsManager - Begin Loading Ratings Map 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.RatingsManager - Loading Ratings file .\STVs\Phoenix\metadata\ratings.properties 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.RatingsManager - End Loading Ratings Map 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Ratings Manager Initialized 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.FileMatcherManager - Begin loading Title Matchers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.FileMatcherManager - Loading User media titles from .\userdata\Phoenix\scrapers\MediaTitles.xml 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.metadata.search.FileMatcherManager - End loading Title Matchers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - MediaTitles.xml Initialized 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - Being Loading URL Resolvers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - End Loading URL Resolvers 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - URL Resolvers has been loaded 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Scripting Services Factory initialized 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Url Cleanup Task Created monitoring .\userdata\Phoenix\cache\url 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.util.TaskManager - Cancelling scheduled task since it's being updated for: urlcachecleaner 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.util.TaskManager - Adding Scheduled Task: urlcachecleaner; Date: Mon Apr 29 10:48:08 PDT 2013; Repeat: 86400000 2013-04-29 10:48:08,179 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Core Scheduled Tasks Initialized 2013-04-29 10:48:08,179 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Begin URL Cache Cleanup 2013-04-29 10:48:08,241 [Timer-7] WARN sagex.phoenix.util.url.CachedUrlCleanupTask - CachedUrlCleanupTask failed java.lang.NullPointerException at java.io.File.(Unknown Source) at sagex.phoenix.util.url.CachedUrl.getCachedFile(CachedUrl.java:121) at sagex.phoenix.util.url.CachedUrlCleanupTask$FileCleaner.handleFile(CachedUrlCleanupTask.java:43) at org.apache.commons.io.DirectoryWalker.walk(DirectoryWalker.java:367) at org.apache.commons.io.DirectoryWalker.walk(DirectoryWalker.java:335) at sagex.phoenix.util.url.CachedUrlCleanupTask$FileCleaner.clean(CachedUrlCleanupTask.java:34) at sagex.phoenix.util.url.CachedUrlCleanupTask.run(CachedUrlCleanupTask.java:69) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2013-04-29 10:48:08,397 [PluginEventQueue] WARN sagex.phoenix.weather.yahoo.YahooWeatherSupport - Failed to convert null to woeid java.lang.NullPointerException at sagex.phoenix.weather.yahoo.YahooWeatherSupport.setLocation(YahooWeatherSupport.java:138) at phoenix.impl.WeatherAPI.SetLocation(WeatherAPI.java:62) at phoenix.weather.SetLocation(weather.java:62) at sagex.phoenix.Phoenix.initServices(Phoenix.java:381) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:243) 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.invoke(AbstractPlugin.java:267) at sagex.plugin.AbstractPlugin.sageEvent(AbstractPlugin.java:201) at sage.plugin.b.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2013-04-29 10:48:08,397 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Configuring weather using EPG zip code 2013-04-29 10:48:08,397 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Phoenix Services initialized. 2013-04-29 10:48:08,397 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - End: Phoenix looking for plugins that contribute to the Phoenix Core 2013-04-29 10:48:28,927 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initializing 2013-04-29 10:48:28,927 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.PhoenixFanartSupport2 - Phoenix Fanart initialized 2013-04-29 10:48:33,529 [7356374@qtp-15062321-4] INFO sagex.phoenix.vfs.views.ViewFactory - Creating view for: Video Imports by Folder; id: gemstone.source.videofolders; Options: null 2013-04-29 10:48:33,529 [7356374@qtp-15062321-4] INFO sagex.phoenix.vfs.sources.SageMediaNodeFactory - Creating Media Node source using VideosByFolder 2013-04-29 10:48:33,544 [7356374@qtp-15062321-4] INFO sagex.phoenix.vfs.views.ViewFactory - Created Source: Sage File System in 15ms 2013-04-29 10:48:33,560 [7356374@qtp-15062321-4] INFO sagex.phoenix.vfs.views.ViewFactory - Created view: Video Imports by Folder in 31ms 2013-04-29 10:48:50,736 [7356374@qtp-15062321-4] WARN sagex.phoenix.metadata.persistence.TVSeriesUtil - Unable to create a SeriesInfoID for : 2013-04-29 10:48:50,736 [7356374@qtp-15062321-4] WARN phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for DecoratedItem: [SageMediaFile [sageId=1511317, sageObject=sage.b.p@1ea7a8c]] 2013-04-29 10:48:51,266 [29609651@qtp-15062321-5] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:48:51,266 [27146232@qtp-15062321-7] INFO sagex.phoenix.image.ImageUtil - Created Image Cache Dir: .\userdata\Phoenix\cache\imagetrans; exists: true 2013-04-29 10:48:51,266 [7356374@qtp-15062321-4] INFO sagex.phoenix.image.ImageUtil - Created Image Cache Dir: .\userdata\Phoenix\cache\imagetrans; exists: true 2013-04-29 10:48:52,592 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:48:55,291 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Scraper title-s00e00 created a query: SearchQuery [type=TV, fields={EPISODE: 03,RAW_TITLE: Murphy's Law -,SEASON: 01,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] for file SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}] 2013-04-29 10:48:55,291 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=TV, fields={EPISODE: 03,EPISODE_DATE: 2011-09-13,CLEAN_TITLE: Murphy's Law,RAW_TITLE: Murphy's Law,FILE: \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi,SEASON: 01,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] 2013-04-29 10:48:56,866 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - search(): tvdb; SearchQuery [type=TV, fields={EPISODE: 3,EPISODE_DATE: 2011-09-13,YEAR: null,EPISODE_TITLE: ,QUERY: Murphy's Law,SEASON: 1,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] 2013-04-29 10:48:56,866 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Searching: Murphy's Law using TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]] 2013-04-29 10:48:56,866 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - TVDB Search: http://www.thetvdb.com/api/GetSeries.php?seriesname=Murphy%27s+Law 2013-04-29 10:48:56,866 [27146232@qtp-15062321-7] INFO sagex.phoenix.util.url.CachedUrlFactory - Caching URL Factory in use. 2013-04-29 10:48:58,130 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Fetching Metadata for MediaSearchResult [extraArgs={SEASON: 1,EPISODE: 3,EPISODE_DATE: 2011-09-13,}, id=76257, imdbId=null, metadata=null, providerId=tvdb, score=1.0, title=Murphy's Law, type=TV, url=76257, year=1988] 2013-04-29 10:48:58,130 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Series: http://www.thetvdb.com/api/5645B594A3F32D27/series/76257/en.xml 2013-04-29 10:48:58,364 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Actors: http://www.thetvdb.com/api/5645B594A3F32D27/series/76257/actors.xml 2013-04-29 10:48:58,458 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - TVDB Episode: http://www.thetvdb.com/api/5645B594A3F32D27/series/76257/default/1/3/en.xml 2013-04-29 10:48:58,567 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - Parsing TVDB Banners url: http://www.thetvdb.com/api/5645B594A3F32D27/series/76257/banners.xml 2013-04-29 10:48:58,660 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Skipping Fanart since Fanart Provider is empty 2013-04-29 10:48:58,692 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Saving Metadata for SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,preserve_original: false,scan_missing_metadata: false,scan_subfolders: false,import_tv_as_recording: false,}] 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.persistence.Sage7Persistence - Storing updated metadata for item SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Saving Phoenix Fanart for SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,preserve_original: false,scan_missing_metadata: false,scan_subfolders: false,import_tv_as_recording: false,}] 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No BACKGROUND for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Backgrounds in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No BACKGROUND for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Season 1\Backgrounds in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No BANNER for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Banners in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No BANNER for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Season 1\Banners in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No POSTER for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Posters in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No POSTER for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Season 1\Posters in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ACTOR for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Actors in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ACTOR for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Season 1\Actors in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No EPISODE for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Episodes in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No EPISODE for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Season 1\Episodes in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ALBUM for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Albums in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ALBUM for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law (1988)\Season 1\Albums in the metadata. 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Imported MediaFile as Recording: SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] 2013-04-29 10:49:03,106 [27146232@qtp-15062321-7] WARN phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] 2013-04-29 10:49:03,138 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:05,634 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:08,161 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Scraper title-s00e00 created a query: SearchQuery [type=TV, fields={EPISODE: 03,RAW_TITLE: Murphy's Law -,SEASON: 01,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] for file SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}] 2013-04-29 10:49:08,161 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=TV, fields={EPISODE: 03,EPISODE_DATE: 2011-09-13,CLEAN_TITLE: Murphy's Law,RAW_TITLE: Murphy's Law,FILE: \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi,SEASON: 01,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] 2013-04-29 10:49:10,938 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.search.TVScraperManager - Scraper title-s00e00 created a query: SearchQuery [type=TV, fields={EPISODE: 03,RAW_TITLE: Murphy's Law -,SEASON: 01,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] for file SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] with hints Hints [hints={update_metadata: true,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}] 2013-04-29 10:49:10,938 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=TV, fields={EPISODE: 03,EPISODE_DATE: 2011-09-13,CLEAN_TITLE: Murphy's Law,RAW_TITLE: Murphy's Law,FILE: \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi,SEASON: 01,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] 2013-04-29 10:49:11,562 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - search(): tvdb; SearchQuery [type=TV, fields={EPISODE: 3,EPISODE_DATE: 2011-09-13,YEAR: null,EPISODE_TITLE: ,QUERY: Murphy's Law,SEASON: 1,}, hints=Hints [hints={update_fanart: true,update_metadata: true,scan_missing_metadata: true,scan_subfolders: true,import_tv_as_recording: true,}]] 2013-04-29 10:49:11,562 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Searching: Murphy's Law using TVDBMetadataProvider[MetadataProviderInfo [id=tvdb, fanartId=null, mediaTypes=[TV], name=thetvdb.com]] 2013-04-29 10:49:11,562 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSearchParser - TVDB Search: http://www.thetvdb.com/api/GetSeries.php?seriesname=Murphy%27s+Law 2013-04-29 10:49:11,562 [27146232@qtp-15062321-7] INFO sagex.phoenix.util.url.CachedUrl - Removing Cached Url File: .\userdata\Phoenix\cache\url\694d08cd8e2e7a396a3127a3ffa9bf1f.cache 2013-04-29 10:49:13,168 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Fetching Metadata for MediaSearchResult [extraArgs={SEASON: 1,EPISODE: 3,EPISODE_DATE: 2011-09-13,}, id=73425, imdbId=null, metadata=null, providerId=tvdb, score=1.0, title=Murphy's Law, type=TV, url=73425, year=2001] 2013-04-29 10:49:13,168 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Series: http://www.thetvdb.com/api/5645B594A3F32D27/series/73425/en.xml 2013-04-29 10:49:13,168 [27146232@qtp-15062321-7] INFO sagex.phoenix.util.url.CachedUrl - Removing Cached Url File: .\userdata\Phoenix\cache\url\a1a36e22cd8f64f020c0752cb3c5cbe0.cache 2013-04-29 10:49:13,309 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBSeriesParser - TVDB Actors: http://www.thetvdb.com/api/5645B594A3F32D27/series/73425/actors.xml 2013-04-29 10:49:13,309 [27146232@qtp-15062321-7] INFO sagex.phoenix.util.url.CachedUrl - Removing Cached Url File: .\userdata\Phoenix\cache\url\44a6ddcfb8ca96cc92608b93944a4175.cache 2013-04-29 10:49:13,402 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - TVDB Episode: http://www.thetvdb.com/api/5645B594A3F32D27/series/73425/default/1/3/en.xml 2013-04-29 10:49:13,402 [27146232@qtp-15062321-7] INFO sagex.phoenix.util.url.CachedUrl - Removing Cached Url File: .\userdata\Phoenix\cache\url\17610d36e5da9689474646fba3df5344.cache 2013-04-29 10:49:13,543 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.provider.tvdb.TVDBItemParser - Parsing TVDB Banners url: http://www.thetvdb.com/api/5645B594A3F32D27/series/73425/banners.xml 2013-04-29 10:49:13,543 [27146232@qtp-15062321-7] INFO sagex.phoenix.util.url.CachedUrl - Removing Cached Url File: .\userdata\Phoenix\cache\url\1511eff05e16037c3d48e575101d7aac.cache 2013-04-29 10:49:13,683 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Skipping Fanart since Fanart Provider is empty 2013-04-29 10:49:13,714 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Saving Metadata for SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,preserve_original: false,scan_missing_metadata: false,scan_subfolders: false,import_tv_as_recording: false,}] 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.persistence.Sage7Persistence - Storing updated metadata for item SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Saving Phoenix Fanart for SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]]; Hints: Hints [hints={update_metadata: true,update_fanart: true,preserve_original: false,scan_missing_metadata: false,scan_subfolders: false,import_tv_as_recording: false,}] 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/73425-2.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/73425-3.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/fanart/original/73425-1.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No BACKGROUND for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Season 1\Backgrounds in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/73425-g2.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/73425-g.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/22980-g.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/graphical/73425-g3.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No BANNER for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Season 1\Banners in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/posters/73425-1.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/seasons/22980-1.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ACTOR for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Actors in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ACTOR for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Season 1\Actors in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No EPISODE for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Episodes in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.fanart.FanartStorage - Skipping Image file: http://www.thetvdb.com/banners/episodes/73425/114565.jpg because it's in the image skip file. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ALBUM for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Albums in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.FanartStorage - No ALBUM for C:\Program Files (x86)\SageTV\SageTV\Fan_Art\TV\Murphy's Law\Season 1\Albums in the metadata. 2013-04-29 10:49:16,257 [27146232@qtp-15062321-7] INFO sagex.phoenix.metadata.MetadataManager - Imported MediaFile as Recording: SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] 2013-04-29 10:49:16,273 [27146232@qtp-15062321-7] WARN phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for SageMediaFile [sageId=1511317, sageObject=MediaFile[id=1511317 A[1511320,1511318,"Murphy's Law - S01E03xxx",0@0913.18:45,0] mask=V host=Sage-PC encodedBy= format=null \\Sage-PC\TV_M_P\Murphy's Law\Season 1\Murphy's Law - S01E03xxx.avi, Seg0[Tue 9/13 18:45:48.847-Tue 9/13 18:45:48.848]]] 2013-04-29 10:49:16,288 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:50,390 [27146232@qtp-15062321-7] WARN sagex.phoenix.metadata.persistence.TVSeriesUtil - Unable to create a SeriesInfoID for : 2013-04-29 10:49:50,390 [27146232@qtp-15062321-7] WARN phoenix.impl.MediaAPI - Invalid year for Movie, so ignoring Title Mask for DecoratedItem: [SageMediaFile [sageId=1511286, sageObject=sage.b.p@10989fb]] 2013-04-29 10:49:50,421 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:50,421 [29609651@qtp-15062321-5] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:50,421 [7356374@qtp-15062321-4] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:50,421 [3102483@qtp-15062321-6] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:50,421 [27146232@qtp-15062321-7] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:50,421 [1842378@qtp-15062321-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found 2013-04-29 10:49:53,214 [1842378@qtp-15062321-3] WARN sagex.phoenix.fanart.AdvancedFanartMediaRequestHandler - Fanart Failed: 404; Fanart not found