Completed HTTPConnectionPool read timed out

December 4, 2019

Hi there, long time no see! Hoping everyone doing great.

After jurialmunkey released Arctic Zephyr 2 skin I' ve started to use my Kodi+Emby setup again.

I am running on 18.5 Leia/Windows 10 and 4.1.16 version of the Kodi Add-on. Emby backend is on 4.3.0.30 stable version.

When I manually try to update thememedia (for using Tvtunes both script and newly introduced service version.) I got below errors on Kodi log. Both Kodi client and Emby server is on the same LAN. Emby server is accessible via Web browser without any problems. (looks like I don't have firewall issue on server)

2019-12-04 00:46:39.612 T:23528  NOTICE: Emby.emby.core.connection_manager -> testing connection mode 2 with server ServerName
2019-12-04 00:46:39.616 T:23528  NOTICE: Emby.emby.core.connection_manager -> tryConnect url: http://{emby-server}/emby/system/info/public
2019-12-04 00:46:39.679 T:23528  NOTICE: Emby.emby.core.connection_manager -> calling onSuccessfulConnection with connection mode 2 with server ServerName
2019-12-04 00:46:39.741 T:23528  NOTICE: Emby.emby.client -> User is authenticated.
2019-12-04 00:46:39.914 T:1116  NOTICE: Emby.emby.core.ws_client -> Websocket url: ws://{emby-server}/embywebsocket?api_key={emby-token}&device_id=AC262A228CBC4A0E84E0B8691Cxxxxxxx
2019-12-04 00:46:39.921 T:11020  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/LoadServer ]
2019-12-04 00:46:39.923 T:11020  NOTICE: EMBY.hooks.monitor -> --[ post capabilities/ab830a458c254fc2b52e67dxxxxxxxxx ]
2019-12-04 00:46:39.943 T:23528  NOTICE: EMBY.setup -> ---<[ setup ]
2019-12-04 00:46:39.971 T:18592  NOTICE: EMBY.library -> --->[ library ]
2019-12-04 00:46:39.978 T:1116  NOTICE: Emby.emby.core.ws_client -> --->[ websocket ]
2019-12-04 00:46:40.263 T:21376  NOTICE: EMBY.entrypoint.service -> [ onNotification/plugin.video.emby/ServerOnline ]
2019-12-04 00:46:40.667 T:20600   ERROR: Window Translator: Can't find window videolibrary
2019-12-04 00:46:41.671 T:12908  NOTICE: script.tv.show.next.aired --> Update complete
2019-12-04 00:46:42.241 T:18592  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-12-04 00:46:43.837 T:20600  NOTICE: Previous line repeats 11 times.
2019-12-04 00:46:43.837 T:20600   ERROR: Window Translator: Can't find window videolibrary
2019-12-04 00:46:44.168 T:18592  NOTICE: EMBY.helper.wrapper -> Processing None
2019-12-04 00:46:44.174 T:18592  NOTICE: EMBY.library -> --[ retrieve changes ] 2019-12-03T21:14:17z
2019-12-04 00:47:33.699 T:20600   ERROR: Window Translator: Can't find window videolibrary
2019-12-04 00:47:38.474 T:10384 WARNING: Unable to determine channel type. Defaulting to TV.
2019-12-04 00:47:59.877 T:20200 WARNING: Previous line repeats 2 times.
2019-12-04 00:47:59.877 T:20200  NOTICE: Emby -> ---[ START EMBYCLIENT ]---
2019-12-04 00:47:59.935 T:20200  NOTICE: EMBY.entrypoint.default -> path:  params: {}
2019-12-04 00:47:59.971 T:20200  NOTICE: EMBY.default -> ---<[ default ]
2019-12-04 00:48:06.135 T:8948  NOTICE: Emby -> ---[ START EMBYCLIENT ]---
2019-12-04 00:48:06.192 T:8948  NOTICE: EMBY.entrypoint.default -> path: ?mode=managelibs params: {
                                                "mode": "managelibs"
                                            }
2019-12-04 00:48:06.215 T:8948  NOTICE: EMBY.default -> ---<[ default ]
2019-12-04 00:48:08.733 T:15968  NOTICE: Emby -> ---[ START EMBYCLIENT ]---
2019-12-04 00:48:08.791 T:15968  NOTICE: EMBY.entrypoint.default -> path: ?mode=thememedia params: {
                                                "mode": "thememedia"
                                            }
