Jump to content

Server crashing within minutes


Darkieth

Recommended Posts

Darkieth

Server lasts a few minutes before crashing and requiring a manual start. Been working fine and I haven't altered anything recently. Somewhat inept at reading logs so could anyone tell me what's causing it? Thanks in advance.

 

EDIT: Currently been up for around 30 minutes without crashing, perhaps solved itself.

System.IO.IOException: Unable to read data from the transport connection: Connection reset by peer. ---> System.Net.Sockets.SocketException: Connection reset by peer
   --- End of inner exception stack trace ---
   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error)
   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
   at System.Net.Security.SslState.ThrowIfExceptional()
   at System.Net.Security.SslState.CheckThrow(Boolean authSuccessCheck, Boolean shutdownCheck)
   at System.Net.Security.SslState.CheckOldKeyDecryptedData(Memory`1 buffer)
   at System.Net.Security.SslState.HandleQueuedCallback(Object& queuedStateRequest)
   at System.Net.Security.SslState.FinishHandshakeRead(Int32 newState)
   at System.Net.Security.SslState.FinishHandshake(Exception e, AsyncProtocolRequest asyncRequest)
   at System.Net.Security.SslState.RehandshakeCompleteCallback(IAsyncResult result)
   at System.Net.LazyAsyncResult.Complete(IntPtr userToken)
   at System.Net.LazyAsyncResult.ProtectedInvokeCallback(Object result, IntPtr userToken)
   at System.Net.AsyncProtocolRequest.CompleteUserWithError(Exception e)
   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location where exception was thrown ---
   at System.Threading.ThreadPoolWorkQueue.Dispatch()
System.IO.IOException
   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error)
   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
   at System.Net.Security.SslState.ThrowIfExceptional()
   at System.Net.Security.SslState.CheckThrow(Boolean authSuccessCheck, Boolean shutdownCheck)
   at System.Net.Security.SslState.CheckOldKeyDecryptedData(Memory`1 buffer)
   at System.Net.Security.SslState.HandleQueuedCallback(Object& queuedStateRequest)
   at System.Net.Security.SslState.FinishHandshakeRead(Int32 newState)
   at System.Net.Security.SslState.FinishHandshake(Exception e, AsyncProtocolRequest asyncRequest)
   at System.Net.Security.SslState.RehandshakeCompleteCallback(IAsyncResult result)
   at System.Net.LazyAsyncResult.Complete(IntPtr userToken)
   at System.Net.LazyAsyncResult.ProtectedInvokeCallback(Object result, IntPtr userToken)
   at System.Net.AsyncProtocolRequest.CompleteUserWithError(Exception e)
   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location where exception was thrown ---
   at System.Threading.ThreadPoolWorkQueue.Dispatch()
InnerException: System.Net.Sockets.SocketException
System.Net.Sockets.SocketException (104): Connection reset by peer

2018-07-28 13:50:47.091 Info Main: Application path: /opt/emby-server/system/EmbyServer.dll
2018-07-28 13:50:47.099 Info Main: Emby
	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}_amd64.deb
	Operating system: Unix 4.15.0.24
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 6
	Program data path: /var/lib/emby
	Application directory: /opt/emby-server/system
