Jump to content

Emby-server killed after a while

Steve L

By Steve L
in Linux

 Share

Recommended Posts

Steve L

Steve L   0

Posted
Posted

Hello,

I've been deploying emby on a dietpi (raspberry pi distrib) for a few days and everything works smoothly except the server will crash after a moment (see sample init.d status below):

XXX@Stream-server:~# /etc/init.d/emby-server status
● emby-server.service - Emby Server is a personal media server with apps on just about every device.
   Loaded: loaded (/usr/lib/systemd/system/emby-server.service; enabled; vendor preset: enabled)
   Active: failed (Result: signal) since Sat 2018-07-28 19:40:15 CEST; 2h 48min ago
  Process: 383 ExecStart=/opt/emby-server/bin/emby-server (code=killed, signal=ABRT)
 Main PID: 383 (code=killed, signal=ABRT)

juil. 28 15:17:29 Stream-server emby-server[383]: Info App: Sqlite compiler options: COMPILER=gcc-6.4.1 20170707,ENABLE_COLUMN_METADATA,ENABLE_DBSTAT_VTAB,ENABLE_FTS3,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS3_TOKENIZER,ENABLE_FTS5,ENABLE_JS…=1
juil. 28 15:17:29 Stream-server emby-server[383]: Info App: Default journal_mode for /var/lib/emby/data/sync14.db is wal
juil. 28 15:17:29 Stream-server emby-server[383]: Info App: PRAGMA synchronous=1
juil. 28 15:17:29 Stream-server emby-server[383]: Info App: Entry point completed: Emby.Server.Sync.SyncManagerEntryPoint. Duration: 0.091151 seconds
juil. 28 15:17:29 Stream-server emby-server[383]: Info App: Starting entry point Emby.Server.Sync.SyncNotificationEntryPoint
juil. 28 15:17:29 Stream-server emby-server[383]: Info App: Entry point completed: Emby.Server.Sync.SyncNotificationEntryPoint. Duration: 0.0148415 seconds
juil. 28 15:17:29 Stream-server emby-server[383]: Info App: All entry points have started
juil. 28 19:40:15 Stream-server systemd[1]: emby-server.service: Main process exited, code=killed, status=6/ABRT
juil. 28 19:40:15 Stream-server systemd[1]: emby-server.service: Unit entered failed state.
juil. 28 19:40:15 Stream-server systemd[1]: emby-server.service: Failed with result 'signal'.
Hint: Some lines were ellipsized, use -l to show in full.


XXX@Stream-server:~# /etc/init.d/emby-server restart
[ ok ] Restarting emby-server (via systemctl): emby-server.service.


XXX@Stream-server:~# /etc/init.d/emby-server status
● emby-server.service - Emby Server is a personal media server with apps on just about every device.
   Loaded: loaded (/usr/lib/systemd/system/emby-server.service; enabled; vendor preset: enabled)
   Active: failed (Result: signal) since Sat 2018-07-28 23:02:24 CEST; 1h 36min ago
  Process: 9630 ExecStart=/opt/emby-server/bin/emby-server (code=killed, signal=ABRT)
 Main PID: 9630 (code=killed, signal=ABRT)

