2018-10-14 11:17:49,543 [SageTV] INFO sagex.phoenix.Phoenix - Initializing Phoenix - Version: 2.5.13 2018-10-14 11:17:49,544 [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.jar;JARs\api-common-1.0.jar;JARs\cling-core-2.0.1.jar;JARs\cling-support-2.0.1.jar;JARs\CommercialDetector.jar;JARs\commons-codec-1.3.jar;JARs\commons-dbutils-1.3.jar;JARs\commons-io-2.4.jar;JARs\commons-jxpath-1.1.jar;JARs\commons-lang-2.4.jar;JARs\commons-lang3-3.0.jar;JARs\commons-net-1.4.1.jar;JARs\core-3.1.1.jar;JARs\dom4j-1.6.1.jar;JARs\GoogleWeather.jar;JARs\gson-1.7.1.jar;JARs\htmlparser-1.6.jar;JARs\jackson-annotations-2.5.1.jar;JARs\jackson-core-2.5.1.jar;JARs\jackson-databind-2.5.1.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\jsoup.jar;JARs\jsp-2.1-jetty-6.1.19.jar;JARs\jsp-2.1.jar;JARs\jsp-api-2.1.jar;JARs\log4j-1.2.15.jar;JARs\nielm_sageimdb.jar;JARs\nielm_sageutls.jar;JARs\nielm_sagewebserver.jar;JARs\nielm_sagexmlinfo.jar;JARs\phoenix.jar;JARs\sagex-api.jar;JARs\sbbi-upnplib-1.0.3.jar;JARs\seamless-http-1.1.0.jar;JARs\seamless-util-1.1.0.jar;JARs\seamless-xml-1.1.0.jar;JARs\servlet-api-2.5-6.1.19.jar;JARs\servlet.jar;JARs\slf4j-api-1.6.0.jar;JARs\themoviedb-3.0.1.jar;JARs\vecmath.jar 2018-10-14 11:17:49,544 [SageTV] INFO sagex.phoenix.Phoenix - Java Impl: Oracle Corporation - 1.8.0_131 2018-10-14 11:17:49,544 [SageTV] INFO sagex.phoenix.Phoenix - OS: Windows 10 - x86 - 10.0 2018-10-14 11:17:49,544 [SageTV] INFO sagex.phoenix.Phoenix - User: UBServer 2018-10-14 11:17:49,544 [SageTV] INFO sagex.phoenix.Phoenix - Registering System Message Handler to the Event Bus 2018-10-14 11:17:49,544 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.SageSystemMessageListener for Event Type: phoenix.SystemMessage 2018-10-14 11:17:49,549 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Begin Loading Configuration Metadata 2018-10-14 11:17:49,550 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\log4j.xml 2018-10-14 11:17:49,562 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Phoenix.xml 2018-10-14 11:17:49,580 [SageTV] ERROR sagex.phoenix.configuration.XmlMetadataParser - Failed to Create a Group from Class: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerConfiguration java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerConfiguration at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.startElement(XmlMetadataParser.java:64) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl.parse(Unknown Source) at javax.xml.parsers.SAXParser.parse(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.parseMetadata(XmlMetadataParser.java:37) at sagex.phoenix.configuration.XmlMetadataParser.parse(XmlMetadataParser.java:44) at sagex.phoenix.configuration.XmlMetadataProvider.load(XmlMetadataProvider.java:28) at sagex.phoenix.configuration.ConfigurationMetadataManager.visitConfigurationFile(ConfigurationMetadataManager.java:101) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.configuration.ConfigurationMetadataManager.loadConfigurations(ConfigurationMetadataManager.java:115) at sagex.phoenix.Phoenix.init(Phoenix.java:165) at sagex.phoenix.Phoenix.(Phoenix.java:87) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:49,583 [SageTV] ERROR sagex.phoenix.configuration.XmlMetadataParser - Failed to Create a Group from Class: sagex.phoenix.metadata.provider.mymovies.MyMoviesConfiguration java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.mymovies.MyMoviesConfiguration at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.startElement(XmlMetadataParser.java:64) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl.parse(Unknown Source) at javax.xml.parsers.SAXParser.parse(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.parseMetadata(XmlMetadataParser.java:37) at sagex.phoenix.configuration.XmlMetadataParser.parse(XmlMetadataParser.java:44) at sagex.phoenix.configuration.XmlMetadataProvider.load(XmlMetadataProvider.java:28) at sagex.phoenix.configuration.ConfigurationMetadataManager.visitConfigurationFile(ConfigurationMetadataManager.java:101) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.configuration.ConfigurationMetadataManager.loadConfigurations(ConfigurationMetadataManager.java:115) at sagex.phoenix.Phoenix.init(Phoenix.java:165) at sagex.phoenix.Phoenix.(Phoenix.java:87) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:49,586 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:49,586 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:49,586 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:49,586 [SageTV] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:49,592 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Sage.xml 2018-10-14 11:17:49,593 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Streaming.xml 2018-10-14 11:17:49,597 [SageTV] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - End Loading Configuration Metadata 2018-10-14 11:17:49,599 [SageTV] INFO sagex.phoenix.Phoenix - Configuration Metadata Initialized 2018-10-14 11:17:49,621 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: PlaybackFinished 2018-10-14 11:17:49,621 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: PlaybackStopped 2018-10-14 11:17:49,838 [SageTV] INFO sagex.phoenix.upnp.PhoenixUPNPServer - Phoenix is listening to UPnP MediaServer devices 2018-10-14 11:17:49,848 [SageTV] INFO sagex.phoenix.upnp.PhoenixUPNPServer - Phoenix UPnP Services are online 2018-10-14 11:17:49,848 [SageTV] INFO sagex.phoenix.Phoenix - Phoenix base system initialized. 2018-10-14 11:17:49,848 [SageTV] INFO sagex.phoenix.Phoenix - Initializing Phoenix Services 2018-10-14 11:17:49,848 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:49,850 [SageTV] WARN sagex.phoenix.vfs.VFSManager - Downloading x-vfs.xml view from Phoenix, since it appears to be missing 2018-10-14 11:17:49,850 [SageTV] INFO sagex.phoenix.download.DownloadManager - Scheduled Download: DownloadItem [bytesDownloaded=0, id=57679937177, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=WAITING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] with delay 0 2018-10-14 11:17:49,850 [SageTV] INFO sagex.phoenix.download.DownloadManager - Waiting for file to download... 2018-10-14 11:17:49,850 [Downloader-0] INFO sagex.phoenix.download.DownloadManager - Download Start: DownloadItem [bytesDownloaded=0, id=57679937177, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=WAITING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] 2018-10-14 11:17:50,271 [Downloader-0] WARN sagex.phoenix.util.FileUtils - Failed to delete file: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:50,271 [Downloader-0] WARN sagex.phoenix.download.DownloadManager - Failed to download item: DownloadItem [bytesDownloaded=0, id=57679937177, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=DOWNLOADING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] but it may retry later java.io.FileNotFoundException: http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(Unknown Source) at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source) at sagex.phoenix.download.DownloadTask.run(DownloadTask.java:40) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 11:17:50,272 [Downloader-0] WARN sagex.phoenix.download.DownloadManager - Failed to download item: DownloadItem [bytesDownloaded=0, id=57679937177, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=2, state=DOWNLOADING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] java.lang.Exception: Max Retry has been exceeded: 2 at sagex.phoenix.download.DownloadManager.reschedule(DownloadManager.java:100) at sagex.phoenix.download.DownloadTask.run(DownloadTask.java:67) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 11:17:50,272 [Downloader-0] WARN sagex.phoenix.util.FileUtils - Failed to delete file: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:50,283 [SageTV] INFO sagex.phoenix.download.DownloadManager - Done waiting 2018-10-14 11:17:50,291 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Rebuilding VFS Cache .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:50,351 [SageTV] INFO sagex.phoenix.vfs.VFSManager - New VFS Cache Written to .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:50,351 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Loading VFS entries from .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:50,351 [SageTV] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.event.SimpleEventBus - Registering Event Handler: sagex.phoenix.event.ReflectionEventListener for Event Type: phoenix.vfs.reload 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.Phoenix - VFS Initialized 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.menu.MenuManager - Begin Loading Menus 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.menu.MenuManager - Adjusting menu item visibility based on stored settings 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.menu.MenuManager - Processing Menu Fragments... 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.menu.MenuManager - Ordering Menu Items... 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.menu.MenuManager - End Loading Menus 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.Phoenix - Menus Initialized 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.skins.SkinManager - Begin Loading Skins 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.skins.SkinManager - End Loading Skins 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.Phoenix - Skins Initialized 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Begin Loading Metadata Providers 2018-10-14 11:17:50,362 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\dvdprofiler.xml 2018-10-14 11:17:50,362 [SageTV] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 3, column: 88; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\dvdprofiler.xml org.xml.sax.SAXParseException; lineNumber: 3; columnNumber: 88; Failed to create Metadata Provider: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerMetadataProvider at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:122) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:52) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) Caused by: java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerMetadataProvider at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:48) ... 23 more 2018-10-14 11:17:50,362 [SageTV] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 9, column: 12; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\dvdprofiler.xml org.xml.sax.SAXParseException; lineNumber: 9; columnNumber: 12; Failed to parse a valid metadata provider! at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:127) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:44) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:50,371 [SageTV] WARN sagex.phoenix.metadata.MetadataManager - IO Error java.io.IOException: Failed to parse provider for file .\STVs\Phoenix\metadata\dvdprofiler.xml at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:783) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:50,371 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\htbackdrops.xml 2018-10-14 11:17:50,371 [Thread-37] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:50,372 [Thread-38] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:50,375 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\imdb.xml 2018-10-14 11:17:50,375 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\mymovies.xml 2018-10-14 11:17:50,375 [SageTV] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 3, column: 82; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\mymovies.xml org.xml.sax.SAXParseException; lineNumber: 3; columnNumber: 82; Failed to create Metadata Provider: sagex.phoenix.metadata.provider.mymovies.MyMoviesMetadataProvider at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:122) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:52) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) Caused by: java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.mymovies.MyMoviesMetadataProvider at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:48) ... 23 more 2018-10-14 11:17:50,375 [SageTV] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 9, column: 12; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\mymovies.xml org.xml.sax.SAXParseException; lineNumber: 9; columnNumber: 12; Failed to parse a valid metadata provider! at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:127) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:44) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:50,375 [SageTV] WARN sagex.phoenix.metadata.MetadataManager - IO Error java.io.IOException: Failed to parse provider for file .\STVs\Phoenix\metadata\mymovies.xml at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:783) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:50,375 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\nielm_imdb.xml 2018-10-14 11:17:50,375 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb.xml 2018-10-14 11:17:50,384 [Thread-40] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:50,384 [Thread-39] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:51,380 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb3.xml 2018-10-14 11:17:51,616 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tvdb.xml 2018-10-14 11:17:51,618 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Begin Loading Xbmc Metadata Providers 2018-10-14 11:17:51,619 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Loading XBMC Metadata Scraper: .\STVs\Phoenix\scrapers\xbmc\video\imdb.xml 2018-10-14 11:17:51,642 [SageTV] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - End Loading Xbmc Metadata Providers 2018-10-14 11:17:51,642 [SageTV] INFO sagex.phoenix.metadata.MetadataManager - End Loading Metadata Providers 2018-10-14 11:17:51,642 [SageTV] INFO sagex.phoenix.Phoenix - Metadata Scrapers Initialized 2018-10-14 11:17:51,642 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Begin Loading Movie Filename Scrapers 2018-10-14 11:17:51,654 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear1 with priority 116 2018-10-14 11:17:51,657 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear2 with priority 116 2018-10-14 11:17:51,659 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-dot-year with priority 116 2018-10-14 11:17:51,660 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - End Loading Movie Filename Scrapers 2018-10-14 11:17:51,661 [SageTV] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: defaultmovie with priority 2147483647 2018-10-14 11:17:51,661 [SageTV] INFO sagex.phoenix.Phoenix - Movie Filename Scrapers Initialized 2018-10-14 11:17:51,661 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Begin Loading TV Filename Scrapers 2018-10-14 11:17:51,665 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecordingNoEpisode with priority 116 2018-10-14 11:17:51,667 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecording with priority 116 2018-10-14 11:17:51,670 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-show-episode with priority 116 2018-10-14 11:17:51,673 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-00x00 with priority 116 2018-10-14 11:17:51,676 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-airedDate with priority 116 2018-10-14 11:17:51,678 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00d00 with priority 116 2018-10-14 11:17:51,681 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00e00 with priority 116 2018-10-14 11:17:51,686 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00-Disc# with priority 116 2018-10-14 11:17:51,690 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-Season00Episode00 with priority 116 2018-10-14 11:17:51,690 [SageTV] INFO sagex.phoenix.metadata.search.TVScraperManager - End Loading TV Filename Scrapers 2018-10-14 11:17:51,690 [SageTV] INFO sagex.phoenix.Phoenix - TV Filename Scrapers Initialized 2018-10-14 11:17:51,690 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - Begin Loading Ratings Map 2018-10-14 11:17:51,690 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - Loading Ratings file .\STVs\Phoenix\metadata\ratings.properties 2018-10-14 11:17:51,691 [SageTV] INFO sagex.phoenix.metadata.RatingsManager - End Loading Ratings Map 2018-10-14 11:17:51,691 [SageTV] INFO sagex.phoenix.Phoenix - Ratings Manager Initialized 2018-10-14 11:17:51,691 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - Begin loading Title Matchers 2018-10-14 11:17:51,692 [SageTV] INFO sagex.phoenix.metadata.search.FileMatcherManager - End loading Title Matchers 2018-10-14 11:17:51,692 [SageTV] INFO sagex.phoenix.Phoenix - MediaTitles.xml Initialized 2018-10-14 11:17:51,692 [SageTV] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - Being Loading URL Resolvers 2018-10-14 11:17:51,694 [SageTV] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - End Loading URL Resolvers 2018-10-14 11:17:51,694 [SageTV] INFO sagex.phoenix.Phoenix - URL Resolvers has been loaded 2018-10-14 11:17:51,697 [SageTV] INFO sagex.phoenix.Phoenix - Scripting Services Factory initialized 2018-10-14 11:17:51,698 [SageTV] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Url Cleanup Task Created monitoring .\userdata\Phoenix\cache\url 2018-10-14 11:17:51,698 [SageTV] INFO sagex.phoenix.util.TaskManager - Adding Scheduled Task: urlcachecleaner; Date: Sun Oct 14 11:17:51 AEDT 2018; Repeat: 86400000 2018-10-14 11:17:51,698 [SageTV] INFO sagex.phoenix.Phoenix - Core Scheduled Tasks Initialized 2018-10-14 11:17:52,165 [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:141) at phoenix.impl.WeatherAPI.SetLocation(WeatherAPI.java:64) at phoenix.weather.SetLocation(weather.java:58) at sagex.phoenix.Phoenix.initServices(Phoenix.java:337) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:52,165 [SageTV] INFO sagex.phoenix.Phoenix - Configuring weather using EPG zip code 2018-10-14 11:17:52,382 [SageTV] WARN sagex.phoenix.weather.yahoo.YahooWeatherSupport - Failed to convert 00000 to woeid java.lang.NullPointerException at sagex.phoenix.weather.yahoo.YahooWeatherSupport.setLocation(YahooWeatherSupport.java:141) at phoenix.impl.WeatherAPI.SetLocation(WeatherAPI.java:64) at phoenix.weather.SetLocation(weather.java:58) at sagex.phoenix.Phoenix.initServices(Phoenix.java:348) at sagex.phoenix.Phoenix.(Phoenix.java:88) at sagex.phoenix.plugin.PhoenixPlugin.start(PhoenixPlugin.java:258) at sage.plugin.a.case(Unknown Source) at sage.SageTV.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2018-10-14 11:17:52,382 [SageTV] INFO sagex.phoenix.Phoenix - Phoenix Services initialized. 2018-10-14 11:17:52,382 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Begin URL Cache Cleanup 2018-10-14 11:17:52,383 [SageTV] INFO sagex.phoenix.event.EventBus - Setting the Event bus to sagex.phoenix.event.SageEventBus@1090d3e 2018-10-14 11:17:52,383 [SageTV] INFO phoenix.log - Adding Event Listener for PlaybackFinished routing to sagex.phoenix.event.ReflectionEventListener@12a5813 2018-10-14 11:17:52,383 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener PlaybackFinished; sagex.phoenix.event.ReflectionEventListener@12a5813; to new Event bus 2018-10-14 11:17:52,383 [SageTV] INFO phoenix.log - Adding Event Listener for phoenix.vfs.reload routing to sagex.phoenix.event.ReflectionEventListener@19c835 2018-10-14 11:17:52,383 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener phoenix.vfs.reload; sagex.phoenix.event.ReflectionEventListener@19c835; to new Event bus 2018-10-14 11:17:52,383 [SageTV] INFO phoenix.log - Adding Event Listener for phoenix.SystemMessage routing to sagex.phoenix.event.SageSystemMessageListener@42a7cf 2018-10-14 11:17:52,383 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener phoenix.SystemMessage; sagex.phoenix.event.SageSystemMessageListener@42a7cf; to new Event bus 2018-10-14 11:17:52,383 [SageTV] INFO phoenix.log - Adding Event Listener for PlaybackStopped routing to sagex.phoenix.event.ReflectionEventListener@431513 2018-10-14 11:17:52,383 [SageTV] INFO sagex.phoenix.event.EventBus - Transfered Listener PlaybackStopped; sagex.phoenix.event.ReflectionEventListener@431513; to new Event bus 2018-10-14 11:17:52,385 [SageTV] INFO phoenix.log - Adding Event Listener for configuration.button.event routing to sagex.phoenix.plugin.PhoenixPlugin$1@16a5a69 2018-10-14 11:17:52,399 [SageTV] INFO phoenix.log - Setting Custom Metadata Fields: DefaultBackground;DefaultBanner;DefaultPoster;DiscNumber;EpisodeNumber;Fanart;IMDBID;MediaProviderDataID;MediaProviderID;MediaTitle;MediaType;MediaUrl;Quotes;ScrapedBy;ScrapedDate;SeasonNumber;SeriesInfoID;TagLine;TrailerUrl;Trivia;UserCategory;UserRating 2018-10-14 11:17:52,404 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\012885871899e7c72e7dbb94440ea848.cache 2018-10-14 11:17:52,405 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0237655455407738795a0f5e060f4e24.cache 2018-10-14 11:17:52,408 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\035683dd3040da6b20e9b2532393bef8.cache 2018-10-14 11:17:52,409 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - Begin: Phoenix looking for plugins that contribute to the Phoenix Core... 2018-10-14 11:17:52,410 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0601f95932a71e42fccf249a0c143eec.cache 2018-10-14 11:17:52,413 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\069062129509bef64afa8b8d7f30a057.cache 2018-10-14 11:17:52,415 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\079a6663df12510a08f47466ccd35ad5.cache 2018-10-14 11:17:52,417 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\0c7ff8fb1b9a08aeeb306e4d4e360bcd.cache 2018-10-14 11:17:52,420 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\10ae59ce6cdd85888af754afbb1f567d.cache 2018-10-14 11:17:52,423 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1251d701da12e86cfdc5178dc449821f.cache 2018-10-14 11:17:52,424 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1477b618cf96869b0f3263daebe531ef.cache 2018-10-14 11:17:52,426 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1703e4ae1009b3777e7171290bb88d07.cache 2018-10-14 11:17:52,428 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\187189ff07b45cfb32054df1a1a78b6a.cache 2018-10-14 11:17:52,431 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1e5164245172ee50764d0001b9e9b083.cache 2018-10-14 11:17:52,433 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\1fe030f3ecb9ea4ad5e42f219bff28a7.cache 2018-10-14 11:17:52,435 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\20d9708b296242fc152e6d2c42ce3c6d.cache 2018-10-14 11:17:52,438 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\234c030397a4c5b1409c840f22181f99.cache 2018-10-14 11:17:52,440 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\23ddd2c7e0d53244c236ac7e06627d48.cache 2018-10-14 11:17:52,443 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\258da7b25e3b0bedf84396bfb2414365.cache 2018-10-14 11:17:52,444 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\2b7b87613cd0c1df0dfd32947a42b189.cache 2018-10-14 11:17:52,447 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\2e97cb802c7a19f1343c422449239559.cache 2018-10-14 11:17:52,449 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\3514c2af5920b65a6f5e21e28b909062.cache 2018-10-14 11:17:52,451 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\362189b9015b330f5dcc4d4a43eb1e8d.cache 2018-10-14 11:17:52,453 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\382e9afaea168ee4403ed31e3d06c1e6.cache 2018-10-14 11:17:52,455 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\39a076d6fcb71a974aa31948efc6dfb2.cache 2018-10-14 11:17:52,457 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\3ad27ba362b3bdbe96145f917be7b0a4.cache 2018-10-14 11:17:52,459 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\3b67ad8174226f9bba79da56b9332fc4.cache 2018-10-14 11:17:52,461 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\3e8ffbe35116926818c6c5b443ee871b.cache 2018-10-14 11:17:52,463 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\458bd3a7a2300d4baa62284fe7164bae.cache 2018-10-14 11:17:52,466 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4f558aafb93e2ca01496aabb30b5b503.cache 2018-10-14 11:17:52,467 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\4fd0de5750df2582934b806052695973.cache 2018-10-14 11:17:52,470 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\503304291a6830e3e5d7a110dd4af672.cache 2018-10-14 11:17:52,472 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\52123fa5cb92f4b7b5ab98fa338fb186.cache 2018-10-14 11:17:52,474 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\52c25f47102b02198a2dd04156813370.cache 2018-10-14 11:17:52,477 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5639a1d4c41a35c2811bef1b62f07e43.cache 2018-10-14 11:17:52,480 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\588782d38594a77d1f4e090cd8de17f9.cache 2018-10-14 11:17:52,483 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5be05cc536b38e42d90d73450cda0fb4.cache 2018-10-14 11:17:52,485 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5e81404133a0cef0cec02158e7ae6f71.cache 2018-10-14 11:17:52,487 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\5eec13856467e6a16cfb589d1d18a2a7.cache 2018-10-14 11:17:52,489 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\609026e81451609f5a50dcbaa20f1e2f.cache 2018-10-14 11:17:52,492 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\60b0a638d2cd4dd36e540484276b9ed4.cache 2018-10-14 11:17:52,495 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\62dc28b48e9fc6eb41dc3f3a4273e28a.cache 2018-10-14 11:17:52,497 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\68b650ae9b268c96c91107c4be4672eb.cache 2018-10-14 11:17:52,499 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\69805c27ab9802475b0b2312fb8bcf63.cache 2018-10-14 11:17:52,500 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\6ece5d90764cad20649a385deab3dafa.cache 2018-10-14 11:17:52,502 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\6f506068f3142af055e2a8e83bb8e31d.cache 2018-10-14 11:17:52,505 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\6f9840b457aa8a8d35e0c7322536b06c.cache 2018-10-14 11:17:52,507 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\748568942d8c5cae04419058188b137a.cache 2018-10-14 11:17:52,510 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\748fdc85c45dc29c8f852e0f0637d186.cache 2018-10-14 11:17:52,512 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\74d1305239082d18684889290c4bf55f.cache 2018-10-14 11:17:52,514 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\750a280efab6e23cd5d0d1518c195ff7.cache 2018-10-14 11:17:52,516 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\7553d65cadc6bc49f0646bfa55842db8.cache 2018-10-14 11:17:52,517 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\76e94b8f6f93aeac6f4a8be592dd2c15.cache 2018-10-14 11:17:52,520 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\77071a41c54046a2831844a45bcd39f4.cache 2018-10-14 11:17:52,522 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\779cf8a0eed12cd25efd11ed2ca0e21b.cache 2018-10-14 11:17:52,524 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\784f678a0d6ec18723b156246740949d.cache 2018-10-14 11:17:52,526 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\7d671fc272bb9a7621d6036b305227d3.cache 2018-10-14 11:17:52,529 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\7f5cfe93fd768cea10d2ebd05d709407.cache 2018-10-14 11:17:52,534 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\86f0ffe189cd8f55fb390b327e54f2f6.cache 2018-10-14 11:17:52,536 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\8dc56336712b9f86d522733094e0ec83.cache 2018-10-14 11:17:52,539 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\8e08a7a85498f6e502470bb7738dc131.cache 2018-10-14 11:17:52,541 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - Begin Reloading some configurations because of plugin contributions 2018-10-14 11:17:52,541 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Begin Loading Configuration Metadata 2018-10-14 11:17:52,542 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\log4j.xml 2018-10-14 11:17:52,543 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Phoenix.xml 2018-10-14 11:17:52,547 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\8f2a856bcbb3e839bfe5ba4264833872.cache 2018-10-14 11:17:52,549 [PluginEventQueue] ERROR sagex.phoenix.configuration.XmlMetadataParser - Failed to Create a Group from Class: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerConfiguration java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerConfiguration at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.startElement(XmlMetadataParser.java:64) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl.parse(Unknown Source) at javax.xml.parsers.SAXParser.parse(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.parseMetadata(XmlMetadataParser.java:37) at sagex.phoenix.configuration.XmlMetadataParser.parse(XmlMetadataParser.java:44) at sagex.phoenix.configuration.XmlMetadataProvider.load(XmlMetadataProvider.java:28) at sagex.phoenix.configuration.ConfigurationMetadataManager.visitConfigurationFile(ConfigurationMetadataManager.java:101) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.configuration.ConfigurationMetadataManager.loadConfigurations(ConfigurationMetadataManager.java:115) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:227) 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) 2018-10-14 11:17:52,550 [PluginEventQueue] ERROR sagex.phoenix.configuration.XmlMetadataParser - Failed to Create a Group from Class: sagex.phoenix.metadata.provider.mymovies.MyMoviesConfiguration java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.mymovies.MyMoviesConfiguration at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.startElement(XmlMetadataParser.java:64) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl.parse(Unknown Source) at javax.xml.parsers.SAXParser.parse(Unknown Source) at sagex.phoenix.configuration.XmlMetadataParser.parseMetadata(XmlMetadataParser.java:37) at sagex.phoenix.configuration.XmlMetadataParser.parse(XmlMetadataParser.java:44) at sagex.phoenix.configuration.XmlMetadataProvider.load(XmlMetadataProvider.java:28) at sagex.phoenix.configuration.ConfigurationMetadataManager.visitConfigurationFile(ConfigurationMetadataManager.java:101) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.configuration.ConfigurationMetadataManager.loadConfigurations(ConfigurationMetadataManager.java:115) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:227) 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) 2018-10-14 11:17:52,551 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:52,552 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:52,553 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:52,553 [PluginEventQueue] WARN sagex.phoenix.util.var.Variable - Invalid Property Notation: prop:phoenix/core/enableAdvancedOptions; Must be prop:[client:server:user]:key 2018-10-14 11:17:52,556 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\8f5f900843c5e92c3349f79346db9771.cache 2018-10-14 11:17:52,556 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Sage.xml 2018-10-14 11:17:52,558 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in .\STVs\Phoenix\Configuration\Streaming.xml 2018-10-14 11:17:52,558 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\91c6cbc5114ad606f6797ac4d5ed6779.cache 2018-10-14 11:17:52,560 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9296f1cae1be0bf8cfb2364226217590.cache 2018-10-14 11:17:52,561 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - Loading Configuration Metadata in plugins\bmtweb\Phoenix\Configuration\bmt.xml 2018-10-14 11:17:52,562 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\93c9b28729e67e91884dc4e6bd92ca8e.cache 2018-10-14 11:17:52,570 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\945ca941f2c32552a19c56c0adbf9bc1.cache 2018-10-14 11:17:52,572 [PluginEventQueue] INFO sagex.phoenix.configuration.ConfigurationMetadataManager - End Loading Configuration Metadata 2018-10-14 11:17:52,572 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:52,573 [PluginEventQueue] WARN sagex.phoenix.vfs.VFSManager - Downloading x-vfs.xml view from Phoenix, since it appears to be missing 2018-10-14 11:17:52,573 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\995af8f77a91843a4ba3ca9c93b78f00.cache 2018-10-14 11:17:52,573 [PluginEventQueue] INFO sagex.phoenix.download.DownloadManager - Scheduled Download: DownloadItem [bytesDownloaded=0, id=60403411404, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=WAITING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] with delay 0 2018-10-14 11:17:52,573 [PluginEventQueue] INFO sagex.phoenix.download.DownloadManager - Waiting for file to download... 2018-10-14 11:17:52,574 [Downloader-1] INFO sagex.phoenix.download.DownloadManager - Download Start: DownloadItem [bytesDownloaded=0, id=60403411404, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=WAITING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] 2018-10-14 11:17:52,576 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\99be1e540e7d3f8789c34931141ee530.cache 2018-10-14 11:17:52,578 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\9d7efab054e749818e84c5b8573f1725.cache 2018-10-14 11:17:52,580 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a21f22fe9c942b2a6fb7ec70d91bc334.cache 2018-10-14 11:17:52,582 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a48b2caa98c9d63ba226f94280a43972.cache 2018-10-14 11:17:52,586 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\a9bea0c686d1bc886ae8cb998a955e84.cache 2018-10-14 11:17:52,597 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\acc62720d6cbfb675c6c108973d8ebf1.cache 2018-10-14 11:17:52,599 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ad34c0b15b1243bcb96e3ca1a05cfbfb.cache 2018-10-14 11:17:52,606 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\b32be96a6c3aeaa5e6763f114a26ad7c.cache 2018-10-14 11:17:52,607 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\b4d5a92fd37548e001f18cbda8cf93ce.cache 2018-10-14 11:17:52,614 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\b6a80c3c593b387070afc52268ffcdc9.cache 2018-10-14 11:17:52,616 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ba64d2a3a65c5021f903eda9033db5b9.cache 2018-10-14 11:17:52,618 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ba952ed0579bc2e7aa09b58bf257f72e.cache 2018-10-14 11:17:52,620 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\baf76f55cffe899257e1901798f0d775.cache 2018-10-14 11:17:52,622 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\bca1309db5d2382aba55af3665591984.cache 2018-10-14 11:17:52,624 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\be4cdad2f8036e5605228cf2f1d8a074.cache 2018-10-14 11:17:52,626 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\c36a6446a4efa77dbaaf4e58b1904c5f.cache 2018-10-14 11:17:52,628 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\c4cba2dcaca46a9febb20c3e26749413.cache 2018-10-14 11:17:52,631 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\c63599c6e81664274d8e2a181eb80a40.cache 2018-10-14 11:17:52,632 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\c88a1a64286c6bd55a42a18150c54722.cache 2018-10-14 11:17:52,634 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\cbcaa41a41ec4d92aadf04387258c7f7.cache 2018-10-14 11:17:52,637 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ccc6cfd000e289da2d398e335f6b456c.cache 2018-10-14 11:17:52,639 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\cd0342b8ce7fc9d3fd56d3311baddd3a.cache 2018-10-14 11:17:52,641 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\d52d9da8e2bb06bf6bc4a434e2b50a00.cache 2018-10-14 11:17:52,642 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\d840f40ff3de61ca2d35424c8c6dcd8c.cache 2018-10-14 11:17:52,651 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\d94683ad62cf33e0903a5c5ba7e0e699.cache 2018-10-14 11:17:52,654 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\dbb209a89ff28162b624b81c108703eb.cache 2018-10-14 11:17:52,656 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\dc5e375ad3ce8b65d40d0128b41e91e4.cache 2018-10-14 11:17:52,658 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\e39235544c98cf036cdfd35b744a0e66.cache 2018-10-14 11:17:52,661 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\e4a79dc6058d94a9f45d88034ce2414b.cache 2018-10-14 11:17:52,662 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\e5cdf56bc2fe8b482db2f758c028069e.cache 2018-10-14 11:17:52,665 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\e8f58d8b04a526c8f090fd1a085f5343.cache 2018-10-14 11:17:52,673 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ea70e62ad3cdb7c320cb569c554a1040.cache 2018-10-14 11:17:52,675 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ee49177d7ce0ae3b74d1334dbfe0b91f.cache 2018-10-14 11:17:52,677 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ef8a1e4ff97e3eb12c1c49c33242e45b.cache 2018-10-14 11:17:52,679 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\f1854a843e3886c0bebcc5370ff78e3e.cache 2018-10-14 11:17:52,681 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\f2e8c4e394e5f93f2742c67b89db8535.cache 2018-10-14 11:17:52,683 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\f743c3831cdc8d11724084a8e282bade.cache 2018-10-14 11:17:52,685 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\fc0c7becd6bd12536cc82d4c4ac4b83f.cache 2018-10-14 11:17:52,688 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\fdb7fd7aeb599e23ac41d8cd442ecd3b.cache 2018-10-14 11:17:52,690 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Removing Cached Url File: .\userdata\Phoenix\cache\url\ff55d9eb57e697857b79b9eb5dc32ab7.cache 2018-10-14 11:17:52,695 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Finished URL Cache Cleanup; Cleaned 107 items. 2018-10-14 11:17:52,766 [Downloader-1] WARN sagex.phoenix.util.FileUtils - Failed to delete file: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:52,767 [Downloader-1] WARN sagex.phoenix.download.DownloadManager - Failed to download item: DownloadItem [bytesDownloaded=0, id=60403411404, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=DOWNLOADING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] but it may retry later java.io.FileNotFoundException: http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(Unknown Source) at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source) at sagex.phoenix.download.DownloadTask.run(DownloadTask.java:40) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 11:17:52,768 [Downloader-1] WARN sagex.phoenix.download.DownloadManager - Failed to download item: DownloadItem [bytesDownloaded=0, id=60403411404, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=2, state=DOWNLOADING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] java.lang.Exception: Max Retry has been exceeded: 2 at sagex.phoenix.download.DownloadManager.reschedule(DownloadManager.java:100) at sagex.phoenix.download.DownloadTask.run(DownloadTask.java:67) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 11:17:52,768 [Downloader-1] WARN sagex.phoenix.util.FileUtils - Failed to delete file: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:52,776 [PluginEventQueue] INFO sagex.phoenix.download.DownloadManager - Done waiting 2018-10-14 11:17:52,778 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Rebuilding VFS Cache .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:52,779 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\plugins\bmtweb\Phoenix\vfs\bmt.xml 2018-10-14 11:17:52,794 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - New VFS Cache Written to .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:52,794 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Loading VFS entries from .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:52,794 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:52,802 [PluginEventQueue] WARN sagex.phoenix.vfs.builder.ViewsBuilder - Xml Parser Failure at line 12, column: 60; for: unknown xml org.xml.sax.SAXParseException; lineNumber: 12; columnNumber: 60; unknown source: phoenix.view.primary.recordedtv at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:127) at sagex.phoenix.vfs.builder.ViewsBuilder.startElement(ViewsBuilder.java:76) at sagex.phoenix.vfs.builder.VFSBuilder.startElement(VFSBuilder.java:116) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:88) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:54) at sagex.phoenix.vfs.VFSManager.visitConfigurationFile(VFSManager.java:195) at sagex.phoenix.vfs.VFSManager.rebuildCache(VFSManager.java:258) at sagex.phoenix.vfs.VFSManager.loadConfigurations(VFSManager.java:211) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:227) 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) 2018-10-14 11:17:52,802 [PluginEventQueue] WARN sagex.phoenix.vfs.VFSManager - Failed to load vfs sources from: .\userdata\Phoenix\cache\vfs-cached.xml; Dynamic Views/Sources will most likely be empty until the problem is resolved. java.lang.NullPointerException at sagex.phoenix.vfs.builder.ViewsBuilder.startElement(ViewsBuilder.java:78) at sagex.phoenix.vfs.builder.VFSBuilder.startElement(VFSBuilder.java:116) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:88) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:54) at sagex.phoenix.vfs.VFSManager.visitConfigurationFile(VFSManager.java:195) at sagex.phoenix.vfs.VFSManager.rebuildCache(VFSManager.java:258) at sagex.phoenix.vfs.VFSManager.loadConfigurations(VFSManager.java:211) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:227) 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) 2018-10-14 11:17:52,802 [Thread-44] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:52,804 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - End Reloading some configurations because of plugin contributions 2018-10-14 11:17:52,804 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - Reloading Phoenix Services in case Plugins have contributed. 2018-10-14 11:17:52,804 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Initializing Phoenix Services 2018-10-14 11:17:52,804 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Checking for core Phoenix Views: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:52,804 [PluginEventQueue] WARN sagex.phoenix.vfs.VFSManager - Downloading x-vfs.xml view from Phoenix, since it appears to be missing 2018-10-14 11:17:52,805 [PluginEventQueue] INFO sagex.phoenix.download.DownloadManager - Scheduled Download: DownloadItem [bytesDownloaded=0, id=60634382480, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=WAITING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] with delay 0 2018-10-14 11:17:52,805 [Downloader-0] INFO sagex.phoenix.download.DownloadManager - Download Start: DownloadItem [bytesDownloaded=0, id=60634382480, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=WAITING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] 2018-10-14 11:17:52,805 [PluginEventQueue] INFO sagex.phoenix.download.DownloadManager - Waiting for file to download... 2018-10-14 11:17:53,130 [Downloader-0] WARN sagex.phoenix.util.FileUtils - Failed to delete file: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:53,130 [Downloader-0] WARN sagex.phoenix.download.DownloadManager - Failed to download item: DownloadItem [bytesDownloaded=0, id=60634382480, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=1, state=DOWNLOADING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] but it may retry later java.io.FileNotFoundException: http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(Unknown Source) at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source) at sagex.phoenix.download.DownloadTask.run(DownloadTask.java:40) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 11:17:53,130 [Downloader-0] WARN sagex.phoenix.download.DownloadManager - Failed to download item: DownloadItem [bytesDownloaded=0, id=60634382480, localFile=.\STVs\Phoenix\vfs\x-vfs.xml, maxReties=1, remoteURL=http://sagephoenix.googlecode.com/svn/trunk/PhoenixUI/STVs/Phoenix/vfs/x-vfs.xml, retries=2, state=DOWNLOADING, totalBytes=0, userAgent=Mozilla/5.0 (X11; Linux i686) AppleWebKit/535.1 (KHTML, like Gecko) Ubuntu/11.10 Chromium/14.0.835.202 Chrome/14.0.835.202 Safari/535.1] java.lang.Exception: Max Retry has been exceeded: 2 at sagex.phoenix.download.DownloadManager.reschedule(DownloadManager.java:100) at sagex.phoenix.download.DownloadTask.run(DownloadTask.java:67) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 11:17:53,130 [Downloader-0] WARN sagex.phoenix.util.FileUtils - Failed to delete file: .\STVs\Phoenix\vfs\x-vfs.xml 2018-10-14 11:17:53,210 [PluginEventQueue] INFO sagex.phoenix.download.DownloadManager - Done waiting 2018-10-14 11:17:53,212 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Rebuilding VFS Cache .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:53,213 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSOrganizer - Organizing VFS C:\Program Files (x86)\SageTV\SageTV\plugins\bmtweb\Phoenix\vfs\bmt.xml 2018-10-14 11:17:53,220 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - New VFS Cache Written to .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:53,220 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Loading VFS entries from .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:53,220 [PluginEventQueue] INFO sagex.phoenix.vfs.VFSManager - Processing VFS File: .\userdata\Phoenix\cache\vfs-cached.xml 2018-10-14 11:17:53,222 [PluginEventQueue] WARN sagex.phoenix.vfs.builder.ViewsBuilder - Xml Parser Failure at line 12, column: 60; for: unknown xml org.xml.sax.SAXParseException; lineNumber: 12; columnNumber: 60; unknown source: phoenix.view.primary.recordedtv at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:127) at sagex.phoenix.vfs.builder.ViewsBuilder.startElement(ViewsBuilder.java:76) at sagex.phoenix.vfs.builder.VFSBuilder.startElement(VFSBuilder.java:116) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:88) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:54) at sagex.phoenix.vfs.VFSManager.visitConfigurationFile(VFSManager.java:195) at sagex.phoenix.vfs.VFSManager.rebuildCache(VFSManager.java:258) at sagex.phoenix.vfs.VFSManager.loadConfigurations(VFSManager.java:211) at sagex.phoenix.Phoenix.initServices(Phoenix.java:237) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:53,222 [PluginEventQueue] WARN sagex.phoenix.vfs.VFSManager - Failed to load vfs sources from: .\userdata\Phoenix\cache\vfs-cached.xml; Dynamic Views/Sources will most likely be empty until the problem is resolved. java.lang.NullPointerException at sagex.phoenix.vfs.builder.ViewsBuilder.startElement(ViewsBuilder.java:78) at sagex.phoenix.vfs.builder.VFSBuilder.startElement(VFSBuilder.java:116) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.emptyElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.scanStartElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:88) at sagex.phoenix.vfs.builder.VFSBuilder.registerVFSSources(VFSBuilder.java:54) at sagex.phoenix.vfs.VFSManager.visitConfigurationFile(VFSManager.java:195) at sagex.phoenix.vfs.VFSManager.rebuildCache(VFSManager.java:258) at sagex.phoenix.vfs.VFSManager.loadConfigurations(VFSManager.java:211) at sagex.phoenix.Phoenix.initServices(Phoenix.java:237) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:53,223 [Thread-45] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:53,223 [PluginEventQueue] INFO phoenix.log - Adding Event Listener for phoenix.vfs.reload routing to sagex.phoenix.event.ReflectionEventListener@a8d324 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.Phoenix - VFS Initialized 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Begin Loading Menus 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Adjusting menu item visibility based on stored settings 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Processing Menu Fragments... 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - Ordering Menu Items... 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.menu.MenuManager - End Loading Menus 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Menus Initialized 2018-10-14 11:17:53,225 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - Begin Loading Skins 2018-10-14 11:17:53,226 [PluginEventQueue] INFO sagex.phoenix.skins.SkinManager - End Loading Skins 2018-10-14 11:17:53,226 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Skins Initialized 2018-10-14 11:17:53,226 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Begin Loading Metadata Providers 2018-10-14 11:17:53,227 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\dvdprofiler.xml 2018-10-14 11:17:53,228 [PluginEventQueue] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 3, column: 88; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\dvdprofiler.xml org.xml.sax.SAXParseException; lineNumber: 3; columnNumber: 88; Failed to create Metadata Provider: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerMetadataProvider at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:122) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:52) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) Caused by: java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.dvdprofiler.DVDProfilerMetadataProvider at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:48) ... 27 more 2018-10-14 11:17:53,229 [PluginEventQueue] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 9, column: 12; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\dvdprofiler.xml org.xml.sax.SAXParseException; lineNumber: 9; columnNumber: 12; Failed to parse a valid metadata provider! at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:127) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:44) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:53,229 [PluginEventQueue] WARN sagex.phoenix.metadata.MetadataManager - IO Error java.io.IOException: Failed to parse provider for file .\STVs\Phoenix\metadata\dvdprofiler.xml at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:783) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:53,229 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\htbackdrops.xml 2018-10-14 11:17:53,230 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\imdb.xml 2018-10-14 11:17:53,232 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\mymovies.xml 2018-10-14 11:17:53,233 [PluginEventQueue] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 3, column: 82; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\mymovies.xml org.xml.sax.SAXParseException; lineNumber: 3; columnNumber: 82; Failed to create Metadata Provider: sagex.phoenix.metadata.provider.mymovies.MyMoviesMetadataProvider at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:122) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:52) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) Caused by: java.lang.ClassNotFoundException: sagex.phoenix.metadata.provider.mymovies.MyMoviesMetadataProvider at java.net.URLClassLoader.findClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source) at java.lang.ClassLoader.loadClass(Unknown Source) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Unknown Source) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:48) ... 27 more 2018-10-14 11:17:53,233 [PluginEventQueue] WARN sagex.phoenix.metadata.factory.MetadataProviderBuilder - Xml Parser Failure at line 9, column: 12; for: C:\Program Files (x86)\SageTV\SageTV\.\STVs\Phoenix\metadata\mymovies.xml org.xml.sax.SAXParseException; lineNumber: 9; columnNumber: 12; Failed to parse a valid metadata provider! at sagex.phoenix.util.BaseBuilder.error(BaseBuilder.java:127) at sagex.phoenix.metadata.factory.MetadataProviderBuilder.endElement(MetadataProviderBuilder.java:44) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLNSDTDValidator.endNamespaceScope(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.handleEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.dtd.XMLDTDValidator.endElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanEndElement(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(Unknown Source) at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(Unknown Source) at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(Unknown Source) at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:780) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:53,233 [PluginEventQueue] WARN sagex.phoenix.metadata.MetadataManager - IO Error java.io.IOException: Failed to parse provider for file .\STVs\Phoenix\metadata\mymovies.xml at sagex.phoenix.metadata.MetadataManager.visitConfigurationFile(MetadataManager.java:783) at sagex.phoenix.common.SystemConfigurationFileManager.visitFiles(SystemConfigurationFileManager.java:105) at sagex.phoenix.common.SystemConfigurationFileManager.accept(SystemConfigurationFileManager.java:78) at sagex.phoenix.metadata.MetadataManager.loadConfigurations(MetadataManager.java:759) at sagex.phoenix.Phoenix.initServices(Phoenix.java:265) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:53,233 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\nielm_imdb.xml 2018-10-14 11:17:53,235 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb.xml 2018-10-14 11:17:53,236 [Thread-48] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:53,236 [Thread-46] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:53,236 [Thread-49] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:53,236 [Thread-47] WARN sagex.phoenix.ConfigurationErrorEventBus - No Event Handlers configured for type: configurationevent.newerror 2018-10-14 11:17:53,492 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tmdb3.xml 2018-10-14 11:17:53,788 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - Loading Metadata Provider: .\STVs\Phoenix\metadata\tvdb.xml 2018-10-14 11:17:53,792 [PluginEventQueue] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Begin Loading Xbmc Metadata Providers 2018-10-14 11:17:53,793 [PluginEventQueue] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - Loading XBMC Metadata Scraper: .\STVs\Phoenix\scrapers\xbmc\video\imdb.xml 2018-10-14 11:17:53,807 [PluginEventQueue] INFO sagex.phoenix.metadata.XbmcScraperMetadataProviderConfiguration - End Loading Xbmc Metadata Providers 2018-10-14 11:17:53,807 [PluginEventQueue] INFO sagex.phoenix.metadata.MetadataManager - End Loading Metadata Providers 2018-10-14 11:17:53,807 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Metadata Scrapers Initialized 2018-10-14 11:17:53,807 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Begin Loading Movie Filename Scrapers 2018-10-14 11:17:53,811 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear1 with priority 116 2018-10-14 11:17:53,812 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-withyear2 with priority 116 2018-10-14 11:17:53,814 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: title-dot-year with priority 116 2018-10-14 11:17:53,814 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - End Loading Movie Filename Scrapers 2018-10-14 11:17:53,814 [PluginEventQueue] INFO sagex.phoenix.metadata.search.MovieScraperManager - Added System Movie Scraper: defaultmovie with priority 2147483647 2018-10-14 11:17:53,814 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Movie Filename Scrapers Initialized 2018-10-14 11:17:53,814 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Begin Loading TV Filename Scrapers 2018-10-14 11:17:53,817 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecordingNoEpisode with priority 116 2018-10-14 11:17:53,819 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-sageRecording with priority 116 2018-10-14 11:17:53,823 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-show-episode with priority 116 2018-10-14 11:17:53,825 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-00x00 with priority 116 2018-10-14 11:17:53,827 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-airedDate with priority 116 2018-10-14 11:17:53,829 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00d00 with priority 116 2018-10-14 11:17:53,831 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00e00 with priority 116 2018-10-14 11:17:53,834 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-s00-Disc# with priority 116 2018-10-14 11:17:53,836 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - Added System TV Scraper: title-Season00Episode00 with priority 116 2018-10-14 11:17:53,836 [PluginEventQueue] INFO sagex.phoenix.metadata.search.TVScraperManager - End Loading TV Filename Scrapers 2018-10-14 11:17:53,836 [PluginEventQueue] INFO sagex.phoenix.Phoenix - TV Filename Scrapers Initialized 2018-10-14 11:17:53,836 [PluginEventQueue] INFO sagex.phoenix.metadata.RatingsManager - Begin Loading Ratings Map 2018-10-14 11:17:53,838 [PluginEventQueue] INFO sagex.phoenix.metadata.RatingsManager - Loading Ratings file .\STVs\Phoenix\metadata\ratings.properties 2018-10-14 11:17:53,839 [PluginEventQueue] INFO sagex.phoenix.metadata.RatingsManager - End Loading Ratings Map 2018-10-14 11:17:53,839 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Ratings Manager Initialized 2018-10-14 11:17:53,839 [PluginEventQueue] INFO sagex.phoenix.metadata.search.FileMatcherManager - Begin loading Title Matchers 2018-10-14 11:17:53,839 [PluginEventQueue] INFO sagex.phoenix.metadata.search.FileMatcherManager - End loading Title Matchers 2018-10-14 11:17:53,840 [PluginEventQueue] INFO sagex.phoenix.Phoenix - MediaTitles.xml Initialized 2018-10-14 11:17:53,840 [PluginEventQueue] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - Being Loading URL Resolvers 2018-10-14 11:17:53,841 [PluginEventQueue] INFO sagex.phoenix.vfs.ov.OnlineVideosUrlResolverManager - End Loading URL Resolvers 2018-10-14 11:17:53,841 [PluginEventQueue] INFO sagex.phoenix.Phoenix - URL Resolvers has been loaded 2018-10-14 11:17:53,842 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Scripting Services Factory initialized 2018-10-14 11:17:53,842 [PluginEventQueue] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Url Cleanup Task Created monitoring .\userdata\Phoenix\cache\url 2018-10-14 11:17:53,842 [PluginEventQueue] INFO sagex.phoenix.util.TaskManager - Cancelling scheduled task since it's being updated for: urlcachecleaner 2018-10-14 11:17:53,842 [PluginEventQueue] INFO sagex.phoenix.util.TaskManager - Adding Scheduled Task: urlcachecleaner; Date: Sun Oct 14 11:17:53 AEDT 2018; Repeat: 86400000 2018-10-14 11:17:53,842 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Core Scheduled Tasks Initialized 2018-10-14 11:17:53,842 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Begin URL Cache Cleanup 2018-10-14 11:17:53,843 [Timer-7] INFO sagex.phoenix.util.url.CachedUrlCleanupTask - Finished URL Cache Cleanup; Cleaned 0 items. 2018-10-14 11:17:54,098 [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:141) at phoenix.impl.WeatherAPI.SetLocation(WeatherAPI.java:64) at phoenix.weather.SetLocation(weather.java:58) at sagex.phoenix.Phoenix.initServices(Phoenix.java:337) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:54,098 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Configuring weather using EPG zip code 2018-10-14 11:17:54,341 [PluginEventQueue] WARN sagex.phoenix.weather.yahoo.YahooWeatherSupport - Failed to convert 00000 to woeid java.lang.NullPointerException at sagex.phoenix.weather.yahoo.YahooWeatherSupport.setLocation(YahooWeatherSupport.java:141) at phoenix.impl.WeatherAPI.SetLocation(WeatherAPI.java:64) at phoenix.weather.SetLocation(weather.java:58) at sagex.phoenix.Phoenix.initServices(Phoenix.java:348) at sagex.phoenix.plugin.PhoenixPlugin.onPluginsLoaded(PhoenixPlugin.java:236) 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) 2018-10-14 11:17:54,341 [PluginEventQueue] INFO sagex.phoenix.Phoenix - Phoenix Services initialized. 2018-10-14 11:17:54,341 [PluginEventQueue] INFO sagex.phoenix.plugin.PhoenixPlugin - End: Phoenix looking for plugins that contribute to the Phoenix Core 2018-10-14 12:02:06,125 [Timer-8] WARN sagex.phoenix.metadata.search.TVScraperManager - No TV Scraper could handle file: SageMediaFile [sageId=962260, sageObject=MediaFile[id=962260 A[962263,962261,"Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]",0@1014.10:10,110] mask=V host=BServer encodedBy= format=AVI 1:50:07 1787 kbps [#0 Video[MPEG4-Video 23.98 fps 720x304 45:19 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps idx=1]] \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi, Seg0[Sun 10/14 10:10:58.363-Sun 10/14 12:01:06.163]]] 2018-10-14 12:02:06,128 [Timer-8] INFO sagex.phoenix.metadata.search.MovieScraperManager - Scraper title-dot-year created a query: SearchQuery [type=MOVIE, fields={RAW_TITLE: Oceans.8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] for file SageMediaFile [sageId=962260, sageObject=MediaFile[id=962260 A[962263,962261,"Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]",0@1014.10:10,110] mask=V host=BServer encodedBy= format=AVI 1:50:07 1787 kbps [#0 Video[MPEG4-Video 23.98 fps 720x304 45:19 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps idx=1]] \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi, Seg0[Sun 10/14 10:10:58.363-Sun 10/14 12:01:06.163]]] with hints Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}] 2018-10-14 12:02:06,131 [Timer-8] INFO sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] 2018-10-14 12:02:06,131 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - search(): tmdb,imdb; SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] 2018-10-14 12:02:06,131 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - Searching: Oceans 8 using TMDB3MetadataProvider[MetadataProviderInfo [id=tmdb, fanartId=tmdb, mediaTypes=[MOVIE], name=themoviedb.org v3]] 2018-10-14 12:02:07,211 [Timer-8] WARN sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider - Alternate Title Search failed for SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,QUERY: Oceans 8,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] com.omertron.themoviedbapi.MovieDbException: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:535) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.addMovie(TMDB3MetadataProvider.java:410) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.getResults(TMDB3MetadataProvider.java:367) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.search(TMDB3MetadataProvider.java:353) at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:272) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:513) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:479) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:153) at sagex.phoenix.plugin.PhoenixPlugin.mediaFileImported(PhoenixPlugin.java:127) at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) Caused by: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException.from(UnrecognizedPropertyException.java:51) at com.fasterxml.jackson.databind.DeserializationContext.reportUnknownProperty(DeserializationContext.java:817) at com.fasterxml.jackson.databind.deser.std.StdDeserializer.handleUnknownProperty(StdDeserializer.java:954) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownProperty(BeanDeserializerBase.java:1324) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownVanilla(BeanDeserializerBase.java:1302) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:249) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:240) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:212) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:25) at com.fasterxml.jackson.databind.deser.SettableBeanProperty.deserialize(SettableBeanProperty.java:523) at com.fasterxml.jackson.databind.deser.impl.MethodProperty.deserializeAndSet(MethodProperty.java:95) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap._findDeserializeAndSet2(BeanPropertyMap.java:313) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap.findDeserializeAndSet(BeanPropertyMap.java:291) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:248) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:3562) at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:2578) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:529) ... 14 more 2018-10-14 12:02:07,821 [Timer-8] WARN sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider - Alternate Title Search failed for SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,QUERY: Oceans 8,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] com.omertron.themoviedbapi.MovieDbException: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:535) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.addMovie(TMDB3MetadataProvider.java:410) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.getResults(TMDB3MetadataProvider.java:379) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.search(TMDB3MetadataProvider.java:353) at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:272) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:513) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:479) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:153) at sagex.phoenix.plugin.PhoenixPlugin.mediaFileImported(PhoenixPlugin.java:127) at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) Caused by: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException.from(UnrecognizedPropertyException.java:51) at com.fasterxml.jackson.databind.DeserializationContext.reportUnknownProperty(DeserializationContext.java:817) at com.fasterxml.jackson.databind.deser.std.StdDeserializer.handleUnknownProperty(StdDeserializer.java:954) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownProperty(BeanDeserializerBase.java:1324) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownVanilla(BeanDeserializerBase.java:1302) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:249) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:240) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:212) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:25) at com.fasterxml.jackson.databind.deser.SettableBeanProperty.deserialize(SettableBeanProperty.java:523) at com.fasterxml.jackson.databind.deser.impl.MethodProperty.deserializeAndSet(MethodProperty.java:95) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap._findDeserializeAndSet2(BeanPropertyMap.java:313) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap.findDeserializeAndSet(BeanPropertyMap.java:291) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:248) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:3562) at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:2578) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:529) ... 14 more 2018-10-14 12:02:07,822 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - No good matches for Oceans 8 will try other providers if available. 2018-10-14 12:02:07,822 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - Searching: Oceans 8 using NielmIMDBMetaDataProvider[MetadataProviderInfo [id=imdb, fanartId=tmdb, mediaTypes=[MOVIE], name=IMDb]] 2018-10-14 12:02:12,096 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - No good matches for Oceans 8 will try other providers if available. 2018-10-14 12:02:12,097 [Timer-8] WARN phoenix.log - ERROR; \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi; Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]; 962260; Metadata lookup failed for Oceans 8 MetadataException [query=SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\(Incomplete)\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,QUERY: Oceans 8,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]], ] at sagex.phoenix.metadata.MetadataManager.getMetdata(MetadataManager.java:412) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:513) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:479) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:153) at sagex.phoenix.plugin.PhoenixPlugin.mediaFileImported(PhoenixPlugin.java:127) at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 12:07:05,898 [Timer-8] WARN sagex.phoenix.metadata.search.TVScraperManager - No TV Scraper could handle file: SageMediaFile [sageId=962264, sageObject=MediaFile[id=962264 A[962266,962265,"Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]",0@1014.10:12,110] mask=V host=BServer encodedBy= format=AVI 1:50:07 1787 kbps [#0 Video[MPEG4-Video 23.98 fps 720x304 45:19 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps idx=1]] \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi, Seg0[Sun 10/14 10:12:08.896-Sun 10/14 12:02:16.696]]] 2018-10-14 12:07:05,899 [Timer-8] INFO sagex.phoenix.metadata.search.MovieScraperManager - Scraper title-dot-year created a query: SearchQuery [type=MOVIE, fields={RAW_TITLE: Oceans.8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] for file SageMediaFile [sageId=962264, sageObject=MediaFile[id=962264 A[962266,962265,"Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]",0@1014.10:12,110] mask=V host=BServer encodedBy= format=AVI 1:50:07 1787 kbps [#0 Video[MPEG4-Video 23.98 fps 720x304 45:19 progressive]#1 Audio[AC3 48000 Hz 6 channels 384 kbps idx=1]] \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi, Seg0[Sun 10/14 10:12:08.896-Sun 10/14 12:02:16.696]]] with hints Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}] 2018-10-14 12:07:05,900 [Timer-8] INFO sagex.phoenix.metadata.search.SearchQueryFactory - Created Search Query: SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] 2018-10-14 12:07:05,900 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - search(): tmdb,imdb; SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] 2018-10-14 12:07:05,900 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - Searching: Oceans 8 using TMDB3MetadataProvider[MetadataProviderInfo [id=tmdb, fanartId=tmdb, mediaTypes=[MOVIE], name=themoviedb.org v3]] 2018-10-14 12:07:06,763 [Timer-8] WARN sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider - Alternate Title Search failed for SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,QUERY: Oceans 8,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] com.omertron.themoviedbapi.MovieDbException: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:535) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.addMovie(TMDB3MetadataProvider.java:410) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.getResults(TMDB3MetadataProvider.java:367) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.search(TMDB3MetadataProvider.java:353) at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:272) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:513) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:479) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:153) at sagex.phoenix.plugin.PhoenixPlugin.mediaFileImported(PhoenixPlugin.java:127) at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) Caused by: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException.from(UnrecognizedPropertyException.java:51) at com.fasterxml.jackson.databind.DeserializationContext.reportUnknownProperty(DeserializationContext.java:817) at com.fasterxml.jackson.databind.deser.std.StdDeserializer.handleUnknownProperty(StdDeserializer.java:954) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownProperty(BeanDeserializerBase.java:1324) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownVanilla(BeanDeserializerBase.java:1302) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:249) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:240) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:212) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:25) at com.fasterxml.jackson.databind.deser.SettableBeanProperty.deserialize(SettableBeanProperty.java:523) at com.fasterxml.jackson.databind.deser.impl.MethodProperty.deserializeAndSet(MethodProperty.java:95) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap._findDeserializeAndSet2(BeanPropertyMap.java:313) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap.findDeserializeAndSet(BeanPropertyMap.java:291) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:248) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:3562) at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:2578) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:529) ... 14 more 2018-10-14 12:07:07,302 [Timer-8] WARN sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider - Alternate Title Search failed for SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,QUERY: Oceans 8,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]] com.omertron.themoviedbapi.MovieDbException: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:535) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.addMovie(TMDB3MetadataProvider.java:410) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.getResults(TMDB3MetadataProvider.java:379) at sagex.phoenix.metadata.provider.tmdb3.TMDB3MetadataProvider.search(TMDB3MetadataProvider.java:353) at sagex.phoenix.metadata.MetadataManager.search(MetadataManager.java:272) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:513) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:479) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:153) at sagex.phoenix.plugin.PhoenixPlugin.mediaFileImported(PhoenixPlugin.java:127) at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) Caused by: com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException: Unrecognized field "type" (class com.omertron.themoviedbapi.model.AlternativeTitle), not marked as ignorable (2 known properties: "title", "iso_3166_1"]) at [Source: {"id":402900,"titles":[{"iso_3166_1":"US","title":"Ocean's 8","type":"Informal title"},{"iso_3166_1":"AR","title":"Ocean's 8: las estafadoras","type":""},{"iso_3166_1":"BR","title":"Oito mulheres e um segredo","type":""},{"iso_3166_1":"CA","title":"Debbie Ocean 8","type":"French title"},{"iso_3166_1":"RO","title":"Ocean's 8: Jaf cu clas?","type":""},{"iso_3166_1":"KR","title":"??? ???","type":""},{"iso_3166_1":"PT","title":"Ocean's 8","type":""},{"iso_3166_1":"US","title":"Oceans 8","type":""},{"iso_3166_1":"KR","title":"??? 8","type":""}]}; line: 1, column: 71] (through reference chain: com.omertron.themoviedbapi.wrapper.WrapperAlternativeTitles["titles"]->java.util.ArrayList[0]->com.omertron.themoviedbapi.model.AlternativeTitle["type"]) at com.fasterxml.jackson.databind.exc.UnrecognizedPropertyException.from(UnrecognizedPropertyException.java:51) at com.fasterxml.jackson.databind.DeserializationContext.reportUnknownProperty(DeserializationContext.java:817) at com.fasterxml.jackson.databind.deser.std.StdDeserializer.handleUnknownProperty(StdDeserializer.java:954) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownProperty(BeanDeserializerBase.java:1324) at com.fasterxml.jackson.databind.deser.BeanDeserializerBase.handleUnknownVanilla(BeanDeserializerBase.java:1302) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:249) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:240) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:212) at com.fasterxml.jackson.databind.deser.std.CollectionDeserializer.deserialize(CollectionDeserializer.java:25) at com.fasterxml.jackson.databind.deser.SettableBeanProperty.deserialize(SettableBeanProperty.java:523) at com.fasterxml.jackson.databind.deser.impl.MethodProperty.deserializeAndSet(MethodProperty.java:95) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap._findDeserializeAndSet2(BeanPropertyMap.java:313) at com.fasterxml.jackson.databind.deser.impl.BeanPropertyMap.findDeserializeAndSet(BeanPropertyMap.java:291) at com.fasterxml.jackson.databind.deser.BeanDeserializer.vanillaDeserialize(BeanDeserializer.java:248) at com.fasterxml.jackson.databind.deser.BeanDeserializer.deserialize(BeanDeserializer.java:136) at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:3562) at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:2578) at com.omertron.themoviedbapi.TheMovieDbApi.getMovieAlternativeTitles(TheMovieDbApi.java:529) ... 14 more 2018-10-14 12:07:07,305 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - No good matches for Oceans 8 will try other providers if available. 2018-10-14 12:07:07,306 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - Searching: Oceans 8 using NielmIMDBMetaDataProvider[MetadataProviderInfo [id=imdb, fanartId=tmdb, mediaTypes=[MOVIE], name=IMDb]] 2018-10-14 12:07:07,319 [Timer-8] INFO sagex.phoenix.metadata.MetadataManager - No good matches for Oceans 8 will try other providers if available. 2018-10-14 12:07:07,320 [Timer-8] WARN phoenix.log - ERROR; \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi; Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]; 962264; Metadata lookup failed for Oceans 8 MetadataException [query=SearchQuery [type=MOVIE, fields={FILE: \\BServer\FServe\New\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies]\Oceans.8.2018.HDRip.XviD.AC3-EVO[EtMovies].avi,QUERY: Oceans 8,EPISODE_DATE: 2018-10-14,RAW_TITLE: Oceans 8,CLEAN_TITLE: Oceans 8,YEAR: 2018,}, hints=Hints [hints={known_recording: false,auto: true,import_tv_as_recording: false,update_fanart: true,scan_missing_metadata: true,scan_subfolders: true,update_metadata: true,}]], ] at sagex.phoenix.metadata.MetadataManager.getMetdata(MetadataManager.java:412) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:513) at sagex.phoenix.metadata.MetadataManager.automaticUpdate(MetadataManager.java:479) at sagex.phoenix.plugin.PhoenixPlugin.updateMetadata(PhoenixPlugin.java:153) at sagex.phoenix.plugin.PhoenixPlugin.mediaFileImported(PhoenixPlugin.java:127) at sun.reflect.GeneratedMethodAccessor12.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at sagex.plugin.AbstractPlugin$1.run(AbstractPlugin.java:254) at java.util.TimerThread.mainLoop(Unknown Source) at java.util.TimerThread.run(Unknown Source) 2018-10-14 12:18:37,658 [PluginEventQueue] INFO sagex.phoenix.stv.OnlineVideoPlaybackManager - Playback Stopped for null 2018-10-14 12:19:01,944 [PluginEventQueue] INFO sagex.phoenix.stv.OnlineVideoPlaybackManager - Playback Stopped for null