[cdvreede]

Running several Pi2 and Windows Kodi front-ends against a win7MCE backend. Gradually with new Kodi releases I've noticed that the back-end is getting less responsive when doing things like deleting recordings or setting timers. Just timed a delete and see up to 20 seconds delay between confirming a delete and item actually being deleted. The UI seems to lock up for 5 secs or so, then unlocks but takes another 10 secs before the "working" widget appears. Times are roughly equal between Windows and Pi2 front-ends.
Back-end is pretty much idling - connected via wired 1Gb - simultaneous playback to up to 4 front-ends is no problem at all so no capacity issue in network or MCE back-end.

Any ideas??

thnx

Dennis

[sbthomas]

Do you have the remove commercial files option enabled?

Probably be best to upload a log including a file deletion to pastebin and put the link here.

[cdvreede]

Nope - no commercial files enabled..

Also not sure about PasteBin - but relevant log entries pasted below - noticed it's not as bad as I thought but still quite unreasonable for a machine that's idling..

2015/08/21 09:51:56.029 Received client request: officePC|192.168.0.131|DeleteRecording|10977524093001250|Mythbusters - Eye Pop Sneeze / Stopping Car in Reverse / Killer Butts ®|smb://mcecdvreede:*******@WIN7mce/Recorded TV/Mythbusters_7mate_2015_08_17_18_15_00.wtv
2015/08/21 09:51:58.358 DeleteRecording> deleting: Mythbusters - Eye Pop Sneeze / Stopping Car in Reverse / Killer Butts ®, ID: 10977524093001250, file: smb://mcecdvreede:*******@WIN7mce/Recorded TV/Mythbusters_7mate_2015_08_17_18_15_00.wtv
2015/08/21 09:51:58.358 DeleteRecording> Recording found: Mythbusters - Eye Pop Sneeze / Stopping Car in Reverse / Killer Butts Id:0xE000000138B6B (3940649675230059) at 17/08/2015 8:15:06 AM (10977524093001250)
2015/08/21 09:51:59.040 ScheduleEventStateChanged> Error: oldState=HasOccurred newState=Canceled evChange=!Generated!62fe6391bff4453487ad5053949e4131 Exception=Specified ScheduleEvent no longer exists.
2015/08/21 09:51:59.153 ScheduleEventStateChanged> Error: oldState=Canceled newState=Deleted evChange=!Generated!62fe6391bff4453487ad5053949e4131 Exception=Specified ScheduleEvent no longer exists.
2015/08/21 09:52:01.375 Sending trigger updateRecordings to client 'FirstPI'
2015/08/21 09:52:01.400 Received client request: FirstPI|192.168.0.136|GetRecordings
2015/08/21 09:52:08.475 Finished request DeleteRecording in 12.45s
2015/08/21 09:52:08.494 Received client request: officePC|192.168.0.131|GetRecordings
2015/08/21 09:52:12.402 Received client request: FirstPI|192.168.0.136|GetRecordings
2015/08/21 09:52:12.745 Finished request GetRecordings in 11.35s
2015/08/21 09:52:17.020 Finished request GetRecordings in 8.53s
2015/08/21 09:52:17.066 Received client request: officePC|192.168.0.131|GetRecordings
2015/08/21 09:52:20.490 Finished request GetRecordings in 8.09s
2015/08/21 09:52:22.942 Finished request GetRecordings in 5.88s
2015/08/21 09:52:35.021 Received client request: officePC|192.168.0.131|ClientGoingDown
2015/08/21 09:52:35.021 ClientGoingDown> Client 'officePC' disconnecting
2015/08/21 09:52:35.022 Finished request ClientGoingDown in 0.00s
2015/08/21 09:56:29.528 MaintenanceTimer> Callback started at 9:56 AM
2015/08/21 09:56:29.529 MaintenanceTimer> Callback - complete
2015/08/21 10:01:39.873 ScheduleEventStateChanged> Error: oldState=Deleted newState=Canceled evChange=!Generated!62fe6391bff4453487ad5053949e4131 Exception=Specified ScheduleEvent no longer exists.
2015/08/21 10:04:29.563 MaintenanceTimer> Callback started at 10:04 AM
2015/08/21 10:04:29.565 MaintenanceTimer> Callback - complete
2015/08/21 10:12:29.599 MaintenanceTimer> Callback started at 10:12 AM
2015/08/21 10:12:29.599 MaintenanceTimer> Callback - complete
2015/08/21 10:17:24.740 Received client request: FirstPI|192.168.0.136|GetTimers
2015/08/21 10:17:26.925 Finished request GetTimers in 2.19s
2015/08/21 10:17:26.952 Received client request: FirstPI|192.168.0.136|SetResumePosition|10977524093022957|1030
2015/08/21 10:17:29.451 Finished request SetResumePosition in 2.50s
2015/08/21 10:17:29.460 Received client request: FirstPI|192.168.0.136|GetRecordings
2015/08/21 10:17:32.946 Finished request GetRecordings in 3.49s
2015/08/21 10:17:36.188 Received client request: FirstPI|192.168.0.136|GetRecordings
2015/08/21 10:17:38.664 Finished request GetRecordings in 2.48s
2015/08/21 10:17:55.324 Received client request: FirstPI|192.168.0.136|GetResumePosition|10977524093022957
2015/08/21 10:17:57.533 Finished request GetResumePosition in 2.21s
2015/08/21 10:18:02.483 Received client request: FirstPI|192.168.0.136|DeleteRecording|10977524093022957|Mythbusters - Ninja Bullet Slap / Tire Breathing / Rule of Thumb / RFID ®|smb://mcecdvreede:*******@WIN7mce/Recorded TV/Mythbusters_7mate_2015_08_20_18_20_00.wtv
2015/08/21 10:18:04.714 DeleteRecording> deleting: Mythbusters - Ninja Bullet Slap / Tire Breathing / Rule of Thumb / RFID ®, ID: 10977524093022957, file: smb://mcecdvreede:*******@WIN7mce/Recorded TV/Mythbusters_7mate_2015_08_20_18_20_00.wtv
2015/08/21 10:18:04.714 DeleteRecording> Recording found: Mythbusters - Ninja Bullet Slap / Tire Breathing / Rule of Thumb / RFID Id:0xE00000013F2B1 (3940649675256497) at 20/08/2015 8:20:04 AM (10977524093022957)
2015/08/21 10:18:05.100 ScheduleEventStateChanged> Error: oldState=HasOccurred newState=Canceled evChange=!Generated!8a6ac1335d894e7c8902c9bf654c3a96 Exception=Specified ScheduleEvent no longer exists.
2015/08/21 10:18:05.104 ScheduleEventStateChanged> Error: oldState=Canceled newState=Deleted evChange=!Generated!8a6ac1335d894e7c8902c9bf654c3a96 Exception=Specified ScheduleEvent no longer exists.
2015/08/21 10:18:10.797 Finished request DeleteRecording in 8.31s
2015/08/21 10:18:10.828 Received client request: FirstPI|192.168.0.136|GetRecordings
2015/08/21 10:18:15.708 Finished request GetRecordings in 4.88s
2015/08/21 10:19:26.893 ScheduleEventStateChanged> Error: oldState=Deleted newState=Canceled evChange=!Generated!8a6ac1335d894e7c8902c9bf654c3a96 Exception=Specified ScheduleEvent no longer exists.
2015/08/21 10:20:29.621 MaintenanceTimer> Callback started at 10:20 AM

[krustyreturns]

GetRecordings and DeleteRecording do seem to be taking a long time. Roughly how many recordings do you have?

fyi, the kodi forum likes to keep the posts free of long log posts, so they prefer you put your long on pastebin.com and put a link to the post here.

[sbthomas]

Does it take a long time to delete through media center?

[cdvreede]

683 recordings - I know, the kids should clean up a bit ;-) I'm using a 2 TB drive for recorded TV to make sure they don't record even more....

Sorry for the long post - will use Pastebin going 4ward.

Thing is MCE itself, the native MS GUI, deletes as fast as ever - no noticeable delay. Tried Kodi running directly on the MCE box to eliminate the network as a factor - same problem with long response time and blocking behaviour. As I understand the Server app uses MCE API's so no real reason why it should be any slower??

BTW - running version 1199

thnx

[krustyreturns]

Go to the settings file and change Debug_LogGetRecordings to true. Then save the file and restart serverwmc, then kodi. When kodi has finished loading data, delete a file (if you have one you can delete). Then post the resulting log on pastebin.com.