Login at Kodi Home

Ilia · 2009-12-16, 11:30

This is the full(er) log, post that the movie loads instantly.
My setup is: Mac OS 10.6.2/AppleTV 3.0.1 connecting to Time Capsule via 11n wireless or wired with files on it.

Wireless

PHP Code:
20:03 T:2689025280 M:295272448   DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0
20:03 T:2689025280 M:295272448   DEBUG: OnKey: 61453 pressed, action is 7
20:03 T:2689025280 M:295235584   DEBUG: new file set audiostream:0
20:03 T:2689025280 M:295227392   DEBUG: CPlayerCoreFactory::GetPlayers(smb://MogiTC.local/Data/movies/Black.Book(2006).avi)
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
20:03 T:2689025280 M:295223296   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
20:03 T:2689025280 M:295223296   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
20:03 T:2689025280 M:295194624   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
20:03 T:2689025280 M:295194624   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
20:03 T:2689025280 M:295194624   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
20:03 T:2689025280 M:295194624   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
20:03 T:2689025280 M:295194624   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
20:03 T:2689025280 M:293875712  NOTICE: DVDPlayer: Opening: smb://MogiTC.local/Data/movies/Black.Book(2006).avi
20:03 T:2953850880 M:293855232   DEBUG: thread start, auto delete: 1
20:03 T:2689025280 M:293855232 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
20:03 T:2957758464 M:293834752   DEBUG: thread start, auto delete: 0
20:03 T:2957758464 M:293826560  NOTICE: Creating InputStream
20:03 T:2957758464 M:293797888   DEBUG: CFileSMB::Open - opened Data/movies/Black.Book(2006).avi, fd=10001
20:03 T:2957758464 M:293797888    INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
20:03 T:2957758464 M:293793792   DEBUG: CacheSubtitles: START
20:03 T:2957758464 M:293793792   DEBUG: CacheSubtitles: Checking for common subirs...
20:03 T:2957758464 M:293793792   DEBUG: CacheSubtitles: Done (time: 461 ms)
20:03 T:2957758464 M:293793792   DEBUG: CacheSubtitles: Searching for subtitles...
20:03 T:2957758464 M:293789696   DEBUG: OpenDir - Using authentication url smb://MogiTC%2elocal/Data/movies
20:06 T:2953850880 M:293060608    INFO: Loading skin file: DialogProgress.xml
20:06 T:2953850880 M:293023744   DEBUG: Load DialogProgress.xml: 55.95ms
20:06 T:2953850880 M:293023744   DEBUG: DialogProgress::StartModal called
20:06 T:2953850880 M:293023744   DEBUG: ------ Window Init (DialogProgress.xml) ------
20:06 T:2953850880 M:293023744   DEBUG: Alloc resources: 0.03ms (0.00 ms skin load)
20:18 T:2957225984 M:298110976   DEBUG: Thread 2957225984 terminating (autodelete)
20:31 T:2956693504 M:298123264   DEBUG: Thread 2956693504 terminating (autodelete)
20:31 T:2955628544 M:298184704   DEBUG: Thread 2955628544 terminating (autodelete)
20:31 T:2956161024 M:298250240   DEBUG: Thread 2956161024 terminating (autodelete)
20:34 T:2957758464 M:298323968   DEBUG: CacheSubtitles: Done (time: 30849 ms)
20:34 T:2957758464 M:298323968   DEBUG: CacheSubtitles: END (total time: 31310 ms)
20:34 T:2957758464 M:298323968    INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
20:34 T:2957758464 M:298311680  NOTICE: Creating Demuxer
20:34 T:2957758464 M:298295296   DEBUG: Open - probing detected format [avi] 

Wired

PHP Code:
27:42 T:2957758464 M:293797888   DEBUG: CFileSMB::Open - opened Data/movies/Black.Book(2006).avi, fd=10001
27:42 T:2957758464 M:293797888    INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles
27:42 T:2957758464 M:293797888   DEBUG: CacheSubtitles: START
27:42 T:2957758464 M:293797888   DEBUG: CacheSubtitles: Checking for common subirs...
27:42 T:2957758464 M:293806080   DEBUG: CacheSubtitles: Done (time: 550 ms)
27:42 T:2957758464 M:293806080   DEBUG: CacheSubtitles: Searching for subtitles...
27:42 T:2957758464 M:293806080   DEBUG: OpenDir - Using authentication url smb://MogiTC%2elocal/Data/movies
27:45 T:2953318400 M:293584896    INFO: Loading skin file: DialogProgress.xml
27:45 T:2953318400 M:293556224   DEBUG: Load DialogProgress.xml: 24.23ms
27:45 T:2953318400 M:293556224   DEBUG: DialogProgress::StartModal called
27:45 T:2953318400 M:293556224   DEBUG: ------ Window Init (DialogProgress.xml) ------
27:45 T:2953318400 M:293556224   DEBUG: Alloc resources: 0.03ms (0.00 ms skin load)
28:04 T:2956693504 M:293502976   DEBUG: Thread 2956693504 terminating (autodelete)
28:11 T:2955628544 M:293572608   DEBUG: Thread 2955628544 terminating (autodelete)
28:11 T:2957225984 M:293638144   DEBUG: Thread 2957225984 terminating (autodelete)
28:11 T:2956161024 M:293699584   DEBUG: Thread 2956161024 terminating (autodelete)
28:19 T:2957758464 M:286359552   DEBUG: CacheSubtitles: Done (time: 37103 ms)
28:19 T:2957758464 M:286359552   DEBUG: CacheSubtitles: END (total time: 37653 ms)
28:19 T:2957758464 M:286359552    INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done
28:19 T:2957758464 M:286359552  NOTICE: Creating Demuxer
28:19 T:2957758464 M:286273536   DEBUG: Open - probing detected format [avi]
28:21 T:2957758464 M:284954624   DEBUG: Open - av_find_stream_info starting
28:21 T:2957758464 M:284708864   DEBUG: ffmpeg[B04BD000]: [NULL] Invalid and inefficient vfw-avi packed B frames detected
28:21 T:2957758464 M:284192768   DEBUG: Open - av_find_stream_info finished
28:21 T:2957758464 M:284192768    INFO: ffmpeg[B04BD000]: Input #0, avi, from 'smb://MogiTC.local/Data/movies/Black.Book(2006).avi':
28:21 T:2957758464 M:284192768    INFO: ffmpeg[B04BD000]:   Duration: 02:19:28.96, start: 0.000000, bitrate: 848 kb/s
28:21 T:2957758464 M:284192768    INFO: ffmpeg[B04BD000]:     Stream #0.0: Video: mpeg4, yuv420p, 640x340 [PAR 1:1 DAR 32:17], 25 tbr, 25 tbn, 25 tbc
28:21 T:2957758464 M:284192768    INFO: ffmpeg[B04BD000]:     Stream #0.1: Audio: mp3, 48000 Hz, 2 channels, s16, 128 kb/s
28:21 T:2957758464 M:284192768  NOTICE: Opening video stream: 0 source: 256
28:21 T:2957758464 M:284192768  NOTICE: Creating video codec with codec id: 13
28:21 T:2957758464 M:284192768   DEBUG: FactoryCodec - Video:  - Opening 

Ilia · 2009-12-16, 11:51

The folder I am testing has 1000 files - these include avi/nfo/tbns

if I use a folder with less movies (50-100 files) - it starts instantly.

Also, this is not dependent on any skin. It looks like a smb issue, when it start instanly there is no mention in the log of:

PHP Code:
27:45 T:2953318400 M:293556224   DEBUG: ------ Window Init (DialogProgress.xml) ------
27:45 T:2953318400 M:293556224   DEBUG: Alloc resources: 0.03ms (0.00 ms skin load)
28:04 T:2956693504 M:293502976   DEBUG: Thread 2956693504 terminating (autodelete)
28:11 T:2955628544 M:293572608   DEBUG: Thread 2955628544 terminating (autodelete)
28:11 T:2957225984 M:293638144   DEBUG: Thread 2957225984 terminating (autodelete)
28:11 T:2956161024 M:293699584   DEBUG: Thread 2956161024 terminating (autodelete)
28:19 T:2957758464 M:286359552   DEBUG: CacheSubtitles: Done (time: 37103 ms) 

Ilia · 2009-12-16, 11:56

To add more.

When going to view "Files" instead of library

I get this in the logs:

PHP Code:
52:37 T:2689025280 M:370561024   DEBUG: Getting Directory (smb://MogiTC.local/Data/movies/)
52:37 T:2689025280 M:370561024   DEBUG: OpenDir - Using authentication url smb://MogiTC%2elocal/Data/movies
52:55 T:2956693504 M:370614272   DEBUG: Thread 2956693504 terminating (autodelete)
52:55 T:2956161024 M:370651136   DEBUG: Thread 2956161024 terminating (autodelete)
52:56 T:2955096064 M:370712576   DEBUG: Thread 2955096064 terminating (autodelete)
52:56 T:2955628544 M:370683904   DEBUG: Thread 2955628544 terminating (autodelete) 

So it does seem like a smb issue.

So if some skinner can give me a piece of code that I can try putting into the DialogProgress.xml i would be grateful, if this is even possible.

Ilia · 2009-12-17, 10:26

so hmmm... anyone care to comment?

Jezz_X · 2009-12-17, 10:35

your still cutting up the logs and only posting snippits, make sure you put the full one on pastebin.com not on the forum