m-joy 15 Posted December 6, 2016 Posted December 6, 2016 Hello, i have the strange feeling, that emby for kodi crashes my NAS Application. This already happended twice. While updating the database, the emby server on my synology DS216play just quit and needed to be restarted manually... I added some log files....The database update was running on an nvidia shield android tv box with the latest beta of kodi and the kodi for emby beta . kodi crash.zip
Angelblue05 4132 Posted December 6, 2016 Posted December 6, 2016 Thanks for reporting Sent from my iPhone using Tapatalk
Angelblue05 4132 Posted December 6, 2016 Posted December 6, 2016 Try the following, go to the addon settings > sync options and reduce the number of download thread to two or one and let me know if it's better. Sent from my iPhone using Tapatalk
m-joy 15 Posted December 7, 2016 Author Posted December 7, 2016 Hi thank you. I will try 2 and See what happens
m-joy 15 Posted December 7, 2016 Author Posted December 7, 2016 HI, the server is crashing again.... But i can not be sure if this is a problem with KODI since i also use a new beta for the android tv client.... Can you see in the debug log clearly that this is caused by kodi? I added a new log. I think this is the last activity : 2016-12-07 20:45:30.8706 Debug PortMapper: Searching for: PmpSearcher 2016-12-07 20:45:46.8886 Debug PortMapper: Searching for: PmpSearcher 2016-12-07 20:45:53.1197 Debug Dlna: Search Request Received From 192.168.2.2:59082, Target = ssdp:all 2016-12-07 20:45:53.1563 Debug PortMapper: Found NAT device: uuid:75802409-bccb-40e7-8e6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:1 2016-12-07 20:45:53.1579 Debug NetworkManager: Querying interface: lo. Type: Loopback. Status: Unknown 2016-12-07 20:45:53.1584 Debug NetworkManager: Querying interface: eth0. Type: Ethernet. Status: Up 2016-12-07 20:45:53.1594 Debug PortMapper: Found NAT device: uuid:75802409-bccb-40e7-8e6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:1 2016-12-07 20:45:53.1684 Debug PortMapper: Found NAT device: uuid:75802409-bccb-40e7-9f6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:2 2016-12-07 20:45:53.1684 Debug PortMapper: Found NAT device: uuid:75802409-bccb-40e7-9f6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:2 2016-12-07 20:45:53.1694 Debug PortMapper: Calling Nat.Handle on uuid:75802409-bccb-40e7-8e6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:1 2016-12-07 20:45:53.1694 Debug PortMapper: Found device at: http://192.168.2.1:49000/igddesc.xml 2016-12-07 20:45:53.1694 Debug PortMapper: Parsed device as: 192.168.2.1:49000 2016-12-07 20:45:53.1694 Debug PortMapper: Fetching service list: 192.168.2.1:49000 2016-12-07 20:45:53.1694 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1694 Debug PortMapper: Creating port map on port 8096 2016-12-07 20:45:53.1694 Debug PortMapper: Initiating request to: http://192.168.2.1:49000 2016-12-07 20:45:53.1748 Debug PortMapper: Creating port map on port 8920 2016-12-07 20:45:53.1748 Debug PortMapper: Initiating request to: http://192.168.2.1:49000 2016-12-07 20:45:53.1775 Debug PortMapper: Calling Nat.Handle on uuid:75802409-bccb-40e7-8e6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:1 2016-12-07 20:45:53.1775 Debug PortMapper: Found device at: http://192.168.2.1:49000/igddesc.xml 2016-12-07 20:45:53.1775 Debug PortMapper: Parsed device as: 192.168.2.1:49000 2016-12-07 20:45:53.1775 Debug PortMapper: Fetching service list: 192.168.2.1:49000 2016-12-07 20:45:53.1775 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1775 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1775 Debug PortMapper: Calling Nat.Handle on uuid:75802409-bccb-40e7-9f6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:2 2016-12-07 20:45:53.1784 Debug PortMapper: Found device at: http://192.168.2.1:49000/igd2desc.xml 2016-12-07 20:45:53.1784 Debug PortMapper: Parsed device as: 192.168.2.1:49000 2016-12-07 20:45:53.1784 Debug PortMapper: Fetching service list: 192.168.2.1:49000 2016-12-07 20:45:53.1784 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1784 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1801 Debug PortMapper: Calling Nat.Handle on uuid:75802409-bccb-40e7-9f6a-3431C4C567C6::urn:schemas-upnp-org:service:WANIPConnection:2 2016-12-07 20:45:53.1801 Debug PortMapper: Found device at: http://192.168.2.1:49000/igd2desc.xml 2016-12-07 20:45:53.1801 Debug PortMapper: Parsed device as: 192.168.2.1:49000 2016-12-07 20:45:53.1801 Debug PortMapper: Fetching service list: 192.168.2.1:49000 2016-12-07 20:45:53.1801 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1803 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1803 Info HttpClient: HttpClientManager POST: http://192.168.2.1:49000 2016-12-07 20:45:53.1814 Debug PortMapper: NAT device found: 192.168.2.2 2016-12-07 20:45:53.1814 Debug PortMapper: Creating port map on port 8096 2016-12-07 20:45:53.1814 Info HttpClient: HttpClientManager POST: http://192.168.2.1:49000 2016-12-07 20:45:53.1814 Debug PortMapper: Initiating request to: http://192.168.2.1:49000 2016-12-07 20:45:53.1830 Debug PortMapper: Creating port map on port 8920 2016-12-07 20:45:53.1830 Debug PortMapper: Initiating request to: http://192.168.2.1:49000 2016-12-07 20:45:53.1849 Info HttpClient: HttpClientManager POST: http://192.168.2.1:49000 2016-12-07 20:45:53.1859 Info HttpClient: HttpClientManager POST: http://192.168.2.1:49000 2016-12-07 20:45:53.1898 Error HttpClient: Error getting response from http://192.168.2.1:49000 *** Error Report *** Version: 3.1.248.0 Command line: /var/packages/EmbyServer/target/server/MediaBrowser.Server.Mono.exe -package synology -programdata /var/packages/EmbyServer/target/var -ffmpeg /var/packages/EmbyServer/target/ffmpeg/bin/ffmpeg -ffprobe /var/packages/EmbyServer/target/ffmpeg/bin/ffprobe -restartpath /var/packages/EmbyServer/scripts/restart Operating system: Unix 3.10.77.0 64-Bit OS: False 64-Bit Process: False Mono: 4.4.2 (Stable 4.4.2.11/f72fe45 Fri Oct 7 19:47:45 CST 2016) Processor count: 2 Program data path: /var/packages/EmbyServer/target/var Application directory: /volume1/@appstore/EmbyServer/releases/v3.1.248-beta System.AggregateException: One or more errors occurred. ---> System.Net.WebException: The remote server returned an error: (500) Internal Server Error. at System.Net.HttpWebRequest.EndGetResponse (IAsyncResult asyncResult) <0x72b7afb0 + 0x00160> in <filename unknown>:0 at System.Threading.Tasks.TaskFactory`1[TResult].FromAsyncCoreLogic (IAsyncResult iar, System.Func`2 endFunction, System.Action`1 endAction, System.Threading.Tasks.Task`1 promise, Boolean requiresSynchronization) <0x72b7aad0 + 0x00087> in <filename unknown>:0 --- End of inner exception stack trace --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () <0x7135f8a8 + 0x00024> in <filename unknown>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) <0x7135f360 + 0x000bb> in <filename unknown>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) <0x7135f280 + 0x0008b> in <filename unknown>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) <0x7258eef0 + 0x0003f> in <filename unknown>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () <0x723f2a98 + 0x0001b> in <filename unknown>:0 at Emby.Common.Implementations.HttpClientManager.HttpClientManager+<SendAsyncInternal>d__20.MoveNext () <0x72c10de8 + 0x00cf7> in <filename unknown>:0 ---> (Inner Exception #0) System.Net.WebException: The remote server returned an error: (500) Internal Server Error. at System.Net.HttpWebRequest.EndGetResponse (IAsyncResult asyncResult) <0x72b7afb0 + 0x00160> in <filename unknown>:0 at System.Threading.Tasks.TaskFactory`1[TResult].FromAsyncCoreLogic (IAsyncResult iar, System.Func`2 endFunction, System.Action`1 endAction, System.Threading.Tasks.Task`1 promise, Boolean requiresSynchronization) <0x72b7aad0 + 0x00087> in <filename unknown>:0 <--- System.AggregateException at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () <0x7135f8a8 + 0x00024> in <filename unknown>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) <0x7135f360 + 0x000bb> in <filename unknown>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) <0x7135f280 + 0x0008b> in <filename unknown>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) <0x7258eef0 + 0x0003f> in <filename unknown>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () <0x723f2a98 + 0x0001b> in <filename unknown>:0 at Emby.Common.Implementations.HttpClientManager.HttpClientManager+<SendAsyncInternal>d__20.MoveNext () <0x72c10de8 + 0x00cf7> in <filename unknown>:0 InnerException: System.Net.WebException System.Net.WebException: The remote server returned an error: (500) Internal Server Error. at System.Net.HttpWebRequest.EndGetResponse (IAsyncResult asyncResult) <0x72b7afb0 + 0x00160> in <filename unknown>:0 at System.Threading.Tasks.TaskFactory`1[TResult].FromAsyncCoreLogic (IAsyncResult iar, System.Func`2 endFunction, System.Action`1 endAction, System.Threading.Tasks.Task`1 promise, Boolean requiresSynchronization) <0x72b7aad0 + 0x00087> in <filename unknown>:0 at System.Net.HttpWebRequest.EndGetResponse (IAsyncResult asyncResult) <0x72b7afb0 + 0x00160> in <filename unknown>:0 at System.Threading.Tasks.TaskFactory`1[TResult].FromAsyncCoreLogic (IAsyncResult iar, System.Func`2 endFunction, System.Action`1 endAction, System.Threading.Tasks.Task`1 promise, Boolean requiresSynchronization) <0x72b7aad0 + 0x00087> in <filename unknown>:0
m-joy 15 Posted December 7, 2016 Author Posted December 7, 2016 There are also some things going on with DLNA. I disabled the DLNA server in the dashboard, could this be a problem?
Luke 42077 Posted December 7, 2016 Posted December 7, 2016 Can you please try removing all plugins and seeing if the issue persists with the core server? thanks !
m-joy 15 Posted December 8, 2016 Author Posted December 8, 2016 Okay this is strange. I had 4 plugins installed, the Kodi Fast Startup plugin, Movie Theme SOngs, TV Theme Songs and Trailer. I deinstalled all, restarted the server, but only the kodi plugin was removed. The other ones still exist. I deinstalled them again, restarted the hole NAS. They are still there. I did this three times now, but i can not remove them.... Back to the error, i found out that this is caused when jumping between kodi with emby for kodi, and the android TV emby app. I can not say exactly when this happens, but maybe it has to do with changes in database and synchronisation....
m-joy 15 Posted December 8, 2016 Author Posted December 8, 2016 Well i needed to manually remove the dlls... not sure if this was a good idea. but they are gone now.... I will see what happens with the crashing now, but there is definetly something weird with the plugin management... -joy@DS216play:/var/packages/EmbyServer/target/var$ cd plugins m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ dir total 5436 drwxr-xr-x 3 embysvr users 4096 Dec 8 11:44 . drwxr-xr-x 13 embysvr users 4096 Dec 8 12:11 .. drwxr-xr-x 2 embysvr users 4096 Dec 7 13:06 configurations -rw-r--r-- 1 embysvr users 102400 Dec 7 22:43 MediaBrowser.Plugins.Anime.dll -rw-r--r-- 1 embysvr users 5329408 Dec 6 22:33 Mediabrowser.Plugins.Trailers.d ll -rw-r--r-- 1 embysvr users 68608 Dec 6 22:35 moviethemesongs.dll -rw-r--r-- 1 embysvr users 47616 Dec 6 22:39 themesongs.dll m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ sudo rm MediaBrowser.Plugins.Anime.dll Password: m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ sudo rm Mediabrowser.Plugins.Trailers.dll m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ sudo rm moviethemesongs.dll m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ sudo rm themesongs.dll m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ dir total 12 drwxr-xr-x 3 embysvr users 4096 Dec 8 12:18 . drwxr-xr-x 13 embysvr users 4096 Dec 8 12:16 .. drwxr-xr-x 2 embysvr users 4096 Dec 7 13:06 configurations m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins$ cd configurations/ m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins/configurations$ dir total 20 drwxr-xr-x 2 embysvr users 4096 Dec 7 13:06 . drwxr-xr-x 3 embysvr users 4096 Dec 8 12:18 .. -rw-r--r-- 1 embysvr users 701 Nov 17 18:31 MediaBrowser.Plugins.VuPlus.xml -rw-r--r-- 1 embysvr users 568 Dec 7 13:07 MovieThemeSongs.xml -rw-r--r-- 1 embysvr users 441 Dec 7 13:06 ThemeSongs.xml m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins/configurations$ sudo rm MovieThemeSongs.xml m-joy@DS216play:/var/packages/EmbyServer/target/var/plugins/configurations$ sudo rm ThemeSongs.xml
m-joy 15 Posted December 8, 2016 Author Posted December 8, 2016 (edited) Okay after Deleting all plugins, the error is still present. Emby server crashes.Here is how i can make the Server crash like 90% of time:- go to emby Android TV apllication on nvidia shield and Mark a watched movie as unwatched- go to kodi on the nvidia shield device and wait for the emby plugin to Update-start the movie marked as unwatched and forward a bunch of minutes so that it is marked as being watched- stop the movie and wait for kodi to sync- quit kodi and Start the android tv emby application- Server is now offline But to be honest, i can not say for sure if this is caused by the kodi plugin since the log files seems to throw out unhanled expections exceptions like crazy. i will try to use emby as normal without the kodi and see if the strange bugs still occur. I will do a reboot now of the nas and just use kodi for emby. lets see what happens... logs_new.zip Edited December 8, 2016 by m-joy
Angelblue05 4132 Posted December 8, 2016 Posted December 8, 2016 Keep us posted. I think the server has been prone to crashing. It doesn't seem to recover well anymore. This can possibly be linked to the issue I reported @@Luke (in dev server) Sent from my iPhone using Tapatalk
m-joy 15 Posted December 8, 2016 Author Posted December 8, 2016 Hi there. After working with emby today and did not use kodi, the same error appears again... So i might say that this has nothing to do with the kodi plugin. Starting the android tv app for emby sometimes leads to an strange Android volley blabla error and sometimes the system is dead then... Seems to be an deep issue which i can not completely localize =(
Luke 42077 Posted December 8, 2016 Posted December 8, 2016 Hi there. After working with emby today and did not use kodi, the same error appears again... So i might say that this has nothing to do with the kodi plugin. Starting the android tv app for emby sometimes leads to an strange Android volley blabla error and sometimes the system is dead then... Seems to be an deep issue which i can not completely localize =( Please make sure to see how to report a problem. Thanks !
m-joy 15 Posted December 8, 2016 Author Posted December 8, 2016 Please make sure to see how to report a problem. Thanks ! Hi, i do not really know what you mean with this post. Greets
Angelblue05 4132 Posted December 9, 2016 Posted December 9, 2016 (edited) @@m-joy try setting the download thread to one (addon settings > sync options) and let me know if it does better for you. I don't see why it would make a difference if there's less than 15 items to sync, but you never know... Sent from my iPhone using Tapatalk Edited December 9, 2016 by Angelblue05
m-joy 15 Posted December 9, 2016 Author Posted December 9, 2016 Hi Angelblue, thank you. I did that. But i can say for sure that it is not the kodi thing making my server crash. I think we can close this thread. I opened a new thread in the test server area: https://emby.media/community/index.php?/topic/42360-server-version-312480-beta-crash-in-1minuten/ Greeting 1
Recommended Posts