Jump to content

My initial library scan takes forever after upgrade to 4.0.0.2

chjohans

By chjohans
in General/Windows

 Share

Recommended Posts

chjohans

chjohans 34

Posted
Posted

I have two installations that share exact copies of the exact same fairly large media libraries. One installation auto upgraded and did the initial media scan without me even noticing, the other installation now takes forever to do the initial media scan. The only difference between the two installations is Live TV. Both have live TV but the installation that now takes "forever" has many more channels from two different Live TV providers.

 

Below is a snippet from my server logfile, take note of the "Info App: Cleaning item" entries posted about once every second. Those looks to me like they might be programs or parts of programs from my "historical TV Guide", they all seem useless to me and I would delete them from my DB if I knew how.

 

My media library scan wen't reasonably fast to 99.4%, it has now beens stucked at 99.4% without moving for about 15 hours. It logs an "Info App: Cleaning item" about every second and I'm pretty sure this is the reason it takes "forever" to finish.

 

Exactly what is it doing with those "TV guide snippets" this seems to be and why? Historical TV guide data is not at all interesting. How can I stop this and delete these data so I can finish my library scan?

2019-01-19 04:35:46.299 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:35:47.387 Info App: Cleaning item Episode .169. type: LiveTvProgram path: 
2019-01-19 04:35:49.113 Info App: Cleaning item Weekend Midday News (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:35:50.100 Info App: Cleaning item Episode .398. type: LiveTvProgram path: 
2019-01-19 04:35:51.165 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:35:52.162 Info App: Cleaning item Episode .79. type: LiveTvProgram path: 
2019-01-19 04:35:53.854 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:35:54.370 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&SortBy=Random&IncludeItemTypes=Movie,Series&ImageTypeLimit=1. UserAgent: 
2019-01-19 04:35:54.979 Info App: Cleaning item Episode .169. type: LiveTvProgram path: 
2019-01-19 04:35:54.983 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 613ms. http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&SortBy=Random&IncludeItemTypes=Movie,Series&ImageTypeLimit=1
2019-01-19 04:35:55.697 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:35:56.867 Info App: Cleaning item Episode .71. type: LiveTvProgram path: 
2019-01-19 04:35:57.922 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:00.113 Info App: Cleaning item Episode 2.603. type: LiveTvProgram path: 
2019-01-19 04:36:01.140 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:02.870 Info App: Cleaning item Episode .346. type: LiveTvProgram path: 
2019-01-19 04:36:04.106 Info App: Cleaning item Phoenix Focus (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:06.165 Info App: Cleaning item News Talk (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:07.852 Info App: Cleaning item China News Live (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:09.496 Info App: Cleaning item Episode .137. type: LiveTvProgram path: 
2019-01-19 04:36:10.495 Info App: Cleaning item Phoenix Focus (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:12.132 Info App: Cleaning item Episode .398. type: LiveTvProgram path: 
2019-01-19 04:36:14.317 Info App: Cleaning item Phoenix Evening Express (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:15.340 Info App: Cleaning item Episode .280. type: LiveTvProgram path: 
2019-01-19 04:36:17.013 Info App: Cleaning item Phoenix Focus (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:18.158 Info App: Cleaning item News Talk (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:19.869 Info App: Cleaning item News On The Hour (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:20.976 Info App: Cleaning item Decoding News Terms <Combined Version> type: LiveTvProgram path: 
2019-01-19 04:36:22.142 Info App: Cleaning item Episode 2.603. type: LiveTvProgram path: 
2019-01-19 04:36:23.213 Info App: Cleaning item Weekend Midnight Express (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:24.385 Info App: Cleaning item Episode .398. type: LiveTvProgram path: 
2019-01-19 04:36:24.980 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&SortBy=Random&IncludeItemTypes=Movie,Series&ImageTypeLimit=1. UserAgent: 
2019-01-19 04:36:26.051 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:26.054 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 1074ms. http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&SortBy=Random&IncludeItemTypes=Movie,Series&ImageTypeLimit=1
2019-01-19 04:36:26.224 Info HttpServer: HTTP HEAD http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:27.073 Info App: Cleaning item Episode .137. type: LiveTvProgram path: 
2019-01-19 04:36:27.212 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 988ms. http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f
2019-01-19 04:36:27.223 Info HttpServer: HTTP HEAD http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:27.224 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 4ms. http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f
2019-01-19 04:36:27.241 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:27.244 Info HttpResultFactory: Setting range response values for \\CUBESTATION-01\video\Movies\The Chronicles of Narnia, The Voyage of the Dawn Treader (2010)\fanart.jpg. RangeRequest: bytes=0- Content-Length: 880194, Content-Range: bytes 0-880193/880194
2019-01-19 04:36:27.372 Info HttpServer: HTTP Response 206 to 192.168.3.152. Time: 132ms. http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f
2019-01-19 04:36:27.518 Info HttpServer: HTTP HEAD http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:27.522 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 4ms. http://192.168.3.105:8096/emby/Items/3090549/Images/Backdrop/0?Format=original&Tag=74f8358c8d387c2f18bae34ed4071a1f
2019-01-19 04:36:28.223 Info App: Cleaning item Episode .731. type: LiveTvProgram path: 
2019-01-19 04:36:29.158 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&Fields=DateCreated,Etag&SortBy=DateCreated&SortOrder=Descending&IncludeItemTypes=Movie,Episode&ImageTypeLimit=0&format=json. UserAgent: 
2019-01-19 04:36:30.004 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:30.005 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 848ms. http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&Fields=DateCreated,Etag&SortBy=DateCreated&SortOrder=Descending&IncludeItemTypes=Movie,Episode&ImageTypeLimit=0&format=json
2019-01-19 04:36:30.052 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&Fields=DateCreated,Etag&SortBy=DatePlayed&SortOrder=Descending&IncludeItemTypes=Movie,Episode&ImageTypeLimit=0&format=json. UserAgent: 
2019-01-19 04:36:31.510 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 1457ms. http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&Fields=DateCreated,Etag&SortBy=DatePlayed&SortOrder=Descending&IncludeItemTypes=Movie,Episode&ImageTypeLimit=0&format=json
2019-01-19 04:36:31.510 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:33.348 Info App: Cleaning item Episode 1.156. type: LiveTvProgram path: 
2019-01-19 04:36:34.383 Info App: Cleaning item Episode .280. type: LiveTvProgram path: 
2019-01-19 04:36:36.075 Info App: Cleaning item Episode .732. type: LiveTvProgram path: 
2019-01-19 04:36:37.768 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:38.973 Info App: Cleaning item News Talk (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:39.659 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:40.928 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:42.034 Info App: Cleaning item Phoenix Morning Express (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:43.226 Info App: Cleaning item China News Live (19/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:44.853 Info App: Cleaning item Episode .346. type: LiveTvProgram path: 
2019-01-19 04:36:46.652 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:48.555 Info App: Cleaning item Episode .137. type: LiveTvProgram path: 
2019-01-19 04:36:49.540 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:51.135 Info App: Cleaning item Episode .72. type: LiveTvProgram path: 
2019-01-19 04:36:52.757 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:54.326 Info App: Cleaning item Episode .243. type: LiveTvProgram path: 
2019-01-19 04:36:55.663 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&SortBy=Random&IncludeItemTypes=Movie,Series&ImageTypeLimit=1. UserAgent: 
2019-01-19 04:36:56.109 Info App: Cleaning item Episode 2.603. type: LiveTvProgram path: 
2019-01-19 04:36:57.481 Info App: Cleaning item The Midday Express (S2) (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:36:57.481 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 1818ms. http://192.168.3.105:8096/emby/Users/314620670f7447b08059900535ba660f/Items?Recursive=true&limit=1&SortBy=Random&IncludeItemTypes=Movie,Series&ImageTypeLimit=1
2019-01-19 04:36:57.645 Info HttpServer: HTTP HEAD http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:58.066 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 421ms. http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a
2019-01-19 04:36:58.073 Info HttpServer: HTTP HEAD http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:58.077 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 3ms. http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a
2019-01-19 04:36:58.096 Info HttpServer: HTTP GET http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:58.100 Info HttpResultFactory: Setting range response values for \\CUBESTATION-01\video\Movies\24 Hours to Live (2017)\fanart.jpg. RangeRequest: bytes=0- Content-Length: 1423659, Content-Range: bytes 0-1423658/1423659
2019-01-19 04:36:58.244 Info HttpServer: HTTP Response 206 to 192.168.3.152. Time: 148ms. http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a
2019-01-19 04:36:58.517 Info HttpServer: HTTP HEAD http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a. UserAgent: Kodi/17.6 (X11; Linux armv7l) Linux/2018.08-2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6
2019-01-19 04:36:58.523 Info HttpServer: HTTP Response 200 to 192.168.3.152. Time: 6ms. http://192.168.3.105:8096/emby/Items/3087898/Images/Backdrop/0?Format=original&Tag=6e32008bd3049a962d0f3cc446ed669a
2019-01-19 04:36:59.190 Info App: Cleaning item Episode .120. type: LiveTvProgram path: 
2019-01-19 04:37:00.570 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:02.106 Info App: Cleaning item Episode .280. type: LiveTvProgram path: 
2019-01-19 04:37:04.248 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:05.441 Info App: Cleaning item Episode .243. type: LiveTvProgram path: 
2019-01-19 04:37:07.616 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:09.260 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:10.234 Info App: Cleaning item Episode .733. type: LiveTvProgram path: 
2019-01-19 04:37:11.826 Info App: Cleaning item News On The Hour (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:12.814 Info App: Cleaning item Financial Journal (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:14.499 Info App: Cleaning item Phoenix Focus (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:16.058 Info App: Cleaning item News Talk (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:17.776 Info App: Cleaning item China News Live (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:19.369 Info App: Cleaning item Episode .733. type: LiveTvProgram path: 
2019-01-19 04:37:20.380 Info App: Cleaning item Phoenix Focus (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:22.024 Info App: Cleaning item Episode .243. type: LiveTvProgram path: 
2019-01-19 04:37:23.650 Info App: Cleaning item Phoenix Financial Daily Report (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:24.809 Info App: Cleaning item Phoenix Evening Express (20/8/18) type: LiveTvProgram path: 
2019-01-19 04:37:25.450 Info App: Cleaning item Chief Editor's Time (20/8/18) type: LiveTvProgram path:
Luke Emby Team

Luke 42078

Posted
Posted

Hi there, please attach the complete emby server log. thanks !

chjohans

chjohans 34

Posted
  • Author
Posted (edited)

After updating to v 4.0.0.2 my scheduled task "Refresh Guide" no longer finish. It will go sloooooowly to 100% and then hang there forever.

 

I will attach a server log later, but right now there are so many things going on after the "update" so this have to wait until I can post a less "contaminated" logfile.

Edited by chjohans
Luke Emby Team

Luke 42078

Posted
Posted

Hi there, please attach the emby server log . thanks !

chjohans

chjohans 34

Posted
  • Author
Posted

Sure, will do that when I have sorted a few other things out. Right now it's too "contaminated"....

chjohans

chjohans 34

Posted
  • Author
Posted

It's the same logfile as in another post you also have read but here it is. My library scan started long before this logfile were created though as it "rolled over" at midnight.

embyserver.txt

chjohans

chjohans 34

Posted
  • Author
Posted

The current log is actually in my other two recent threads which ytou have already commented on. But right now my initial scan that seems to hang forever is going on, so I thought for this isue I would restart the server, restart "Refresh Guide" and wait for it to reach 100% and then post the server log. That way there will be more relevant info I the log I think, right now my logfile has "rolled over" since "Refresh Gude" (still hanging) was started.

 

Will do this once my "initial scan" issue is sorted out.

Luke Emby Team

Luke 42078

Posted
Posted

Try turning off chapter image extraction for your libraries and that should get the scan to complete quicker. Then you can turn that back on after the first scan is done. thanks.

chjohans

chjohans 34

Posted
  • Author
Posted

But "Enable chapter image extraction" is not enabled for any of my libraries. However the scheduler will start the scheduled chapter image extraction as per schedule.

 

 

Could you also please comment on the "TV guide snippets" and why it seems to do "Cleaning item" for thousands of historical TV guide data snippets?

 

Will my library scan ever finish or should I just abort it? It is still sitting at 99.4%.

chjohans

chjohans 34

Posted
  • Author
Posted

Thanks for looking into this, attached is the logfile from when the server started up in case you need the complete logfile

embyserver-63683452800.txt

Luke Emby Team

Luke 42078

Posted
Posted

I'm just going by what i see in the log and it suggests you have chapter images enabled.

Luke Emby Team

Luke 42078

Posted
Posted

Could you also please comment on the "TV guide snippets" and why it seems to do "Cleaning item" for thousands of historical TV guide data snippets?

 

Will my library scan ever finish or should I just abort it? It is still sitting at 99.4%.

 

As the guide rotates this is normal. It's usually just a small amount each day. Perhaps you had old data in your previous database that never got cleaned properly, and following the migration, now it is.

 

It could also just be that the initial guide refresh is doing a complete wipe and re-import, which might be happening for external live tv plugins. I'd have to double check on that.

chjohans

chjohans 34

Posted
  • Author
Posted

Ok, so are you saying that what happens now is that the migratiopn process is cleaning/deleting those old TV schedule data?

 

And I assume my library scan will eventually finish, even if it takes days, so Ill just leave it alone until it does. Unless you advise me to stop it.

 

My guide refresh is hanging while this is happening, at 100% but it hangs there and won't finish. I thought I had posted about it but now I can't find that post. Anyhow my "Refresh Guide" sheduled task hangs at 100% while the library scan is going on, it has hung at 100% for about half a day now. I don't think it will ever finish. Both server logs attached to this thread is valid for that issue as well. Would appreciated if you could have a look at this as well.

Luke Emby Team

Luke 42078

Posted
Posted

The refresh guide hasn't hung, it's just taking a long time at the final step which is clearing out of old entries. Yes I would just allow it to finish. Thanks.

chjohans

chjohans 34

Posted
  • Author
Posted

Ok I will let it run until it finishes then. My library scan has moved from 99.4% to 99.5% now so at least there are some progress. Will leave it alone until it finish and update here :)

chjohans

chjohans 34

Posted
  • Author
Posted

I had to restart my server yesterday, but I assume it will resume where it was interrupted. My initial library scan really takes forever, it has been about 6 days total now, this is really ridiculous. And my "Refresh guide" always hangs at 100%.

 

Is there something I can do to speed up this? Can I manually deleted those useless data elements from guide data?

 

And I see a lot of errors posted in my log, please have a look and let me know if I should worry about them.

embyserver newlog.txt

chjohans

chjohans 34

Posted
  • Author
Posted

@@Luke could you please take a look at the logfile in the post above this? My server is spewing out error messages similar to this:

2019-01-23 11:22:23.688 Info HttpServer: HTTP POST http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json. UserAgent: 
2019-01-23 11:22:23.692 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 4.0.0.2
	Command line: C:\Users\media\AppData\Roaming\Emby-Server\System\EmbyServer.dll -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 4
	Program data path: C:\Users\media\AppData\Roaming\Emby-Server
	Application directory: C:\Users\media\AppData\Roaming\Emby-Server\System
	System.ArgumentNullException: System.ArgumentNullException: Value cannot be null.
	Parameter name: username
	   at Emby.Server.Implementations.Library.UserManager.AuthenticateUser(String username, String password, String hashedPassword, String remoteEndPoint, Boolean isUserSession, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.AuthenticateNewSessionInternal(AuthenticationRequest request, Boolean enforcePassword, CancellationToken cancellationToken)
	   at MediaBrowser.Api.UserService.Post(AuthenticateUserByName request)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	Source: Emby.Server.Implementations
	TargetSite: Void MoveNext()
	   at Emby.Server.Implementations.Library.UserManager.AuthenticateUser(String username, String password, String hashedPassword, String remoteEndPoint, Boolean isUserSession, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.AuthenticateNewSessionInternal(AuthenticationRequest request, Boolean enforcePassword, CancellationToken cancellationToken)
	   at MediaBrowser.Api.UserService.Post(AuthenticateUserByName request)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	
2019-01-23 11:22:23.692 Info HttpServer: HTTP Response 400 to 192.168.3.152. Time: 4ms. http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json

Should I be concerned about this?

Luke Emby Team

Luke 42078

Posted
Posted

I had to restart my server yesterday, but I assume it will resume where it was interrupted. My initial library scan really takes forever, it has been about 6 days total now, this is really ridiculous. And my "Refresh guide" always hangs at 100%.

 

Is there something I can do to speed up this? Can I manually deleted those useless data elements from guide data?

 

And I see a lot of errors posted in my log, please have a look and let me know if I should worry about them.

 

Try turning off chapter image extraction for your libraries.

Luke Emby Team

Luke 42078

Posted
Posted

@@Luke could you please take a look at the logfile in the post above this? My server is spewing out error messages similar to this:

2019-01-23 11:22:23.688 Info HttpServer: HTTP POST http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json. UserAgent: 
2019-01-23 11:22:23.692 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 4.0.0.2
	Command line: C:\Users\media\AppData\Roaming\Emby-Server\System\EmbyServer.dll -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 4
	Program data path: C:\Users\media\AppData\Roaming\Emby-Server
	Application directory: C:\Users\media\AppData\Roaming\Emby-Server\System
	System.ArgumentNullException: System.ArgumentNullException: Value cannot be null.
	Parameter name: username
	   at Emby.Server.Implementations.Library.UserManager.AuthenticateUser(String username, String password, String hashedPassword, String remoteEndPoint, Boolean isUserSession, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.AuthenticateNewSessionInternal(AuthenticationRequest request, Boolean enforcePassword, CancellationToken cancellationToken)
	   at MediaBrowser.Api.UserService.Post(AuthenticateUserByName request)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	Source: Emby.Server.Implementations
	TargetSite: Void MoveNext()
	   at Emby.Server.Implementations.Library.UserManager.AuthenticateUser(String username, String password, String hashedPassword, String remoteEndPoint, Boolean isUserSession, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.AuthenticateNewSessionInternal(AuthenticationRequest request, Boolean enforcePassword, CancellationToken cancellationToken)
	   at MediaBrowser.Api.UserService.Post(AuthenticateUserByName request)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	
2019-01-23 11:22:23.692 Info HttpServer: HTTP Response 400 to 192.168.3.152. Time: 4ms. http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json

Should I be concerned about this?

 

I don't think so.

chjohans

chjohans 34

Posted
  • Author
Posted

OK. I will turn off chapter image extraction until this is sorted out. It's only on for one of my libraries anyhow, and not during library scan only by it's own scheduled task.

Luke Emby Team

Luke 42078

Posted
Posted

Let us know how you get on.

chjohans

chjohans 34

Posted
  • Author
Posted

I had to reboot the server for other reasons. SO I updated to the latest server release and updated some plugins at the same time.

 

My library scan is again stuck at 99.4% and "Refresh Guide" is stuck at 100%. 

 

My log shows quite a lot of these error messages:

2019-01-24 05:42:47.359 Info HttpServer: HTTP POST http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json. UserAgent: 
2019-01-24 05:42:47.367 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 4.0.1.0
	Command line: C:\Users\media\AppData\Roaming\Emby-Server\System\EmbyServer.dll -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 4
	Program data path: C:\Users\media\AppData\Roaming\Emby-Server
	Application directory: C:\Users\media\AppData\Roaming\Emby-Server\System
	System.ArgumentNullException: System.ArgumentNullException: Value cannot be null.
	Parameter name: username
	   at Emby.Server.Implementations.Library.UserManager.AuthenticateUser(String username, String password, String hashedPassword, String remoteEndPoint, Boolean isUserSession, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.AuthenticateNewSessionInternal(AuthenticationRequest request, Boolean enforcePassword, CancellationToken cancellationToken)
	   at MediaBrowser.Api.UserService.Post(AuthenticateUserByName request)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	Source: Emby.Server.Implementations
	TargetSite: Void MoveNext()
	   at Emby.Server.Implementations.Library.UserManager.AuthenticateUser(String username, String password, String hashedPassword, String remoteEndPoint, Boolean isUserSession, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.AuthenticateNewSessionInternal(AuthenticationRequest request, Boolean enforcePassword, CancellationToken cancellationToken)
	   at MediaBrowser.Api.UserService.Post(AuthenticateUserByName request)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.GetTaskResult(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, ILogger logger, String operationName, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, String urlString, String host, String localPath, CancellationToken cancellationToken)
	
2019-01-24 05:42:47.368 Info HttpServer: HTTP Response 400 to 192.168.3.152. Time: 9ms. http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json

And quite a bit of messages like this:

2019-01-24 05:42:47.368 Info HttpServer: HTTP Response 400 to 192.168.3.152. Time: 9ms. http://192.168.3.105:8096/emby/Users/AuthenticateByName?format=json
2019-01-24 05:42:47.657 Info App: Cleaning item Episode 2.310. - Pups Save The Polar Bears / A Pup In Sheep's Clothing type: LiveTvProgram path: 
2019-01-24 05:42:49.299 Info App: Cleaning item Episode 3.423. - Pups Save Francois The Penguin / / Pups Save Daring Danny's Hippo type: LiveTvProgram path: 
2019-01-24 05:42:50.249 Info App: Cleaning item Episode 1.206. - Bling, Bling / Staffinated type: LiveTvProgram path: 
2019-01-24 05:42:51.867 Info App: Cleaning item Episode 0.102. - Knighty Knight Dragons / Inside And Seek type: LiveTvProgram path: 
2019-01-24 05:42:52.814 Info App: Cleaning item Episode 0.110. - Shirley's Rocket Adventure type: LiveTvProgram path: 
2019-01-24 05:42:53.772 Info App: Cleaning item Episode 1.221. - Pups Save A Floundering Francois / Pups Save The Pop-Up Penguins type: LiveTvProgram path: 
2019-01-24 05:42:54.789 Info App: Cleaning item Episode 0.100. - Rusty's Rex Rescue / Rusty's Park N' Fly type: LiveTvProgram path: 
2019-01-24 05:42:55.807 Info App: Cleaning item Episode 0.103. - How To Bathe Your Dragon type: LiveTvProgram path:

Based on your earlier response I assume the latter is "Refresh Guide" cleaning up old guide data.

 

I will just leave it alone until it hopefully finish.

chjohans

chjohans 34

Posted
  • Author
Posted

These are the same error messages I've asked about before. Anyhow new serverlog attached.

 

Exactly what does these error messages mean?

Luke Emby Team

Luke 42078

Posted
Posted

It looks like a bad request coming from an app, but i have no idea what app it is. All i know is that it's not an Emby app.

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

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