xnappo 1611 Posted May 21, 2015 Posted May 21, 2015 Saw this error tonight after starting playback on something after a wake from suspend - but nada in my logfile. Anyone else seeing this?
Angelblue05 4132 Posted May 21, 2015 Posted May 21, 2015 (edited) That's odd. Search for errno 10061 in your logs, do you find anything? Edited May 21, 2015 by Angelblue05
xnappo 1611 Posted May 21, 2015 Author Posted May 21, 2015 (edited) Nope. Here is a pastebin: The error was somewhere between 23:35:38 and 23:39:13 I see nothing in the log that looks like an error, yet I got a GUI error... That shouldn't be possible right? Granted I have the logs set to '0' but anything that causes a GUI error needs to be logged at 0. xnappo [EDIT] Probably we have places where we have GUI errors that do not log with log set to 0. We should change that, but I will change my logging on this family machine to 1 and see if it happens again. Edited May 21, 2015 by xnappo 1
Angelblue05 4132 Posted May 21, 2015 Posted May 21, 2015 Yeah, we need to improve logging. It is on the trello board. We need to review what should be posted at which level.
xnappo 1611 Posted May 22, 2015 Author Posted May 22, 2015 (edited) Still not seeing much. Pretty reproducible when playing back the first item after a resume. The popup occurred in this time frame. I am thinking it might be these 'send progress update' errors? 23:35:51 T:140421234378496 NOTICE: EMBY Player -> ADDING_FILE: {'smb://mars/d/video/tv shows/Modern Family/Season 06/Modern Family.S06E15.Fight or Flight.mpg': {'refresh_i 23:35:51 T:140421234378496 NOTICE: Doing_Db_Sync Post Resume: syncDatabase (Started) -> 0 23:36:09 T:140420969379584 NOTICE: ES: Client from 127.0.0.1 timed out 23:36:10 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: 0f3765c02f0d0c2271d2e547f3d250a4 - Startalk - S01E01 - George Takei 23:36:10 T:140421225985792 NOTICE: script.tv.show.next.aired: ### starting data update 23:36:11 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: f761acaef81b627bbce19d799d96ba68 - Startalk - S01E02 23:36:13 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: 4fd41cdbd9a94876e9e42137d34d3ce7 - Choked on Multi-Colored Scarves 23:36:16 T:140421234378496 NOTICE: UPDATE episode to Kodi library -> Id: d673f7cba20371dabe95a5ca7ee96c9b - Title: Fight or Flight 23:36:17 T:140421225985792 NOTICE: script.tv.show.next.aired: ### data update finished 23:36:21 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: d3a4022d614667175cdac5f5d4c8e2d8 - doctor_who_2005.2013_christmas_special.the_ti 23:36:26 T:140421234378496 NOTICE: Sync DB -> syncDatabase Exiting 23:36:26 T:140421234378496 NOTICE: Doing_Db_Sync Post Resume: syncDatabase (Finished) True -> 0 23:36:26 T:140421234378496 NOTICE: Doing_Db_Sync Post Resume: syncDatabase (Started) -> 0 23:36:26 T:140420597573376 NOTICE: Thread VideoInfoScanner start, auto delete: false 23:36:26 T:140420597573376 NOTICE: VideoInfoScanner: Starting scan .. 23:36:26 T:140420597573376 NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:00 23:36:41 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: 0f3765c02f0d0c2271d2e547f3d250a4 - Startalk - S01E01 - George Takei 23:36:41 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: f761acaef81b627bbce19d799d96ba68 - Startalk - S01E02 23:36:42 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: 4fd41cdbd9a94876e9e42137d34d3ce7 - Choked on Multi-Colored Scarves 23:36:50 T:140421234378496 NOTICE: Emby -> SKIP adding episode to Kodi Library, no ParentIndexNumber - ID: d3a4022d614667175cdac5f5d4c8e2d8 - doctor_who_2005.2013_christmas_special.the_ti 23:36:54 T:140421234378496 NOTICE: Sync DB -> syncDatabase Exiting 23:36:54 T:140421234378496 NOTICE: Doing_Db_Sync Post Resume: syncDatabase (Finished) True -> 0 23:36:54 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:36:54 T:140420977772288 NOTICE: EMBY WebSocketThread -> Error: argument of type 'int' is not iterable 23:36:54 T:140420977772288 NOTICE: EMBY WebSocketThread -> Server is unreachable. 23:36:54 T:140420597573376 NOTICE: Thread VideoInfoScanner start, auto delete: false 23:36:54 T:140420597573376 NOTICE: VideoInfoScanner: Starting scan .. 23:36:54 T:140420597573376 NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:00 23:36:56 T:140421234378496 NOTICE: EMBY Service -> Server is online and ready. 23:36:59 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:36:59 T:140421234378496 NOTICE: EMBY WebSocketThread -> Exception: 'NoneType' object has no attribute 'send' 23:36:59 T:140420977772288 NOTICE: EMBY DownloadUtils -> Posted capabilities to http://192.168.1.6:8096 23:37:00 T:140420977772288 NOTICE: EMBY DownloadUtils -> SessionId: 33d93fbdaa68db1f94fce2c06b38c215 23:37:03 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:37:51 T:140420250810112 NOTICE: Previous line repeats 13 times. 23:37:51 T:140420250810112 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available 23:37:51 T:140421234378496 WARNING: Previous line repeats 2 times. 23:37:51 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:45:57 T:140420250810112 NOTICE: Previous line repeats 125 times. 23:45:57 T:140420250810112 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available 23:45:58 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:46:03 T:140420250810112 NOTICE: Previous line repeats 5 times. 23:46:03 T:140420250810112 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available 23:46:03 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:58:32 T:140420977772288 NOTICE: Previous line repeats 185 times. 23:58:32 T:140420977772288 NOTICE: EMBY WebSocketThread -> Message : {"MessageType":"LibraryChanged","Data":{"FoldersAddedTo":[],"FoldersRemovedFrom":[],"ItemsAdded":[],"ItemsRemoved":[], 23:58:32 T:140420977772288 NOTICE: EMBY WebSocketThread -> Message : WebSocket LibraryChanged : Items Added : [] 23:58:32 T:140420977772288 NOTICE: EMBY WebSocketThread -> Message : WebSocket LibraryChanged : Items Updated : [u'5a8f0c453849dc6cea22c7888cd792a2'] 23:58:32 T:140420977772288 NOTICE: EMBY WebSocketThread -> Message : WebSocket LibraryChanged : Items Removed : [] 23:58:32 T:140420977772288 NOTICE: EMBY WebSocketThread -> Message : Doing LibraryChanged : Processing Added and Updated : [u'5a8f0c453849dc6cea22c7888cd792a2'] 23:58:34 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate 23:58:38 T:140420977772288 NOTICE: Previous line repeats 1 times. 23:58:38 T:140420977772288 NOTICE: UPDATE episode to Kodi library -> Id: 5a8f0c453849dc6cea22c7888cd792a2 - Title: Mini Myth Medley 23:58:38 T:140420597573376 NOTICE: Thread VideoInfoScanner start, auto delete: false 23:58:38 T:140420597573376 NOTICE: VideoInfoScanner: Starting scan .. 23:58:38 T:140420597573376 NOTICE: VideoInfoScanner: Finished scan. Scanning for video info took 00:00 23:58:40 T:140420977772288 NOTICE: EMBY WebSocketThread -> Message : {"MessageType":"ScheduledTaskEnded","Data":{"StartTimeUtc":"2015-05-21T23:57:50.2283099Z","EndTimeUtc":"2015-05-21T23: 23:58:42 T:140421234378496 NOTICE: EMBY WebSocketThread -> sendProgressUpdate Edited May 22, 2015 by xnappo 1
xnappo 1611 Posted May 22, 2015 Author Posted May 22, 2015 I think it is something in this code and how it reacts to suspend/resume: while not self.KodiMonitor.abortRequested(): if WINDOW.getProperty("Server_online") == "true": # Server came back online, repost capabilities self.doUtils.postCapabilities(deviceId) self.client.run_forever() if (self.keepRunning): # Server is not online if WINDOW.getProperty("Server_online") == "true": self.logMsg("Server is unreachable.", 1) WINDOW.setProperty("Server_online", "false") xbmcgui.Dialog().notification("Error connecting", "%s Server is unreachable." % self.addonName) if self.KodiMonitor.waitForAbort(5): break
Angelblue05 4132 Posted May 22, 2015 Posted May 22, 2015 (edited) NVM, I see it now. Hmm let me check the sendprogress report and I'll get back to you. @@xnappo can you try to change this line in player.py Line 183 if playTime: postdata['PositionTicks'] = int(playTime) * 10000000 As I can't reproduce the problem. Thanks. Edited May 22, 2015 by Angelblue05 1
Recommended Posts