Emby shows playback of a user who's not streaming

[Schleudertrauma 10]

Hey friends,

can you tell me why emby shows actual playback in the settings overview although the user doesn't stream anything and when i reload the page it shows other media being watched?

Momentarily it switches between two movies but none of them are streamed anyway.

 

Greetz

Schleudi

embyserver.txt

[sa2000 674]

The log shows an issue with the library.db database which may be relevant

2024-07-16 03:30:00.793 Info App: Sqlite: 1 - no such column: UserDatas.played in "select count(*) OVER() AS TotalRecordCount,A.Id,A.Name,A.RunTimeTicks,A.ParentId,A.ProviderIds,A.Images from MediaItems A where A.Type=5 AND UserDatas.played=1 AND A.Id in 
2024-07-16 03:30:00.795 Error Server: Error processing request
	*** Error Report ***
	Version: 4.8.8.0
	Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffdetect /opt/emby-server/bin/ffdetect -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_amd64.deb
	Operating system: Linux version 5.15.0-112-generic (buildd@lcy02-amd64-051) (gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #122-Ubun
	Framework: .NET 6.0.25
	OS/Process: x64/x64
	Runtime: opt/emby-server/system/System.Private.CoreLib.dll
	Processor count: 8
	Data path: /var/lib/emby
	Application path: /opt/emby-server/system
	SQLitePCL.pretty.SQLiteException: Error: no such column: UserDatas.played - select count(*) OVER() AS TotalRecordCount,A.Id,A.Name,A.RunTimeTicks,A.ParentId,A.ProviderIds,A.Images from MediaItems A where A.Type=5 AND UserDatas.played=1 AND A.Id in (select ItemId from AncestorIds2 where AncestorId=27 ) ORDER BY UserDatas.LastPlayedDateInt DESC LIMIT 5
	SQLitePCL.pretty.SQLiteException: Exception of type 'SQLitePCL.pretty.SQLiteException' was thrown.
	   at SQLitePCL.pretty.SQLiteDatabaseConnection.PrepareStatement(String sql, ReadOnlySpan`1& tail)
	   at SQLitePCL.pretty.DatabaseConnection.PrepareStatement(IDatabaseConnection This, String sql)
	   at Emby.Server.Implementations.Data.SqliteItemRepository.QueryItemsInTransaction[T](InternalItemsQuery query, IDatabaseConnection db, String[] columnsToSelect, Boolean filterColumns, String[] groupBy, Func`5 rowReaderFn, String methodName)
	   at Emby.Server.Implementations.Data.SqliteItemRepository.GetItems(InternalItemsQuery query)
	   at MediaBrowser.Controller.Entities.CollectionFolder.GetItemsInternal(InternalItemsQuery query)
	   at MediaBrowser.Controller.Entities.Folder.GetItems(InternalItemsQuery query)
	   at Emby.Api.UserLibrary.ItemsService.GetQueryResult(GetItems request, DtoOptions dtoOptions, User user)
	   at Emby.Api.UserLibrary.ItemsService.GetItems(GetItems request)
	   at Emby.Api.UserLibrary.ItemsService.Get(GetItems request)
	   at Emby.Server.Implementations.Services.ServiceController.Execute(HttpListenerHost appHost, Object requestDto, IRequest req, Type serviceType)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost httpHost, IServerApplicationHost appHost, IRequest httpReq, IResponse httpRes, IStreamHelper streamHelper, RestPath restPath, String responseContentType, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, ReadOnlyMemory`1 urlString, ReadOnlyMemory`1 localPath, CancellationToken cancellationToken)
	Source: SQLitePCL.pretty
	TargetSite: SQLitePCL.pretty.IStatement PrepareStatement(System.String, System.ReadOnlySpan`1[System.Byte] ByRef)

What is the history for the database ? Has there been any corruption and repair (https://emby.media/support/articles/Corrupt-Database.html) ?

The logged error showing that a table in the database - table "UserDatas" has a column missing - column "played" 

Edited July 16, 2024 by sa2000

[Schleudertrauma 10]

Thank you.

I have tested the database as described in your link and the integrity check says "ok", so the database seems to be ok and i did not recover the database. But every time when i shutdown and restart emby server, i.e. emby updates etc., everything is fine until the same scenario occurs when the specific user has watched some stuff. BTW: That situation occurs always on the same user. No other user is triggering that kind of problem.

[Happy2Play 9780]

Sort of doesn't make since as that table is used for all users so I don't really see how it can be a issue for only this specific users.

But I guess last resort would be deleting and recreating the user, not user if you would be able to restore userdata for the user as I would think it would just move the issue to the new user but you can always test.

@Lukeis this error from the OMBI request?

[sa2000 674]

Actually there is more to this than what appeared to be a database error

Two identical requests for different userIds - would expect to be accessing the  same database fields - the first one was ok but the second was not !!

2024-07-16 03:30:00.748 Debug Server: http/1.1 GET https://192.168.11.41:8920/emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=8e0230edfdba44af90a50993dbafbce1&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=21. UserAgent: Ombi/4.43.5 (https://ombi.io/)

2024-07-16 03:30:00.788 Debug Server: http/1.1 Response 200 to host1. Time: 41ms. GET https://192.168.11.41:8920/emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=8e0230edfdba44af90a50993dbafbce1&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=21

2024-07-16 03:30:00.793 Debug Server: http/1.1 GET https://192.168.11.41:8920/emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=cb8988e302004617bc4d723641cf67b8&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=21. UserAgent: Ombi/4.43.5 (https://ombi.io/)

2024-07-16 03:30:00.793 Info App: Sqlite: 1 - no such column: UserDatas.played in "select count(*) OVER() AS TotalRecordCount,A.Id,A.Name,A.RunTimeTicks,A.ParentId,A.ProviderIds,A.Images from MediaItems A where A.Type=5 AND UserDatas.played=1 AND A.Id in 
2024-07-16 03:30:00.795 Error Server: Error processing request
	*** Error Report ***
	Version: 4.8.8.0
	Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffdetect /opt/emby-server/bin/ffdetect -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_amd64.deb
	Operating system: Linux version 5.15.0-112-generic (buildd@lcy02-amd64-051) (gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #122-Ubun
	Framework: .NET 6.0.25
	OS/Process: x64/x64
	Runtime: opt/emby-server/system/System.Private.CoreLib.dll
	Processor count: 8
	Data path: /var/lib/emby
	Application path: /opt/emby-server/system
	SQLitePCL.pretty.SQLiteException: Error: no such column: UserDatas.played - select count(*) OVER() AS TotalRecordCount,A.Id,A.Name,A.RunTimeTicks,A.ParentId,A.ProviderIds,A.Images from MediaItems A where A.Type=5 AND UserDatas.played=1 AND A.Id in (select ItemId from AncestorIds2 where AncestorId=27 ) ORDER BY UserDatas.LastPlayedDateInt DESC LIMIT 5
	SQLitePCL.pretty.SQLiteException: Exception of type 'SQLitePCL.pretty.SQLiteException' was thrown.
	   at SQLitePCL.pretty.SQLiteDatabaseConnection.PrepareStatement(String sql, ReadOnlySpan`1& tail)
	   at SQLitePCL.pretty.DatabaseConnection.PrepareStatement(IDatabaseConnection This, String sql)
	   at Emby.Server.Implementations.Data.SqliteItemRepository.QueryItemsInTransaction[T](InternalItemsQuery query, IDatabaseConnection db, String[] columnsToSelect, Boolean filterColumns, String[] groupBy, Func`5 rowReaderFn, String methodName)
	   at Emby.Server.Implementations.Data.SqliteItemRepository.GetItems(InternalItemsQuery query)
	   at MediaBrowser.Controller.Entities.CollectionFolder.GetItemsInternal(InternalItemsQuery query)
	   at MediaBrowser.Controller.Entities.Folder.GetItems(InternalItemsQuery query)
	   at Emby.Api.UserLibrary.ItemsService.GetQueryResult(GetItems request, DtoOptions dtoOptions, User user)
	   at Emby.Api.UserLibrary.ItemsService.GetItems(GetItems request)
	   at Emby.Api.UserLibrary.ItemsService.Get(GetItems request)
	   at Emby.Server.Implementations.Services.ServiceController.Execute(HttpListenerHost appHost, Object requestDto, IRequest req, Type serviceType)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost httpHost, IServerApplicationHost appHost, IRequest httpReq, IResponse httpRes, IStreamHelper streamHelper, RestPath restPath, String responseContentType, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, ReadOnlyMemory`1 urlString, ReadOnlyMemory`1 localPath, CancellationToken cancellationToken)
	Source: SQLitePCL.pretty
	TargetSite: SQLitePCL.pretty.IStatement PrepareStatement(System.String, System.ReadOnlySpan`1[System.Byte] ByRef)
	