2018-07-28 13:50:47.307 Info App: Application version: 3.6.0.2
2018-07-28 13:50:47.308 Info App: Loading assemblies
2018-07-28 13:50:47.330 Info App: File /var/lib/emby/plugins/Emby.Server.CinemaMode.dll has version 1.0.2.0
2018-07-28 13:50:47.330 Info App: File /opt/emby-server/system/plugins/Emby.Server.CinemaMode.dll has version 1.0.2.0
2018-07-28 13:50:47.330 Info App: File /var/lib/emby/plugins/OpenSubtitles.dll has version 1.0.2.0
2018-07-28 13:50:47.330 Info App: File /opt/emby-server/system/plugins/OpenSubtitles.dll has version 1.0.2.0
2018-07-28 13:50:47.330 Info App: File /var/lib/emby/plugins/IMVDb.dll has version 1.0.1.0
2018-07-28 13:50:47.330 Info App: File /opt/emby-server/system/plugins/IMVDb.dll has version 1.0.1.0
2018-07-28 13:50:47.342 Info App: Loading Emby.Server.CinemaMode, Version=1.0.2.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/Emby.Server.CinemaMode.dll
2018-07-28 13:50:47.342 Info App: Loading PodCasts, Version=1.1.4.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/PodCasts.dll
2018-07-28 13:50:47.342 Info App: Loading OpenSubtitles, Version=1.0.2.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/OpenSubtitles.dll
2018-07-28 13:50:47.342 Info App: Loading IMVDb, Version=1.0.1.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/IMVDb.dll
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.Plugins.Anime, Version=1.2.8.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/MediaBrowser.Plugins.Anime.dll
2018-07-28 13:50:47.342 Info App: Loading MBBackup, Version=1.2.3.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/MBBackup.dll
2018-07-28 13:50:47.342 Info App: Loading Lastfm, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/Lastfm.dll
2018-07-28 13:50:47.342 Info App: Loading statistics, Version=2.0.5.1, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/Statistics.dll
2018-07-28 13:50:47.342 Info App: Loading AutoBoxSets, Version=1.1.3.0, Culture=neutral, PublicKeyToken=null from /var/lib/emby/plugins/AutoBoxSets.dll
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.Api, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.WebDashboard, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.Model, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.Common, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.Controller, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.Providers, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Photos, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Server.Implementations, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Server.MediaEncoding, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Dlna, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.LocalMetadata, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Notifications, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading MediaBrowser.XbmcMetadata, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Server.Connect, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.342 Info App: Loading Emby.Server.Sync, Version=3.6.0.2, Culture=neutral, PublicKeyToken=null
2018-07-28 13:50:47.500 Info SqliteUserRepository: Sqlite version: 3.24.0
2018-07-28 13:50:47.501 Info SqliteUserRepository: Sqlite compiler options: COMPILER=gcc-6.3.0,ENABLE_COLUMN_METADATA,ENABLE_DBSTAT_VTAB,ENABLE_FTS3,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS3_TOKENIZER,ENABLE_FTS5,ENABLE_JSON1,ENABLE_PREUPDATE_HOOK,ENABLE_RTREE,ENABLE_SESSION,ENABLE_UNLOCK_NOTIFY,ENABLE_UPDATE_DELETE_LIMIT,LIKE_DOESNT_MATCH_BLOBS,MAX_SCHEMA_RETRY=25,MAX_VARIABLE_NUMBER=250000,OMIT_LOOKASIDE,SECURE_DELETE,THREADSAFE=1
2018-07-28 13:50:47.532 Info SqliteUserRepository: Default journal_mode for /var/lib/emby/data/users.db is wal
2018-07-28 13:50:47.534 Info SqliteUserRepository: PRAGMA synchronous=1
2018-07-28 13:50:47.549 Info AuthenticationRepository: Default journal_mode for /var/lib/emby/data/authentication.db is wal
2018-07-28 13:50:47.549 Info AuthenticationRepository: PRAGMA synchronous=1
2018-07-28 13:50:47.640 Info ActivityRepository: Default journal_mode for /var/lib/emby/data/activitylog.db is wal
2018-07-28 13:50:47.640 Info ActivityRepository: PRAGMA synchronous=1
2018-07-28 13:50:47.645 Info SqliteDisplayPreferencesRepository: Default journal_mode for /var/lib/emby/data/displaypreferences.db is wal
2018-07-28 13:50:47.645 Info SqliteDisplayPreferencesRepository: PRAGMA synchronous=1
2018-07-28 13:50:47.759 Debug XmlSerializer: Deserializing file /var/lib/emby/config/users/3e1877189f324bf39c09d62a15459328/policy.xml
2018-07-28 13:50:47.808 Info SqliteItemRepository: Default journal_mode for /var/lib/emby/data/library.db is wal
2018-07-28 13:50:47.809 Info SqliteItemRepository: PRAGMA synchronous=1
2018-07-28 13:50:47.897 Debug XmlSerializer: Deserializing file /var/lib/emby/config/encoding.xml
2018-07-28 13:50:48.005 Info HttpServer: Calling ServiceStack AppHost.Init
2018-07-28 13:50:48.539 Info HttpServer: Adding HttpListener prefix http://+:8096/
2018-07-28 13:50:48.540 Info HttpServer: Adding HttpListener prefix https://+:8920/
2018-07-28 13:50:48.845 Info Skia: SkiaSharp version: 1.60.0.0
2018-07-28 13:50:48.876 Info TaskManager: Daily trigger for Configuration Backup set to fire at 7/29/18 12:10:00 AM, which is 619.185401336667 minutes from now.
2018-07-28 13:50:48.908 Info TaskManager: Daily trigger for Calculate statistics for all users set to fire at 7/29/18 5:00:00 AM, which is 909.184861465 minutes from now.
2018-07-28 13:50:48.912 Info TaskManager: Daily trigger for Chapter image extraction set to fire at 7/29/18 2:00:00 AM, which is 729.184800156667 minutes from now.
2018-07-28 13:50:48.917 Info TaskManager: Daily trigger for Rotate log file set to fire at 7/29/18 12:00:00 AM, which is 609.18470195 minutes from now.
2018-07-28 13:50:48.923 Info MediaEncoder: FFMpeg: /opt/emby-server/bin/ffmpeg
2018-07-28 13:50:48.923 Info MediaEncoder: FFProbe: /opt/emby-server/bin/ffprobe
2018-07-28 13:50:48.923 Info MediaEncoder: Validating media encoder at /opt/emby-server/bin/ffmpeg
2018-07-28 13:50:48.926 Info MediaEncoder: Running /opt/emby-server/bin/ffmpeg -decoders
2018-07-28 13:50:48.985 Info MediaEncoder: Decoder available: mpeg2video
2018-07-28 13:50:48.990 Info MediaEncoder: Decoder available: h264_cuvid
2018-07-28 13:50:48.990 Info MediaEncoder: Decoder available: hevc_cuvid
2018-07-28 13:50:48.990 Info MediaEncoder: Decoder available: mpeg2_cuvid
2018-07-28 13:50:48.991 Info MediaEncoder: Decoder available: mpeg4_cuvid
2018-07-28 13:50:48.991 Info MediaEncoder: Decoder available: vc1_cuvid
2018-07-28 13:50:48.991 Info MediaEncoder: Decoder available: vp8_cuvid
2018-07-28 13:50:48.991 Info MediaEncoder: Decoder available: vp9_cuvid
2018-07-28 13:50:48.996 Info MediaEncoder: Decoder available: ac3
2018-07-28 13:50:48.996 Info MediaEncoder: Decoder available: aac
2018-07-28 13:50:48.997 Info MediaEncoder: Decoder available: mp3
2018-07-28 13:50:48.997 Info MediaEncoder: Decoder available: h264
2018-07-28 13:50:48.997 Info MediaEncoder: Decoder available: hevc
2018-07-28 13:50:49.002 Info MediaEncoder: Running /opt/emby-server/bin/ffmpeg -encoders
2018-07-28 13:50:49.055 Info MediaEncoder: Encoder available: libx264
2018-07-28 13:50:49.055 Info MediaEncoder: Encoder available: mpeg4
2018-07-28 13:50:49.055 Info MediaEncoder: Encoder available: msmpeg4
2018-07-28 13:50:49.057 Info MediaEncoder: Encoder available: aac
2018-07-28 13:50:49.057 Info MediaEncoder: Encoder available: libmp3lame
2018-07-28 13:50:49.057 Info MediaEncoder: Encoder available: libopus
2018-07-28 13:50:49.057 Info MediaEncoder: Encoder available: libvorbis
2018-07-28 13:50:49.058 Info MediaEncoder: Encoder available: srt
2018-07-28 13:50:49.058 Info MediaEncoder: Encoder available: h264_nvenc
2018-07-28 13:50:49.058 Info MediaEncoder: Encoder available: hevc_nvenc
2018-07-28 13:50:49.061 Info MediaEncoder: Encoder available: h264_vaapi
2018-07-28 13:50:49.061 Info MediaEncoder: Encoder available: hevc_vaapi
2018-07-28 13:50:49.061 Info MediaEncoder: Encoder available: h264_v4l2m2m
2018-07-28 13:50:49.061 Info MediaEncoder: Encoder available: libwebp
2018-07-28 13:50:49.061 Info MediaEncoder: Encoder available: ac3
2018-07-28 13:50:49.063 Info MediaEncoder: Running /opt/emby-server/bin/ffmpeg -protocols
2018-07-28 13:50:49.111 Info MediaEncoder: Encoder validation complete
2018-07-28 13:50:49.111 Info MediaEncoder: ffmpeg supported protocols: async,cache,concat,crypto,data,ffrtmphttp,file,ftp,gopher,hls,http,httpproxy,https,mmsh,mmst,pipe,rtmp,rtmps,rtmpt,rtmpts,rtp,srtp,subfile,tcp,tls,udp,udplite,unix,smb
2018-07-28 13:50:49.113 Info App: ServerId: b2136b5f44634897ad34c3300667d1cc
2018-07-28 13:50:49.184 Info App: Starting entry point MediaBrowser.WebDashboard.ServerEntryPoint
2018-07-28 13:50:49.186 Info App: Entry point completed: MediaBrowser.WebDashboard.ServerEntryPoint. Duration: 0.0015308 seconds
2018-07-28 13:50:49.186 Info App: Starting entry point Emby.Dlna.Main.DlnaEntryPoint
2018-07-28 13:50:49.193 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Bravia (2012).xml
2018-07-28 13:50:49.300 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/foobar2000.xml
2018-07-28 13:50:49.300 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Bravia (2014).xml
2018-07-28 13:50:49.301 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Bravia (2013).xml
2018-07-28 13:50:49.302 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony PlayStation 3.xml
2018-07-28 13:50:49.302 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/DirecTV HD-DVR.xml
2018-07-28 13:50:49.303 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/MediaMonkey.xml
2018-07-28 13:50:49.303 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Blu-ray Player 2013.xml
2018-07-28 13:50:49.304 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Marantz.xml
2018-07-28 13:50:49.304 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Denon AVR.xml
2018-07-28 13:50:49.305 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony PlayStation 4.xml
2018-07-28 13:50:49.305 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Blu-ray Player 2016.xml
2018-07-28 13:50:49.306 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Blu-ray Player.xml
2018-07-28 13:50:49.307 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Dish Hopper-Joey.xml
2018-07-28 13:50:49.307 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Linksys DMA2100.xml
2018-07-28 13:50:49.308 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Panasonic Viera.xml
2018-07-28 13:50:49.308 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Popcorn Hour.xml
2018-07-28 13:50:49.309 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/LG Smart TV.xml
2018-07-28 13:50:49.309 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Blu-ray Player 2015.xml
2018-07-28 13:50:49.310 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Bravia (2010).xml
2018-07-28 13:50:49.311 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/WDTV Live.xml
2018-07-28 13:50:49.311 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Bravia (2011).xml
2018-07-28 13:50:49.312 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Default.xml
2018-07-28 13:50:49.312 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Samsung Smart TV.xml
2018-07-28 13:50:49.313 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sharp Smart TV.xml
2018-07-28 13:50:49.314 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Xbox One.xml
2018-07-28 13:50:49.315 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna/system/Sony Blu-ray Player 2014.xml
2018-07-28 13:50:49.316 Debug XmlSerializer: Deserializing file /var/lib/emby/config/dlna.xml
2018-07-28 13:50:49.414 Info App: Entry point completed: Emby.Dlna.Main.DlnaEntryPoint. Duration: 0.2284628 seconds
2018-07-28 13:50:49.414 Info App: Starting entry point Emby.Server.Connect.ConnectEntryPoint
2018-07-28 13:50:49.416 Info App: Loading data from /var/lib/emby/data/connect.txt
2018-07-28 13:50:49.424 Info App: Loading data from /var/lib/emby/data/wan.dat
2018-07-28 13:50:49.432 Info App: Entry point completed: Emby.Server.Connect.ConnectEntryPoint. Duration: 0.0178785 seconds
2018-07-28 13:50:49.432 Info App: Core startup complete
2018-07-28 13:50:49.432 Info App: Post-init migrations complete
2018-07-28 13:50:49.432 Info App: Starting entry point Emby.Security.PluginSecurityManager
2018-07-28 13:50:49.432 Info App: Entry point completed: Emby.Security.PluginSecurityManager. Duration: 4.03E-05 seconds
2018-07-28 13:50:49.432 Info App: Starting entry point Emby.Server.CinemaMode.IntrosEntryPoint
2018-07-28 13:50:49.433 Info App: Entry point completed: Emby.Server.CinemaMode.IntrosEntryPoint. Duration: 0.0004143 seconds
2018-07-28 13:50:49.433 Info App: Starting entry point Emby.Security.PluginSecurityManager
2018-07-28 13:50:49.433 Info App: Entry point completed: Emby.Security.PluginSecurityManager. Duration: 4.05E-05 seconds
2018-07-28 13:50:49.433 Info App: Starting entry point Emby.Security.PluginSecurityManager
2018-07-28 13:50:49.433 Info App: Entry point completed: Emby.Security.PluginSecurityManager. Duration: 5.61E-05 seconds
2018-07-28 13:50:49.433 Info App: Starting entry point MBBackup.ServerEntryPoint
2018-07-28 13:50:49.433 Info App: Entry point completed: MBBackup.ServerEntryPoint. Duration: 3.18E-05 seconds
2018-07-28 13:50:49.433 Info App: Starting entry point Lastfm.ServerEntryPoint
2018-07-28 13:50:49.434 Info App: Entry point completed: Lastfm.ServerEntryPoint. Duration: 0.0007245 seconds
2018-07-28 13:50:49.434 Info App: Starting entry point AutoBoxSets.ServerEntryPoint
2018-07-28 13:50:49.436 Debug XmlSerializer: Deserializing file /var/lib/emby/plugins/configurations/AutoBoxSets.xml
2018-07-28 13:50:49.444 Info App: Entry point completed: AutoBoxSets.ServerEntryPoint. Duration: 0.0106408 seconds
2018-07-28 13:50:49.444 Info App: Starting entry point MediaBrowser.Api.ApiEntryPoint
2018-07-28 13:50:49.445 Info App: Entry point completed: MediaBrowser.Api.ApiEntryPoint. Duration: 0.00014 seconds
2018-07-28 13:50:49.445 Info App: Starting entry point Emby.Server.Implementations.News.NewsEntryPoint
2018-07-28 13:50:49.445 Info App: Entry point completed: Emby.Server.Implementations.News.NewsEntryPoint. Duration: 0.0003496 seconds
2018-07-28 13:50:49.445 Info App: Starting entry point Emby.Server.Implementations.LiveTv.EmbyTV.EntryPoint
2018-07-28 13:50:49.449 Info App: Loading live tv data from /var/lib/emby/data/livetv/timers
2018-07-28 13:50:49.459 Debug XmlSerializer: Deserializing file /var/lib/emby/config/livetv.xml
2018-07-28 13:50:49.533 Debug App: Ping test result to http://192.168.1.53:8096/system/ping. Success: True
2018-07-28 13:50:49.536 Debug App: Ping test result to http://192.168.1.53:8096/system/ping. Success: True
2018-07-28 13:50:49.537 Info Dlna: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on 192.168.1.53
2018-07-28 13:50:49.629 Debug XmlSerializer: Deserializing file /var/lib/emby/root/default/FILM/options.xml
2018-07-28 13:50:49.683 Debug XmlSerializer: Deserializing file /var/lib/emby/root/default/Collections/options.xml
2018-07-28 13:50:49.684 Debug XmlSerializer: Deserializing file /var/lib/emby/root/default/LIVE/options.xml
2018-07-28 13:50:49.684 Debug XmlSerializer: Deserializing file /var/lib/emby/root/default/AUDIOBOOKS/options.xml
2018-07-28 13:50:49.684 Debug XmlSerializer: Deserializing file /var/lib/emby/root/default/MUSIC/options.xml
2018-07-28 13:50:49.689 Debug XmlSerializer: Deserializing file /var/lib/emby/root/default/TELEVISION/options.xml
2018-07-28 13:50:49.691 Info App: Entry point completed: Emby.Server.Implementations.LiveTv.EmbyTV.EntryPoint. Duration: 0.246232 seconds
2018-07-28 13:50:49.691 Info App: Starting entry point Emby.Server.Implementations.Library.DeviceAccessEntryPoint
2018-07-28 13:50:49.692 Info App: Entry point completed: Emby.Server.Implementations.Library.DeviceAccessEntryPoint. Duration: 0.0004407 seconds
2018-07-28 13:50:49.692 Info App: Starting entry point Emby.Server.Implementations.IO.LibraryMonitorStartup
2018-07-28 13:50:49.721 Info App: Entry point completed: Emby.Server.Implementations.IO.LibraryMonitorStartup. Duration: 0.0293442 seconds
2018-07-28 13:50:49.726 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.AutomaticRestartEntryPoint
2018-07-28 13:50:49.727 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.AutomaticRestartEntryPoint. Duration: 0.0009701 seconds
2018-07-28 13:50:49.727 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.ExternalPortForwarding
2018-07-28 13:50:49.728 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.ExternalPortForwarding. Duration: 0.00087 seconds
2018-07-28 13:50:49.728 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.KeepServerAwake
2018-07-28 13:50:49.728 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.KeepServerAwake. Duration: 0.0004245 seconds
2018-07-28 13:50:49.728 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.LibraryChangedNotifier
2018-07-28 13:50:49.730 Info LibraryMonitor: Watching directory /media/plex/MEDIA 2/MEDIA/MUSIC/LIVE
2018-07-28 13:50:49.730 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.LibraryChangedNotifier. Duration: 0.0021577 seconds
2018-07-28 13:50:49.730 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.RecordingNotifier
2018-07-28 13:50:49.732 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.RecordingNotifier. Duration: 0.0014267 seconds
2018-07-28 13:50:49.732 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.ServerEventNotifier
2018-07-28 13:50:49.735 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.ServerEventNotifier. Duration: 0.0030831 seconds
2018-07-28 13:50:49.735 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.StartupWizard
2018-07-28 13:50:49.736 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.StartupWizard. Duration: 0.0009777 seconds
2018-07-28 13:50:49.736 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.SystemEvents
2018-07-28 13:50:49.737 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.SystemEvents. Duration: 0.000376 seconds
2018-07-28 13:50:49.737 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.UdpServerEntryPoint
2018-07-28 13:50:49.739 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.UdpServerEntryPoint. Duration: 0.0021698 seconds
2018-07-28 13:50:49.739 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.UsageEntryPoint
2018-07-28 13:50:49.740 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.UsageEntryPoint. Duration: 0.0008534 seconds
2018-07-28 13:50:49.740 Info App: Starting entry point Emby.Server.Implementations.EntryPoints.UserDataChangeNotifier
2018-07-28 13:50:49.740 Info App: Entry point completed: Emby.Server.Implementations.EntryPoints.UserDataChangeNotifier. Duration: 0.0001533 seconds
2018-07-28 13:50:49.740 Info App: Starting entry point Emby.Server.Implementations.Devices.DeviceManagerEntryPoint
2018-07-28 13:50:49.741 Info App: Entry point completed: Emby.Server.Implementations.Devices.DeviceManagerEntryPoint. Duration: 0.0011762 seconds
2018-07-28 13:50:49.741 Info App: Starting entry point Emby.Server.Implementations.Collections.CollectionManagerEntryPoint
2018-07-28 13:50:49.742 Info App: Entry point completed: Emby.Server.Implementations.Collections.CollectionManagerEntryPoint. Duration: 0.0012759 seconds
2018-07-28 13:50:49.742 Info App: Starting entry point Emby.Server.Implementations.Activity.ActivityLogEntryPoint
2018-07-28 13:50:49.746 Info App: Entry point completed: Emby.Server.Implementations.Activity.ActivityLogEntryPoint. Duration: 0.0032054 seconds
2018-07-28 13:50:49.746 Info App: Starting entry point Emby.Server.MediaEncoding.Api.ApiEntryPoint
2018-07-28 13:50:49.747 Info App: Entry point completed: Emby.Server.MediaEncoding.Api.ApiEntryPoint. Duration: 0.00097 seconds
2018-07-28 13:50:49.747 Info App: Starting entry point Emby.Notifications.Notifications
2018-07-28 13:50:49.747 Info App: Entry point completed: Emby.Notifications.Notifications. Duration: 0.0005609 seconds
2018-07-28 13:50:49.747 Info App: Starting entry point MediaBrowser.XbmcMetadata.EntryPoint
2018-07-28 13:50:49.747 Info App: Entry point completed: MediaBrowser.XbmcMetadata.EntryPoint. Duration: 0.0001676 seconds
2018-07-28 13:50:49.747 Info App: Starting entry point Emby.Security.PluginSecurityManager
2018-07-28 13:50:49.748 Info App: Entry point completed: Emby.Security.PluginSecurityManager. Duration: 5.13E-05 seconds
2018-07-28 13:50:49.748 Info App: Starting entry point Emby.Server.Sync.SyncManagerEntryPoint
2018-07-28 13:50:49.754 Info App: Sqlite version: 3.24.0
2018-07-28 13:50:49.754 Info App: Sqlite compiler options: COMPILER=gcc-6.3.0,ENABLE_COLUMN_METADATA,ENABLE_DBSTAT_VTAB,ENABLE_FTS3,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS3_TOKENIZER,ENABLE_FTS5,ENABLE_JSON1,ENABLE_PREUPDATE_HOOK,ENABLE_RTREE,ENABLE_SESSION,ENABLE_UNLOCK_NOTIFY,ENABLE_UPDATE_DELETE_LIMIT,LIKE_DOESNT_MATCH_BLOBS,MAX_SCHEMA_RETRY=25,MAX_VARIABLE_NUMBER=250000,OMIT_LOOKASIDE,SECURE_DELETE,THREADSAFE=1
2018-07-28 13:50:49.755 Info App: Default journal_mode for /var/lib/emby/data/sync14.db is wal
2018-07-28 13:50:49.756 Info App: PRAGMA synchronous=1
2018-07-28 13:50:49.757 Info App: Entry point completed: Emby.Server.Sync.SyncManagerEntryPoint. Duration: 0.0097477 seconds
2018-07-28 13:50:49.757 Info App: Starting entry point Emby.Server.Sync.SyncNotificationEntryPoint
2018-07-28 13:50:49.759 Info App: Entry point completed: Emby.Server.Sync.SyncNotificationEntryPoint. Duration: 0.0014429 seconds
2018-07-28 13:50:49.759 Info App: All entry points have started
2018-07-28 13:50:49.862 Info LibraryMonitor: Watching directory /media/plex/MEDIA 1/MEDIA/TV SHOWS/720x265
2018-07-28 13:50:49.881 Info LibraryMonitor: Watching directory /media/plex/MEDIA 1/MEDIA/TV SHOWS/MIXED
2018-07-28 13:50:49.894 Info LibraryMonitor: Watching directory /media/plex/MEDIA 3/MEDIA/FILM/4K
2018-07-28 13:50:49.963 Info LibraryMonitor: Watching directory /media/plex/MEDIA 1/MEDIA/TV SHOWS/1080x265
2018-07-28 13:50:49.969 Info HttpClient: GET https://emby.media/community/index.php?/blog/rss/1-media-browser-developers-blog
2018-07-28 13:50:50.002 Info LibraryMonitor: Watching directory /media/plex/MEDIA 3/MEDIA/FILM/MIXED
2018-07-28 13:50:50.005 Info LibraryMonitor: Watching directory /media/plex/MEDIA 3/MEDIA/TV/4K
2018-07-28 13:50:50.008 Info LibraryMonitor: Watching directory /media/plex/MEDIA 3/MEDIA/TV/1080x265
2018-07-28 13:50:50.060 Info LibraryMonitor: Watching directory /media/plex/MEDIA 2/MEDIA/AUDIOBOOKS/ENGELSKA
2018-07-28 13:50:50.074 Info LibraryMonitor: Watching directory /media/plex/MEDIA 2/MEDIA/TV SHOWS/1080x265
2018-07-28 13:50:50.127 Info LibraryMonitor: Watching directory /media/plex/MEDIA 3/MEDIA/FILM/1080x265
2018-07-28 13:50:50.344 Info LibraryMonitor: Watching directory /media/plex/MEDIA 2/MEDIA/MUSIC/ARTISTS
2018-07-28 13:50:51.893 Info HttpServer: HTTP GET http://192.168.1.53:8096/web/strings/en-GB.json?v=1532778653047. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:51.893 Info HttpServer: HTTP GET http://192.168.1.53:8096/web/bower_components/emby-webcomponents/strings/en-gb.json?v=1532778653047. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:51.893 Info HttpServer: HTTP GET http://192.168.1.53:8096/web/favicon.ico. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:51.903 Debug HttpResultFactory: Transmit file /opt/emby-server/system/dashboard-ui/strings/en-GB.json
2018-07-28 13:50:51.903 Debug HttpResultFactory: Transmit file /opt/emby-server/system/dashboard-ui/favicon.ico
2018-07-28 13:50:51.903 Debug HttpResultFactory: Transmit file /opt/emby-server/system/dashboard-ui/bower_components/emby-webcomponents/strings/en-gb.json
2018-07-28 13:50:51.909 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 16ms. http://192.168.1.53:8096/web/favicon.ico 
2018-07-28 13:50:51.909 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 19ms. http://192.168.1.53:8096/web/bower_components/emby-webcomponents/strings/en-gb.json?v=1532778653047 
2018-07-28 13:50:51.909 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 20ms. http://192.168.1.53:8096/web/strings/en-GB.json?v=1532778653047 
2018-07-28 13:50:51.914 Info TaskManager: StartupTrigger fired for task: Check for plugin updates
2018-07-28 13:50:51.916 Info TaskManager: StartupTrigger fired for task: Check for application updates
2018-07-28 13:50:51.916 Info TaskManager: Queueing task PluginUpdateTask
2018-07-28 13:50:51.916 Info TaskManager: Queueing task SystemUpdateTask
2018-07-28 13:50:51.941 Info TaskManager: Executing Check for plugin updates
2018-07-28 13:50:51.941 Info TaskManager: Executing Check for application updates
2018-07-28 13:50:51.951 Info HttpClient: GET https://www.mb3admin.com/admin/service/EmbyPackages.json
2018-07-28 13:50:52.023 Debug App: No application update available.
2018-07-28 13:50:52.024 Info TaskManager: Check for application updates Completed after 0 minute(s) and 0 seconds
2018-07-28 13:50:52.041 Info TaskManager: ExecuteQueuedTasks
2018-07-28 13:50:52.072 Debug XmlSerializer: Deserializing file /var/lib/emby/config/branding.xml
2018-07-28 13:50:52.187 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/system/info/public. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.201 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 14ms. http://192.168.1.53:8096/emby/system/info/public 
2018-07-28 13:50:52.204 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/System/Info. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.316 Info App: App Activity: app: Emby Mobile, version: 3.6.0.2, deviceId: TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NCkgQXBwbGVXZWJLaXQvNTM3LjM2IChLSFRNTCwgbGlrZSBHZWNrbykgQ2hyb21lLzY3LjAuMzM5Ni45OSBTYWZhcmkvNTM3LjM2fDE1MzI3Nzc0NDE5NjY1, deviceName: Chrome
2018-07-28 13:50:52.346 Debug XmlSerializer: Deserializing file /var/lib/emby/config/users/3e1877189f324bf39c09d62a15459328/config.xml
2018-07-28 13:50:52.400 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 196ms. http://192.168.1.53:8096/emby/System/Info 
2018-07-28 13:50:52.406 Info HttpServer: HTTP POST http://192.168.1.53:8096/emby/Sessions/Capabilities/Full. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.408 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/Users/3e1877189f324bf39c09d62a15459328. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.408 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/DisplayPreferences/usersettings?userId=3e1877189f324bf39c09d62a15459328&client=emby. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.414 Info HttpServer: WS http://192.168.1.53:8096/embywebsocket?api_key=87f329c23e8e435eac062530dd41c169&deviceId=TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NCkgQXBwbGVXZWJLaXQvNTM3LjM2IChLSFRNTCwgbGlrZSBHZWNrbykgQ2hyb21lLzY3LjAuMzM5Ni45OSBTYWZhcmkvNTM3LjM2fDE1MzI3Nzc0NDE5NjY1. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.422 Debug HttpServer: Web socket connection allowed
2018-07-28 13:50:52.445 Debug SessionManager: Creating new WebSocketController
2018-07-28 13:50:52.472 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 64ms. http://192.168.1.53:8096/emby/DisplayPreferences/usersettings?userId=3e1877189f324bf39c09d62a15459328&client=emby 
2018-07-28 13:50:52.477 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 69ms. http://192.168.1.53:8096/emby/Users/3e1877189f324bf39c09d62a15459328 
2018-07-28 13:50:52.479 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/Users/3e1877189f324bf39c09d62a15459328. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.480 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 1ms. http://192.168.1.53:8096/emby/Users/3e1877189f324bf39c09d62a15459328 
2018-07-28 13:50:52.482 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/Users/3e1877189f324bf39c09d62a15459328. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.484 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 1ms. http://192.168.1.53:8096/emby/Users/3e1877189f324bf39c09d62a15459328 
2018-07-28 13:50:52.502 Info HttpServer: HTTP Response 204 to 192.168.1.61. Time: 97ms. http://192.168.1.53:8096/emby/Sessions/Capabilities/Full 
2018-07-28 13:50:52.512 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/web/configurationpages?pageType=PluginConfiguration&EnableInMainMenu=true. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.515 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/Plugins. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.527 Info HttpClient: GET https://www.mb3admin.com/admin/service/EmbyPackages.json
2018-07-28 13:50:52.552 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 39ms. http://192.168.1.53:8096/emby/web/configurationpages?pageType=PluginConfiguration&EnableInMainMenu=true 
2018-07-28 13:50:52.562 Info HttpServer: HTTP GET http://192.168.1.53:8096/web/css/images/logoblack.png. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:52.563 Info HttpServer: HTTP Response 304 to 192.168.1.61. Time: 1ms. http://192.168.1.53:8096/web/css/images/logoblack.png 
2018-07-28 13:50:53.177 Info TaskManager: Check for plugin updates Completed after 0 minute(s) and 1 seconds
2018-07-28 13:50:53.178 Info TaskManager: ExecuteQueuedTasks
2018-07-28 13:50:53.776 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 1262ms (slow). http://192.168.1.53:8096/emby/Plugins 
2018-07-28 13:50:53.780 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/web/configurationpages?pageType=PluginConfiguration. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:53.780 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 1ms. http://192.168.1.53:8096/emby/web/configurationpages?pageType=PluginConfiguration 
2018-07-28 13:50:53.891 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/System/Configuration. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:53.891 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/System/Logs. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:53.917 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 26ms. http://192.168.1.53:8096/emby/System/Configuration 
2018-07-28 13:50:53.919 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 29ms. http://192.168.1.53:8096/emby/System/Logs 
2018-07-28 13:50:54.548 Debug Dlna: Begin Sending Alive Notifications For All Devices
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification MediaServer - b2136b5f44634897ad34c3300667d1cc - http://192.168.1.53:8096/dlna/b2136b5f44634897ad34c3300667d1cc/description.xml
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification MediaServer - b2136b5f44634897ad34c3300667d1cc - http://192.168.1.53:8096/dlna/b2136b5f44634897ad34c3300667d1cc/description.xml
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification MediaServer - b2136b5f44634897ad34c3300667d1cc - http://192.168.1.53:8096/dlna/b2136b5f44634897ad34c3300667d1cc/description.xml
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification ContentDirectory - b2136b5f44634897ad34c3300667d1cc
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification ContentDirectory - b2136b5f44634897ad34c3300667d1cc
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification ConnectionManager - b2136b5f44634897ad34c3300667d1cc
2018-07-28 13:50:54.561 Debug Dlna: Sent alive notification ConnectionManager - b2136b5f44634897ad34c3300667d1cc
2018-07-28 13:50:54.561 Debug Dlna: Completed Sending Alive Notifications For All Devices
2018-07-28 13:50:56.164 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/System/Logs/Log?name=unhandled_6f53c478-aabf-40e9-880a-85b041e3aad9.txt. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
2018-07-28 13:50:56.171 Debug HttpResultFactory: Transmit file /var/lib/emby/logs/unhandled_6f53c478-aabf-40e9-880a-85b041e3aad9.txt
2018-07-28 13:50:56.171 Info HttpServer: HTTP Response 200 to 192.168.1.61. Time: 9ms. http://192.168.1.53:8096/emby/System/Logs/Log?name=unhandled_6f53c478-aabf-40e9-880a-85b041e3aad9.txt 
2018-07-28 13:50:58.201 Info HttpServer: HTTP GET http://192.168.1.53:8096/emby/System/Logs/Log?name=embyserver.txt. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/67.0.3396.99 Safari/537.36
Edited by Darkieth
Link to comment
Share on other sites

