Kodi Community Forum

Full Version: undesired auto wake-up of network ressources
You're currently viewing a stripped down version of our content. View the full version with proper formatting.
Hi there,

I am having some issues with frodo, which I haven't noticed prior to rc3.

My setup consists of a central mysql db with the data stored on a different file server using afp. several clients (rpi, mac, atv2) access the setup.
My problem lately: Whenever a client is started and connects to the central mysql, it also tries to access the last file added on the afp file share, which then wakes up the serving mac, although the file is not explicitly selected/played. This is undesired for me and limits the main idea of separating the db and the file share.

This is especially annoying as I am running one client on a raspberry pi, which is powered by the tv. so whenever I turn on the tv, the xbmc wakes up the mac serving the files, although it is not needed. The wakeup also happens using the other clients (mac, atv2) There is no automatic library update set on any of the xbmc clients.
I originally intended to let them connect to the "always on" mysql server and only wake up the afp serving mac when a file is accessed / played.

the logfile does not give any hint to me. I can see that it fails to access the last file added, which triggers a wake in lan event on the mac.
see:
21:20:26 T:4639477760 ERROR: LIBAFPCLIENT: Could not connect, never got a response to getstatus, Operation timed out
21:20:26 T:4639477760 ERROR: AFP: Error connecting to afp://xbmc:pass@myservingmac/fileshare/last_file_added.mkv

But why would it try to access the last file added? As far as I remember, this has never happened before (prior to rc3). So, any idea?
Help is greatly appreciated.

Cheers!
debug log (wiki) needed
Sorry for the late reply. Another try a few months later. Still bugging me with 12.1
Whenever I start xbmc on whatever device it starts accessing the last added video file.
What is "DoWork - trying to extract filestream details from video file" and why does it do this?

see:

av$ tail -f Library/Logs/xbmc.log |grep SHARE -b5 -a5

25488-17:25:59 T:140735262122368 DEBUG: waiting for python thread 2 (/Users/av/Library/Application Support/XBMC/addons/script.artwork.downloader/service.py) to stop
25649-17:25:59 T:140735262122368 DEBUG: python thread 2 (/Users/av/Library/Application Support/XBMC/addons/script.artwork.downloader/service.py) destructed
25801-17:25:59 T:4674478080 DEBUG: CSmbFile::Close closing fd 10000
25865-17:25:59 T:4645130240 DEBUG: RunQuery took 9 ms for 10 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10
26007-17:25:59 T:4645130240 DEBUG: RunQuery took 2 ms for 10 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10
26153:17:25:59 T:4663705600 DEBUG: DoWork - trying to extract filestream details from video file afp://user:pass@server/Share/Folder/Subfolder/video.mkv
26375-17:25:59 T:4663705600 DEBUG: SECTION:LoadDLL(libafpclient.0.dylib)
26444-17:25:59 T:4663705600 DEBUG: Loading: /Applications/XBMC.app/Contents/Frameworks/libafpclient.0.dylib
26548-17:25:59 T:4663705600 DEBUG: AFP: Supported UAMs: Cleartxt Passwrd, No User Authent, Randnum Exchange, 2-Way Randnum Exchange, DHCAST128, DHX2
26693-17:25:59 T:4645130240 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
26840-17:25:59 T:4645130240 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
--
--
26548-17:25:59 T:4663705600 DEBUG: AFP: Supported UAMs: Cleartxt Passwrd, No User Authent, Randnum Exchange, 2-Way Randnum Exchange, DHCAST128, DHX2
26693-17:25:59 T:4645130240 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
26840-17:25:59 T:4645130240 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
26947-17:26:00 T:4663705600 DEBUG: AFP: Connected to server Server using UAM "DHX2"
27027-17:26:00 T:4663705600 DEBUG: AFP: Connected to volume Share
27089:17:26:00 T:4663705600 DEBUG: CAFPFile::Open - opened Share/Folder/Subfolder/video.mkv, fd=0
27235-17:26:00 T:4663705600 DEBUG: Open - probing detected format [matroska,webm]
27313-17:26:00 T:4663705600 DEBUG: Open - avformat_find_stream_info starting
27386-17:26:00 T:4663705600 DEBUG: ffmpeg[15FA8000]: [h264] Increasing reorder buffer to 1
27473-17:26:00 T:4663705600 DEBUG: Open - av_find_stream_info finished
--
27235-17:26:00 T:4663705600 DEBUG: Open - probing detected format [matroska,webm]
27313-17:26:00 T:4663705600 DEBUG: Open - avformat_find_stream_info starting
27386-17:26:00 T:4663705600 DEBUG: ffmpeg[15FA8000]: [h264] Increasing reorder buffer to 1
27473-17:26:00 T:4663705600 DEBUG: Open - av_find_stream_info finished
27540:17:26:00 T:4663705600 INFO: ffmpeg[15FA8000]: Input #0, matroska,webm, from 'afp://user:pass@server/Share/Folder/Subfolder/video.mkv':
27751-17:26:00 T:4663705600 INFO: ffmpeg[15FA8000]: Duration: 00:40:13.63, start: 0.000000, bitrate: 4386 kb/s
27861-17:26:00 T:4663705600 INFO: ffmpeg[15FA8000]: Stream #0:0(eng): Video: h264 (High), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 2k tbc (default)
28037-17:26:00 T:4663705600 INFO: ffmpeg[15FA8000]: Stream #0:1(eng): Audio: ac3, 48000 Hz, 5.1(side), s16, 384 kb/s (default)
28165-17:26:00 T:4663705600 DEBUG: CAFPFile::Close closing fd 0
28225-17:26:01 T:4674478080 DEBUG: JSONRPC: Incoming request: { "jsonrpc" : "2.0" , "method" : "Files.GetDirectory" , "params" : { "directory" : "/Users/av/Library/Application Support/XBMC/userdata/addon_data/weather.wunderground/map" , "sort" : { "method" : "file" } } , "id" : 1 }


Thanks for your help!
(2013-04-26, 17:36)vorband Wrote: [ -> ]25865-17:25:59 T:4645130240 DEBUG: RunQuery took 9 ms for 10 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10
26007-17:25:59 T:4645130240 DEBUG: RunQuery took 2 ms for 10 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10
26153:17:25:59 T:4663705600 DEBUG: DoWork - trying to extract filestream details from video file afp://user:pass@server/Share/Folder/Subfolder/video.mkv
26693-17:25:59 T:4645130240 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
26840-17:25:59 T:4645130240 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update

That bit of the log makes me think that the system is trying to pull information for the "recently added" display on the home window.

Do you have a recently added widget on your home screen? What skin are you using?
found out the solution myself.

When I disable Video > File lists > Extract thumbnails and video information it not longer appears to access the files.

It's probaby a problem specific to my setup. Anyway, solved.

Just documenting in case anyone has the same issue.
Thank you for posting the solution. As you said, others might benefit from this as well. Probably both answers could be responsible for this kind of behaviour.