Login at Kodi Home

toadleyb · 2014-03-19, 18:11

(2014-03-19, 18:06)popcornmix Wrote: Hmmm. I've got 5 wtv files that have been reported as not playing, but are all playing with latest milhouse build.
Can you try with milhouse build?
If you are able to upload a failing wtv file (I know they are large, but google drive can handle large uploads) then I can test it.

I am installing millhouse as we speak and will give that a try. I will see if I can split a portion of the non working file and upload it. It is over 25 GB.

toadleyb · 2014-03-19, 19:26

No luck with milhouse either. I found a 10 GB file that would not play and I am uploading it as we speak. I will post a link as soon as it finishes. It will take a while. I am also copying one of the files to an external hard drive plugged directly into the pi to see if it will play from there to eliminate a network issue.

toadleyb · 2014-03-19, 20:26

Just tried playing a file from external hard drive and that fails as well. My file is about 20% up. Will PM you a link when done.

Thanks for the help.

toadleyb · 2014-03-20, 01:54

PM Sent popcornmix

**popcornmix** · 2014-03-20, 17:45

(2014-03-20, 01:54)toadleyb Wrote: PM Sent popcornmix

Hmm. File is playing fine for me. Tried with deinterlace on/off, passthrough on/off, dual audio on/off and all those options play.

Can you try renaming .xbmc and restarting xbmc so you are playing with default settings. Any different?
Can you post a log of it failing to play?

toadleyb · 2014-03-20, 18:32

I will give it a shot when I get home from work.

Is it the xbmc.log you are looking for?

**popcornmix** · 2014-03-20, 19:09

(2014-03-20, 18:32)toadleyb Wrote: I will give it a shot when I get home from work.

Is it the xbmc.log you are looking for?

Yes. Enable debugging in settings first, reboot, play the wtv file then grab xbmc.log.

toadleyb · 2014-03-20, 21:00

Okay things are getting weirder I just tried this file again with my remote and it failed. Then I tried playing it using yatse remote and it played fine. So then I went to one of my Olympic recordings which are over 20 GB and they fail. Here is a copy of the log file where this happens.

