2011-10-24, 16:56
On my ATV2 (sorry, 4.4 firmware) - I'm seeing significant delays when I go to access a folder I've shared with SMB, from a NAS.
This is before it shows any of the contents, it just sits at "working" for a good 60 seconds, then eventually displays the contents.
Accessing a UpNp share does not have this delay at all..
(But, I don't seem to be able to stack UpNp files nicely)
PS - Should NFS from XBMC work on ATV2?
The log seems to point to it doing this?
15:43:37 T:119115776 DEBUG: Thread Jobworker 119115776 terminating (autodelete)
15:43:37 T:107433984 DEBUG: Thread Jobworker 107433984 terminating (autodelete)
15:43:46 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/1111111_cd1.rar, fd=10000
15:43:46 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:46 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/22222222_cd2.rar, fd=10000
15:43:47 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:47 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/33333333.rar, fd=10000
15:43:47 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:47 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/4444444.rar, fd=10000
15:43:47 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:47 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/aaaaCD1.rar, fd=10000
15:43:48 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:48 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/bbbbbcd1.rar, fd=10000
15:43:50 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:50 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/cccccccd1.rar, fd=10000
15:43:50 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:50 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/ddddddcd2.rar, fd=10000
15:43:51 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:51 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/eeeeeee.rar, fd=10000
15:43:51 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:51 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/fffffff.rar, fd=10000
15:43:52 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:52 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/gggggggg.rar, fd=10000
15:43:52 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:52 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/hhhhhh.rar, fd=10000
(cut excerpts only..)
15:44:09 T:103608320 DEBUG: Saving fileitems [smb://user:pass@ns4600/VIDEO/movies/]
15:44:09 T:103608320 DEBUG: -- items: 426, sort method: 0, ascending: false
15:44:10 T:103608320 DEBUG: RunQuery took 206 ms for 281 items query: select * from movieview where c23=2
15:44:10 T:103608320 DEBUG: RunQuery took 14 ms for 281 items query: select strFilename,playCount from files where idPath=2
15:44:10 T:103608320 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:ViewModes4.db
15:44:11 T:84660224 DEBUG: Previous line repeats 2 times.
15:44:11 T:84660224 DEBUG: Thread Background Loader start, auto delete: 0
15:44:11 T:84660224 DEBUG: OpenDir - Using authentication url smb://user:pass@ns4600/VIDEO/movies/Bond
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:107433984 DEBUG: Previous line repeats 2 times.
15:44:11 T:107433984 DEBUG: Thread Jobworker start, auto delete: 1
15:44:11 T:84660224 DEBUG: Previous line repeats 2 times.
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:107433984 DEBUG: DoWork - took 171 ms to load special://masterprofile/Thumbnails/Video/d/d3a407d4.tbn
15:44:11 T:116551680 DEBUG: DoWork - took 202 ms to load special://masterprofile/Thumbnails/Video/c/c229b84a.tbn
15:44:11 T:116019200 DEBUG: DoWork - took 206 ms to load special://masterprofile/Thumbnails/Video/1/15b09899.tbn
15:44:11 T:119115776 DEBUG: DoWork - took 214 ms to load special://masterprofile/Thumbnails/Video/6/66094d8a.tbn
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:116551680 DEBUG: DoWork - took 211 ms to load special://masterprofile/Thumbnails/Video/b/be44bce7.tbn
15:44:11 T:119115776 DEBUG: DoWork - took 195 ms to load special://masterprofile/Thumbnails/Video/a/ab73cc5f.tbn
15:44:11 T:107433984 DEBUG: DoWork - took 295 ms to load special://masterprofile/Thumbnails/Video/a/a576ece7.tbn
15:44:11 T:116019200 DEBUG: DoWork - took 255 ms to load special://masterprofile/Thumbnails/Video/1/1af71135.tbn
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:103608320 DEBUG: SECTION:UnloadDll(libcurl.4.dylib)
15:44:11 T:103608320 DEBUG: Unloading: libcurl.4.dylib
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:12 T:103608320 DEBUG: Previous line repeats 1 times.
15:44:12 T:103608320 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
15:44:12 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:19 T:84660224 DEBUG: Previous line repeats 52 times.
15:44:19 T:84660224 INFO: Caching image from: smb://user:pass@ns4600/VIDEO/movies/aaaaaa.tbn to special://masterprofile/Thumbnails/Video/4/47a1573c.tbn with width 512 and height 512
15:44:19 T:84660224 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm-osx.so)
15:44:19 T:84660224 DEBUG: Loading: /private/var/Applications/XBMC.frappliance/XBMCData/XBMCHome/system/ImageLib-arm-osx.so
15:44:19 T:84660224 DEBUG: CFileSMB::Open - opened VIDEO/movies/aaaaaaa.tbn, fd=10000
15:44:20 T:84660224 DEBUG: CFileSMB::Close closing fd 10000
15:44:20 T:84660224 DEBUG: CFileSMB::Open - opened VIDEO/movies/aaaaaa.tbn, fd=10000
15:44:21 T:84660224 DEBUG: CFileSMB::Close closing fd 10000
15:44:22 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:30 T:84660224 DEBUG: Previous line repeats 56 times.
15:44:30 T:84660224 INFO: Caching image from: smb://user:pass@ns4600/VIDEO/movies/aaaaa.tbn to special://masterprofile/Thumbnails/Video/a/adb788ba.tbn with width 512 and height 512
This is before it shows any of the contents, it just sits at "working" for a good 60 seconds, then eventually displays the contents.
Accessing a UpNp share does not have this delay at all..
(But, I don't seem to be able to stack UpNp files nicely)
PS - Should NFS from XBMC work on ATV2?
The log seems to point to it doing this?
15:43:37 T:119115776 DEBUG: Thread Jobworker 119115776 terminating (autodelete)
15:43:37 T:107433984 DEBUG: Thread Jobworker 107433984 terminating (autodelete)
15:43:46 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/1111111_cd1.rar, fd=10000
15:43:46 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:46 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/22222222_cd2.rar, fd=10000
15:43:47 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:47 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/33333333.rar, fd=10000
15:43:47 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:47 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/4444444.rar, fd=10000
15:43:47 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:47 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/aaaaCD1.rar, fd=10000
15:43:48 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:48 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/bbbbbcd1.rar, fd=10000
15:43:50 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:50 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/cccccccd1.rar, fd=10000
15:43:50 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:50 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/ddddddcd2.rar, fd=10000
15:43:51 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:51 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/eeeeeee.rar, fd=10000
15:43:51 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:51 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/fffffff.rar, fd=10000
15:43:52 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:52 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/gggggggg.rar, fd=10000
15:43:52 T:103608320 DEBUG: CFileSMB::Close closing fd 10000
15:43:52 T:103608320 DEBUG: CFileSMB::Open - opened VIDEO/movies/hhhhhh.rar, fd=10000
(cut excerpts only..)
15:44:09 T:103608320 DEBUG: Saving fileitems [smb://user:pass@ns4600/VIDEO/movies/]
15:44:09 T:103608320 DEBUG: -- items: 426, sort method: 0, ascending: false
15:44:10 T:103608320 DEBUG: RunQuery took 206 ms for 281 items query: select * from movieview where c23=2
15:44:10 T:103608320 DEBUG: RunQuery took 14 ms for 281 items query: select strFilename,playCount from files where idPath=2
15:44:10 T:103608320 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:ViewModes4.db
15:44:11 T:84660224 DEBUG: Previous line repeats 2 times.
15:44:11 T:84660224 DEBUG: Thread Background Loader start, auto delete: 0
15:44:11 T:84660224 DEBUG: OpenDir - Using authentication url smb://user:pass@ns4600/VIDEO/movies/Bond
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:107433984 DEBUG: Previous line repeats 2 times.
15:44:11 T:107433984 DEBUG: Thread Jobworker start, auto delete: 1
15:44:11 T:84660224 DEBUG: Previous line repeats 2 times.
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:107433984 DEBUG: DoWork - took 171 ms to load special://masterprofile/Thumbnails/Video/d/d3a407d4.tbn
15:44:11 T:116551680 DEBUG: DoWork - took 202 ms to load special://masterprofile/Thumbnails/Video/c/c229b84a.tbn
15:44:11 T:116019200 DEBUG: DoWork - took 206 ms to load special://masterprofile/Thumbnails/Video/1/15b09899.tbn
15:44:11 T:119115776 DEBUG: DoWork - took 214 ms to load special://masterprofile/Thumbnails/Video/6/66094d8a.tbn
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:116551680 DEBUG: DoWork - took 211 ms to load special://masterprofile/Thumbnails/Video/b/be44bce7.tbn
15:44:11 T:119115776 DEBUG: DoWork - took 195 ms to load special://masterprofile/Thumbnails/Video/a/ab73cc5f.tbn
15:44:11 T:107433984 DEBUG: DoWork - took 295 ms to load special://masterprofile/Thumbnails/Video/a/a576ece7.tbn
15:44:11 T:116019200 DEBUG: DoWork - took 255 ms to load special://masterprofile/Thumbnails/Video/1/1af71135.tbn
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:11 T:103608320 DEBUG: SECTION:UnloadDll(libcurl.4.dylib)
15:44:11 T:103608320 DEBUG: Unloading: libcurl.4.dylib
15:44:11 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:12 T:103608320 DEBUG: Previous line repeats 1 times.
15:44:12 T:103608320 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
15:44:12 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:19 T:84660224 DEBUG: Previous line repeats 52 times.
15:44:19 T:84660224 INFO: Caching image from: smb://user:pass@ns4600/VIDEO/movies/aaaaaa.tbn to special://masterprofile/Thumbnails/Video/4/47a1573c.tbn with width 512 and height 512
15:44:19 T:84660224 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm-osx.so)
15:44:19 T:84660224 DEBUG: Loading: /private/var/Applications/XBMC.frappliance/XBMCData/XBMCHome/system/ImageLib-arm-osx.so
15:44:19 T:84660224 DEBUG: CFileSMB::Open - opened VIDEO/movies/aaaaaaa.tbn, fd=10000
15:44:20 T:84660224 DEBUG: CFileSMB::Close closing fd 10000
15:44:20 T:84660224 DEBUG: CFileSMB::Open - opened VIDEO/movies/aaaaaa.tbn, fd=10000
15:44:21 T:84660224 DEBUG: CFileSMB::Close closing fd 10000
15:44:22 T:84660224 DEBUG: Connecting to sqlite:/var/mobile/Library/Preferences/XBMC/userdata/Database/:MyVideos56.db
15:44:30 T:84660224 DEBUG: Previous line repeats 56 times.
15:44:30 T:84660224 INFO: Caching image from: smb://user:pass@ns4600/VIDEO/movies/aaaaa.tbn to special://masterprofile/Thumbnails/Video/a/adb788ba.tbn with width 512 and height 512