2024-07-16 03:30:00.795 Info Server: http/1.1 Response 500 to host1. Time: 2ms. GET https://192.168.11.41:8920/emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=cb8988e302004617bc4d723641cf67b8&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=21

 

Edited July 18, 2024 by sa2000

[Schleudertrauma 10]

Sorry but i don't get what you are talking about.

What can i do to resolve the problems? Do you need further information from me or something else?

[sa2000 674]

25 minutes ago, Schleudertrauma said:

Sorry but i don't get what you are talking about.

What can i do to resolve the problems? Do you need further information from me or something else?

sorry - was not clear. I was adding to @Happy2Playcomment and would need @Luketo have a look. Plugins related to the request and playbacks are 

OMDb 1.0.21.0
Playback Reporting 2.1.0.5

 

You could copy out the following files and directories, zip them and make the zip available to me in a  Private message - will probably need to upload externally and send me a download link

Directory:
/var/lib/emby/programdata/config/users

Files:
/var/lib/emby/programdata/data/activitylog.db
/var/lib/emby/programdata/data/library.db
/var/lib/emby/programdata/data/users.db

Best to do it when the server is shutdown - if you find any .db-shm and .db-wal files  copy these as well

 

 

 

[Happy2Play 9780]

6 hours ago, sa2000 said:

Actually there is more to this than what appeared to be a database error

Two identical requests for different userIds - would expect to be accessing the  same database fields - the first one was ok but the second was not !!

Yes this would suggest a corrupt user info as OP has described.  Just not sure how only one user can be affected here as that column referenced applies to all users not just one.  But these requests are from third party OMBI.io.

So I guess a question would be if they were to disconnect/shutdown Ombi is there still an issue?

But a Stuck session on dashboard is not normal but does happen from time to time per dozens of other topics.  With the only current option to remove it is restarting Emby.

[Schleudertrauma 10]

@sa2000I have zipped the data as you requested and sent them in a private message.

There are no .db-shm or .db-wal files because emby deletes these files automatically during shutting down. These file are only present if emby is running.

If you need these files anyway i can copy them when emby is running, just hit me up.

 

Greetz

Schleudi

[sa2000 674]

15 hours ago, Schleudertrauma said:

I have zipped the data as you requested and sent them in a private message.

Thanks for the files

The column that was logged as not found in the log provided does exist - so not sure how it said it did not exist

I suggest getting fresh debug logs with new tests - one of playback with a user that does not have the problem and another with the user that does - noting down the times of each test

Also separately - do tests of playback without going through the OMBI plugin - again note down the times of the tests and please provide a debug log covering both sets of tests

 

[Schleudertrauma 10]

Ok, i will have a look on how i can match that.

But none of my users plays media through Ombi. Omby runs on a different machine and is implemented via Emby's API and is only for media requests and notifications in a telegram channel. It is not possible to play media in Ombi, the only thing you can do is "Watch now in Emby" which forwards to Emby directly but noone uses that feature afair.

To match your needs i will shutdown Ombi completely and let the user stream some stuff, so that Ombi definitively can't interact with emby during the test, am i right at that point?

