Jobber8742 9 Posted March 15, 2014 Share Posted March 15, 2014 2014-03-15 09:45:37.0712 Info - Main: Media Browser Server started2014-03-15 09:45:37.1025 Info - Main: Command line: C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe2014-03-15 09:45:37.1025 Info - Main: Server: MEDIASERVER-PC2014-03-15 09:45:37.1025 Info - Main: Operating system: Microsoft Windows NT 6.1.7601 Service Pack 12014-03-15 09:45:37.1025 Info - Main: Program data path: C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server2014-03-15 09:45:37.1025 Info - Main: Application Path: C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe2014-03-15 09:45:38.1962 Info - App: Application Configuration: {"WeatherLocation":"61240","WeatherUnit":"Fahrenheit","EnableHttpLevelLogging":true,"EnableUPnP":true,"HttpServerPortNumber":8096,"LegacyWebSocketPortNumber":8945,"EnableInternetProviders":true,"SeasonZeroDisplayName":"Specials","MetadataRefreshDays":30,"SaveLocalMeta":true,"RefreshItemImages":false,"PreferredMetadataLanguage":"en","MetadataCountryCode":"US","SortReplaceCharacters":[".","+","%"],"SortRemoveCharacters":[",","&","-","{","}","'"],"SortRemoveWords":["the","a","an"],"ShowLogWindow":false,"RecentItemDays":10,"MinResumePct":2,"MaxResumePct":90,"MinResumeDurationSeconds":180,"RealtimeWatcherDelay":20,"EnableDashboardResponseCaching":true,"ManualLoginClients":[],"EnableTvDbUpdates":false,"EnableTmdbUpdates":false,"EnableFanArtUpdates":false,"ImageSavingConvention":"Legacy","EnablePeoplePrefixSubFolders":false,"MediaEncodingQuality":"Auto","AllowVideoUpscaling":false,"EnableMovieChapterImageExtraction":true,"EnableEpisodeChapterImageExtraction":false,"EnableOtherVideoChapterImageExtraction":false,"MetadataOptions":[{"ItemType":"Book","ImageOptions":[{"Type":"Backdrop","Limit":1,"MinWidth":1280}],"DisabledMetadataSavers":[],"LocalMetadataReaderOrder":[],"DisabledMetadataFetchers":[],"MetadataFetcherOrder":[],"DisabledImageFetchers":[],"ImageFetcherOrder":[]},{"ItemType":"MusicAlbum","ImageOptions":[{"Type":"Backdrop","Limit":1,"MinWidth":1280}],"DisabledMetadataSavers":[],"LocalMetadataReaderOrder":[],"DisabledMetadataFetchers":[],"MetadataFetcherOrder":[],"DisabledImageFetchers":[],"ImageFetcherOrder":[]},{"ItemType":"MusicArtist","ImageOptions":[{"Type":"Backdrop","Limit":1,"MinWidth":1280}],"DisabledMetadataSavers":[],"LocalMetadataReaderOrder":[],"DisabledMetadataFetchers":[],"MetadataFetcherOrder":[],"DisabledImageFetchers":[],"ImageFetcherOrder":[]},{"ItemType":"Season","ImageOptions":[{"Type":"Backdrop","Limit":0,"MinWidth":1280}],"DisabledMetadataSavers":[],"LocalMetadataReaderOrder":[],"DisabledMetadataFetchers":[],"MetadataFetcherOrder":[],"DisabledImageFetchers":[],"ImageFetcherOrder":[]}],"EnableDebugEncodingLogging":false,"EnableAutomaticRestart":true,"TvFileOrganizationOptions":{"IsEnabled":false,"MinFileSizeMb":50,"LeftOverFileExtensionsToDelete":[],"WatchLocations":[],"SeasonFolderPattern":"Season %s","SeasonZeroFolderName":"Season 0","EpisodeNamePattern":"%sn - %sx%0e - %en.%ext","MultiEpisodeNamePattern":"%sn - %sx%0e-x%0ed - %en.%ext","OverwriteExistingEpisodes":false,"DeleteEmptyFolders":false},"LiveTvOptions":{},"EnableRealtimeMonitor":true,"PathSubstitutions":[],"EnableDebugLevelLogging":false,"EnableAutoUpdate":true,"SystemUpdateLevel":"Beta","LogFileRetentionDays":3,"RunAtStartup":true,"IsStartupWizardCompleted":true,"CachePath":""}2014-03-15 09:45:38.3681 Info - App: Loading CoverArt, Version=4.0.5.6, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading GameBrowser, Version=2.9.5184.24602, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading GenreCleaner, Version=1.0.1.1, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MBBookshelf, Version=1.0.5165.23845, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Plugins.Trailers, Version=1.0.5164.78, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MovieThemeVideos, Version=1.1.0.5, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading ThemeSongs, Version=1.0.4.8, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading ThemeVideos, Version=1.0.9.1, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Api, Version=3.0.5171.19958, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.WebDashboard, Version=3.0.5171.19957, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Model, Version=3.0.5171.19956, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Common, Version=3.0.5171.19957, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Controller, Version=3.0.5171.19957, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Providers, Version=3.0.5171.19958, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Common.Implementations, Version=3.0.5171.19957, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Server.Implementations, Version=3.0.5171.19958, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.Dlna, Version=3.0.5171.19958, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.IsoMounter, Version=1.0.5079.1480, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.3681 Info - App: Loading MediaBrowser.ServerApplication, Version=3.0.5171.19958, Culture=neutral, PublicKeyToken=null2014-03-15 09:45:38.6806 Info - App: Version 3.0.5171.19958 initializing2014-03-15 09:45:38.8525 Info - SqliteUserRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\users.db2014-03-15 09:45:39.0244 Info - SqliteItemRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\chapters.db2014-03-15 09:45:39.0400 Info - SqliteItemRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\mediainfo.db2014-03-15 09:45:39.0556 Info - SqliteFileOrganizationRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\fileorganization.db2014-03-15 09:45:39.7744 Info - SqliteNotificationsRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\notifications.db2014-03-15 09:45:39.7744 Info - SqliteItemRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\library.db2014-03-15 09:45:39.7900 Info - SqliteUserDataRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\userdata_v2.db2014-03-15 09:45:39.7900 Info - SqliteDisplayPreferencesRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\displaypreferences.db2014-03-15 09:45:39.7900 Info - SqliteProviderInfoRepository: Opening C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\data\refreshinfo.db2014-03-15 09:45:40.2275 Info - HttpServer: Calling ServiceStack AppHost.Init2014-03-15 09:45:41.6806 Info - ServiceStackHost: Initializing Application took 2515.625ms2014-03-15 09:45:41.7119 Info - App: Loading Http Server2014-03-15 09:45:41.7275 Info - HttpServer: Adding HttpListener prefix http://+:8096/mediabrowser/2014-03-15 09:45:41.7431 Info - HttpServer: Starting HttpListner2014-03-15 09:45:41.7744 Info - App: Starting Alchemy web socket server on port 89452014-03-15 09:45:41.7744 Info - App: Alchemy Web Socket Server started2014-03-15 09:45:46.5869 Info - App: Core startup complete2014-03-15 09:45:46.7900 Info - CoverArt: CoverArt Version 4.0.5.6 Loaded.2014-03-15 09:45:47.1806 Debug - App: Starting NAT discovery2014-03-15 09:45:47.1806 Debug - App: Searching for: UpnpSearcher2014-03-15 09:45:47.1962 Debug - App: Searching for: PmpSearcher2014-03-15 09:45:47.2431 Debug - CoverArt: CoverArt Registration Status - Registered: True In trial: False Expiration Date: 1/1/0001 12:00:00 AM Is Valid: True2014-03-15 09:45:47.2431 Debug - Genre Cleaner: GenreCleaner Registration Status - Registered: True In trial: False Expiration Date: 1/1/0001 12:00:00 AM Is Valid: True2014-03-15 09:45:47.2587 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:45:47.2587 Info - App: Movie Theme Videos (version 1.1.0.5) | Registration Status - Registered?: True | Is in Trial : False | Registration Is Valid : True2014-03-15 09:45:47.4306 Info - App: HttpClientManager GET: http://www.mb3admin.com/admin/service/MB3ServerNotifications.json2014-03-15 09:45:47.5244 Info - App: HttpClientManager GET: http://mediabrowser.tv/community/index.php?/blog/rss/1-media-browser-developers-blog2014-03-15 09:45:47.5400 Info - App: HttpClientManager POST: http://www.mb3admin.com/admin/service/registration/validate2014-03-15 09:45:47.6494 Info - App: HttpClientManager POST: http://www.mb3admin.com/admin/service/registration/validate2014-03-15 09:45:47.6650 Info - App: TV Theme Videos (version 1.0.9.1) | Registration Status - Registered?: True | Is in Trial : False | Registration Is Valid : True2014-03-15 09:45:47.7119 Debug - App: Searching for: PmpSearcher2014-03-15 09:45:47.7275 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:45:48.1025 Info - App: TV Theme Songs Registration Status - Registered?: False | Is in Trial : False | Registration Is Valid : False2014-03-15 09:45:49.5869 Info - App: StartupTrigger fired for task: Scan media library2014-03-15 09:45:49.5869 Info - App: StartupTrigger fired for task: Check for application updates2014-03-15 09:45:49.5869 Info - App: StartupTrigger fired for task: Refresh Guide2014-03-15 09:45:49.5869 Info - App: Executing Scan media library2014-03-15 09:45:49.6025 Info - App: Executing Check for application updates2014-03-15 09:45:49.6025 Info - App: Executing Refresh Guide2014-03-15 09:45:49.6025 Info - App: Validating media library2014-03-15 09:45:49.6181 Info - App: HttpClientManager GET: http://www.mb3admin.com/admin/service/MB3Packages.json2014-03-15 09:45:49.6181 Info - App: Refresh Guide Completed after 0 minute(s) and 0 seconds2014-03-15 09:45:49.6337 Info - App: StartupTrigger fired for task: Check for plugin updates2014-03-15 09:45:49.6337 Info - App: Executing Check for plugin updates2014-03-15 09:45:49.7119 Info - App: HttpClientManager GET: http://www.mb3admin.com/admin/service/MB3Packages.json2014-03-15 09:45:49.7119 Info - App: StartupTrigger fired for task: Log file cleanup2014-03-15 09:45:49.7119 Info - App: Executing Log file cleanup2014-03-15 09:45:49.7119 Info - App: StartupTrigger fired for task: Cache file cleanup2014-03-15 09:45:49.7275 Info - App: Executing Cache file cleanup2014-03-15 09:45:49.7275 Debug - App: Searching for: PmpSearcher2014-03-15 09:45:49.7275 Info - App: Log file cleanup Completed after 0 minute(s) and 0 seconds2014-03-15 09:45:49.7587 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:45:49.9931 Debug - App: No application update available.2014-03-15 09:45:49.9931 Info - App: Check for application updates Completed after 0 minute(s) and 0 seconds2014-03-15 09:45:51.1494 Info - App: Check for plugin updates Completed after 0 minute(s) and 1 seconds2014-03-15 09:45:57.2119 Info - App: HttpClientManager POST: http://www.mb3admin.com/admin/service/registration/ping2014-03-15 09:45:57.5556 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/dashboard/index.html2014-03-15 09:45:57.6650 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Movies\Enchanted.lnk.lnk2014-03-15 09:45:57.7431 Debug - App: Searching for: PmpSearcher2014-03-15 09:45:57.7744 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:45:58.0400 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Movies\Hook.lnk.lnk2014-03-15 09:45:58.1962 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Movies\Who Framed Roger Rabbit.lnk.lnk2014-03-15 09:45:58.6650 Debug - HttpServer: HTTP Response 304 to 192.168.1.3:55406. Response time: 2703.125 ms Url: http://192.168.1.2:8096/mediabrowser/dashboard/index.html2014-03-15 09:46:04.0712 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/dashboard/thirdparty/jquerymobile-1.4.1/jquery.mobile-1.4.1.min.css2014-03-15 09:46:04.0712 Debug - HttpServer: HTTP Response 304 to 192.168.1.3:55406. Response time: 5359.375 ms Url: http://192.168.1.2:8096/mediabrowser/dashboard/thirdparty/jquerymobile-1.4.1/jquery.mobile-1.4.1.min.css2014-03-15 09:46:04.0712 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/dashboard/thirdparty/jstree1.0/jquery.jstree.min.js2014-03-15 09:46:04.0712 Debug - HttpServer: HTTP Response 304 to 192.168.1.3:55407. Response time: 5359.375 ms Url: http://192.168.1.2:8096/mediabrowser/dashboard/thirdparty/jstree1.0/jquery.jstree.min.js2014-03-15 09:46:04.5400 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab892014-03-15 09:46:04.8212 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55406. Response time: 281.25 ms Url: http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab892014-03-15 09:46:04.8212 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/System/Info2014-03-15 09:46:05.0400 Info - BaseItem: Shortcut links have changed for C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\root\default\Movies2014-03-15 09:46:05.0712 Debug - App: Saving C:\Users\Jeff\AppData\Roaming\MediaBrowser-Server\root\default\Movies to database.2014-03-15 09:46:05.6337 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55407. Response time: 1093.75 ms Url: http://192.168.1.2:8096/mediabrowser/System/Info2014-03-15 09:46:05.6337 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Items/Counts?userId=d346910290dfc268bdf2c915f879ab892014-03-15 09:46:12.0712 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?sortBy=SortName2014-03-15 09:46:15.4931 Info - App: Cache file cleanup Completed after 0 minute(s) and 25 seconds2014-03-15 09:46:15.7587 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/LiveTv/Info?2014-03-15 09:46:15.7900 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55406. Response time: 10812.5 ms Url: http://192.168.1.2:8096/mediabrowser/LiveTv/Info?2014-03-15 09:46:15.8212 Info - App: Client connected from 192.168.1.3:554102014-03-15 09:46:23.0712 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/dashboard/dashboard.html2014-03-15 09:46:23.0712 Debug - HttpServer: HTTP Response 304 to 192.168.1.3:55407. Response time: 16906.25 ms Url: http://192.168.1.2:8096/mediabrowser/dashboard/dashboard.html2014-03-15 09:46:23.0869 Debug - SessionWebSocketListener: Received Identity message: Dashboard|2b37a1c9bb4ef4494c5d549b28f3e231639f526d|3.0.5171.19958|Firefox 27.02014-03-15 09:46:23.2744 Info - App: DashboardInfoWebSocketListener Begin transmitting over websocket to 192.168.1.3:554102014-03-15 09:46:24.5869 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Plugins/SecurityInfo2014-03-15 09:46:24.6025 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55407. Response time: 1046.875 ms Url: http://192.168.1.2:8096/mediabrowser/Plugins/SecurityInfo2014-03-15 09:46:24.6025 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Notifications/d346910290dfc268bdf2c915f879ab89/Summary2014-03-15 09:46:24.6181 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/dashboard/dashboardInfo2014-03-15 09:46:24.6337 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/News/Product?StartIndex=0&Limit=52014-03-15 09:46:24.6337 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55406. Response time: 1078.125 ms Url: http://192.168.1.2:8096/mediabrowser/Notifications/d346910290dfc268bdf2c915f879ab89/Summary2014-03-15 09:46:24.7744 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Packages/Updates?PackageType=System2014-03-15 09:46:24.7744 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55407. Response time: 0 ms Url: http://192.168.1.2:8096/mediabrowser/Packages/Updates?PackageType=System2014-03-15 09:46:24.7744 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Packages/Updates?PackageType=UserInstalled2014-03-15 09:46:24.8056 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55406. Response time: 31.25 ms Url: http://192.168.1.2:8096/mediabrowser/Packages/Updates?PackageType=UserInstalled2014-03-15 09:46:24.8837 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55412. Response time: 1328.125 ms Url: http://192.168.1.2:8096/mediabrowser/News/Product?StartIndex=0&Limit=52014-03-15 09:46:26.3212 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55411. Response time: 2765.625 ms Url: http://192.168.1.2:8096/mediabrowser/dashboard/dashboardInfo2014-03-15 09:46:29.7587 Debug - App: Searching for: PmpSearcher2014-03-15 09:46:29.7744 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:46:30.0400 Debug - App: Searching for: PmpSearcher2014-03-15 09:46:30.0869 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:46:31.0556 Debug - App: Searching for: PmpSearcher2014-03-15 09:46:31.0712 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:46:35.1025 Debug - App: Searching for: PmpSearcher2014-03-15 09:46:35.1494 Error - App: Error reported by Mono.Nat: An existing connection was forcibly closed by the remote host System.Net.Sockets.SocketException at System.Net.Sockets.Socket.ReceiveFrom(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, EndPoint& remoteEP) at System.Net.Sockets.UdpClient.Receive(IPEndPoint& remoteEP) at Mono.Nat.NatUtility.Receive(ISearcher searcher, List`1 clients) at Mono.Nat.NatUtility.SearchAndListen() 2014-03-15 09:46:42.4150 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55408. Response time: 37875 ms Url: http://192.168.1.2:8096/mediabrowser/Items/Counts?userId=d346910290dfc268bdf2c915f879ab892014-03-15 09:46:42.4150 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55409. Response time: 37843.75 ms Url: http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?sortBy=SortName2014-03-15 09:46:42.4619 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Items/Counts?userId=d346910290dfc268bdf2c915f879ab892014-03-15 09:46:42.4619 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?SortBy=random&IncludeItemTypes=Movie%2CTrailer&Limit=6&ImageTypes=Primary&Recursive=true2014-03-15 09:46:42.4619 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?SortBy=random&IncludeItemTypes=Series&Limit=6&ImageTypes=Primary&Recursive=true2014-03-15 09:46:42.4931 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?SortBy=random&IncludeItemTypes=MusicAlbum&Limit=6&ImageTypes=Primary&Recursive=true2014-03-15 09:46:42.6181 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55412. Response time: 156.25 ms Url: http://192.168.1.2:8096/mediabrowser/Items/Counts?userId=d346910290dfc268bdf2c915f879ab892014-03-15 09:46:42.6494 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55407. Response time: 187.5 ms Url: http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?SortBy=random&IncludeItemTypes=MusicAlbum&Limit=6&ImageTypes=Primary&Recursive=true2014-03-15 09:46:42.6650 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55411. Response time: 203.125 ms Url: http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?SortBy=random&IncludeItemTypes=Series&Limit=6&ImageTypes=Primary&Recursive=true2014-03-15 09:46:42.7119 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55409. Response time: 250 ms Url: http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?SortBy=random&IncludeItemTypes=Movie%2CTrailer&Limit=6&ImageTypes=Primary&Recursive=true2014-03-15 09:46:46.8994 Info - App: HttpClientManager GET: http://bot.whatismyipaddress.com/2014-03-15 09:46:51.1181 Debug - App: Searching for: PmpSearcher Link to comment Share on other sites More sharing options...
Jobber8742 9 Posted March 15, 2014 Share Posted March 15, 2014 (edited) 2014-03-15 09:47:23.4150 Debug - HttpServer: HTTP GET http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?Recursive=true&Limit=3&Filters=IsNotFolder&SortBy=DateCreated&SortOrder=Descending&ImageTypes=Primary&Ids=2b03fe760f91cb72a59751c8b21cfcae%2Cb91a4b75995e5439c432ea3f197367f5%2Cd457d5d4f00eddc054d42f6c5c165ec0%2Cdcc2265d4e96fe66939418033a4f9c112014-03-15 09:47:23.4150 Debug - HttpServer: HTTP Response 200 to 192.168.1.3:55412. Response time: 15.625 ms Url: http://192.168.1.2:8096/mediabrowser/Users/d346910290dfc268bdf2c915f879ab89/Items?Recursive=true&Limit=3&Filters=IsNotFolder&SortBy=DateCreated&SortOrder=Descending&ImageTypes=Primary&Ids=2b03fe760f91cb72a59751c8b21cfcae%2Cb91a4b75995e5439c432ea3f197367f5%2Cd457d5d4f00eddc054d42f6c5c165ec0%2Cdcc2265d4e96fe66939418033a4f9c112014-03-15 09:47:33.1806 Debug - App: Movie Theme Videos is attempting to get response stream2014-03-15 09:47:33.2119 Info - App: HttpClientManager GET: http://www.movie-list.com/trailers.php?id=rookie(2002)2014-03-15 09:47:33.5087 Info - App: HttpClientManager GET: http://www.hd-trailers.net/Library/R/2014-03-15 09:47:33.5712 Debug - App: Movie Theme Videos is attempting to get response stream2014-03-15 09:47:34.3837 Debug - App: Movie Theme Videos - No Video Found for [Rookie (2002)] | TmdbID: []2014-03-15 09:47:34.3837 Debug - App: Movie Theme Videos - No Video Found for [up in the Air (2009)] | TmdbID: []2014-03-15 09:47:34.3837 Debug - App: Movie Theme Videos - No Video Found for [Live Free or Die Hard (2007)] | TmdbID: []2014-03-15 09:47:34.3837 Debug - App: Movie Theme Videos - No Video Found for [iron Man (2008)] | TmdbID: []2014-03-15 09:47:34.8369 Info - App: HttpClientManager GET: http://www.movie-list.com/trailers.php?id=upintheair(2009)2014-03-15 09:47:35.3837 Info - App: HttpClientManager GET: http://www.hd-trailers.net/Library/U/2014-03-15 09:47:37.6650 Info - App: HttpClientManager GET: http://www.movie-list.com/trailers.php?id=livefreeordiehard(2007)2014-03-15 09:47:37.9150 Info - App: HttpClientManager GET: http://www.hd-trailers.net/Library/L/2014-03-15 09:47:38.0712 Info - App: HttpClientManager GET: http://www.movie-list.com/trailers.php?id=ironman(2008)2014-03-15 09:47:38.8056 Info - App: HttpClientManager GET: http://www.hd-trailers.net/Library/I/2014-03-15 09:50:32.5244 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Movies\Enchanted.lnk.lnk2014-03-15 09:50:33.2587 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Movies\Hook.lnk.lnk2014-03-15 09:50:33.4462 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Movies\Who Framed Roger Rabbit.lnk.lnk2014-03-15 09:50:47.2119 Debug - App: Searching for: UpnpSearcher2014-03-15 09:50:47.6181 Debug - App: UPnP Response: Router advertised a 'urn:schemas-upnp-org:service:WANIPConnection:1' service2014-03-15 09:50:47.6181 Debug - App: Found device at: http://192.168.1.1:5000/Public_UPNP_gatedesc.xml2014-03-15 09:50:47.6181 Debug - App: Parsed device as: 192.168.1.1:50002014-03-15 09:50:47.6181 Debug - App: Fetching service list: 192.168.1.1:50002014-03-15 09:50:47.6806 Debug - App: UPnP Response: Router advertised a 'urn:schemas-upnp-org:service:WANPPPConnection:' service2014-03-15 09:50:47.6806 Debug - App: Found device at: http://192.168.1.1:5000/Public_UPNP_gatedesc.xml2014-03-15 09:50:47.6806 Debug - App: Parsed device as: 192.168.1.1:50002014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Parsed services list2014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Found service: urn:schemas-upnp-org:service:Layer3Forwarding:12014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Found service: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:12014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Found service: urn:schemas-upnp-org:service:WANEthernetLinkConfig:12014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Found service: urn:schemas-upnp-org:service:WANIPConnection:12014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Found upnp service at: /Public_UPNP_C32014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Assuming control Uri is relative: /Public_UPNP_C32014-03-15 09:50:47.9775 Debug - App: 192.168.1.1:5000: Handshake Complete2014-03-15 09:50:47.9775 Debug - App: NAT device found: 192.168.1.22014-03-15 09:50:48.1962 Info - App: Creating port map on port 80962014-03-15 09:50:48.1962 Debug - App: Initiating request to: http://192.168.1.1:5000/Public_UPNP_C32014-03-15 09:50:48.2119 Error - App: Error creating port forwarding rules Error 402: InvalidArgs Mono.Nat.MappingException at Mono.Nat.Upnp.UpnpNatDevice.EndCreatePortMap(IAsyncResult result) at Mono.Nat.AbstractNatDevice.CreatePortMap(Mapping mapping) at MediaBrowser.Server.Implementations.EntryPoints.ExternalPortForwarding.CreatePortMap(INatDevice device, Int32 port) at MediaBrowser.Server.Implementations.EntryPoints.ExternalPortForwarding.CreateRules(INatDevice device) at MediaBrowser.Server.Implementations.EntryPoints.ExternalPortForwarding.NatUtility_DeviceFound(Object sender, DeviceEventArgs e) 2014-03-15 09:50:50.2275 Debug - App: Running MovieXmlProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\Live Free or Die Hard (2007)\Live Free or Die Hard.mkv2014-03-15 09:50:50.3525 Debug - App: MovieXmlProvider returned no metadata for \\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\Live Free or Die Hard (2007)\Live Free or Die Hard.mkv2014-03-15 09:50:50.3681 Debug - App: Running BaseGenreCleaner for \\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\Live Free or Die Hard (2007)\Live Free or Die Hard.mkv2014-03-15 09:50:50.3994 Debug - App: Running FFProbeProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\Live Free or Die Hard (2007)\Live Free or Die Hard.mkv2014-03-15 09:50:50.5087 Debug - App: Saving \\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\Live Free or Die Hard (2007)\Live Free or Die Hard.mkv to database.2014-03-15 09:50:59.7744 Debug - App: Running MovieXmlProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Iron Man Collection [boxset]\Iron Man (2008)\Iron Man.mkv2014-03-15 09:50:59.8681 Debug - App: MovieXmlProvider returned no metadata for \\MEDIASERVER-PC\Server\Media\Video\Movies\Iron Man Collection [boxset]\Iron Man (2008)\Iron Man.mkv2014-03-15 09:50:59.8681 Debug - App: Running BaseGenreCleaner for \\MEDIASERVER-PC\Server\Media\Video\Movies\Iron Man Collection [boxset]\Iron Man (2008)\Iron Man.mkv2014-03-15 09:50:59.8681 Debug - App: Running FFProbeProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Iron Man Collection [boxset]\Iron Man (2008)\Iron Man.mkv2014-03-15 09:50:59.8837 Debug - App: Saving \\MEDIASERVER-PC\Server\Media\Video\Movies\Iron Man Collection [boxset]\Iron Man (2008)\Iron Man.mkv to database.2014-03-15 09:52:04.4306 Debug - App: Running MovieXmlProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Rookie (2002)\The Rookie.mkv2014-03-15 09:52:04.4462 Debug - App: Running MovieXmlProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Up in the Air (2009)\Up in the Air.mkv2014-03-15 09:52:04.5244 Debug - App: MovieXmlProvider returned no metadata for \\MEDIASERVER-PC\Server\Media\Video\Movies\Rookie (2002)\The Rookie.mkv2014-03-15 09:52:04.5244 Debug - App: Running BaseGenreCleaner for \\MEDIASERVER-PC\Server\Media\Video\Movies\Rookie (2002)\The Rookie.mkv2014-03-15 09:52:04.5244 Debug - App: Running FFProbeProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Rookie (2002)\The Rookie.mkv2014-03-15 09:52:04.5244 Debug - App: Saving \\MEDIASERVER-PC\Server\Media\Video\Movies\Rookie (2002)\The Rookie.mkv to database.2014-03-15 09:52:04.5400 Debug - App: MovieXmlProvider returned no metadata for \\MEDIASERVER-PC\Server\Media\Video\Movies\Up in the Air (2009)\Up in the Air.mkv2014-03-15 09:52:04.5400 Debug - App: Running BaseGenreCleaner for \\MEDIASERVER-PC\Server\Media\Video\Movies\Up in the Air (2009)\Up in the Air.mkv2014-03-15 09:52:04.5400 Debug - App: Running FFProbeProvider for \\MEDIASERVER-PC\Server\Media\Video\Movies\Up in the Air (2009)\Up in the Air.mkv2014-03-15 09:52:04.5869 Debug - App: Saving \\MEDIASERVER-PC\Server\Media\Video\Movies\Up in the Air (2009)\Up in the Air.mkv to database.2014-03-15 09:53:33.0087 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Kids\Disney Buddies Collection [boxset]\Santa Buddies.lnk.lnk2014-03-15 09:53:33.0556 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Kids\Disney Buddies Collection [boxset]\Santa Paws 2 - Santa Pups.lnk.lnk2014-03-15 09:53:33.0712 Debug - BaseItem: Found shortcut at \\MEDIASERVER-PC\Server\Media\Video\Kids\Disney Buddies Collection [boxset]\The Search for Santa Paws.lnk.lnk2014-03-15 09:53:33.3525 Debug - App: Running SeriesXmlProvider for \\MEDIASERVER-PC\Server\Media\Video\Kids\Cadillacs and Dinosaurs2014-03-15 09:53:33.3994 Error - App: Error in Media Browser Xml Root element is missing. System.Xml.XmlException at System.Xml.XmlTextReaderImpl.Throw(Exception e) at System.Xml.XmlTextReaderImpl.ParseDocumentContent() at System.Xml.XmlTextReaderImpl.Read() at System.Xml.XmlReader.MoveToContent() at MediaBrowser.Controller.Providers.BaseItemXmlParser`1.Fetch(T item, String metadataFile, XmlReaderSettings settings, Encoding encoding, CancellationToken cancellationToken) at MediaBrowser.Controller.Providers.BaseItemXmlParser`1.Fetch(T item, String metadataFile, CancellationToken cancellationToken) at MediaBrowser.Providers.TV.SeriesXmlProvider.Fetch(LocalMetadataResult`1 result, String path, CancellationToken cancellationToken) at MediaBrowser.Providers.BaseXmlProvider`1.<GetMetadata>d__0.MoveNext() --- End of stack trace from previous location where exception was thrown --- at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task) at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task) at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd(Task task) at MediaBrowser.Providers.Manager.MetadataService`2.<RefreshWithProviders>d__23.MoveNext() Edited March 15, 2014 by Jobber8742 Link to comment Share on other sites More sharing options...
ebr 14903 Posted March 15, 2014 Share Posted March 15, 2014 Your images are so badly compressed I cannot read the text but it looks like the one with the plain cover has no media information and that would explain the plain cover. As for attaching logs, select "More Reply Options" and you will get a bigger form with attachment capabilities. Link to comment Share on other sites More sharing options...
Jobber8742 9 Posted March 15, 2014 Share Posted March 15, 2014 Your images are so badly compressed I cannot read the text but it looks like the one with the plain cover has no media information and that would explain the plain cover. As for attaching logs, select "More Reply Options" and you will get a bigger form with attachment capabilities. This is interesting because I thought it was odd that I wasn't seeing the media information. I figured they just changed something in the latest release that didn't show it anymore, but looking at it again, it seems only the newly transferred movies and tv shows aren't displaying the media information. Any idea what could cause the media info to not be processed? It seems that these are the only movies that are doing this, but it also seems to be doing it with all new tv shows I'm adding to the library as well. Cover Art pops up as TV Series for those though, I'm assuming due to the folder structure. Link to comment Share on other sites More sharing options...
ebr 14903 Posted March 15, 2014 Share Posted March 15, 2014 Any idea what could cause the media info to not be processed? Read from the beginning of this very thread... Link to comment Share on other sites More sharing options...
Jobber8742 9 Posted March 15, 2014 Share Posted March 15, 2014 (edited) Read from the beginning of this very thread... But the files are completed before it processes the files. I know they have to be. I've moved the files, deleting everything but the video file, then rescanned, then turned off the mediabrowser server, then moved the files back, then turned the server back on. It scans and still outputs what I was showing you. I've tried this like 5 times. I was having the problem before like you mentioned in the thread but then I solved it by using the temporary folder. Now, it's doing this in spite of the use of the temporary folder. And the server is off when I transfer the files into the library folder, so I'm not seeing how the files could still be incomplete when scanned. I should mention that I did more investigating, and all of my tv shows are actually fine. I can see the video info on all of them. It's just these few movies that aren't working. Edited March 15, 2014 by Jobber8742 Link to comment Share on other sites More sharing options...
ebr 14903 Posted March 15, 2014 Share Posted March 15, 2014 Then that simply means that FFProbe is not able to decipher that particular file. Are there errors in the log if you refresh one of them? Link to comment Share on other sites More sharing options...
Jobber8742 9 Posted March 15, 2014 Share Posted March 15, 2014 (edited) Then that simply means that FFProbe is not able to decipher that particular file. Are there errors in the log if you refresh one of them? Here's the snipet of the code where one was added: 014-03-15 18:02:17.4169 Debug - CoverArt: CoverArt will treat \\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\Live Free or Die Hard (2007)\folder.jpg 2014-03-15 18:02:17.4326 Debug - CoverArt: CoverArt using profile default for Live Free or Die Hard 2014-03-15 18:02:17.4326 Debug - CoverArt: CoverArt using frame type default from image set Case for Live Free or Die Hard I've attached the whole log for clarity as well. Interestingly enough, I tried to move this particular title outside of the the Die Hard Collection boxset and it applied the correct Blu-Ray overlay. I then moved it back inside the boxset, rescanned, and it reverted back to no overlay. server log.txt Edited March 16, 2014 by Jobber8742 Link to comment Share on other sites More sharing options...
ebr 14903 Posted March 16, 2014 Share Posted March 16, 2014 CA is working properly here. This is all about whether or not the particular item has any specific information about the video within it. In one case it does and another it doesn't. So, for the purpose of this topic we know what is happening and there is nothing else to be done. You should take your issue to a new topic in the server area about obtaining video information for this title BUT, I didn't see you actually manually refresh that item from the editor to force ffprobe to run again so I'm not sure if you've run that test yet. Also, there is this error in your log which indicates some other issues: Access to the path '\\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\collection.xml' is denied. System.UnauthorizedAccessException at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath) at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost) at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share) at MediaBrowser.Providers.Savers.XmlSaverHelpers.Save(StringBuilder xml, String path, List`1 xmlTagsUsed) at MediaBrowser.Providers.Savers.BoxSetXmlSaver.Save(IHasMetadata item, CancellationToken cancellationToken) at MediaBrowser.Providers.Manager.ProviderManager.<SaveMetadata>d__5e.MoveNext() But, CA is doing what it is supposed to here so you should take this to the server area. Thanks. Link to comment Share on other sites More sharing options...
Jobber8742 9 Posted March 16, 2014 Share Posted March 16, 2014 CA is working properly here. This is all about whether or not the particular item has any specific information about the video within it. In one case it does and another it doesn't. So, for the purpose of this topic we know what is happening and there is nothing else to be done. You should take your issue to a new topic in the server area about obtaining video information for this title BUT, I didn't see you actually manually refresh that item from the editor to force ffprobe to run again so I'm not sure if you've run that test yet. Also, there is this error in your log which indicates some other issues: Access to the path '\\MEDIASERVER-PC\Server\Media\Video\Movies\Die Hard Collection [boxset]\collection.xml' is denied. System.UnauthorizedAccessException at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath) at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost) at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share) at MediaBrowser.Providers.Savers.XmlSaverHelpers.Save(StringBuilder xml, String path, List`1 xmlTagsUsed) at MediaBrowser.Providers.Savers.BoxSetXmlSaver.Save(IHasMetadata item, CancellationToken cancellationToken) at MediaBrowser.Providers.Manager.ProviderManager.<SaveMetadata>d__5e.MoveNext() But, CA is doing what it is supposed to here so you should take this to the server area. Thanks. I appreciate all the help. I tried the refresh option several times and it did nothing in the log; however, I just ran the server update and now when I hit refresh, the correct information is being grabbed. Must've been a bug that was fixed with this update. Again, thanks for the help. Link to comment Share on other sites More sharing options...
Recommended Posts
Create an account or sign in to comment
You need to be a member in order to leave a comment
Create an account
Sign up for a new account in our community. It's easy!
Register a new accountSign in
Already have an account? Sign in here.
Sign In Now