search

jellyfin / jellyfin-plugin-playbackreporting

Playback Statistics Plugin for Jellyfin
https://jellyfin.org
GNU General Public License v3.0
67 stars 25 forks source link

[bug] "Too many open files" message appears when using the PlaybackReporting plugin API #12

Open macarrie opened 4 years ago

macarrie commented 4 years ago

Describe the bug

I started using a Nagios/Shinken monitoring plugin of my own to get stats from Jellyfin to display in grafana dashboards. It works great until Jellyfin starts throwing a "Too many open files" error, which causes the Web UI to be unavailable.

After a bit of investigating, it seems that the Playback reporting does not close some connections to its local db.

The Shinken plugin is executed every minute and performs http requests to the playback reporting plugin API. The problem also appears when doing simple curl using a shell.

Please let me know if this is not the correct place to open this issue, and if you want me to open an issue on the playback_reporting repo instead.

System (please complete the following information):

To Reproduce

The simplest way to reproduce this error is by doing curls manually to the playback reporting plugin API. For example: watch curl -v "http://jellyfin_addr:8096/emby/user_usage_stats/UserId/BreakDownReport?days=1&filter=Movie,Episode" In parallel, you can see the open files list growing when doing the curls: lsof -p JELLYFINPID | wc -l

The script I use for monitoring that caused the problem initially is available here: https://github.com/macarrie/check_jellyfin/blob/master/libexec/check_jellyfin.py

Expected behavior

Connections to playback reporting db are closed when necessary.

Logs

Journalctl logs at the moment the error appeared.

The playback_reporting open files lines occupy the major part on the fd list, with a total of elements being over 4000 items after the error appears:

# lsof -p 19386 | wc -l
4349
JustAMan commented 4 years ago

Great bug report! Moving it to the plugin's repo.

Also cc-ing @jellyfin/backend just in case.

oddstr13 commented 3 years ago

Is this still an issue with current master?

macarrie commented 3 years ago

I will test this and get back to you in a few days.

macarrie commented 3 years ago

Hi,

Sorry it took so long for me to test. In short, the issue is still present but does not have the same effect anymore.

I tested the same way on a CentOS 7.9 VM, with Jellyfin 10.7.0 RC2.

When the "too many open files" stacktrace happens, Jellyfin automatically restarts. So the issue is still present but is almost unnoticeable in my case because Jellyfin restarts automatically.

This could be an issue for some people though (with big configuration that makes Jellyfin startup quite long for example). To make jellyfin crash/restart, I launched a curl every 2s to the playback monitoring plugin and it took a few hours to reach the file descriptor usage problem. Even with monitoring querying the playback reporting plugin every minute, the issue only occurs after a few days, so I think it is unlikely that this problem will bother 99,9% of users in the future.

Thanks.