[Luke 42077]

1 hour ago, Schleudertrauma said:

Ok, i will have a look on how i can match that.

But none of my users plays media through Ombi. Omby runs on a different machine and is implemented via Emby's API and is only for media requests and notifications in a telegram channel. It is not possible to play media in Ombi, the only thing you can do is "Watch now in Emby" which forwards to Emby directly but noone uses that feature afair.

To match your needs i will shutdown Ombi completely and let the user stream some stuff, so that Ombi definitively can't interact with emby during the test, am i right at that point?

Hi yes that sounds right. Thanks.

[sa2000 674]

@SchleudertraumaMany thanks for all the diagnostics that you provided. Having looked into it in more detail and correlating the programdata/config/users folders with the databases, I have reproduced the database sqlite3 exception error that you got - but of course, I have not yet established if the sqlite3 missing column error is to do with the problem that you reported here - which I will look into next and also see if my explanation of how the sqlite3 exception arises is related

The error that is being logged is arising because OMBI is sending in a request with I presume its own api_key for playback counts for a deleted emby user 

From the folder list, I see you have 12 emby user accounts plus 4 user accounts that no longer exist. It was one of these 4 deleted accounts that the failed exception was logged for.

I reproduced the problem by creating a test user, sending in the request for the info for a specific movies library for this new test user and getting the response back 

response 
{
    "Items": [],
    "TotalRecordCount": 0
}

and then deleting the test user local account and repeating the request with a working token and the same error arises