juil. 28 22:29:18 Stream-server emby-server[9630]: Info App: Default journal_mode for /var/lib/emby/data/sync14.db is wal
juil. 28 22:29:18 Stream-server emby-server[9630]: Info App: PRAGMA synchronous=1
juil. 28 22:29:18 Stream-server emby-server[9630]: Info App: Entry point completed: Emby.Server.Sync.SyncManagerEntryPoint. Duration: 0.0742366 seconds
juil. 28 22:29:18 Stream-server emby-server[9630]: Info App: Starting entry point Emby.Server.Sync.SyncNotificationEntryPoint
juil. 28 22:29:18 Stream-server emby-server[9630]: Info App: Entry point completed: Emby.Server.Sync.SyncNotificationEntryPoint. Duration: 0.0177944 seconds
juil. 28 22:29:18 Stream-server emby-server[9630]: Info App: All entry points have started
juil. 28 23:02:24 Stream-server emby-server[9630]: *** Error in `/opt/emby-server/system/EmbyServer': double free or corruption (fasttop): 0x749104d0 ***
juil. 28 23:02:24 Stream-server systemd[1]: emby-server.service: Main process exited, code=killed, status=6/ABRT
juil. 28 23:02:24 Stream-server systemd[1]: emby-server.service: Unit entered failed state.
juil. 28 23:02:24 Stream-server systemd[1]: emby-server.service: Failed with result 'signal'.

As you can see, the crash can occur after few hours or minutes, I have read similar topics and tried removing any plugin and disabling audioDB (it seems on another topics that a recent change in URL was causing regular crashes)

 

logs in /var/lib/emby-server only monitor network requests and does not seems to bring any clues...

2018-07-28 22:32:32.302 Info HttpServer: HTTP Response 200 to 192.168.1.14. Time: 573ms. http://mystream.server:8092/emby/Libraries/AvailableOptions?LibraryContentType=music&IsNewLibrary=true
2018-07-28 22:33:11.454 Info HttpServer: HTTP GET http://mystream.server:8092/emby/Library/VirtualFolders. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:61.0) Gecko/20100101 Firefox/61.0
2018-07-28 22:33:11.472 Info HttpServer: HTTP Response 200 to 192.168.1.14. Time: 18ms. http://mystream.server:8092/emby/Library/VirtualFolders
2018-07-28 22:34:10.537 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml. UserAgent: FDSSDP
2018-07-28 22:34:10.542 Info HttpServer: HTTP Response 200 to 192.168.1.14. Time: 5ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml
2018-07-28 22:38:38.756 Info HttpServer: HTTP GET http://mystream.server:8092/emby/System/Configuration/dlna. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:61.0) Gecko/20100101 Firefox/61.0
2018-07-28 22:38:38.772 Info HttpServer: HTTP GET http://mystream.server:8092/emby/users. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:61.0) Gecko/20100101 Firefox/61.0
2018-07-28 22:38:38.797 Info HttpServer: HTTP Response 200 to 192.168.1.14. Time: 41ms. http://mystream.server:8092/emby/System/Configuration/dlna
2018-07-28 22:38:38.814 Info HttpServer: HTTP Response 200 to 192.168.1.14. Time: 42ms. http://mystream.server:8092/emby/users
2018-07-28 22:41:13.823 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:41:13.827 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 4ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml
2018-07-28 22:41:13.940 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/contentdirectory.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:41:14.057 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 117ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/contentdirectory.xml
2018-07-28 22:41:14.072 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/connectionmanager.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:41:14.126 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 55ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/connectionmanager.xml
2018-07-28 22:41:14.134 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/events. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:41:14.135 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 1ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/events
2018-07-28 22:41:14.141 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/events. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:41:14.149 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 8ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/events
2018-07-28 22:43:43.319 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:43:43.323 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 4ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml
2018-07-28 22:43:43.433 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/contentdirectory.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:43:43.441 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 8ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/contentdirectory.xml
2018-07-28 22:43:43.451 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/connectionmanager.xml. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:43:43.454 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 3ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/connectionmanager.xml
2018-07-28 22:43:43.461 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/events. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:43:43.463 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 2ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/events
2018-07-28 22:43:43.470 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/events. UserAgent: UPnP/1.0 DLNADOC/1.50 Kodi
2018-07-28 22:43:43.472 Info HttpServer: HTTP Response 200 to 192.168.1.50. Time: 2ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/connectionmanager/events
2018-07-28 22:44:52.939 Info HttpServer: HTTP GET http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml. UserAgent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50
2018-07-28 22:44:52.944 Info HttpServer: HTTP Response 200 to 192.168.1.14. Time: 6ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/description.xml
2018-07-28 23:00:04.054 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/events. UserAgent:
2018-07-28 23:00:04.057 Info HttpServer: HTTP Response 200 to 192.168.1.26. Time: 3ms. http://192.168.1.23:8092/dlna/7ba9132c1513480c85857a72ddd579f5/contentdirectory/events

I'm a bit lost with it and any tips would be welcome (I will look for more comprehensive logs and have activated debugging in admin panel...)

 

thanks for your time!

Link to comment
Share on other sites
Luke Emby Team

Luke   37238

Posted
Posted

Can you try disabling the server's dlna features, and then restarting it? thanks.

Link to comment
Share on other sites
Steve L

Steve L   0

Posted
  • Author
Posted

Hello,

I have disabled it (but it's one of the feature I need so I hope it is not that).

 

the mode debug brought more interesting detail on the last fault:

2018-07-29 01:37:17.190 Debug HttpClient: POST http://192.168.1.26:49154/AVTransport/ctrl
2018-07-29 01:37:17.196 Info HttpServer: HTTP Response 204 to 192.168.1.44. Time: 28ms. http://192.168.1.23:8092/emby/Sessions/4caed4abc9bf06d42a49b5015c50f4c3/Playing/stop 
2018-07-29 01:37:17.262 Info HttpServer: HTTP Response 500 to 192.168.1.26. Time: 79964ms. http://192.168.1.23:8092/audio/283eb39aaf9ccf70fb1f04517c7525c2/stream.flac?DeviceId=uuid:9ab0c000-f668-11de-9976-ac44f241df06&MediaSourceId=283eb39aaf9ccf70fb1f04517c7525c2&Static=true&StartTimeTicks=30000000&Tag=56ef4a4896010e6cedcb509256856b10&dlnaheaders=true 
2018-07-29 01:37:33.977 Debug App: SessionInfoWebSocketListener stop transmitting over websocket to 192.168.1.44:40197
2018-07-29 01:39:21.625 Debug SessionManager: Disposing session controller WebSocketController
2018-07-29 01:48:42.130 Error HttpServer: WebSocket ConnectionClosedPrematurely
2018-07-29 01:48:42.136 Error HttpServer: Error closing web socket response
	*** Error Report ***
	Version: 3.5.1.0
	Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
	Operating system: Unix 4.14.52.7
	64-Bit OS: False
	64-Bit Process: False
	User Interactive: True
	Processor count: 4
	Program data path: /var/lib/emby
	Application directory: /opt/emby-server/system
	System.Net.Sockets.SocketException (32): Broken pipe
	   at SocketHttpListener.SocketStream.Write(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.InternalWrite(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.DisposeCore()
	   at SocketHttpListener.Net.HttpResponseStream.Dispose(Boolean disposing)
	   at SocketHttpListener.Net.HttpConnection.Close(Boolean force)
	   at SocketHttpListener.Net.HttpListenerResponse.Close(Boolean force)
	   at EmbyServer.SocketSharp.WebSocketSharpListener.TryClose(HttpListenerContext ctx, Int32 statusCode)
	System.Net.Sockets.SocketException
	   at SocketHttpListener.SocketStream.Write(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.InternalWrite(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.DisposeCore()
	   at SocketHttpListener.Net.HttpResponseStream.Dispose(Boolean disposing)
	   at SocketHttpListener.Net.HttpConnection.Close(Boolean force)
	   at SocketHttpListener.Net.HttpListenerResponse.Close(Boolean force)
	   at EmbyServer.SocketSharp.WebSocketSharpListener.TryClose(HttpListenerContext ctx, Int32 statusCode)
	
2018-07-29 01:48:57.135 Info HttpServer: HTTP POST http://192.168.1.23:8092/emby/Sessions/4caed4abc9bf06d42a49b5015c50f4c3/Playing?ItemIds=283eb39aaf9ccf70fb1f04517c7525c2&PlayCommand=PlayNow&StartPositionTicks=30000000. UserAgent: Mozilla/5.0 (Linux; Android 7.1.1; GT-I9195 Build/NMF26V; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/52.0.2743.100 Safari/537.36
2018-07-29 01:48:57.147 Debug Dlna: Ampli salon - Received PlayRequest: PlayNow
2018-07-29 01:48:57.151 Info Dlna: No matching device profile found. The default will need to be used.
	DeviceDescription:
	FriendlyName:Ampli salon
	Manufacturer:Yamaha Corporation
	ManufacturerUrl:http://www.yamaha.com/
	ModelDescription:MusicCast
	ModelName:R-N602
	ModelNumber:N602
	ModelUrl:http://www.yamaha.com/
	SerialNumber:0BF6E753
2018-07-29 01:48:57.155 Debug Dlna: Ampli salon - Playlist created
2018-07-29 01:48:57.155 Debug Dlna: Ampli salon - Playing 1 items
2018-07-29 01:48:57.155 Debug Dlna: Ampli salon - SetAvTransport Uri: http://192.168.1.23:8092/audio/283eb39aaf9ccf70fb1f04517c7525c2/stream.flac?DeviceId=uuid:9ab0c000-f668-11de-9976-ac44f241df06&MediaSourceId=283eb39aaf9ccf70fb1f04517c7525c2&Static=true&StartTimeTicks=30000000&Tag=56ef4a4896010e6cedcb509256856b10&dlnaheaders=true DlnaHeaders: DLNA.ORG_PN=FLAC;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01D00000000000000000000000000000
2018-07-29 01:48:57.156 Debug HttpClient: POST http://192.168.1.26:49154/AVTransport/ctrl
2018-07-29 01:49:15.708 Error HttpClient: Error getting response from http://192.168.1.26:49154/AVTransport/ctrl
	*** Error Report ***
	Version: 3.5.1.0
	Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
	Operating system: Unix 4.14.52.7
	64-Bit OS: False
	64-Bit Process: False
	User Interactive: True
	Processor count: 4
	Program data path: /var/lib/emby
	Application directory: /opt/emby-server/system
	System.Net.Http.HttpRequestException: No route to host ---> System.Net.Sockets.SocketException: No route to host
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	   --- End of inner exception stack trace ---
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	   at System.Threading.Tasks.ValueTask`1.get_Result()
	   at System.Net.Http.HttpConnectionPool.CreateConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken)
	   at System.Threading.Tasks.ValueTask`1.get_Result()
	   at System.Net.Http.HttpConnectionPool.WaitForCreatedConnectionAsync(ValueTask`1 creationTask)
	   at System.Threading.Tasks.ValueTask`1.get_Result()
	   at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken)
	   at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
	   at System.Net.Http.DecompressionHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
	   at System.Net.Http.HttpClient.FinishSendAsyncUnbuffered(Task`1 sendTask, HttpRequestMessage request, CancellationTokenSource cts, Boolean disposeCts)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	System.Net.Http.HttpRequestException
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	   at System.Threading.Tasks.ValueTask`1.get_Result()
	   at System.Net.Http.HttpConnectionPool.CreateConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken)
	   at System.Threading.Tasks.ValueTask`1.get_Result()
	   at System.Net.Http.HttpConnectionPool.WaitForCreatedConnectionAsync(ValueTask`1 creationTask)
	   at System.Threading.Tasks.ValueTask`1.get_Result()
	   at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken)
	   at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
	   at System.Net.Http.DecompressionHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken)
	   at System.Net.Http.HttpClient.FinishSendAsyncUnbuffered(Task`1 sendTask, HttpRequestMessage request, CancellationTokenSource cts, Boolean disposeCts)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	InnerException: System.Net.Sockets.SocketException
	System.Net.Sockets.SocketException (113): No route to host
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	
2018-07-29 01:49:15.742 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 3.5.1.0
	Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_armhf.deb
	Operating system: Unix 4.14.52.7
	64-Bit OS: False
	64-Bit Process: False
	User Interactive: True
	Processor count: 4
	Program data path: /var/lib/emby
	Application directory: /opt/emby-server/system
	System.Net.Http.HttpRequestException: No route to host ---> System.Net.Sockets.SocketException: No route to host
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	   --- End of inner exception stack trace ---
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsync(HttpRequestOptions options, String httpMethod)
	   at Emby.Dlna.PlayTo.SsdpHttpClient.SendCommandAsync(String baseUrl, DeviceService service, String command, String postData, Boolean logRequest, String header)
	   at Emby.Dlna.PlayTo.Device.SetAvTransport(String url, String header, String metaData, CancellationToken cancellationToken)
	   at Emby.Dlna.PlayTo.PlayToController.SetPlaylistIndex(Int32 index)
	   at Emby.Dlna.PlayTo.PlayToController.PlayItems(IEnumerable`1 items)
	   at Emby.Dlna.PlayTo.PlayToController.SendPlayCommand(PlayRequest command, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.SendMessageToSession[T](SessionInfo session, String name, T data, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.SendPlayCommand(String controllingSessionId, String sessionId, PlayRequest command, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IHttpRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	System.Net.Http.HttpRequestException
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsync(HttpRequestOptions options, String httpMethod)
	   at Emby.Dlna.PlayTo.SsdpHttpClient.SendCommandAsync(String baseUrl, DeviceService service, String command, String postData, Boolean logRequest, String header)
	   at Emby.Dlna.PlayTo.Device.SetAvTransport(String url, String header, String metaData, CancellationToken cancellationToken)
	   at Emby.Dlna.PlayTo.PlayToController.SetPlaylistIndex(Int32 index)
	   at Emby.Dlna.PlayTo.PlayToController.PlayItems(IEnumerable`1 items)
	   at Emby.Dlna.PlayTo.PlayToController.SendPlayCommand(PlayRequest command, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.SendMessageToSession[T](SessionInfo session, String name, T data, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.SendPlayCommand(String controllingSessionId, String sessionId, PlayRequest command, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IHttpRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	InnerException: System.Net.Sockets.SocketException
	System.Net.Sockets.SocketException (113): No route to host
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	   at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken)
	
2018-07-29 01:49:15.742 Info HttpServer: HTTP Response 500 to 192.168.1.44. Time: 18617ms (slow). http://192.168.1.23:8092/emby/Sessions/4caed4abc9bf06d42a49b5015c50f4c3/Playing?ItemIds=283eb39aaf9ccf70fb1f04517c7525c2&PlayCommand=PlayNow&StartPositionTicks=30000000

At 1:48 I was finishing playing with both app and webUI to send music on my amp, according to status, the server got killed at 1:57 but no logs past the last response 500 at 01:49:15. Can't figure yet if it's related, I will keep you up to date with the dlna-disabled restart...

Link to comment
Share on other sites
Steve L

Steve L   0

Posted
  • Author
Posted

The server is now up and running for like 7h with both local and internet playback, browsing on the UI, everything is fine, so I assume the DLNA is indeed involved with the crashes.

What would be the next step for investigation?

 

meanwhile I will try to reenable dlna server (not player) as it's the only feature I need, is there any place where I can get more detailed logs for DLNA?

Link to comment
Share on other sites
Steve L

Steve L   0

Posted
  • Author
Posted

Dietpi-software installs the 3.3.1.0+68.1 from repository, I have then manually upgraded with the latest package available (3.5.1.0) from the website

 

btw I have re enabled DLNA playback yesterday and the server is dead this morning (after 1.5 hours), logs are attached.

I have noticed in DLNA setting I can activate debug also, hope the next time we will have sufficient trail.

Log.txt

Link to comment
Share on other sites
Luke Emby Team

Luke   37238

Posted
Posted

Try going into dlna settings and changing the dlna alive message interval to 1800. That is the new default for new installations. Thanks.

Link to comment
Share on other sites
Luke Emby Team

Luke   37238

Posted
Posted

Ok for now I would suggest turning off the donation features until we chase this down. Thanks.

Link to comment
Share on other sites
Steve L

Steve L   0

Posted
  • Author
Posted (edited)

Hello,

A little update, the server still up and running after 24h having reenabled DLNA (except presence advertising), but one odd things I have noticed:

  • I only used to listen song here and there to test the server but if I play on the amp there is no playlist, for example, only the first song of the album will play and then it stops.
  • More over, there isn't any osd to control what is currently played, and if I go to "remote control" I have a kind of uninitialiazed control panel (see pic)

5b60e0d59cf36_controller.png

 

I can't tell what is buggy from what is not supported and could thoses bugs (if any) could be related with server crashing?

 

EDIT: I have noticed I could try the same with my android device (and the app installed) and I can control it from the desktop, got picture and time tracker, in short everything is fine but it has the app. may it be a trouble in protocol handling (on emby or yamaha side...)?

Edited by Steve L
Link to comment
Share on other sites
Luke Emby Team

Luke   37238

Posted
Posted

I haven't tested personally with a yamaha so it's possible there could be issues. Looking at your screenshot, it would appear to me that the initial playback command is successful but then it's just not getting status updates from the device, or getting them but not understanding them.

Link to comment
Share on other sites
Steve L

Steve L   0

Posted
  • Author
Posted (edited)

Yamaha claimed the RN602 is fully DLNA compliant but If I have time I will monitor network messages on LAN and compare them with the android device com or other DLNA playto software...

Edited by Steve L
Link to comment
Share on other sites
  • 2 months later...
Mr_christer

Mr_christer   0

Posted
Posted

Just came here to report that I have the same problem with emby and dietpi so I assume this is an OS related issue. My emby server worked great for 12 hours today and then crashed tonight around 3am. I'll disable DLNA and see if this fixes it.

Link to comment
Share on other sites
Luke Emby Team

Luke   37238

Posted
Posted

Strange as this log looks ok to me. Can you try turning off the server's dlna features, then restart the server and see if it happens again? thanks.

Link to comment
Share on other sites

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 account

Sign in

Already have an account? Sign in here.

Sign In Now
 Share
Go to topic listing
×
×
  • Create New...