Page 1 of 1
Deconding on TS stream failed
Posted: 11 May 2016 00:53
by Axlprince
Hi everyone!!!
First of all I love your work with MobileVLCKit! It's simply the best choice for everything about video compatibility on all platforms! Good job guys!
These days I'm experiencing with a strange situation, I've compiled without problems the Framework for iOS and the Library version is the 3.0.0 but unfortunately I'm facing with an issue on TS stream.
I attach my debug logging :
Code: Select all
2016-05-11 00:20:49.264 IPTv[1232:892197] using access module "access"
2016-05-11 00:20:49.265 IPTv[1232:892197] looking for stream_filter module matching "cache_block": 12 candidates
2016-05-11 00:20:49.265 IPTv[1232:892197] Using block method for AStream*
2016-05-11 00:20:49.266 IPTv[1232:892197] starting pre-buffering
2016-05-11 00:20:49.266 IPTv[1232:892197] received first data after 0 ms
2016-05-11 00:20:49.267 IPTv[1232:892197] prebuffering done 2048 bytes in 0s - 1876 KiB/s
2016-05-11 00:20:49.267 IPTv[1232:892197] using stream_filter module "cache_block"
2016-05-11 00:20:49.267 IPTv[1232:892197] looking for stream_filter module matching "any": 12 candidates
2016-05-11 00:20:49.267 IPTv[1232:892197] no stream_filter modules matched
2016-05-11 00:20:49.268 IPTv[1232:892197] looking for stream_filter module matching "record": 12 candidates
2016-05-11 00:20:49.271 IPTv[1232:892197] using stream_filter module "record"
2016-05-11 00:20:49.271 IPTv[1232:892197] creating demux: access='http' demux='ts' location='www.mysite.com' file='(null)'
2016-05-11 00:20:49.272 IPTv[1232:892197] looking for demux module matching "ts": 60 candidates
2016-05-11 00:20:49.272 IPTv[1232:892197] Standard set to Auto
2016-05-11 00:20:49.273 IPTv[1232:892197] using demux module "ts"
2016-05-11 00:20:49.273 IPTv[1232:892197] DEMUX_SET_GROUP 0 0x0
2016-05-11 00:20:49.273 IPTv[1232:892197] looking for meta reader module matching "any": 1 candidates
2016-05-11 00:20:49.273 IPTv[1232:892197] no meta reader modules matched
2016-05-11 00:20:49.274 IPTv[1232:892197] `http://www.mysite.com/643.ts' successfully opened
2016-05-11 00:20:49.274 IPTv[1232:892197] pid[17] unknown
2016-05-11 00:20:49.274 IPTv[1232:892197] PATCallBack called
2016-05-11 00:20:49.274 IPTv[1232:892197] new PAT ts_id=1 version=0 current_next=1
2016-05-11 00:20:49.275 IPTv[1232:892197] * number=1 pid=4096
2016-05-11 00:20:49.275 IPTv[1232:892197] temporary receiving program 1
2016-05-11 00:20:49.275 IPTv[1232:892197] PMTCallBack called for program 1
2016-05-11 00:20:49.275 IPTv[1232:892197] new PMT program number=1 version=0 pid_pcr=256
2016-05-11 00:20:49.276 IPTv[1232:892197] * pid=256 type=0x1b AVC video stream as defined in ITU-T Rec. H.264
2016-05-11 00:20:49.276 IPTv[1232:892197] => pid 256 has now es fcc=h264
2016-05-11 00:20:49.278 IPTv[1232:892197] selecting program id=1
2016-05-11 00:20:49.278 IPTv[1232:892197] looking for packetizer module matching "any": 24 candidates
2016-05-11 00:20:49.279 IPTv[1232:892197] using packetizer module "h264"
2016-05-11 00:20:49.279 IPTv[1232:892197] looking for decoder module matching "any": 34 candidates
2016-05-11 00:20:49.279 IPTv[1232:892197] H264 profile and level parsing failed because it didn't arrive yet
2016-05-11 00:20:49.280 IPTv[1232:892197] waiting for H264 SPS/PPS, will start late
2016-05-11 00:20:49.280 IPTv[1232:892197] zero-copy rendering pipeline enabled
2016-05-11 00:20:49.283 IPTv[1232:892197] Using Video Toolbox to decode 'h264'
2016-05-11 00:20:49.284 IPTv[1232:892197] using decoder module "videotoolbox"
2016-05-11 00:20:49.284 IPTv[1232:892197] Default program is 1
2016-05-11 00:20:49.284 IPTv[1232:892197] enabling pid 256 from program 1
2016-05-11 00:20:49.284 IPTv[1232:892197] enabling pcr pid 256 from program 1
2016-05-11 00:20:49.284 IPTv[1232:892197] * pid=257 type=0xf ISO/IEC 13818-7 Audio with ADTS transport
2016-05-11 00:20:49.285 IPTv[1232:892197] - SL/FMC descriptor not found/matched
2016-05-11 00:20:49.286 IPTv[1232:892197] => pid 257 has now es fcc=mp4a
2016-05-11 00:20:49.287 IPTv[1232:892197] looking for packetizer module matching "any": 24 candidates
2016-05-11 00:20:49.287 IPTv[1232:892197] running MPEG4 audio packetizer
2016-05-11 00:20:49.287 IPTv[1232:892197] no decoder specific info, must be an ADTS or LOAS stream
2016-05-11 00:20:49.287 IPTv[1232:892197] using packetizer module "mpeg4audio"
2016-05-11 00:20:49.287 IPTv[1232:892197] looking for decoder module matching "any": 34 candidates
2016-05-11 00:20:49.288 IPTv[1232:892197] option quiet does not exist
2016-05-11 00:20:49.288 IPTv[1232:892197] CPU flags: 0x00000068
2016-05-11 00:20:49.292 IPTv[1232:892197] codec (aac) started
2016-05-11 00:20:49.294 IPTv[1232:892197] using decoder module "avcodec"
2016-05-11 00:20:49.295 IPTv[1232:892197] enabling pid 256 from program 1
2016-05-11 00:20:49.296 IPTv[1232:892197] enabling pid 2State: VLCMediaPlayerStatePlaying
57 from program 1
2016-05-11 00:20:49.299 IPTv[1232:892197] enabling pcr pid 256 from program 1
2016-05-11 00:20:49.299 IPTv[1232:892197] * pid=17 listening for SDT
2016-05-11 00:20:49.302 IPTv[1232:892197] enabling pid 256 from program 1
2016-05-11 00:20:49.302 IPTv[1232:892197] enabling pid 257 from program 1
2016-05-11 00:20:49.302 IPTv[1232:892197] enabling pcr pid 256 from program 1
2016-05-11 00:20:49.302 IPTv[1232:892197] Buffering 0%
2016-05-11 00:20:49.302 IPTv[1232:892197] first packet for pid=256 cc=0xa
State: VLCMediaPlayerStateBuffering
Buffering
Buffering
2016-05-11 00:20:49.504 IPTv[1232:892204] found NAL_SPS (sps_id=0)
2016-05-11 00:20:49.504 IPTv[1232:892204] found NAL_PPS (pps_id=0 sps_id=0)
2016-05-11 00:20:49.548 IPTv[1232:892204] restarting module due to input format change
2016-05-11 00:20:49.548 IPTv[1232:892204] removing module "videotoolbox"
2016-05-11 00:20:49.549 IPTv[1232:892204] looking for decoder module matching "any": 34 candidates
2016-05-11 00:20:49.549 IPTv[1232:892204] H264 profile and level parsing failed because it didn't arrive yet
2016-05-11 00:20:49.550 IPTv[1232:892204] waiting for H264 SPS/PPS, will start late
2016-05-11 00:20:49.550 IPTv[1232:892204] zero-copy rendering pipeline enabled
2016-05-11 00:20:49.550 IPTv[1232:892204] Using Video Toolbox to decode 'h264'
2016-05-11 00:20:49.550 IPTv[1232:892204] using decoder module "videotoolbox"
2016-05-11 00:20:49.569 IPTv[1232:892204] looking for text renderer module matching "freetype": 1 candidates
2016-05-11 00:20:49.866 IPTv[1232:892197] first packet for pid=257 cc=0x5
2016-05-11 00:20:49.906 IPTv[1232:892205] detected ADTS format
2016-05-11 00:20:49.906 IPTv[1232:892205] AAC channels: 2 samplerate: 48000
2016-05-11 00:20:49.906 IPTv[1232:892205] restarting module due to input format change
2016-05-11 00:20:49.906 IPTv[1232:892205] removing module "avcodec"
2016-05-11 00:20:49.906 IPTv[1232:892205] ffmpeg codec (aac) stopped
2016-05-11 00:20:49.907 IPTv[1232:892205] looking for decoder module matching "any": 34 candidates
2016-05-11 00:20:49.907 IPTv[1232:892205] option quiet does not exist
2016-05-11 00:20:49.907 IPTv[1232:892205] CPU flags: 0x00000068
2016-05-11 00:20:49.909 IPTv[1232:892205] codec (aac) started
2016-05-11 00:20:49.909 IPTv[1232:892205] using decoder module "avcodec"
2016-05-11 00:20:49.909 IPTv[1232:892205] creating audio output
2016-05-11 00:20:49.909 IPTv[1232:892205] looking for audio output module matching "any": 3 candidates
2016-05-11 00:20:49.909 IPTv[1232:892205] using audio output module "audiounit_ios"
2016-05-11 00:20:49.910 IPTv[1232:892205] VLC is looking for: 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
2016-05-11 00:20:49.923 IPTv[1232:892205] we set the AU format: [48000.000000][mcpl][9][8][1][8][2][32]
2016-05-11 00:20:49.924 IPTv[1232:892205] the actual set AU format is [48000.000000][mcpl][9][8][1][8][2][32]
2016-05-11 00:20:50.094 IPTv[1232:892205] audio output unit started: 0
2016-05-11 00:20:50.095 IPTv[1232:892205] analog AudioUnit output successfully opened
2016-05-11 00:20:50.095 IPTv[1232:892205] output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
2016-05-11 00:20:50.098 IPTv[1232:892205] looking for audio volume module matching "any": 2 candidates
2016-05-11 00:20:50.099 IPTv[1232:892205] using audio volume module "float_mixer"
2016-05-11 00:20:50.099 IPTv[1232:892205] input 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
2016-05-11 00:20:50.099 IPTv[1232:892205] looking for audio filter module matching "scaletempo": 11 candidates
2016-05-11 00:20:50.099 IPTv[1232:892205] format: 48000 rate, 2 nch, 4 bps, fl32
2016-05-11 00:20:50.104 IPTv[1232:892205] params: 30 stride, 0.200 overlap, 14 search
2016-05-11 00:20:50.104 IPTv[1232:892205] 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
2016-05-11 00:20:50.104 IPTv[1232:892205] using audio filter module "scaletempo"
2016-05-11 00:20:50.104 IPTv[1232:892205] conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
2016-05-11 00:20:50.104 IPTv[1232:892205] conversion pipeline complete
2016-05-11 00:20:50.105 IPTv[1232:892205] conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
2016-05-11 00:20:50.105 IPTv[1232:892205] conversion pipeline complete
2016-05-11 00:20:50.106 IPTv[1232:892205] looking for audio resampler module matching "any": 1 candidates
2016-05-11 00:20:50.106 IPTv[1232:892205] using audio resampler module "ugly"
2016-05-11 00:20:50.449 IPTv[1232:892197] Stream buffering done (1199 ms in 1146 ms)
State: VLCMediaPlayerStateBuffering
Buffering
Buffering
2016-05-11 00:20:50.577 IPTv[1232:892204] using text renderer module "freetype"
2016-05-11 00:20:50.578 IPTv[1232:892204] looking for video filter2 module matching "any": 23 candidates
2016-05-11 00:20:50.579 IPTv[1232:892204] 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality)
2016-05-11 00:20:50.579 IPTv[1232:892204] using video filter2 module "swscale"
2016-05-11 00:20:50.580 IPTv[1232:892204] looking for video filter2 module matching "any": 23 candidates
2016-05-11 00:20:50.581 IPTv[1232:892204] YUVP to YUVA converter
2016-05-11 00:20:50.582 IPTv[1232:892204] using video filter2 module "yuvp"
2016-05-11 00:20:50.582 IPTv[1232:892204] Deinterlacing available
2016-05-11 00:20:50.583 IPTv[1232:892204] deinterlace -1, mode blend, is_needed 0
2016-05-11 00:20:50.584 IPTv[1232:892204] looking for vout window module matching "any": 0 candidates
2016-05-11 00:20:50.585 IPTv[1232:892204] no vout window modules
2016-05-11 00:20:50.585 IPTv[1232:892271] Opening vout display wrapper
2016-05-11 00:20:50.585 IPTv[1232:892271] looking for vout display module matching "any": 3 candidates
2016-05-11 00:20:50.586 IPTv[1232:892271] will use zero-copy rendering
2016-05-11 00:20:50.597 IPTv[1232:892271] VoutDisplayEvent 'resize' 360x588
2016-05-11 00:20:50.598 IPTv[1232:891088] VoutDisplayEvent 'resize' 720x1176
2016-05-11 00:20:50.598 IPTv[1232:891088] VoutDisplayEvent 'resize' 720x1176
2016-05-11 00:20:50.599 IPTv[1232:892271] VoutDisplayEvent 'fullscreen' 0
2016-05-11 00:20:50.600 IPTv[1232:892271] VoutDisplayEvent 'resize' 360x588
2016-05-11 00:20:50.600 IPTv[1232:892271] VoutDisplayEvent 'resize' 720x1176
2016-05-11 00:20:50.600 IPTv[1232:892271] using vout display module "ios2"
2016-05-11 00:20:50.600 IPTv[1232:892271] original format sz 1280x720, of (0,0), vsz 1280x720, 4cc CVPX, sar 1:1, msk r0x0 g0x0 b0x0
2016-05-11 00:20:50.600 IPTv[1232:892271] VoutDisplayEvent 'resize' 1280x720
2016-05-11 00:20:50.600 IPTv[1232:892204] removing module "freetype"
2016-05-11 00:20:50.600 IPTv[1232:892204] looking for text renderer module matching "freetype": 1 candidates
2016-05-11 00:20:51.290 IPTv[1232:892204] using text renderer module "freetype"
2016-05-11 00:20:51.291 IPTv[1232:892204] mid stream SAR DEN change found, restarting decoder
2016-05-11 00:20:51.297 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.298 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.299 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.301 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.302 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.302 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.303 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.304 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.304 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.305 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.306 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.306 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.307 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.308 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.308 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.309 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.309 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.310 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.310 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.311 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.312 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.312 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.313 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.314 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.314 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.315 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.315 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.316 IPTv[1232:892197] buffer deadlock prevented
2016-05-11 00:20:51.316 IPTv[1232:892197] Decoder wait done in 864 ms
2016-05-11 00:20:51.317 IPTv[1232:892205] playback way too early (-728808): playing silence
2016-05-11 00:20:51.317 IPTv[1232:892205] inserting 34982 zeroes
2016-05-11 00:20:51.318 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.432 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.447 IPTv[1232:892204] mid stream SAR DEN change found, restarting decoder
2016-05-11 00:20:51.453 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.453 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.454 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.455 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.482 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.484 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.485 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.485 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.574 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.581 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.583 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.585 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.588 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.623 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.627 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.639 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.643 IPTv[1232:892271] auto hiding mouse cursor
2016-05-11 00:20:51.659 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.679 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.700 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.708 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.724 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.741 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.791 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.793 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.868 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.874 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.876 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:51.917 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:52.659 IPTv[1232:892204] sps pps detection failed
2016-05-11 00:20:52.684 IPTv[1232:892204] mid stream SAR DEN change found, restarting decoder
The strange situation is that with version 2.7.7. all works fine.
Can anyone help me to understand the mistake? Libvlc bug with ts stream or a setting to pass when allocating the player or something else?
Re: Deconding on TS stream failed
Posted: 11 May 2016 19:05
by fkuehne
The videotoolbox decoder introduced in 3.0 (but absent from 2.7) can't find needed metadata for decoding video.
Can you dump a sample of that stream and upload it to
http://streams.videolan.org/upload/ ? Please give the file name here once done. Thanks!
Re: Deconding on TS stream failed
Posted: 23 May 2016 16:47
by picobit
Hi Felix,
just uploaded two files Sample_720p.ts and Sample_1080i.ts that demonstrate the issue.
720p TS (DVB transport stream) support is broken since MobileVLCKit 2016-05-11 nightly build. (Maybe because of libvlc update 2.7.4 to 2.8.1 ?)
The 2016-05-05 nightly build support 720p but not 1080i TS.
I could not find any nightly build since July 2015 that supports 1080i TS. Don't know when it was dropped, I believe this was working in earlier versions.
Cheers, Uli
Re: Deconding on TS stream failed
Posted: 24 May 2016 23:50
by fkuehne
Thanks for sharing. Looks like we are on a good way to fix this soon!
Re: Deconding on TS stream failed
Posted: 02 Sep 2016 11:38
by sbfss
Thanks for sharing. Looks like we are on a good way to fix this soon!
Hello, it's fixed? I have very similar problems with version 3.0.0a19
Re: Deconding on TS stream failed
Posted: 12 Sep 2016 16:48
by fkuehne
Works for me. Sample file needed.
Re: Deconding on TS stream failed
Posted: 27 Sep 2017 10:54
by danyial
Hi,
only getting audio output with MobileVLCKit 3.0.0a41 and iOS 11.
Samlple file uploaded a few minutes ago.
is it possible to disable the video toolbox decoder?
Code: Select all
http://10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts
2017-09-27 10:38:42.105400+0200 TestApp[341:15775] creating player instance with private library as options were given
2017-09-27 10:38:42.108404+0200 TestApp[341:15775] VLC media player - 3.0.0-git Vetinari
2017-09-27 10:38:42.108472+0200 TestApp[341:15775] Copyright © 1996-2017 the VideoLAN team
2017-09-27 10:38:42.108506+0200 TestApp[341:15775] revision 2.2.0-git-14620-gbbe96651ce
2017-09-27 10:38:42.108582+0200 TestApp[341:15775] configured with /Users/fkuehne/Desktop/videolan/gits/VLCKit-HEAD/libvlc/vlc/configure '--prefix=/Users/fkuehne/Desktop/videolan/gits/VLCKit-HEAD/libvlc/vlc/install-iPhoneOS/arm64' '--host=aarch64-apple-darwin14' '--with-contrib=/Users/fkuehne/Desktop/videolan/gits/VLCKit-HEAD/libvlc/vlc/contrib/iPhone-aarch64-apple-darwin14-aarch64' '--enable-static' '--enable-dvbpsi' '--enable-avcodec' '--disable-vpx' '--disable-macosx' '--disable-macosx-qtkit' '--disable-macosx-avfoundation' '--disable-shared' '--enable-opus' '--disable-faad' '--disable-lua' '--disable-a52' '--enable-fribidi' '--disable-qt' '--disable-skins2' '--disable-vcd' '--disable-vlc' '--disable-vlm' '--disable-httpd' '--disable-nls' '--disable-sse' '--disable-notify' '--enable-live555' '--enable-realrtsp' '--enable-swscale' '--disable-projectm' '--enable-libass' '--enable-libxml2' '--disable-goom' '--disable-dvdread' '--disable-dvdnav' '--disable-bluray' '--disable-linsys' '--disable-libva' '--disable-gme' '--disable-tremor' '--enable-vorbis' '--disable-fluidsynth' '--disable-jack' '--disable-pulse' '--disable-mtp' '--enable-ogg' '--enable-speex' '--enable-theora' '--enable-flac' '--disable-screen' '--enable-freetype' '--enable-taglib' '--disable-mmx' '--disable-addonmanagermodules' '--disable-mad' 'host_alias=aarch64-apple-darwin14' 'CC=/Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang' 'CFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/SDKs/iPhoneOS10.3.sdk -arch arm64 -O3 -g -miphoneos-version-min=7.0 -DNDEBUG' 'LDFLAGS=-arch arm64 -Wl,-ios_version_min,7.0' 'CPPFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/SDKs/iPhoneOS10.3.sdk -arch arm64 -O3 -g -miphoneos-version-min=7.0' 'CXX=/Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang++' 'CXXFLAGS=-isysroot /Applications/Xcode.app/Contents/Developer/Platforms/iPhoneOS.platform/Developer/SDKs/iPhoneOS10.3.sdk -arch arm64 -O3 -g -miphoneos-version-min=7.0' 'OBJC=/Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang' 'OBJCFLAGS=-O3 -g' 'CCAS=gas-preprocessor.pl /Applications/Xcode.app/Contents/Developer/Toolchains/XcodeDefault.xctoolchain/usr/bin/clang'
2017-09-27 10:38:42.374189+0200 TestApp[341:26575] Creating an input for '20170927 1013 - ProSieben HD - The Middle.ts'
2017-09-27 10:38:42.375172+0200 TestApp[341:26576] using timeshift granularity of 50 MiB
2017-09-27 10:38:42.375234+0200 TestApp[341:26576] using default timeshift path
2017-09-27 10:38:42.375340+0200 TestApp[341:26576] `http://10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts' gives access `http' demux `any' path `10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts'
2017-09-27 10:38:42.375664+0200 TestApp[341:26576] creating demux: access='http' demux='any' location='10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts' file='(null)'
2017-09-27 10:38:42.375726+0200 TestApp[341:26576] looking for access_demux module matching "http": 3 candidates
2017-09-27 10:38:42.375781+0200 TestApp[341:26576] no access_demux modules matched
2017-09-27 10:38:42.375843+0200 TestApp[341:26576] creating access: http://10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts
2017-09-27 10:38:42.378193+0200 TestApp[341:26576] looking for access module matching "http": 18 candidates
2017-09-27 10:38:42.378334+0200 TestApp[341:26576] resolving 10.10.10.45 ...
2017-09-27 10:38:42.379027+0200 TestApp[341:26576] outgoing request:
GET /file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts HTTP/1.1
Host: 10.10.10.45:80
Accept: */*
Accept-Language: en_US
User-Agent: VLC/3.0.0-git LibVLC/3.0.0-git
Range: bytes=0-
2017-09-27 10:38:42.463540+0200 TestApp[341:26576] incoming response:
HTTP/1.1 206 Partial Content
Content-Length: 18096128
Last-Modified: Wed, 27 Sep 2017 08:31:02 GMT
Accept-Ranges: bytes
Server: TwistedWeb/16.2.0
Content-Disposition:: attachment;filename="20170927 1013 - ProSieben HD - The Middle.ts"
Content-Range: bytes 0-18096127/18096128
Date: Wed, 27 Sep 2017 08:38:41 GMT
Content-Type: video/MP2T
Set-Cookie: TWISTED_SESSION=54be0cc6770f9a5c3f05be90cc1c23ee; Path=/
2017-09-27 10:38:42.463705+0200 TestApp[341:26576] using access module "access"
2017-09-27 10:38:42.463756+0200 TestApp[341:26576] looking for stream_filter module matching "prefetch,cache_block": 25 candidates
2017-09-27 10:38:42.463929+0200 TestApp[341:26576] using 16777216 bytes buffer, 16384 bytes read
2017-09-27 10:38:42.463963+0200 TestApp[341:26576] using stream_filter module "prefetch"
2017-09-27 10:38:42.463997+0200 TestApp[341:26576] looking for stream_filter module matching "any": 25 candidates
2017-09-27 10:38:42.464430+0200 TestApp[341:26576] no stream_filter modules matched
2017-09-27 10:38:42.467710+0200 TestApp[341:26576] looking for stream_directory module matching "any": 1 candidates
2017-09-27 10:38:42.467771+0200 TestApp[341:26576] no stream_directory modules matched
2017-09-27 10:38:42.467810+0200 TestApp[341:26576] attachment of directory-extractor failed for http://10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts
2017-09-27 10:38:42.468890+0200 TestApp[341:26576] looking for stream_filter module matching "record": 25 candidates
2017-09-27 10:38:42.469107+0200 TestApp[341:26576] using stream_filter module "record"
2017-09-27 10:38:42.469384+0200 TestApp[341:26576] creating demux: access='http' demux='ts' location='10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts' file='(null)'
2017-09-27 10:38:42.469438+0200 TestApp[341:26576] looking for demux module matching "ts": 45 candidates
2017-09-27 10:38:42.469618+0200 TestApp[341:26576] Standard set to Auto
2017-09-27 10:38:42.469655+0200 TestApp[341:26576] pid[6841] unknown
2017-09-27 10:38:42.469854+0200 TestApp[341:26576] first packet for pid=6841 cc=0x2
2017-09-27 10:38:42.469895+0200 TestApp[341:26576] scrambled state changed on pid 6841 (0->1)
2017-09-27 10:38:42.470124+0200 TestApp[341:26576] pid[6844] unknown
2017-09-27 10:38:42.470205+0200 TestApp[341:26576] first packet for pid=6844 cc=0x1
2017-09-27 10:38:42.470240+0200 TestApp[341:26576] pid[6842] unknown
2017-09-27 10:38:42.470269+0200 TestApp[341:26576] first packet for pid=6842 cc=0x3
2017-09-27 10:38:42.471006+0200 TestApp[341:26576] scrambled state changed on pid 6842 (0->1)
2017-09-27 10:38:42.839211+0200 TestApp[341:26576] first packet for pid=0 cc=0xf
2017-09-27 10:38:42.839319+0200 TestApp[341:26576] PATCallBack called
2017-09-27 10:38:42.839353+0200 TestApp[341:26576] new PAT ts_id=10013 version=16 current_next=1
2017-09-27 10:38:42.839520+0200 TestApp[341:26576] * number=0 pid=16
2017-09-27 10:38:42.839555+0200 TestApp[341:26576] * number=50001 pid=101
2017-09-27 10:38:42.839588+0200 TestApp[341:26576] temporary receiving program 50001
2017-09-27 10:38:42.839619+0200 TestApp[341:26576] * number=50002 pid=102
2017-09-27 10:38:42.839960+0200 TestApp[341:26576] * number=50003 pid=103
2017-09-27 10:38:42.839998+0200 TestApp[341:26576] * number=50004 pid=104
2017-09-27 10:38:42.840030+0200 TestApp[341:26576] * number=50013 pid=106
2017-09-27 10:38:42.840087+0200 TestApp[341:26576] * number=50015 pid=105
2017-09-27 10:38:42.840178+0200 TestApp[341:26576] * number=50057 pid=107
2017-09-27 10:38:45.220765+0200 TestApp[341:26576] discontinuity received 0x8 instead of 0x2 (pid=6842)
2017-09-27 10:38:45.221023+0200 TestApp[341:26576] discontinuity received 0xd instead of 0x1 (pid=6841)
2017-09-27 10:38:45.221982+0200 TestApp[341:26576] scrambled state changed on pid 6841 (1->0)
2017-09-27 10:38:45.222249+0200 TestApp[341:26576] discontinuity received 0x4 instead of 0xd (pid=6844)
2017-09-27 10:38:45.228426+0200 TestApp[341:26576] scrambled state changed on pid 6842 (1->0)
2017-09-27 10:38:45.304226+0200 TestApp[341:26576] pid[5646] unknown
2017-09-27 10:38:45.304363+0200 TestApp[341:26576] first packet for pid=5646 cc=0x2
2017-09-27 10:38:45.359164+0200 TestApp[341:26576] discontinuity received 0x9 instead of 0x5 (pid=0)
2017-09-27 10:38:45.359294+0200 TestApp[341:26576] libdvbpsi error (PSI decoder): TS discontinuity (received 9, expected 5) for PID 0
2017-09-27 10:38:45.359418+0200 TestApp[341:26576] PATCallBack called
2017-09-27 10:38:45.376744+0200 TestApp[341:26576] first packet for pid=105 cc=0xe
2017-09-27 10:38:45.376895+0200 TestApp[341:26576] PMTCallBack called for program 50015
2017-09-27 10:38:45.377102+0200 TestApp[341:26576] new PMT program number=50015 version=2 pid_pcr=6841
2017-09-27 10:38:45.377186+0200 TestApp[341:26576] * PMT descriptor: CA (0x9)
2017-09-27 10:38:45.377260+0200 TestApp[341:26576] : CA System ID 0x9c7
2017-09-27 10:38:45.377345+0200 TestApp[341:26576] * PMT descriptor: CA (0x9)
2017-09-27 10:38:45.377491+0200 TestApp[341:26576] : CA System ID 0x1861
2017-09-27 10:38:45.377755+0200 TestApp[341:26576] * PMT descriptor: CA (0x9)
2017-09-27 10:38:45.377877+0200 TestApp[341:26576] : CA System ID 0x1834
2017-09-27 10:38:45.377952+0200 TestApp[341:26576] * PMT descriptor: CA (0x9)
2017-09-27 10:38:45.378024+0200 TestApp[341:26576] : CA System ID 0x9fd
2017-09-27 10:38:45.378100+0200 TestApp[341:26576] * pid=5646 type=0x5 ISO/IEC 13818-1 private_sections
2017-09-27 10:38:45.378247+0200 TestApp[341:26576] - ES descriptor tag 0x6f
2017-09-27 10:38:45.378318+0200 TestApp[341:26576] - ES descriptor tag 0x52
2017-09-27 10:38:45.378398+0200 TestApp[341:26576] - Application type 0x10 version 0
2017-09-27 10:38:45.378880+0200 TestApp[341:26576] => pid 5646 content is *unknown*
2017-09-27 10:38:45.379044+0200 TestApp[341:26576] * pid=6841 type=0x1b AVC video stream as defined in ITU-T Rec. H.264
2017-09-27 10:38:45.379123+0200 TestApp[341:26576] - ES descriptor tag 0x52
2017-09-27 10:38:45.379201+0200 TestApp[341:26576] => pid 6841 has now es fcc=h264
2017-09-27 10:38:45.379276+0200 TestApp[341:26576] * pid=6842 type=0x6 ISO/IEC 13818-1 PES packets containing private data
2017-09-27 10:38:45.379348+0200 TestApp[341:26576] - ES descriptor tag 0x6a
2017-09-27 10:38:45.379532+0200 TestApp[341:26576] - ES descriptor tag 0xa
2017-09-27 10:38:45.379621+0200 TestApp[341:26576] - ES descriptor tag 0x52
2017-09-27 10:38:45.379703+0200 TestApp[341:26576] * Stream Component Identifier: 7
2017-09-27 10:38:45.384129+0200 TestApp[341:26576] found language: ger
2017-09-27 10:38:45.384257+0200 TestApp[341:26576] => pid 6842 has now es fcc=a52
2017-09-27 10:38:45.384341+0200 TestApp[341:26576] * pid=6844 type=0x6 ISO/IEC 13818-1 PES packets containing private data
2017-09-27 10:38:45.384420+0200 TestApp[341:26576] - ES descriptor tag 0x52
2017-09-27 10:38:45.385470+0200 TestApp[341:26576] - ES descriptor tag 0x56
2017-09-27 10:38:45.385598+0200 TestApp[341:26576] * ttxt type=Teletext lan=ger page=100
2017-09-27 10:38:45.386347+0200 TestApp[341:26576] * ttxt type=Teletext subtitles lan=ger page=149
2017-09-27 10:38:45.386550+0200 TestApp[341:26576] * Stream Component Identifier: 10
2017-09-27 10:38:45.386722+0200 TestApp[341:26576] => pid 6844 has now es fcc=telx
2017-09-27 10:38:45.387230+0200 TestApp[341:26576] * pid=17 listening for SDT
2017-09-27 10:38:45.387490+0200 TestApp[341:26576] enabling pcr pid 8191 from program 50001
2017-09-27 10:38:45.387662+0200 TestApp[341:26576] enabling pcr pid 8191 from program 50002
2017-09-27 10:38:45.387961+0200 TestApp[341:26576] enabling pcr pid 8191 from program 50003
2017-09-27 10:38:45.388192+0200 TestApp[341:26576] enabling pcr pid 8191 from program 50004
2017-09-27 10:38:45.388357+0200 TestApp[341:26576] enabling pcr pid 8191 from program 50013
2017-09-27 10:38:45.388522+0200 TestApp[341:26576] enabling pid 5646 from program 50015
2017-09-27 10:38:45.388783+0200 TestApp[341:26576] enabling pid 6841 from program 50015
2017-09-27 10:38:45.389006+0200 TestApp[341:26576] enabling pid 6842 from program 50015
2017-09-27 10:38:45.389169+0200 TestApp[341:26576] enabling pid 6844 from program 50015
2017-09-27 10:38:45.389329+0200 TestApp[341:26576] enabling pcr pid 6841 from program 50015
2017-09-27 10:38:45.389613+0200 TestApp[341:26576] enabling pcr pid 8191 from program 50057
2017-09-27 10:38:45.389897+0200 TestApp[341:26576] first packet for pid=6841 cc=0x1
2017-09-27 10:38:45.390079+0200 TestApp[341:26576] first packet for pid=6844 cc=0xd
2017-09-27 10:38:45.390364+0200 TestApp[341:26576] first packet for pid=6842 cc=0xc
2017-09-27 10:38:45.390658+0200 TestApp[341:26576] using demux module "ts"
2017-09-27 10:38:45.391094+0200 TestApp[341:26576] DEMUX_SET_GROUP 0 0x0
2017-09-27 10:38:45.391362+0200 TestApp[341:26576] looking for meta reader module matching "any": 1 candidates
2017-09-27 10:38:45.391545+0200 TestApp[341:26576] no meta reader modules matched
2017-09-27 10:38:45.392446+0200 TestApp[341:26576] `http://10.10.10.45:80/file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts' successfully opened
2017-09-27 10:38:45.393050+0200 TestApp[341:26576] Creating delayed ES
2017-09-27 10:38:45.393244+0200 TestApp[341:26576] selecting program id=50015
2017-09-27 10:38:45.399842+0200 TestApp[341:26576] looking for packetizer module matching "any": 24 candidates
2017-09-27 10:38:45.400910+0200 TestApp[341:26576] using packetizer module "h264"
2017-09-27 10:38:45.401542+0200 TestApp[341:26576] looking for video decoder module matching "any": 7 candidates
2017-09-27 10:38:45.402256+0200 TestApp[341:26576] H264 profile and level parsing failed because it didn't arrive yet
2017-09-27 10:38:45.402922+0200 TestApp[341:26576] Using Video Toolbox to decode 'h264'
2017-09-27 10:38:45.403705+0200 TestApp[341:26576] using video decoder module "videotoolbox"
2017-09-27 10:38:45.404644+0200 TestApp[341:26576] Default program is 50015
2017-09-27 10:38:45.405387+0200 TestApp[341:26576] looking for packetizer module matching "any": 24 candidates
2017-09-27 10:38:45.405974+0200 TestApp[341:26576] using packetizer module "a52"
2017-09-27 10:38:45.406544+0200 TestApp[341:26576] looking for audio decoder module matching "any": 12 candidates
2017-09-27 10:38:45.406640+0200 TestApp[341:26576] reusing audio output
2017-09-27 10:38:45.407088+0200 TestApp[341:26576] invalid audio channels count
2017-09-27 10:38:45.407310+0200 TestApp[341:26576] keeping audio output
2017-09-27 10:38:45.407825+0200 TestApp[341:26576] failed to create audio output
2017-09-27 10:38:45.408022+0200 TestApp[341:26576] using ffmpeg Lavc57.100.104
2017-09-27 10:38:45.408246+0200 TestApp[341:26576] option quiet does not exist
2017-09-27 10:38:45.408467+0200 TestApp[341:26576] CPU flags: 0x00000068
2017-09-27 10:38:45.409472+0200 TestApp[341:26576] codec (ac3) started
2017-09-27 10:38:45.409614+0200 TestApp[341:26576] using audio decoder module "avcodec"
2017-09-27 10:38:45.410449+0200 TestApp[341:26576] enabling pid 5646 from program 50015
2017-09-27 10:38:45.410512+0200 TestApp[341:26576] enabling pid 6841 from program 50015
2017-09-27 10:38:45.410574+0200 TestApp[341:26576] enabling pid 6842 from program 50015
2017-09-27 10:38:45.410726+0200 TestApp[341:26576] enabling pid 6844 from program 50015
2017-09-27 10:38:45.410944+0200 TestApp[341:26576] enabling pcr pid 6841 from program 50015
2017-09-27 10:38:45.411646+0200 TestApp[341:26576] Buffering 0%
2017-09-27 10:38:45.413056+0200 TestApp[341:26576] Buffering 3%
2017-09-27 10:38:45.415311+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.448405+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.453403+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.453544+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.453610+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.454184+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.454562+0200 TestApp[341:26576] Buffering 7%
2017-09-27 10:38:45.482214+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.485859+0200 TestApp[341:26576] Buffering 11%
2017-09-27 10:38:45.489662+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.491226+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.492334+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.496089+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.500631+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.510309+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.524915+0200 TestApp[341:26576] Buffering 14%
2017-09-27 10:38:45.525347+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.526236+0200 TestApp[341:26587] A/52 channels:2 samplerate:48000 bitrate:384000
2017-09-27 10:38:45.526356+0200 TestApp[341:26587] restarting module due to input format change
2017-09-27 10:38:45.526477+0200 TestApp[341:26587] removing module "avcodec"
2017-09-27 10:38:45.526682+0200 TestApp[341:26587] looking for audio decoder module matching "any": 12 candidates
2017-09-27 10:38:45.526756+0200 TestApp[341:26587] reusing audio output
2017-09-27 10:38:45.526830+0200 TestApp[341:26587] VLC is looking for: 'a52 ' 48000 Hz Stereo frame=1536 samples/1536 bytes
2017-09-27 10:38:45.535945+0200 TestApp[341:26576] Buffering 18%
2017-09-27 10:38:45.537755+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.553197+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.555582+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.557929+0200 TestApp[341:26576] Buffering 22%
2017-09-27 10:38:45.560903+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.595218+0200 TestApp[341:26587] Output on Default, channel count: 2
2017-09-27 10:38:45.623964+0200 TestApp[341:26576] Buffering 25%
2017-09-27 10:38:45.624947+0200 TestApp[341:26587] opening AudioUnit output failed
2017-09-27 10:38:45.624973+0200 TestApp[341:26587] module not functional
2017-09-27 10:38:45.624991+0200 TestApp[341:26587] keeping audio output
2017-09-27 10:38:45.625036+0200 TestApp[341:26587] failed to create audio output
2017-09-27 10:38:45.625055+0200 TestApp[341:26587] using ffmpeg Lavc57.100.104
2017-09-27 10:38:45.625082+0200 TestApp[341:26587] option quiet does not exist
2017-09-27 10:38:45.625093+0200 TestApp[341:26587] CPU flags: 0x00000068
2017-09-27 10:38:45.625285+0200 TestApp[341:26587] codec (ac3) started
2017-09-27 10:38:45.625299+0200 TestApp[341:26587] using audio decoder module "avcodec"
2017-09-27 10:38:45.625380+0200 TestApp[341:26587] reusing audio output
2017-09-27 10:38:45.625401+0200 TestApp[341:26587] VLC is looking for: 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
2017-09-27 10:38:45.625501+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.632141+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.632211+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.632866+0200 TestApp[341:26576] Buffering 29%
2017-09-27 10:38:45.636779+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.655005+0200 TestApp[341:26576] first packet for pid=5646 cc=0x3
2017-09-27 10:38:45.655073+0200 TestApp[341:26576] Buffering 33%
2017-09-27 10:38:45.655706+0200 TestApp[341:26587] Output on Default, channel count: 2
2017-09-27 10:38:45.657989+0200 TestApp[341:26587] not output layout, default to Stereo
2017-09-27 10:38:45.658017+0200 TestApp[341:26587] selected 2 physical channels for device output
2017-09-27 10:38:45.658029+0200 TestApp[341:26587] VLC will output: Stereo
2017-09-27 10:38:45.658051+0200 TestApp[341:26587] Current AU format: [48000.000000][mcpl][9][8][1][8][2][32]
2017-09-27 10:38:45.658292+0200 TestApp[341:26587] Current device has a latency of 4499 us
2017-09-27 10:38:45.672190+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.675494+0200 TestApp[341:26576] Buffering 36%
2017-09-27 10:38:45.681525+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.684638+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.696662+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.700831+0200 TestApp[341:26576] Buffering 40%
2017-09-27 10:38:45.710905+0200 TestApp[341:26587] analog AudioUnit output successfully opened for f32l Stereo
2017-09-27 10:38:45.710952+0200 TestApp[341:26587] output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
2017-09-27 10:38:45.710968+0200 TestApp[341:26587] looking for audio volume module matching "any": 2 candidates
2017-09-27 10:38:45.710980+0200 TestApp[341:26587] using audio volume module "float_mixer"
2017-09-27 10:38:45.710991+0200 TestApp[341:26587] input 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
2017-09-27 10:38:45.711020+0200 TestApp[341:26587] looking for audio filter module matching "scaletempo": 13 candidates
2017-09-27 10:38:45.711049+0200 TestApp[341:26587] format: 48000 rate, 2 nch, 4 bps, fl32
2017-09-27 10:38:45.711146+0200 TestApp[341:26587] params: 30 stride, 0.200 overlap, 14 search
2017-09-27 10:38:45.711171+0200 TestApp[341:26587] 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
2017-09-27 10:38:45.711183+0200 TestApp[341:26587] using audio filter module "scaletempo"
2017-09-27 10:38:45.712235+0200 TestApp[341:26587] conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
2017-09-27 10:38:45.712254+0200 TestApp[341:26587] conversion pipeline complete
2017-09-27 10:38:45.712269+0200 TestApp[341:26587] conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
2017-09-27 10:38:45.712280+0200 TestApp[341:26587] conversion pipeline complete
2017-09-27 10:38:45.712392+0200 TestApp[341:26587] looking for audio resampler module matching "any": 1 candidates
2017-09-27 10:38:45.712423+0200 TestApp[341:26587] using audio resampler module "ugly"
2017-09-27 10:38:45.711409+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.715850+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.796195+0200 TestApp[341:26576] Buffering 44%
2017-09-27 10:38:45.796530+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.796528+0200 TestApp[341:26576] Buffering 47%
2017-09-27 10:38:45.796648+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.829323+0200 TestApp[341:26576] Buffering 51%
2017-09-27 10:38:45.830299+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.841266+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.848886+0200 TestApp[341:26576] Buffering 55%
2017-09-27 10:38:45.857553+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.861322+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.863112+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.869820+0200 TestApp[341:26576] Buffering 58%
2017-09-27 10:38:45.872951+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.890596+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.895360+0200 TestApp[341:26576] Buffering 62%
2017-09-27 10:38:45.909195+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.925886+0200 TestApp[341:26576] Buffering 66%
2017-09-27 10:38:45.933595+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.958936+0200 TestApp[341:26576] Buffering 69%
2017-09-27 10:38:45.960401+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.966351+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.972692+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.989205+0200 TestApp[341:26576] Buffering 73%
2017-09-27 10:38:45.989305+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:45.998839+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.049137+0200 TestApp[341:26576] Buffering 77%
2017-09-27 10:38:46.051463+0200 TestApp[341:26576] Buffering 80%
2017-09-27 10:38:46.051793+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.052022+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.092283+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.097350+0200 TestApp[341:26576] Buffering 84%
2017-09-27 10:38:46.104752+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.126895+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.135029+0200 TestApp[341:26576] Buffering 88%
2017-09-27 10:38:46.135334+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.153198+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.165434+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.166231+0200 TestApp[341:26576] Buffering 91%
2017-09-27 10:38:46.170984+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.211731+0200 TestApp[341:26576] Buffering 95%
2017-09-27 10:38:46.212226+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.234791+0200 TestApp[341:26576] Buffering 99%
2017-09-27 10:38:46.238770+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.252348+0200 TestApp[341:26576] Stream buffering done (1027 ms in 840 ms)
2017-09-27 10:38:46.252539+0200 TestApp[341:26576] buffer deadlock prevented
2017-09-27 10:38:46.252684+0200 TestApp[341:26576] Decoder wait done in 0 ms
2017-09-27 10:38:46.253130+0200 TestApp[341:26587] inserting 3124 zeroes
2017-09-27 10:38:46.253764+0200 TestApp[341:26587] underrun of 204800 bytes
2017-09-27 10:38:46.258753+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.260645+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.269566+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.270622+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.280788+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.285597+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.315002+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.613650+0200 TestApp[341:26586] waiting for SPS/PPS
2017-09-27 10:38:46.614073+0200 TestApp[341:26586] found NAL_SPS (sps_id=0)
2017-09-27 10:38:46.614255+0200 TestApp[341:26586] found NAL_PPS (pps_id=0 sps_id=0)
2017-09-27 10:38:46.652243+0200 TestApp[341:26586] Seen SEI recovery point, 0 recovery frames
2017-09-27 10:38:46.652519+0200 TestApp[341:26586] Recovering using SEI, prerolling 0 reference pics
2017-09-27 10:38:46.652667+0200 TestApp[341:26586] Recovery from SEI recovery point complete
2017-09-27 10:38:46.653013+0200 TestApp[341:26586] restarting module due to input format change
2017-09-27 10:38:46.653161+0200 TestApp[341:26586] removing module "videotoolbox"
2017-09-27 10:38:46.653351+0200 TestApp[341:26586] looking for video decoder module matching "any": 7 candidates
2017-09-27 10:38:46.653611+0200 TestApp[341:26586] H264 profile and level parsing failed because it didn't arrive yet
2017-09-27 10:38:46.653795+0200 TestApp[341:26586] Using Video Toolbox to decode 'h264'
2017-09-27 10:38:46.653929+0200 TestApp[341:26586] using video decoder module "videotoolbox"
2017-09-27 10:38:46.654097+0200 TestApp[341:26586] new SPS parsed: 0
2017-09-27 10:38:46.654240+0200 TestApp[341:26586] new PPS parsed: 0
2017-09-27 10:38:46.663080+0200 TestApp[341:26586] Got SPS/PPS: late opening of H264 decoder
2017-09-27 10:38:46.705680+0200 TestApp[341:26586] VT decoder doesn't handle deinterlacing
2017-09-27 10:38:46.717118+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.717207+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:46.718097+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.718169+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:46.719489+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.719589+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:46.721795+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.721875+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:46.723430+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.723512+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:46.725508+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.725632+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:46.727362+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:46.727472+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
...
2017-09-27 10:38:49.369864+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.391207+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.391402+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.397088+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.397282+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.406743+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.406859+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.428905+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.429018+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.431315+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.431419+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.433585+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.433688+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.511187+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.511393+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.542259+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.542451+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.547364+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.547537+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.551353+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.551458+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.580781+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.580991+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.585840+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.586026+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.589179+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.589280+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.592597+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.593070+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.652162+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.652380+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.658750+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.658858+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.689586+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.689701+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.692531+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.692642+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.694936+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.695051+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.727447+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.727664+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.733667+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.733861+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.737979+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.738104+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.758885+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.759077+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.805340+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.805477+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.808815+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.808892+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.810458+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.810525+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.812675+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.812815+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.835401+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.835504+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.837154+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.837225+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.838905+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.838974+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.873793+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.873922+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.909547+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.909705+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.917129+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.917230+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.918962+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.919264+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.921687+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.921846+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.960006+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.960106+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.962277+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.962345+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.963104+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.963328+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.981855+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.981934+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:49.983648+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:49.983715+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.016151+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.016332+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.018506+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.018577+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.022291+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.022358+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.053211+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.053329+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.054779+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.054857+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.092409+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.092517+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.167139+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.167363+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.204196+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.204393+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.209297+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.209480+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.243226+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.243424+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.251903+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.252110+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.259498+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.259602+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.260821+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.260912+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.262755+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.262885+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.278716+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.278827+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.313285+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.313570+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.354586+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.354802+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.358684+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.358869+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.367462+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.367583+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.370180+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.370283+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.393099+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.394532+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.429119+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.429243+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.460728+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.460838+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.497845+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.497961+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.505614+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.505800+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.508064+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.508139+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.539357+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.539825+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.542624+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.542733+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.544474+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.544572+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.576000+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.576141+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.609020+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.609164+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.650560+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.650694+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.651956+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.652463+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.688398+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.688554+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.690461+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.690555+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.692896+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.692994+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.718649+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.718763+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.720778+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.720850+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.755747+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.755857+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.791605+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.791748+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.794725+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.794827+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.828408+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.828520+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.830696+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.830776+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.880980+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.881104+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.883613+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.883994+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.909476+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.909636+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.912649+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.912771+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.940753+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.941035+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.947120+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.947199+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.977930+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.978012+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:50.981185+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:50.981266+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.014340+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.014426+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.016106+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.016174+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.052749+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.052975+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.094326+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.094536+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.098430+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.098539+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.125307+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.125429+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.129659+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.129837+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.162085+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.162286+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.166921+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.167107+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.196217+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.196380+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.203941+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.204162+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.235442+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.235552+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.239429+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.239547+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.270850+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.271049+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.276266+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.276462+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.313333+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.313742+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.333408+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.333508+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.349744+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.349828+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.353534+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.353954+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.378006+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.378086+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.379502+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.379558+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.419593+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.419718+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.456258+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.456387+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.458789+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.458894+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.491251+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.491387+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.495734+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.495865+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.530172+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.530489+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.566004+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.566174+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.569367+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.569495+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.603378+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.603517+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.606375+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.606549+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.638584+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.638721+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.754726+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.754961+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.867419+0200 TestApp[341:26518] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.867560+0200 TestApp[341:26518] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:51.934859+0200 TestApp[341:24035] vt session error: 'kVTVideoDecoderBadDataErr'
2017-09-27 10:38:51.935067+0200 TestApp[341:24035] decoding of a frame failed (-12909, 1)
2017-09-27 10:38:52.023496+0200 TestApp[341:26576] killing decoder fourcc `h264'
2017-09-27 10:38:52.023577+0200 TestApp[341:26576] removing module "videotoolbox"
2017-09-27 10:38:52.036102+0200 TestApp[341:26576] removing module "h264"
2017-09-27 10:38:52.036483+0200 TestApp[341:26576] killing decoder fourcc `a52 '
2017-09-27 10:38:52.036676+0200 TestApp[341:26576] removing module "avcodec"
2017-09-27 10:38:52.045719+0200 TestApp[341:26576] removing module "ugly"
2017-09-27 10:38:52.045793+0200 TestApp[341:26576] removing module "scaletempo"
2017-09-27 10:38:52.150890+0200 TestApp[341:26576] removing module "float_mixer"
2017-09-27 10:38:52.150959+0200 TestApp[341:26576] keeping audio output
2017-09-27 10:38:52.150986+0200 TestApp[341:26576] removing module "a52"
2017-09-27 10:38:52.151017+0200 TestApp[341:26576] removing module "ts"
2017-09-27 10:38:52.151465+0200 TestApp[341:26576] Program doesn't contain anymore ES
2017-09-27 10:38:52.151502+0200 TestApp[341:26576] removing module "record"
2017-09-27 10:38:52.151520+0200 TestApp[341:26576] removing module "prefetch"
2017-09-27 10:38:52.151640+0200 TestApp[341:26577] resolving 10.10.10.45 ...
2017-09-27 10:38:52.153132+0200 TestApp[341:26577] outgoing request:
GET /file?file=/media/hdd/movie/20170927%201013%20-%20ProSieben%20HD%20-%20The%20Middle.ts HTTP/1.1
Host: 10.10.10.45:80
Accept: */*
Accept-Language: en_US
User-Agent: VLC/3.0.0-git LibVLC/3.0.0-git
If-Unmodified-Since: Wed, 27 Sep 2017 08:31:02 GMT
Range: bytes=4553912-
2017-09-27 10:38:52.153171+0200 TestApp[341:26577] connection failed
2017-09-27 10:38:52.158447+0200 TestApp[341:26577] end of stream
2017-09-27 10:38:52.158840+0200 TestApp[341:26576] removing module "access"
2017-09-27 10:38:52.162750+0200 TestApp[341:26684] removing module "audiounit_ios"
Re: Deconding on TS stream failed
Posted: 27 Sep 2017 11:45
by tguillem
Hello danyial,
Thanks for the sample, I'll try to fix the issue.
In the meantime, you can disable videotoolbox, by disabling "Hardware Decoding" in vlc for ios settings.
Re: Deconding on TS stream failed
Posted: 27 Sep 2017 11:53
by danyial
In the meantime, you can disable videotoolbox, by disabling "Hardware Decoding" in vlc for ios settings.
Thanks for fixing this issue.
I'm not using VLC for iOS but MobileVLCKit.
What are the right options for disabling Hardware Decoding?
VLCMediaPlayer(options: ["??????"])
Re: Deconding on TS stream failed
Posted: 27 Sep 2017 12:00
by tguillem
The issue was fixed yesterday. It will now automatically fallback to software decoder now.
To disable hardware decoding, you can add the following option: ":codec=avcodec,none"
Re: Deconding on TS stream failed
Posted: 27 Sep 2017 12:09
by danyial
Works with "--codec=avcodec,none"
Thank you