2024-07-22 13:50:10.493 Debug Server: http/1.1 GET http://‌‍‍localhost‌:8096/emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=feabb63457954dfa8b62c1909a3be783&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=66563&=&X-Emby-Client=Emby Web&X-Emby-Device-Name=Microsoft Edge Windows&X-Emby-Device-Id=29c04670-f2e9-416b-9911-182a6d5fdf5b&X-Emby-Client-Version=4.8.8.0&X-Emby-Token=‌my-permitted-token‌&X-Emby-Language=en-gb. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0
2024-07-22 13:50:10.493 Info App: Sqlite: 1 - no such column: UserDatas.played in "select count(*) OVER() AS TotalRecordCount,A.Id,A.Name,A.RunTimeTicks,A.ParentId,A.ProviderIds,A.Images from MediaItems A where A.Type=5 AND UserDatas.played=1 AND A.Id in 
2024-07-22 13:50:10.498 Error Server: Error processing request
	*** Error Report ***
	Version: 4.8.8.0
	Command line: C:\Users\SA\AppData\Roaming\Emby-Server\system\EmbyServer.dll
	Operating system: Microsoft Windows 10.0.22631
	Framework: .NET 6.0.30
	OS/Process: x64/x64
	Runtime: C:/Users/SA/AppData/Roaming/Emby-Server/system/System.Private.CoreLib.dll
	Processor count: 16
	Data path: C:\Users\SA\AppData\Roaming\Emby-Server\programdata
	Application path: C:\Users\SA\AppData\Roaming\Emby-Server\system
	SQLitePCL.pretty.SQLiteException: Error: no such column: UserDatas.played - select count(*) OVER() AS TotalRecordCount,A.Id,A.Name,A.RunTimeTicks,A.ParentId,A.ProviderIds,A.Images from MediaItems A where A.Type=5 AND UserDatas.played=1 AND A.Id in (select ItemId from AncestorIds2 where AncestorId=66564 ) ORDER BY UserDatas.LastPlayedDateInt DESC LIMIT 5
	SQLitePCL.pretty.SQLiteException: Exception of type 'SQLitePCL.pretty.SQLiteException' was thrown.
	   at SQLitePCL.pretty.SQLiteDatabaseConnection.PrepareStatement(String sql, ReadOnlySpan`1& tail)
	   at SQLitePCL.pretty.DatabaseConnection.PrepareStatement(IDatabaseConnection This, String sql)
	   at Emby.Server.Implementations.Data.SqliteItemRepository.QueryItemsInTransaction[T](InternalItemsQuery query, IDatabaseConnection db, String[] columnsToSelect, Boolean filterColumns, String[] groupBy, Func`5 rowReaderFn, String methodName)
	   at Emby.Server.Implementations.Data.SqliteItemRepository.GetItems(InternalItemsQuery query)
	   at MediaBrowser.Controller.Entities.CollectionFolder.GetItemsInternal(InternalItemsQuery query)
	   at MediaBrowser.Controller.Entities.Folder.GetItems(InternalItemsQuery query)
	   at Emby.Api.UserLibrary.ItemsService.GetQueryResult(GetItems request, DtoOptions dtoOptions, User user)
	   at Emby.Api.UserLibrary.ItemsService.GetItems(GetItems request)
	   at Emby.Api.UserLibrary.ItemsService.Get(GetItems request)
	   at Emby.Server.Implementations.Services.ServiceController.Execute(HttpListenerHost appHost, Object requestDto, IRequest req, Type serviceType)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost httpHost, IServerApplicationHost appHost, IRequest httpReq, IResponse httpRes, IStreamHelper streamHelper, RestPath restPath, String responseContentType, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, ReadOnlyMemory`1 urlString, ReadOnlyMemory`1 localPath, CancellationToken cancellationToken)
	Source: SQLitePCL.pretty
	TargetSite: SQLitePCL.pretty.IStatement PrepareStatement(System.String, System.ReadOnlySpan`1[System.Byte] ByRef)
	
2024-07-22 13:50:10.498 Info Server: http/1.1 Response 500 to ‌‍‍::1‌. Time: 6ms. GET http://‌‍‍localhost‌:8096/emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=feabb63457954dfa8b62c1909a3be783&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=66563&=&X-Emby-Client=Emby Web&X-Emby-Device-Name=Microsoft Edge Windows&X-Emby-Device-Id=29c04670-f2e9-416b-9911-182a6d5fdf5b&X-Emby-Client-Version=4.8.8.0&X-Emby-Token=‌my-permitted-token‌&X-Emby-Language=en-gb

I will now read again the forum thread and see if this error - OMBI requesting info for deleted user accounts and getting sqlite3 exceptions is related

The tests you are going to do next and the new diagnostics should help understand the issue and establish if the above repro is relevant or just a red herring

 

cc @Luke

Edited July 22, 2024 by sa2000

[sa2000 674]

On 16/07/2024 at 05:32, Schleudertrauma said:

can you tell me why emby shows actual playback in the settings overview although the user doesn't stream anything and when i reload the page it shows other media being watched?

Momentarily it switches between two movies but none of them are streamed anyway.

and can you please tell me exactly which screen you are referring to here and which emby client app / apps it shows on and the steps / buttons i select and follow to get to that view and what user accounts they are - you can PM me with the user account names if you like. And as the screenshot you provided is partly in German, please let me know exactly what lines / text on the screen that are wrong.

Edited July 22, 2024 by sa2000

[Happy2Play 9780]

1 hour ago, sa2000 said:

The error that is being logged is arising because OMBI is sending in a request with I presume its own api_key for playback counts for a deleted emby user 

User applies api key when configuring OMBI.

 

1 hour ago, sa2000 said:

I reproduced the problem by creating a test user, sending in the request for the info for a specific movies library for this new test user and getting the response back 

and then deleting the test user local account and repeating the request with a working token and the same error arises

This is really not a Emby issue, but I will assume if user no longer exist the OP needed to remove or Exclude the user in OMBI.  But have not used/test OMBI in years.

I have no idea if the importer cleans up users when they are delete or if you have to manually delete them from OMBI.

But honestly don't think this is really related to overall issue of stuck session on dashboard per serveral other topics with the same issue.  Currently the only way to fix abandoned dashboard sessions is restarting Emby.

 

[sa2000 674]

1 hour ago, Happy2Play said:

But honestly don't think this is really related to overall issue of stuck session on dashboard per serveral other topics with the same issue.  Currently the only way to fix abandoned dashboard sessions is restarting Emby.

Yes - I think the sqlite exception was a red herring - so just need diagnostics and exact details of examples of the phantom playback. I will check the activitylog database that I have now to see what it has for the OP screenshots.

The user was not referring to stuck playback sessions but to phantom reported sessions for items that were not being streamed

On 16/07/2024 at 05:32, Schleudertrauma said:

emby shows actual playback in the settings overview although the user doesn't stream anything and when i reload the page it shows other media being watched?

Momentarily it switches between two movies but none of them are streamed anyway.

 

Edited July 22, 2024 by sa2000

[sa2000 674]

2 hours ago, Happy2Play said:

But honestly don't think this is really related to overall issue of stuck session on dashboard per serveral other topics with the same issue.  Currently the only way to fix abandoned dashboard sessions is restarting Emby.

You were right !

The playback of these videos showing on the screenshots on the 16th July for user Sandra here were actually stopped playbacks from few days earlier

activitylog.db database extracts for last entries for these videos before the 16th July show that the 

IF: Imaginäre Freunde video was played on the 11th July on the android device and was played on Opera on Windows after that

Dune: Part Two video was played on the 14th July on this android device and we also logged playback stopped.

See extracts from the activitylog database for user Sandra which is the user shown in the original screenshots. I took the liberty to assume the timezone to be CEST 

The user said refreshing the screen brought the 2nd video info - were these stacked notifications. 

Both of these playbacks that show in the screenshot were transcodes.

What we need is to capture the server debug logs and ffmpeg transcode logs for one of these streams that actually stop playback and switch to other videos but the transcode remains to show as active

cc @Luke

[sa2000 674]

@SchleudertraumaI tried to repoduce your reported problem of old playback sessions showing up on the Now Playing screen 

I restarted the android android mobile device whilst the Emby for Android app was streaming a transcoded video. The server did correctly remove the Now Playing session from the screen/

I also tried by  shutting down the Android phone during playback and again the server did a minute later remove the session - after not getting any of the expected periodic  /emby/Sessions/Playing/Progress requests  

So I think what we need when the problem arises again, is to capture the screenshot, and capture all the log files - they should go back 3 days. In the case you reported on the 16th of July, one of the sessions was streaming on the 14th so you would have had the logs for that time and suggest you just copy the Logs folder out "/var/lib/emby/logs" and zip the copy and send me the zip in a PM. Alternatively use the web interface to download redacted log files and zip and attach the zip here including the ffmpeg log files and going back a number of days.

 

[Luke 42077]

@Schleudertrauma?

[Schleudertrauma 10]

Hey friends, i am very sorry for my late reply but i had much things to do aside the daily job.

@sa2000& @Luke: I could not test it yet because the user is on vacation at the moment and therefore not streaming. I have to test it in about 2-3 weeks and then send you all the files i have. The time zone of CEST is correct by the way.

I can't test it with other users because of the weirdness that it only affects the specific user Sandra. No other user is triggering that kind of problem.

All 12 users exist and there are no deleted users but not all users are streaming because i have some users for testing and backup accounts. The API key was generated in the emby settings and then implemented into Ombi. I don't think that the sql error is related to the abandoned playback sessions but maybe we can find out what causes the sql error anyway. If you need me to provide more logs for that kind of error just tell me. I will attach a new embyserver log from half an hour ago where the error arised again, the hardware detection log and the last ffmpeg log i found.

BTW: The two examples you are focussing on (IF & Dune) were just the videos from the last time the problem arised. I have to mention that it is not only related to these two files. It always depends on what the user has watched last times no matter if that were movies or episodes. The problem stays the same.

embyserver.txt hardware_detection-63857987123.txt ffmpeg-transcode-10424e81-36ea-4d18-b282-a0b69cb81feb_1.txt

[sa2000 674]

7 hours ago, Schleudertrauma said:

I don't think that the sql error is related to the abandoned playback sessions but maybe we can find out what causes the sql error anyway.

Yes - agree the sqlite exception is separate and arises if the following authenticated request is received by the emby server for a deleted user account 

"GET  /emby/items?Recursive=True&IncludeItemTypes=Movie&Fields=ProviderIds&UserId=xxxxxxxxxxxxxxxxxxx&isPlayed=True&sortBy=DatePlayed&SortOrder=Descending&startIndex=0&limit=5&ParentId=xxx"

The request being for a library.

A fix would stop this sqlite exception but we would still return an error response back to the originator - probably an http error 404 instead of 500.

You may find some Ombi configuration settings that would allow you to remove all the deleted user accounts

@Lukewould be good to change this to give a 404 error rather than end up with the misleading and not true column not found error.

@SchleudertraumaWill wait for the next time to catch it and hopefully get the logs going back to the time the item was being streamed by Sandra and remained to show even though Sandra user account played other media after that.

 

 

[Schleudertrauma 10]

Hey friends,

some time has gone now and there it is again: the abandoned session on the dashboard. This time it is not the user Sandra, it is the user Selle. It seems that it's not related to just one specific user as i thought in the beginning, as the issue occured only on Sandra.

I'll attach the emby logs here and send all related data as zip to @sa2000

I enabled the debug feature, so the logs are relatively big but maybe you can investigate quite better.

There are again some database errors in the logs saying "database locked" but i don't know what that is and this time there are no ombi related database errors.

embyserver.txt embyserver-63860224265.txt embyserver-63860235065.txt

[sa2000 674]

Thank You @Schleudertraumafor the logs above and the raw log files you zipped and sent to me together with activitylog db and other databases

I can see errors arising when skipping - playback was at offset 00:40:39 of the movie then selle skipped to the last segment or penultimate segment at offset 01:38:41 - this gave an error 500 because of what looks like some timing issue with killing the existing transcode and starting a new one for the new offset. I have passed my analysis and logs to the development team. Not sure at this point if the error 500 after the skip to very near the end was a factor.

[Schleudertrauma 10]

Hmm, i don't know that either. Do you think that has something to do with the abandoned session on the dashboard?

FYI: I have to say that i shutted down my whole infrustructure inclusive emby server. I was not killing the services, i shutted down emby normally at around 4 pm CEST but there were two playbacks at this time, one from "Candy" who looked "Glass" and one from "Selle" who looked "Boogeyman" at that time. When i was finished with the things to do at around 5 pm CEST, i started everything and later Selle watched the rest of the movie and then the session of Selle didn't vanish from dashboard.

I don't think that the abandoned sessions are related to the normal shutdown of emby, because the user Sandra triggered the issue quite more often and emby was running for months.

Maybe that information helps you for the error 500.

[sa2000 674]

I need to look into this further - because there was actually as far as the server is concerned, a playback session still not reported as stopped by the android app.

See if selle can remember the steps and what happened. I will outline what i saw in the logs

The offset showing in the Now Playing screenshot relates to the earlier error 500 when a skip forward to near the end was made. But playback as far as the server is concerned was not stopped.

On Wednesday 25 August at 21:32 Boogeyman playback started on android device MiTV-MSSP3 from offset 00:23:36 in the movie - so 23 minutes in.

This continued to 21:59:54 reaching offset 00:40:42 when there was an error 

Playback resumed at 22:00:03 from offset 00:40:39 and and there was a skip to the end to offset 01:38:39 at 22:00:25 which error'ed 

Playback then resumed at 22:00:43 from offset 00:40:39 - there were some errors again and skips - with the last action being streaming up to offset 00:57:36 with the last request coming in from the device for that playback session being at 22:02:09

There was no indication that the playback was stopped or paused - but the emby client app after requesting the subtitles segment for offset 00:57:36, then went on to request media items info after 22:02:09 - no more playback but no indication from android app to the server that the playback stopped. What was showing on the screen after reaching offset circa 00:57 at 22:02 am on the 26th CET time?

Any errors ?

Did the video playback disappear ?