Darkieth

Where do I find it? What I posted above was the most recent under Logs. Anyways the issue seem to have resolved itself as I haven't had a crash since making the post. 

Edited by Darkieth
Link to comment
Share on other sites

You already found it above. I just want to see entire log files rather than snippets. Thanks !

Link to comment
Share on other sites

Darkieth

What was posted above was all the information in the most recent log file at the time. Anyways, issue resolved for now.

Link to comment
Share on other sites

I was having the exact same issue, however, upon reading the logs, I was able to see that every time it happened for me, it was due to an error with TheAudioDB as shown below. I was able to duplicate it every single time. If I turn off TheAudioDB in the music library settings, no more crashes. You have to turn on the advanced settings in the music library to prevent TheAudioDB from grabbing art and all that stuff as well.

2018-07-28 11:36:13.298 Info TaskManager: Executing Scan media library
2018-07-28 11:36:13.298 Info HttpServer: HTTP Response 204 to 10.0.0.4. Time: 4ms. http://server:8096/emby/ScheduledTasks/Running/6330ee8fb4a957f33981f89aa78b030f 
2018-07-28 11:36:13.306 Info HttpServer: HTTP GET http://server:8096/emby/ScheduledTasks?IsEnabled=true. UserAgent: Mozilla/5.0 (X11; Linux x86_64; rv:61.0) Gecko/20100101 Firefox/61.0
2018-07-28 11:36:13.307 Info LibraryMonitor: Stopping directory watching for path /mnt/media/music
2018-07-28 11:36:13.308 Info HttpServer: HTTP Response 200 to 10.0.0.4. Time: 3ms. http://server:8096/emby/ScheduledTasks?IsEnabled=true 
2018-07-28 11:36:13.317 Info App: Validating media library
2018-07-28 11:36:15.748 Info HttpClient: GET http://www.theaudiodb.com/api/v1/json/49jhsf8248yfahka89724011/album-mb.php?i=6c9f8751-ed37-4513-b534-841da417cd39
2018-07-28 11:36:16.875 Error Main: UnhandledException
	*** Error Report ***
	Version: 3.5.0.0
	Command line: /usr/lib/emby-server/EmbyServer.dll -programdata /var/lib/emby -ffmpeg /usr/bin/ffmpeg -ffprobe /usr/bin/ffprobe -restartexitcode 3
	Operating system: Unix 4.17.9.1
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 24
	Program data path: /var/lib/emby
	Application directory: /usr/lib/emby-server
	System.IO.IOException: Unable to read data from the transport connection: Connection reset by peer. ---> System.Net.Sockets.SocketException: Connection reset by peer
	   --- End of inner exception stack trace ---
	   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error)
	   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
	   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
	   at System.Net.Security.SslState.ThrowIfExceptional()
	   at System.Net.Security.SslState.CheckThrow(Boolean authSuccessCheck, Boolean shutdownCheck)
	   at System.Net.Security.SslState.CheckOldKeyDecryptedData(Memory`1 buffer)
	   at System.Net.Security.SslState.HandleQueuedCallback(Object& queuedStateRequest)
	   at System.Net.Security.SslState.FinishHandshakeRead(Int32 newState)
	   at System.Net.Security.SslState.FinishHandshake(Exception e, AsyncProtocolRequest asyncRequest)
	   at System.Net.Security.SslState.RehandshakeCompleteCallback(IAsyncResult result)
	   at System.Net.LazyAsyncResult.Complete(IntPtr userToken)
	   at System.Net.LazyAsyncResult.ProtectedInvokeCallback(Object result, IntPtr userToken)
	   at System.Net.AsyncProtocolRequest.CompleteUserWithError(Exception e)
	   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
	   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Threading.ThreadPoolWorkQueue.Dispatch()
	System.IO.IOException
	   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error)
	   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
	   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
	   at System.Net.Security.SslState.ThrowIfExceptional()
	   at System.Net.Security.SslState.CheckThrow(Boolean authSuccessCheck, Boolean shutdownCheck)
	   at System.Net.Security.SslState.CheckOldKeyDecryptedData(Memory`1 buffer)
	   at System.Net.Security.SslState.HandleQueuedCallback(Object& queuedStateRequest)
	   at System.Net.Security.SslState.FinishHandshakeRead(Int32 newState)
	   at System.Net.Security.SslState.FinishHandshake(Exception e, AsyncProtocolRequest asyncRequest)
	   at System.Net.Security.SslState.RehandshakeCompleteCallback(IAsyncResult result)
	   at System.Net.LazyAsyncResult.Complete(IntPtr userToken)
	   at System.Net.LazyAsyncResult.ProtectedInvokeCallback(Object result, IntPtr userToken)
	   at System.Net.AsyncProtocolRequest.CompleteUserWithError(Exception e)
	   at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
	   at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Threading.ThreadPoolWorkQueue.Dispatch()
	InnerException: System.Net.Sockets.SocketException
	System.Net.Sockets.SocketException (104): Connection reset by peer