2019-12-04 00:48:08.828 T:15968  NOTICE: EMBY.entrypoint.default -> TV Tunes custom path is enabled and set.
2019-12-04 00:48:38.552 T:10384 WARNING: Unable to determine channel type. Defaulting to TV.
2019-12-04 00:51:14.060 T:15968 WARNING: Previous line repeats 8 times.
2019-12-04 00:51:14.060 T:15968  NOTICE: Emby.emby.core.http -> ERROR:: HTTPConnectionPool(host='192.168.1.10', port=8096): Read timed out. (read timeout=30)
2019-12-04 00:51:14.061 T:15968  NOTICE: EMBY.default -> ERROR:: 
                                            Traceback (most recent call last):
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\default.py", line 41, in <module>
                                                Events()
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\resources\lib\entrypoint\default.py", line 176, in __init__
                                                get_themes()
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\resources\lib\entrypoint\default.py", line 977, in get_themes
                                                result = EMBY['api'].get_items_theme_video(view)
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\libraries\emby\core\api.py", line 249, in get_items_theme_video
                                                'Recursive': True
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\libraries\emby\core\api.py", line 86, in users
                                                return  self._get("Users/{UserId}%s" % handler, params)
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\libraries\emby\core\api.py", line 53, in _get
                                                return  self._http("GET", handler, {'params': params})
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\libraries\emby\core\api.py", line 50, in _http
                                                return  self.client.request(request)
                                              File "C:\Users\username\AppData\Roaming\Kodi\addons\plugin.video.emby\libraries\emby\core\http.py", line 129, in request
                                                raise HTTPException("ReadTimeout", error)
                                            HTTPException
2019-12-04 00:51:14.061 T:15968  NOTICE: EMBY.default -> ---<[ default ]
2019-12-04 00:51:38.741 T:10384 WARNING: Unable to determine channel type. Defaulting to TV.

Any idea about what is wrong with the sync?

December 5, 2019

What is your server version? How many themes do you have?

December 5, 2019

What is your server version? How many themes do you have?

1198 theme.mp4 video files

2180 theme.mp3 audio files.

Server version 4.3.0.30.

I saw 50000-60000ms response times for Kodi addon HTTP Requests in Emby server log but no timeouts in server side. I cant access my server logs right now. I will try to provide those logs in 4-6hrs.

December 5, 2019

Ok, that is a lot. I will fix this for the next update. Thanks for your patience.

December 5, 2019

I have seen the Github issue entry that marks this post as a bug. Below are the examples from my server log. This may help you see what sort of times required to get that data:

2019-12-05 19:11:07.553 Info HttpServer: HTTP Response 200 to 192.168.1.135. Time: 760578ms. http://192.168.1.10:8096/emby/Users/3eff8a317d0ecf2ca5650xxxxxxxxxxx/Items?HasThemeVideo=True&Recursive=True&ParentId=f45cf9dbb754812dcedc518e20xxxxx
2019-12-05 19:11:29.862 Info HttpServer: HTTP Response 200 to 192.168.1.135. Time: 751845ms. http://192.168.1.10:8096/emby/Users/3eff8a317d0ecf2ca5650xxxxxxxxxxx/Items?HasThemeVideo=True&Recursive=True&ParentId=f45cf9dbb754812dcedc518e20xxxxx2019-12-05 19:11:37.949 Info HttpServer: HTTP Response 200 to 192.168.1.135. Time: 728886ms. http://192.168.1.10:8096/emby/Users/3eff8a317d0ecf2ca5650xxxxxxxxxxx/Items?HasThemeVideo=True&Recursive=True&ParentId=f45cf9dbb754812dcedc518e20xxxxx

Edited December 7, 2019 by denethor

December 6, 2019

Can you try this build and let me know if it lets you get further? Thanks.

plugin.video.emby-4.1.18b.zip

December 6, 2019

Can you try this build and let me know if it lets you get further? Thanks.

Looks like you nailed it!

Here is the stats for the process:

First entry in kodi.log:

2019-12-06 10:07:33.932 T:8792  NOTICE: EMBY.entrypoint.default -> TV Tunes custom path is enabled and set.

First folder created in "..\userdata\addon_data\plugin.video.emby\library\" Looks like it took 23min to complete:

‎Friday, ‎December ‎6, ‎2019, ‏‎10:30:48 AM

Number of folders created in above library folder: 3215 (some library items have both theme song and theme video)

Only thing I can complain about there was no log entry in kodi.log during the whole process therefore I couldn't monitor what was going on during the sync. I was curious about "should I wait more or cancel it and re-try"

I will try the sync on my Nvidia Shield on evening and let you know about the results. This test was done on Windows box.

Thank you very much for the quick response and fix

Edit: Same process took only 3 minutes in Nvidia Shield. I don't know if you did some kind of caching on server side or my network was slow during the sync in Windows.

Edited December 6, 2019 by denethor

Sign In

Completed HTTPConnectionPool read timed out

Recommended Posts

denethor 90

Link to comment

Share on other sites

Angelblue05 4130

Link to comment

Share on other sites

denethor 90

Link to comment

Share on other sites

Angelblue05 4130

Link to comment

Share on other sites

denethor 90

Link to comment

Share on other sites

Angelblue05 4130

Link to comment

Share on other sites

denethor 90

Link to comment

Share on other sites

Activity