Code:
40:20 146.555634 T:3037323264   DEBUG: CGUIInfoManager::SetCurrentMovie(nfs://192.168.1.135/d/Recorded TV/Test-XXII Winter Olympics-Figure Skating, Alpine Skiing, Snowboarding, Speed Skating, Bobsled-2014_02_16_18_59_00.wtv)

40:20 146.597168 T:3037323264   DEBUG: GetMovieId (nfs://192.168.1.135/d/Recorded TV/Test-XXII Winter Olympics-Figure Skating, Alpine Skiing, Snowboarding, Speed Skating, Bobsled-2014_02_16_18_59_00.wtv), query = select idMovie from movie where idFile=70

40:20 146.601028 T:3037323264   DEBUG: GetEpisodeId (nfs://192.168.1.135/d/Recorded TV/Test-XXII Winter Olympics-Figure Skating, Alpine Skiing, Snowboarding, Speed Skating, Bobsled-2014_02_16_18_59_00.wtv), query = select idEpisode from episode where idFile=70

40:20 146.604874 T:3037323264   DEBUG: GetMusicVideoId (nfs://192.168.1.135/d/Recorded TV/Test-XXII Winter Olympics-Figure Skating, Alpine Skiing, Snowboarding, Speed Skating, Bobsled-2014_02_16_18_59_00.wtv), query = select idMVideo from musicvideo where idFile=70

40:20 146.806564 T:3037323264   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc

40:20 146.806961 T:3037323264   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay

40:20 146.951813 T:3037323264   DEBUG: ------ Window Deinit (DialogBusy.xml) ------

40:20 147.096680 T:2784388160   DEBUG: ffmpeg[A5F66440]: [wtv] unsupported chunk:8bad05c993774c5e37e8e2b23ddb99de

40:20 147.119644 T:2784388160   DEBUG: ffmpeg[A5F66440]: [wtv] unsupported chunk:d28c9ca7484071956267d216dc314d97

40:21 147.142380 T:2784388160   DEBUG: COMXPlayer::SetCaching - caching state 0

40:21 147.142807 T:2765075520   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 1000

40:21 147.143097 T:2867852352   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000

40:21 147.143341 T:2784388160    INFO: Process - eof reading from demuxer

40:21 147.143539 T:2784388160  NOTICE: COMXPlayer::OnExit()

40:21 147.143677 T:2784388160   DEBUG: OMXClock::OMXStop

40:21 147.144821 T:2784388160  NOTICE: OMXPlayer: eof, waiting for queues to empty

40:21 147.145035 T:2784388160  NOTICE: OMXPlayer: closing audio stream

40:21 147.145187 T:2784388160  NOTICE: Closing audio stream

40:21 147.145340 T:2784388160  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty

40:21 147.145691 T:2765075520   DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE

40:21 147.156113 T:2765075520  NOTICE: thread end: OMXPlayerAudio::OnExit()

40:21 147.156479 T:2765075520   DEBUG: Thread OMXPlayerAudio 2765075520 terminating

40:21 147.156998 T:2784388160  NOTICE: OMXPlayer: closing video stream

40:21 147.157211 T:2784388160  NOTICE: Closing video stream

40:21 147.157364 T:2784388160  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty

40:21 147.157684 T:2867852352   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE

40:21 147.157959 T:2867852352   ERROR: OMXPlayerVideo: Got MSGQ_IS_ERROR(-1) Aborting

40:21 147.158112 T:2867852352  NOTICE: thread end: video_thread

40:21 147.158310 T:2867852352   DEBUG: Thread OMXPlayerVideo 2867852352 terminating

40:21 147.203766 T:2784388160   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0x3537930

40:21 147.204559 T:2784388160  NOTICE: COMXPlayer::OnExit() deleting demuxer

40:21 147.205551 T:2784388160  NOTICE: COMXPlayer::OnExit() deleting input stream

40:21 147.205765 T:2784388160   DEBUG: CNFSFile::Close closing file d/Recorded TV/Test-XXII Winter Olympics-Figure Skating, Alpine Skiing, Snowboarding, Speed Skating, Bobsled-2014_02_16_18_59_00.wtv

40:21 147.209183 T:2784388160   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.clock handle 0x3778328

40:21 147.209869 T:2784388160   DEBUG: OnPlayBackEnded : play state was 2, starting 0

40:21 147.210861 T:2784388160   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc

40:21 147.211227 T:2784388160   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop

40:21 147.211700 T:2784388160   DEBUG: Thread OMXPlayer 2784388160 terminating

40:21 147.276825 T:3037323264   DEBUG: COMXPlayer::CloseFile

40:21 147.277084 T:3037323264   DEBUG: COMXPlayer: waiting for threads to exit

40:21 147.277802 T:3037323264  NOTICE: COMXPlayer: finished waiting

40:21 147.278320 T:3037323264   DEBUG: LinuxRendererGL: Cleaning up GL resources

40:21 147.278519 T:3037323264   DEBUG: COMXPlayer::CloseFile

40:21 147.278687 T:3037323264   DEBUG: COMXPlayer: waiting for threads to exit

40:21 147.279175 T:3037323264  NOTICE: COMXPlayer: finished waiting

40:21 147.279465 T:3037323264   DEBUG: LinuxRendererGL: Cleaning up GL resources

40:21 147.293945 T:3037316160    INFO: CAESinkALSA - ALSA: confmisc.c:768:(parse_card) cannot find card '0'

40:21 147.294220 T:3037316160    INFO: CAESinkALSA - ALSA: conf.c:4241:(_snd_config_evaluate) function snd_func_card_driver returned error: No such file or directory

40:21 147.294403 T:3037316160    INFO: CAESinkALSA - ALSA: confmisc.c:392:(snd_func_concat) error evaluating strings

40:21 147.294617 T:3037316160    INFO: CAESinkALSA - ALSA: conf.c:4241:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory

40:21 147.294769 T:3037316160    INFO: CAESinkALSA - ALSA: confmisc.c:1251:(snd_func_refer) error evaluating name

40:21 147.294952 T:3037316160    INFO: CAESinkALSA - ALSA: conf.c:4241:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory

40:21 147.295135 T:3037316160    INFO: CAESinkALSA - ALSA: conf.c:4720:(snd_config_expand) Evaluate error: No such file or directory

40:21 147.295395 T:3037316160    INFO: CAESinkALSA - ALSA: pcm.c:2217:(snd_pcm_open_noupdate) Unknown PCM default

40:21 147.295593 T:3037316160    INFO: CAESinkALSA - Unable to open device "default" for playback

40:21 147.312576 T:3037316160  NOTICE: Found 1 Lists of Devices

40:21 147.312805 T:3037316160  NOTICE: Enumerated PI devices:

40:21 147.312943 T:3037316160  NOTICE:     Device 1

40:21 147.313293 T:3037316160  NOTICE:         m_deviceName      : HDMI

40:21 147.313461 T:3037316160  NOTICE:         m_displayName     : HDMI

40:21 147.313614 T:3037316160  NOTICE:         m_displayNameExtra:

40:21 147.313751 T:3037316160  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI

40:21 147.313889 T:3037316160  NOTICE:         m_channels        : FL,FR

40:21 147.314041 T:3037316160  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,41400,48000,88200,96000,176400,192000

40:21 147.314194 T:3037316160  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3

40:21 147.314331 T:3037316160  NOTICE:     Device 2

40:21 147.314606 T:3037316160  NOTICE:         m_deviceName      : Analogue

40:21 147.314758 T:3037316160  NOTICE:         m_displayName     : Analogue

40:21 147.314896 T:3037316160  NOTICE:         m_displayNameExtra:

40:21 147.315048 T:3037316160  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM

40:21 147.315186 T:3037316160  NOTICE:         m_channels        : FL,FR

40:21 147.315323 T:3037316160  NOTICE:         m_sampleRates     : 48000

40:21 147.315491 T:3037316160  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE

40:21 147.316193 T:3028927552    INFO: CActiveAESink::OpenSink - initialize sink

40:21 147.316437 T:3028927552   DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI

40:21 147.317184 T:3028927552   DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:35280 bytes/s=352800.00

40:21 147.317383 T:3028927552   DEBUG: CAESinkPi:Initialize

40:21 147.320511 T:3028927552   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xa7da9cf8

40:21 147.326889 T:3028927552   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17640), nBufferAlignmen(16)

40:21 147.328094 T:3028927552   DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:

40:21 147.328308 T:3028927552   DEBUG:   Output Device : HDMI

40:21 147.328461 T:3028927552   DEBUG:   Sample Rate   : 44100

40:21 147.328598 T:3028927552   DEBUG:   Sample Format : AE_FMT_FLOAT

40:21 147.328766 T:3028927552   DEBUG:   Channel Count : 2

40:21 147.328934 T:3028927552   DEBUG:   Channel Layout: FL,FR

40:21 147.329071 T:3028927552   DEBUG:   Frames        : 4410

40:21 147.329208 T:3028927552   DEBUG:   Frame Samples : 8820

40:21 147.329346 T:3028927552   DEBUG:   Frame Size    : 8

40:21 147.348587 T:3037316160   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted

40:21 147.351639 T:2938864704   DEBUG: DoWork - Saving file state for video item nfs://192.168.1.135/d/Recorded TV/Test-XXII Winter Olympics-Figure Skating, Alpine Skiing, Snowboarding, Speed Skating, Bobsled-2014_02_16_18_59_00.wtv

40:21 147.361618 T:2825909312   DEBUG: DoWork - trying to extract thumb from video file nfs://192.168.1.135/d/Recorded TV/42--2014_02_09_18_30_00.wtv

40:21 147.381363 T:2825909312   DEBUG: CNFSFile::Open - opened d/Recorded TV/42--2014_02_09_18_30_00.wtv

40:21 147.428940 T:2825909312   DEBUG: Open - probing detected format [wtv]

40:21 147.700760 T:2938864704   ERROR: SQL: SQL error or missing database

                                            Query: INSERT INTO streamdetails (idFile, iStreamType, strVideoCodec, fVideoAspect, iVideoWidth, iVideoHeight, iVideoDuration, strStereoMode) VALUES (70,0,'mjpeg',NaN,0,0,14421,'')

40:21 147.709885 T:2938864704   ERROR: SetStreamDetailsForFileId (70) failed

40:21 147.768433 T:2825909312   DEBUG: ffmpeg[A86FF440]: [wtv] truncated file

40:21 147.962891 T:2784388160   DEBUG: Previous line repeats 2 times.

40:21 147.962891 T:2784388160   DEBUG: webserver: request received for /jsonrpc

40:22 148.187546 T:2825909312   DEBUG: ffmpeg[A86FF440]: [wtv] truncated file

40:22 148.962296 T:3037323264   DEBUG: OnKey: menu (0xd8) pressed, action is Back

toadleyb · 2014-03-20, 21:37

Here is a link to the entire log if you need it.

http://xbmclogs.com/show.php?id=153744

dmdsoftware · 2014-03-21, 19:05

Which model pi do you have? A or B?

I noticed when playing back very large files, (36GB), my model B has no issue, but on the model A, either freezes or restarts XBMC. Got all the codec licenses installed on both. Nothing showed up in the xbmc.log, but obviously suggestive of the memory difference. This is because all other constants were held the same -- I used the same power supply, swapped out the same SD card between the two, had no overclocking settings enabled, used the same 128MB gpu memory parameter, etc, etc.

toadleyb · 2014-03-21, 23:46

It is a model B. I do have it overclocked. I will try it without it overclocked and see what happens. It is acting like it is simply timing out. It tries to load for about 30 seconds and then just stops, no error messages. Is it possible this could be a problem with my windows set up? It is a fairly old setup that I have done lots of learning on. It has had lots of different codecs installed and uninstalled over the last couple of years. I have been thinking of do a clean install but it would be a pain in the arse.

**popcornmix** · 2014-03-23, 20:19

I did manage to reproduce the wtv failure at home where I'm connected through a wifi bridge.

The ffmpeg demuxer has a hard coded 30 second timeout, and that was getting hit in my setup, stopping the wtv file from playing.
I've increased this to 60 seconds, and that allows the wtv to play. This change is in latest miappa raspbmc build:
http://forum.xbmc.org/showthread.php?tid...pid1661251

toadleyb · 2014-03-24, 17:13

Still no go for me on the olympic recordings that I have. These seem to be the only ones I am having trouble with though.

**popcornmix** · 2014-03-24, 17:26

(2014-03-24, 17:13)toadleyb Wrote: Still no go for me on the olympic recordings that I have. These seem to be the only ones I am having trouble with though.

How long does it take from pressing play until the working spinner disappears?
Did it return to menu, or play with a blank screen?

toadleyb · 2014-03-24, 18:01

It is reaching the end of the 60 second timeout and then the spinner disappears and I am right back at the menu I selected it from. In this case the recorded tv list. I just timed it right at 60 seconds.