Link to comment
Share on other sites

Thanks for the info. Our audiodb urls are still http, and audiodb redirects automatically to https. I have pushed an update to change the urls to https. This will avoid the redirect and perhaps that will simplify the ssl workflow and resolve the issue. The docker container should get the update later this afternoon. If you could let me know if that has any impact that would be helpful. thanks.

  • Like 1
Link to comment
Share on other sites

I disabled AudioDB for my music library and this fixes the crash on scanning. I noticed when I manually click "refresh metadata" on an item from within my music library it still tries to fetch data from AudioDB which results in a server crash.

 

- Is it possible to respect the metadata settings when manually refreshing metadata in a library?

- Maybe handle errors (regarding metada) more graceful (i.e not crash the whole server).

 

Thank you.

 

Is there a way to configure the musicbrainz server url emby uses?

Link to comment
Share on other sites

jon@spoonamore.net

I'm on version  3.5.1.0 on Linux Mint 18.3.  Still having the crashes like everyone else.  I just removed my Music Library and the problem went away.  I will try to add this Library back in a future version.  Good luck fixing this issue.

Link to comment
Share on other sites

I disabled AudioDB for my music library and this fixes the crash on scanning. I noticed when I manually click "refresh metadata" on an item from within my music library it still tries to fetch data from AudioDB which results in a server crash.

 