PS: Here is a log extract of the issue and Jellyfin restart:

Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Query.Internal.SingleQueryingEnumerable`1.AsyncEnumerator.MoveNextAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncEnumerable.SelectEnumerableAsyncIterator`2.MoveNextCore()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncIteratorBase`1.MoveNextAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncIteratorBase`1.MoveNextAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncEnumerable.<ToListAsync>g__Core|620_0[TSource](IAsyncEnumerable`1 source, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncEnumerable.<ToListAsync>g__Core|620_0[TSource](IAsyncEnumerable`1 source, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Implementations.Activity.ActivityManager.GetPagedResultAsync(ActivityLogQuery query)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Implementations.Activity.ActivityManager.GetPagedResultAsync(ActivityLogQuery query)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries(Nullable`1 startIndex, Nullable`1 limit, Nullable`1 minDate, Nullable`1 hasUserId)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at lambda_method1363(Closure , Object )
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ActionMethodExecutor.AwaitableObjectResultExecutor.Execute(IActionResultTypeMapper mapper, ObjectMethodExecutor executor, Object controller, Object[] arguments)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeActionMethodAsync>g__Awaited|12_0(ControllerActionInvoker invoker, ValueTask`1 actionResultValueTask)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeNextActionFilterAsync>g__Awaited|10_0(ControllerActionInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Rethrow(ActionExecutedContextSealed context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.InvokeInnerFilterAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeNextResourceFilter>g__Awaited|24_0(ResourceInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.Rethrow(ResourceExecutedContextSealed context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.InvokeFilterPipelineAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeAsync>g__Awaited|17_0(ResourceInvoker invoker, Task task, IDisposable scope)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Routing.EndpointMiddleware.<Invoke>g__AwaitRequestTask|6_0(Endpoint endpoint, Task requestTask, ILogger logger)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.ServerStartupMessageMiddleware.Invoke(HttpContext httpContext, IServerApplicationHost serverApplicationHost, ILocalizationManager localizationManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.WebSocketHandlerMiddleware.Invoke(HttpContext httpContext, IWebSocketManager webSocketManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.IpBasedAccessValidationMiddleware.Invoke(HttpContext httpContext, INetworkManager networkManager, IServerConfigurationManager serverConfigurationManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.LanFilteringMiddleware.Invoke(HttpContext httpContext, INetworkManager networkManager, IServerConfigurationManager serverConfigurationManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Authorization.Policy.AuthorizationMiddlewareResultHandler.HandleAsync(RequestDelegate next, HttpContext context, AuthorizationPolicy policy, PolicyAuthorizationResult authorizeResult)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Authorization.AuthorizationMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Swashbuckle.AspNetCore.ReDoc.ReDocMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Swashbuckle.AspNetCore.SwaggerUI.SwaggerUIMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Swashbuckle.AspNetCore.Swagger.SwaggerMiddleware.Invoke(HttpContext httpContext, ISwaggerProvider swaggerProvider)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Authentication.AuthenticationMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.RobotsRedirectionMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.LegacyEmbyRouteRewriteMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.ResponseCompression.ResponseCompressionMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.ResponseTimeMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.ExceptionMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: [21:59:08] [ERR] [103] Jellyfin.Server.Middleware.ExceptionMiddleware: Error processing request. URL GET /System/ActivityLog/Entries.
Jan 02 21:59:08 jellyfin jellyfin[11135]: Microsoft.Data.Sqlite.SqliteException (0x80004005): SQLite Error 14: 'unable to open database file'.
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.Data.Sqlite.SqliteException.ThrowExceptionForRC(Int32 rc, sqlite3 db)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.Data.Sqlite.SqliteConnection.Open()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Data.Common.DbConnection.OpenAsync(CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Storage.RelationalConnection.OpenInternalAsync(Boolean errorsExpected, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Storage.RelationalConnection.OpenInternalAsync(Boolean errorsExpected, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Storage.RelationalConnection.OpenAsync(CancellationToken cancellationToken, Boolean errorsExpected)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Storage.RelationalCommand.ExecuteReaderAsync(RelationalCommandParameterObject parameterObject, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Query.Internal.SingleQueryingEnumerable`1.AsyncEnumerator.InitializeReaderAsync(DbContext _, Boolean result, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.EntityFrameworkCore.Query.Internal.SingleQueryingEnumerable`1.AsyncEnumerator.MoveNextAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncEnumerable.SelectEnumerableAsyncIterator`2.MoveNextCore()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncIteratorBase`1.MoveNextAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncIteratorBase`1.MoveNextAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncEnumerable.<ToListAsync>g__Core|620_0[TSource](IAsyncEnumerable`1 source, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at System.Linq.AsyncEnumerable.<ToListAsync>g__Core|620_0[TSource](IAsyncEnumerable`1 source, CancellationToken cancellationToken)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Implementations.Activity.ActivityManager.GetPagedResultAsync(ActivityLogQuery query)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Implementations.Activity.ActivityManager.GetPagedResultAsync(ActivityLogQuery query)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries(Nullable`1 startIndex, Nullable`1 limit, Nullable`1 minDate, Nullable`1 hasUserId)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at lambda_method1363(Closure , Object )
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ActionMethodExecutor.AwaitableObjectResultExecutor.Execute(IActionResultTypeMapper mapper, ObjectMethodExecutor executor, Object controller, Object[] arguments)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeActionMethodAsync>g__Awaited|12_0(ControllerActionInvoker invoker, ValueTask`1 actionResultValueTask)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeNextActionFilterAsync>g__Awaited|10_0(ControllerActionInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Rethrow(ActionExecutedContextSealed context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.InvokeInnerFilterAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeNextResourceFilter>g__Awaited|24_0(ResourceInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.Rethrow(ResourceExecutedContextSealed context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.InvokeFilterPipelineAsync()
Jan 02 21:59:08 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeAsync>g__Awaited|17_0(ResourceInvoker invoker, Task task, IDisposable scope)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Routing.EndpointMiddleware.<Invoke>g__AwaitRequestTask|6_0(Endpoint endpoint, Task requestTask, ILogger logger)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.ServerStartupMessageMiddleware.Invoke(HttpContext httpContext, IServerApplicationHost serverApplicationHost, ILocalizationManager localizationManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.WebSocketHandlerMiddleware.Invoke(HttpContext httpContext, IWebSocketManager webSocketManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.IpBasedAccessValidationMiddleware.Invoke(HttpContext httpContext, INetworkManager networkManager, IServerConfigurationManager serverConfigurationManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.LanFilteringMiddleware.Invoke(HttpContext httpContext, INetworkManager networkManager, IServerConfigurationManager serverConfigurationManager)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Authorization.Policy.AuthorizationMiddlewareResultHandler.HandleAsync(RequestDelegate next, HttpContext context, AuthorizationPolicy policy, PolicyAuthorizationResult authorizeResult)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Authorization.AuthorizationMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Swashbuckle.AspNetCore.ReDoc.ReDocMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Swashbuckle.AspNetCore.SwaggerUI.SwaggerUIMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Swashbuckle.AspNetCore.Swagger.SwaggerMiddleware.Invoke(HttpContext httpContext, ISwaggerProvider swaggerProvider)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.Authentication.AuthenticationMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.RobotsRedirectionMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.LegacyEmbyRouteRewriteMiddleware.Invoke(HttpContext httpContext)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Microsoft.AspNetCore.ResponseCompression.ResponseCompressionMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.ResponseTimeMiddleware.Invoke(HttpContext context)
Jan 02 21:59:08 jellyfin jellyfin[11135]: at Jellyfin.Server.Middleware.ExceptionMiddleware.Invoke(HttpContext context)
Jan 02 21:59:10 jellyfin jellyfin[11135]: [21:59:10] [ERR] [55] Jellyfin.Server.Middleware.ExceptionMiddleware: Error processing request: Too many open files. URL GET /Library/VirtualFolders.
Jan 02 21:59:21 jellyfin jellyfin[11135]: [21:59:21] [FTL] [7] Main: Unhandled Exception
Jan 02 21:59:21 jellyfin jellyfin[11135]: System.IO.IOException: Too many open files
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.IO.StreamReader.ValidateArgsAndOpenPath(String path, Encoding encoding, Int32 bufferSize)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.IO.File.InternalReadAllLines(String path, Encoding encoding)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.LinuxNetworkInterface.LinuxNetworkInterfaceSystemProperties..ctor()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.LinuxNetworkInterface.GetLinuxNetworkInterfaces()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Jellyfin.Networking.Manager.NetworkManager.InitialiseInterfaces()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Jellyfin.Networking.Manager.NetworkManager.OnNetworkChangeAsync()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Jellyfin.Networking.Manager.NetworkManager.OnNetworkChanged()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
Jan 02 21:59:21 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.NetworkChange.OnAddressChanged()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.NetworkChange.OnSocketEvent(NetworkChangeKind kind)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.NetworkChange.ProcessEvent(Int32 socket, NetworkChangeKind kind)
Jan 02 21:59:21 jellyfin jellyfin[11135]: Unhandled exception. System.IO.IOException: Too many open files
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirectory, Func`2 errorRewriter)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.IO.StreamReader.ValidateArgsAndOpenPath(String path, Encoding encoding, Int32 bufferSize)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.IO.File.InternalReadAllLines(String path, Encoding encoding)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.LinuxNetworkInterface.LinuxNetworkInterfaceSystemProperties..ctor()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.LinuxNetworkInterface.GetLinuxNetworkInterfaces()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Jellyfin.Networking.Manager.NetworkManager.InitialiseInterfaces()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Jellyfin.Networking.Manager.NetworkManager.OnNetworkChangeAsync()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at Jellyfin.Networking.Manager.NetworkManager.OnNetworkChanged()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state)
Jan 02 21:59:21 jellyfin jellyfin[11135]: --- End of stack trace from previous location ---
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.NetworkChange.OnAddressChanged()
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.NetworkChange.OnSocketEvent(NetworkChangeKind kind)
Jan 02 21:59:21 jellyfin jellyfin[11135]: at System.Net.NetworkInformation.NetworkChange.ProcessEvent(Int32 socket, NetworkChangeKind kind)

Jan 02 21:59:22 jellyfin systemd[1]: jellyfin.service: main process exited, code=killed, status=6/ABRT
Jan 02 21:59:22 jellyfin systemd[1]: Unit jellyfin.service entered failed state.
Jan 02 21:59:22 jellyfin systemd[1]: jellyfin.service failed.
Jan 02 21:59:22 jellyfin systemd[1]: jellyfin.service holdoff time over, scheduling restart.
Jan 02 21:59:22 jellyfin systemd[1]: Stopped Jellyfin is a free software media system that puts you in control of managing and streaming your media..
Jan 02 21:59:22 jellyfin systemd[1]: Started Jellyfin is a free software media system that puts you in control of managing and streaming your media..

Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Jellyfin version: 10.7.0
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Environment Variables: ["[JELLYFIN_RESTART_OPT, --restartpath=/usr/libexec/jellyfin/restart.sh]", "[JELLYFIN_WEB_OPT, --webdir=/usr/share/jellyfin-web]", "[JELLYFIN_LOG_DIR, /var/log/jellyfin]", "[JELLYFIN_DATA_DIR, /var/lib/jellyfin]", "[JELLYFIN_CACHE_DIR, /var/cache/jellyfin]", "[JELLYFIN_CONFIG_DIR, /etc/jellyfin]"]
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Arguments: ["/usr/lib64/jellyfin/jellyfin.dll", "--webdir=/usr/share/jellyfin-web", "--restartpath=/usr/libexec/jellyfin/restart.sh"]
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Operating system: Linux
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Architecture: X64
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: 64-Bit Process: True
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: User Interactive: True
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Processor count: 4
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Program data path: /var/lib/jellyfin
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Web resources path: /usr/share/jellyfin-web
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Main: Application directory: /usr/lib64/jellyfin/
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /var/cache/jellyfin
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN addresses : [127.0.0.1/8,10.0.0.0/8,172.16.0.0/12,192.168.0.0/16]
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions : []
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using LAN addresses: [127.0.0.1/8,10.0.0.0/8,172.16.0.0/12,192.168.0.0/16]
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using bind addresses: []
Jan 02 21:59:24 jellyfin jellyfin[3514]: [21:59:24] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using bind exclusions: []
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.Fanart, Version=6.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/jellyfin/plugins/Fanart_6.0.0.0/Jellyfin.Plugin.Fanart.dll
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly OpenSubtitlesHandler, Version=0.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/jellyfin/plugins/Open Subtitles_10.0.0.0/OpenSubtitlesHandler.dll
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.OpenSubtitles, Version=10.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/jellyfin/plugins/Open Subtitles_10.0.0.0/Jellyfin.Plugin.OpenSubtitles.dll
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.PlaybackReporting, Version=9.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/jellyfin/plugins/Playback Reporting_9.0.0.0/Jellyfin.Plugin.PlaybackReporting.dll
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.TMDbBoxSets, Version=7.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/jellyfin/plugins/TMDb Box Sets_7.0.0.0/Jellyfin.Plugin.TMDbBoxSets.dll
Jan 02 21:59:25 jellyfin jellyfin[3514]: [21:59:25] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Trakt, Version=11.0.0.0, Culture=neutral, PublicKeyToken=null from /var/lib/jellyfin/plugins/Trakt_11.0.0.0/Trakt.dll
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Fanart 6.0.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Open Subtitles 10.0.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Playback Reporting 9.0.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb Box Sets 7.0.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Trakt 11.0.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.7.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.7.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.7.0.0
Jan 02 21:59:32 jellyfin jellyfin[3514]: [21:59:32] [INF] [1] Main: Kestrel listening on Any IP4 Address
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] Jellyfin.Plugin.PlaybackReporting.TaskCleanDb: TaskCleanDb Loaded
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] Jellyfin.Plugin.PlaybackReporting.TaskRunBackup: TaskRunBackup Loaded
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Playback Reporting Trim Db set to fire at 01/03/2021 00:00, which is 2:00:26.4485945 from now.
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 01/03/2021 02:00, which is 4:00:26.3947473 from now.
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 4.3.1
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["h264", "hevc", "mpeg2video", "mpeg4", "msmpeg4", "aac", "ac3", "flac", "mp3"]
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libx264", "h264_v4l2m2m", "libx265", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "ac3", "flac", "libmp3lame", "libopus", "libvorbis", "srt"]
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["vdpau"]
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: Custom: /usr/bin/ffmpeg
Jan 02 21:59:33 jellyfin jellyfin[3514]: [21:59:33] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: c42934c8783a46cc9954e3d019a20354
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Emby.Dlna.Main.DlnaEntryPoint: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on 192.168.2.176/24
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Emby.Dlna.Main.DlnaEntryPoint: Registering publisher for urn:schemas-upnp-org:device:MediaServer:1 on 10.84.0.14/24
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Emby.Server.Implementations.ApplicationHost: Executed all pre-startup entry points in 0:00:00.8602003
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: EventMonitorEntryPoint Running
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Sqlite version: 3.33.0
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Sqlite compiler options: COMPILER=gcc-5.4.0 20160609,DEFAULT_FOREIGN_KEYS,ENABLE_COLUMN_METADATA,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS4,ENABLE_FTS5,ENABLE_JSON1,ENABLE_RTREE,THREADSAFE=1
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Default journal_mode for /var/lib/jellyfin/data/playback_reporting.db is delete
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Initialize PlaybackActivity Repository
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: PlaybackActivity table schema OK
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Expected : datecreated:datetime|userid:text|itemid:text|itemtype:text|itemname:text|playbackmethod:text|clientname:text|devicename:text|playduration:int
Jan 02 21:59:34 jellyfin jellyfin[3514]: [21:59:34] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Received : datecreated:datetime|userid:text|itemid:text|itemtype:text|itemname:text|playbackmethod:text|clientname:text|devicename:text|playduration:int
Jan 02 21:59:35 jellyfin jellyfin[3514]: [21:59:35] [INF] [1] Emby.Server.Implementations.ApplicationHost: Executed all post-startup entry points in 0:00:00.2981479
Jan 02 21:59:35 jellyfin jellyfin[3514]: [21:59:35] [INF] [1] Main: Startup complete 0:00:11.501094