You need to switch on the advanced tab in the music library and disable TheAudioDB for the other features like poster art.

 

 

I'm on version  3.5.1.0 on Linux Mint 18.3.  Still having the crashes like everyone else.  I just removed my Music Library and the problem went away.  I will try to add this Library back in a future version.  Good luck fixing this issue.

 

Just disable TheAudioDB all the way through the advanced menu for the time being and you can keep your music library going.

 

 

Thanks for the info. Our audiodb urls are still http, and audiodb redirects automatically to https. I have pushed an update to change the urls to https. This will avoid the redirect and perhaps that will simplify the ssl workflow and resolve the issue. The docker container should get the update later this afternoon. If you could let me know if that has any impact that would be helpful. thanks.

 

Mine is prebuilt via arch linux, but I'll try to keep an eye out for the changes. If they are fairly minimal, I can just make them myself locally and go for it.

Edited by 10479
Link to comment
Share on other sites

Johnny-V

Same Server Crashing problem.

Disabling TheAudioDB does not help (I also disabled this in advanced).

Manual metarefresh still crashes the server.

 

Running Linux Ubuntu 16.04.5 LTS Server.

Both versions 3.5.0.0 and 3.5.1.0 same problem.

 

Regards, and Suc6 fixing.

Link to comment
Share on other sites

malo87

Same Server Crashing problem.

Disabling TheAudioDB does not help (I also disabled this in advanced).

Manual metarefresh still crashes the server.

 

Running Linux Ubuntu 16.04.5 LTS Server.

Both versions 3.5.0.0 and 3.5.1.0 same problem.

 

Regards, and Suc6 fixing.

 

I think it is not enough just disabling TheAudioDB.

I have the same error for MusicBrainz as well

 

Ubuntu Linux 18.04

Emby 3.6.0.2

2018-07-29 11:42:10.873 Info HttpClient: GET http://ws.audioscrobbler.com/2.0/?method=album.getInfo&artist=Paramore&album=Misery+Business+%5B7%22+Single%5D%2F7%22+Single&api_key=7b76553c3eb1d341d642755aecc40a33&format=json
2018-07-29 11:42:11.352 Info HttpClient: GET http://musicbrainz01.eastus2.cloudapp.azure.com:8080/ws/2/release/?query="Misery+Business+%5B7%22+Single%5D%2F7%22+Single" AND arid:728ea90d-279b-4201-a8c4-597830883150
2018-07-29 11:42:12.068 Error App: Error in MusicBrainz
	*** Error Report ***
	Version: 3.6.0.2
	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}_amd64.deb
	Operating system: Unix 4.15.0.23
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 4
	Program data path: /var/lib/emby
	Application directory: /opt/emby-server/system
	MediaBrowser.Model.Net.HttpException: BadRequest
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsync(HttpRequestOptions options, String httpMethod)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMusicBrainzResponse(String url, Boolean isSearch, Boolean forceMusicBrainzProper, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetReleaseResult(String albumName, String artistId, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMetadata(AlbumInfo id, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Manager.MetadataService`2.ExecuteRemoteProviders(MetadataResult`1 temp, String logName, TIdType id, IEnumerable`1 providers, CancellationToken cancellationToken)
	MediaBrowser.Model.Net.HttpException
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsync(HttpRequestOptions options, String httpMethod)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMusicBrainzResponse(String url, Boolean isSearch, Boolean forceMusicBrainzProper, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetReleaseResult(String albumName, String artistId, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMetadata(AlbumInfo id, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Manager.MetadataService`2.ExecuteRemoteProviders(MetadataResult`1 temp, String logName, TIdType id, IEnumerable`1 providers, CancellationToken cancellationToken)
Edited by malo87
Link to comment
Share on other sites

Same Server Crashing problem.

Disabling TheAudioDB does not help (I also disabled this in advanced).

Manual metarefresh still crashes the server.

 

Running Linux Ubuntu 16.04.5 LTS Server.

Both versions 3.5.0.0 and 3.5.1.0 same problem.

 

Regards, and Suc6 fixing.

Not that I'm the expert here, but if you have logs showing what happened, it would be great. I can personally attest to completely disabling all TheAudioDB services and instantly not crashing. I can also re-enable it and crash the server.

 

 

I think it is not enough just disabling TheAudioDB.

I have the same error for MusicBrainz as well

 

Ubuntu Linux 18.04

Emby 3.6.0.2

2018-07-29 11:42:10.873 Info HttpClient: GET http://ws.audioscrobbler.com/2.0/?method=album.getInfo&artist=Paramore&album=Misery+Business+%5B7%22+Single%5D%2F7%22+Single&api_key=7b76553c3eb1d341d642755aecc40a33&format=json
2018-07-29 11:42:11.352 Info HttpClient: GET http://musicbrainz01.eastus2.cloudapp.azure.com:8080/ws/2/release/?query="Misery+Business+%5B7%22+Single%5D%2F7%22+Single" AND arid:728ea90d-279b-4201-a8c4-597830883150
2018-07-29 11:42:12.068 Error App: Error in MusicBrainz
	*** Error Report ***
	Version: 3.6.0.2
	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}_amd64.deb
	Operating system: Unix 4.15.0.23
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 4
	Program data path: /var/lib/emby
	Application directory: /opt/emby-server/system
	MediaBrowser.Model.Net.HttpException: BadRequest
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsync(HttpRequestOptions options, String httpMethod)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMusicBrainzResponse(String url, Boolean isSearch, Boolean forceMusicBrainzProper, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetReleaseResult(String albumName, String artistId, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMetadata(AlbumInfo id, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Manager.MetadataService`2.ExecuteRemoteProviders(MetadataResult`1 temp, String logName, TIdType id, IEnumerable`1 providers, CancellationToken cancellationToken)
	MediaBrowser.Model.Net.HttpException
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsyncInternal(HttpRequestOptions options, String httpMethod)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.SendAsync(HttpRequestOptions options, String httpMethod)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMusicBrainzResponse(String url, Boolean isSearch, Boolean forceMusicBrainzProper, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetReleaseResult(String albumName, String artistId, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Music.MusicBrainzAlbumProvider.GetMetadata(AlbumInfo id, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Manager.MetadataService`2.ExecuteRemoteProviders(MetadataResult`1 temp, String logName, TIdType id, IEnumerable`1 providers, CancellationToken cancellationToken)

Did the server actually crash after that?

Link to comment
Share on other sites

Thanks for the info. It appears to be related to AudioDb. We're looking into it.

  • Like 1
Link to comment
Share on other sites

sansoo22

My first crashing issue started with a corrupt video file which lead me to this post.  Here is the log for that.

libavutil 56. 14.100 / 56. 14.100
libavcodec 58. 18.100 / 58. 18.100
libavformat 58. 12.100 / 58. 12.100
libavdevice 58. 3.100 / 58. 3.100
libavfilter 7. 16.100 / 7. 16.100
libswscale 5. 1.100 / 5. 1.100
libswresample 3. 1.100 / 3. 1.100
libpostproc 55. 1.100 / 55. 1.100
[mov,mp4,m4a,3gp,3g2,mj2 @ 0x644b40] moov atom not found
file:/media/Movies/Inside Job (2010)/Inside.Job.2010.720p.mp4: Invalid data found when processing input
UnhandledException
System.IO.IOException: Unable to read data from the transport connection: Connection reset by peer. ---> System.Net.Sockets.SocketException: Connection reset by peer
--- End of inner exception stack trace ---
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error)

at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)
at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
at System.Net.Security.SslState.ThrowIfExceptional()
at System.Net.Security.SslState.CheckThrow(Boolean authSuccessCheck, Boolean shutdownCheck)
at System.Net.Security.SslState.CheckOldKeyDecryptedData(Memory`1 buffer)
at System.Net.Security.SslState.HandleQueuedCallback(Object& queuedStateRequest)
at System.Net.Security.SslState.FinishHandshakeRead(Int32 newState)
at System.Net.Security.SslState.FinishHandshake(Exception e, AsyncProtocolRequest asyncRequest)
at System.Net.Security.SslState.RehandshakeCompleteCallback(IAsyncResult result)
at System.Net.LazyAsyncResult.Complete(IntPtr userToken)
at System.Net.LazyAsyncResult.ProtectedInvokeCallback(Object result, IntPtr userToken)
at System.Net.AsyncProtocolRequest.CompleteUserWithError(Exception e)
at System.Net.FixedSizeReader.ReadPacketAsync(Stream transport, AsyncProtocolRequest request)
at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location where exception was thrown ---
at System.Threading.ThreadPoolWorkQueue.Dispatch()
System.IO.IOException

Once I removed that file Emby restarted and then crashed again on a library scan but this time with no extra info in the log.  I disabled TheAudioDB and MusicBrainz and all is working again.  Not sure if the corrupted file is related at all but thought i would share just in case.

Link to comment
Share on other sites

Emby Server 3.5.2 is on it's way up and will remove the audiodb metadata provider to stop the crashing. We'll get it restored for 3.6. Thanks.

  • Like 1
Link to comment
Share on other sites

malo87

Emby Server 3.5.2 is on it's way up and will remove the audiodb metadata provider to stop the crashing. We'll get it restored for 3.6. Thanks.

 

Today Emby crashed again.

I wonder that I found a log entry for audiodb even if it is disabled for all libraries...

 

Logfile is attached 

emby log.txt

Link to comment
Share on other sites

Well you're on the beta release. I won't be removing audio db from the beta because we want to chase down and solve the problem.

 

If you've already disabled audiodb then you could be seeing the url in your log if an audio db url was already saved into your database.

Link to comment
Share on other sites

malo87

Well you're on the beta release. I won't be removing audio db from the beta because we want to chase down and solve the problem.

 

If you've already disabled audiodb then you could be seeing the url in your log if an audio db url was already saved into your database.

 

yes I already disabled it.

So this means Emby is not fetching the resources like pictures and texts from audiodb itself, it just fetches the links to it. 

And every time I open e.g a artist page in Emby it loads the pictures from audiodb? 

 

I ask because the error doesn't occurs while scanning the media library this time, it was while trying to access a artist page

Edited by malo87
Link to comment
Share on other sites

The_Duke_Sr

Emby Server 3.5.2 is on it's way up and will remove the audiodb metadata provider to stop the crashing. We'll get it restored for 3.6. Thanks.

Did a rescan on my music lubrary with 3.5.2 and NO crash!

Thnx for the quick fix.

  • Like 1
Link to comment
Share on other sites

malo87

on 3.6.0.2 BETA its also fine now.

Had to remove the complete music library and add it again without audiodb and musicbrainz.

Means for me right now just last.fm is active.

 

Just disabling audiodb and musicbrainz and then performing a full library scan had also ended in a server crash

Link to comment
Share on other sites

jon@spoonamore.net

Did a rescan on my music lubrary with 3.5.2 and NO crash!

Thnx for the quick fix.

 

Same here.  I deleted my Music Lib and re-added it after installing v3.5.2.   No crashes now.  Excellent!!!!  :)

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
×
×
  • Create New...