Project

General

Profile

Bug #5062 » vlc.log

saen acro, 2018-04-07 21:33

 
1

    
2
main debug: processing request item: 1826398699, node: ????????, skip: 0
3
main debug: rebuilding array of current - root ????????
4
main debug: rebuild done - 1 items, index 0
5
main debug: starting playback of new item
6
main debug: resyncing on 1826398699
7
main debug: 1826398699 is at 0
8
main debug: creating new input thread
9
main debug: Creating an input for '1826398699'
10
main debug: selected audio language[0] bg
11
main debug: requesting art for new input thread
12
main debug: using timeshift granularity of 50 MiB
13
main debug: using timeshift path: C:\Temp
14
main debug: `http://172.31.90.254:9981/stream/channelid/1826398699' gives access `http' demux `any' path `172.31.90.254:9981/stream/channelid/1826398699'
15
main debug: creating demux: access='http' demux='any' location='172.31.90.254:9981/stream/channelid/1826398699' file='\\172.31.90.254:9981\stream\channelid\1826398699'
16
main debug: looking for access_demux module matching "http": 15 candidates
17
main debug: no access_demux modules matched
18
main debug: creating access: http://172.31.90.254:9981/stream/channelid/1826398699
19
main debug: (path: \\172.31.90.254:9981\stream\channelid\1826398699)
20
main debug: looking for access module matching "http": 25 candidates
21
main debug: looking for meta fetcher module matching "any": 1 candidates
22
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\meta\fetcher
23
http debug: resolving 172.31.90.254 ...
24
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher
25
main debug: no meta fetcher modules matched
26
main debug: looking for art finder module matching "any": 2 candidates
27
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\meta\art
28
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art
29
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
30
http debug: outgoing request: GET /stream/channelid/1826398699 HTTP/1.1 Host: 172.31.90.254:9981 Accept: */* Accept-Language: bg User-Agent: VLC/3.0.0 LibVLC/3.0.0 Range: bytes=0- 
31
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
32
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
33
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
34
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
35
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
36
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
37
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
38
main debug: no art finder modules matched
39
qt debug: IM: Setting an input
40
http debug: incoming response: HTTP/1.1 200 OK Server: HTS/tvheadend Cache-Control: no-cache Connection: Close Content-Type: video/mp2t 
41
main debug: using access module "access"
42
main debug: looking for stream_filter module matching "prefetch,cache_block": 24 candidates
43
prefetch debug: using 16777216 bytes buffer, 16384 bytes read
44
main debug: using stream_filter module "prefetch"
45
main debug: looking for stream_filter module matching "any": 24 candidates
46
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\playlist
47
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\playlist
48
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\anevia_streams.luac
49
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\anevia_xml.luac
50
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\appletrailers.luac
51
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\bbc_co_uk.luac
52
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\cue.luac
53
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\dailymotion.luac
54
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\jamendo.luac
55
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\koreus.luac
56
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\liveleak.luac
57
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\newgrounds.luac
58
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\rockbox_fm_presets.luac
59
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\soundcloud.luac
60
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\twitch.luac
61
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\vimeo.luac
62
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\vocaroo.luac
63
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\youtube.luac
64
main debug: no stream_filter modules matched
65
main debug: looking for stream_directory module matching "any": 1 candidates
66
main debug: no stream_directory modules matched
67
main debug: attachment of directory-extractor failed for http://172.31.90.254:9981/stream/channelid/1826398699
68
main debug: looking for stream_filter module matching "record": 24 candidates
69
main debug: using stream_filter module "record"
70
main debug: creating demux: access='http' demux='ts' location='172.31.90.254:9981/stream/channelid/1826398699' file='\\172.31.90.254:9981\stream\channelid\1826398699'
71
main debug: looking for demux module matching "ts": 55 candidates
72
ts debug: Standard set to Auto
73
main debug: using demux module "ts"
74
ts debug: DEMUX_SET_GROUP 0 00000000
75
main debug: looking for meta reader module matching "any": 2 candidates
76
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\meta\reader
77
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
78
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.luac
79
main debug: no meta reader modules matched
80
main debug: `http://172.31.90.254:9981/stream/channelid/1826398699' successfully opened
81
ts debug: pid[720] unknown
82
ts debug: first packet for pid=720 cc=0x8
83
ts debug: pid[519] unknown
84
ts debug: first packet for pid=519 cc=0x2
85
ts debug: PATCallBack called
86
ts debug: new PAT ts_id=12 version=27 current_next=1
87
ts debug: * number=1 pid=296
88
ts debug: temporary receiving program 1
89
ts debug: PMTCallBack called for program 1
90
ts debug: new PMT program number=1 version=28 pid_pcr=519
91
ts debug: * pid=519 type=0x2 ISO/IEC 13818-2 Video or ISO/IEC 11172-2 constrained parameter video stream
92
ts debug: - ES descriptor tag 0x2
93
ts debug: => pid 519 has now es fcc=mpgv
94
main debug: selecting program id=1
95
main debug: looking for packetizer module matching "any": 24 candidates
96
main debug: using packetizer module "mpegvideo"
97
main debug: looking for video decoder module matching "any": 17 candidates
98
avcodec debug: using ffmpeg Lavc58.6.103
99
avcodec debug: CPU flags: 0x000011db
100
avcodec debug: allowing 5 thread(s) for decoding
101
avcodec debug: codec (mpeg2video) started
102
main debug: using video decoder module "avcodec"
103
ts debug: Default program is 1
104
ts debug: * pid=720 type=0x4 ISO/IEC 13818-3 Audio
105
ts debug: - ES descriptor tag 0x3
106
ts debug: - ES descriptor tag 0xa
107
ts debug: found language: eng
108
ts debug: => pid 720 has now es fcc=mpga
109
main debug: looking for packetizer module matching "any": 24 candidates
110
main debug: using packetizer module "mpegaudio"
111
main debug: looking for audio decoder module matching "any": 23 candidates
112
mpg123 debug: mpga->f32l, bits per sample: 32
113
main debug: using audio decoder module "mpg123"
114
ts debug: * pid=17 listening for SDT
115
ts debug: enabling pid 519 from program 1
116
ts debug: enabling pid 720 from program 1
117
ts debug: enabling pcr pid 519 from program 1
118
ts debug: first packet for pid=519 cc=0xe
119
ts debug: first packet for pid=720 cc=0x4
120
main debug: Buffering 0%
121
main debug: Buffering 2%
122
main debug: Buffering 5%
123
main debug: Buffering 7%
124
main debug: Buffering 10%
125
mpegvideo debug: waiting for sequence start
126
main debug: Buffering 13%
127
mpegvideo debug: waiting for sequence start
128
mpegvideo debug: waiting for sequence start
129
mpegvideo debug: waiting for sequence start
130
main debug: Buffering 15%
131
mpegaudio debug: MPGA channels:2 samplerate:48000 bitrate:128
132
main debug: restarting module due to input format change
133
main debug: removing module "mpg123"
134
main debug: looking for audio decoder module matching "any": 23 candidates
135
mpg123 debug: mpga->f32l, bits per sample: 32
136
main debug: using audio decoder module "mpg123"
137
main debug: reusing audio output
138
main debug: looking for aout stream module matching "any": 2 candidates
139
main debug: Buffering 18%
140
mpegvideo debug: waiting for sequence start
141
mpegvideo debug: waiting for sequence start
142
main debug: Buffering 21%
143
mpegvideo debug: waiting for sequence start
144
wasapi debug: buffer size : 96000 frames
145
wasapi debug: maximum latency: 000 ns
146
wasapi debug: default period : 10000000 ns
147
wasapi debug: minimum period : 3000000 ns
148
main debug: using aout stream module "wasapi"
149
main debug: output 'f32l' 48000 Hz Dolby frame=1 samples/8 bytes
150
main debug: looking for audio volume module matching "any": 2 candidates
151
main debug: using audio volume module "float_mixer"
152
main debug: input 'f32l' 48000 Hz Dolby frame=1 samples/8 bytes
153
main debug: looking for audio filter module matching "scaletempo": 16 candidates
154
scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32
155
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
156
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
157
main debug: using audio filter module "scaletempo"
158
main debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Dolby->Dolby
159
main debug: conversion pipeline complete
160
main debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Dolby->Dolby
161
main debug: conversion pipeline complete
162
main debug: looking for audio resampler module matching "any": 3 candidates
163
main debug: using audio resampler module "samplerate"
164
main debug: Buffering 23%
165
mpegvideo debug: waiting for sequence start
166
mpegvideo debug: waiting for sequence start
167
main debug: Buffering 26%
168
mpegvideo debug: waiting for sequence start
169
main debug: Buffering 29%
170
main debug: Buffering 31%
171
main debug: Buffering 34%
172
main debug: Buffering 37%
173
main debug: Buffering 39%
174
main debug: Buffering 42%
175
main debug: Buffering 45%
176
main debug: Buffering 47%
177
main debug: Buffering 50%
178
mpegvideo debug: waiting for sequence start
179
main debug: Buffering 52%
180
mpegvideo debug: size 720x576/720x576 fps=25.000
181
main debug: restarting module due to input format change
182
main debug: removing module "avcodec"
183
main debug: looking for video decoder module matching "any": 17 candidates
184
avcodec debug: using ffmpeg Lavc58.6.103
185
avcodec debug: CPU flags: 0x000011db
186
avcodec debug: allowing 5 thread(s) for decoding
187
avcodec debug: codec (mpeg2video) started
188
main debug: using video decoder module "avcodec"
189
avcodec debug: available hardware decoder output format 119 (cuda)
190
avcodec debug: available hardware decoder output format 53 (dxva2_vld)
191
avcodec debug: available hardware decoder output format 118 (d3d11va_vld)
192
avcodec debug: available hardware decoder output format 174 (d3d11)
193
avcodec debug: available software decoder output format 0 (yuv420p)
194
avcodec debug: trying format d3d11va_vld
195
main debug: looking for text renderer module matching "any": 3 candidates
196
freetype debug: Using DWrite backend
197
freetype debug: DWrite_GetFamily(): family name: Arial
198
main debug: using text renderer module "freetype"
199
main debug: looking for video converter module matching "any": 24 candidates
200
swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality)
201
main debug: using video converter module "swscale"
202
main debug: looking for video converter module matching "any": 24 candidates
203
yuvp debug: YUVP to YUVA converter
204
main debug: using video converter module "yuvp"
205
main debug: Deinterlacing available
206
main debug: deinterlace -1, mode yadif2x, is_needed 0
207
main debug: looking for vout window module matching "qt,any": 3 candidates
208
qt debug: requesting video window...
209
main debug: Buffering 55%
210
main debug: Buffering 58%
211
main debug: resized to 585x194
212
main debug: resized to 720x576
213
main debug: Buffering 60%
214
main debug: using vout window module "qt"
215
main debug: looking for inhibit module matching "any": 1 candidates
216
main debug: using inhibit module "inhibit"
217
main debug: Opening vout display wrapper
218
main debug: looking for vout display module matching "any": 12 candidates
219
direct3d11 debug: creating Vout EventThread
220
direct3d11 debug: Win32VoutCreateWindow
221
main debug: VoutDisplayEvent 'resize' 720x576
222
direct3d11 debug: inclinometer not found. (hr=0x80070490)
223
direct3d11 debug: created video sub-window
224
direct3d11 debug: Vout EventThread running
225
main debug: Buffering 63%
226
main debug: Buffering 66%
227
qt debug: Logical video size: 768x576
228
main debug: resized to 768x576
229
main debug: VoutDisplayEvent 'resize' 768x576
230
direct3d11 debug: supports colorspace RGB Rec.709 gamma:22 range:FULL
231
direct3d11 debug: supports colorspace RGB Rec.709 gamma:22 range:STUDIO
232
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:22 range:STUDIO
233
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:22 range:FULL
234
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:2084 range:FULL
235
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:2084 range:STUDIO
236
direct3d11 debug: Output max luminance: 270.0, colorspace RGB Rec.709 gamma:22 range:FULL, bits per pixel 10
237
direct3d11 debug: using colorspace RGB Rec.709 gamma:22 range:FULL
238
direct3d11 debug: Using pixel format VA_NV12 for chroma DX11
239
direct3d11 debug: fallback to legacy shader mode for old AMD drivers
240
main debug: Buffering 68%
241
main debug: Buffering 69%
242
main debug: VoutDisplayEvent 'resize' 784x615
243
main debug: Buffering 72%
244
main debug: Buffering 75%
245
main debug: Buffering 77%
246
main debug: Buffering 80%
247
direct3d11 debug: Direct3D11 resources created
248
direct3d11 debug: Direct3D11 device adapter successfully initialized
249
direct3d11 debug: Direct3D11 Open Succeeded
250
main debug: using vout display module "direct3d11"
251
main debug: Buffering 83%
252
direct3d11 debug: D3D11 pool succeed with 22 surfaces (720x576) context 0x0ad58064
253
main debug: original format sz 720x576, of (0,0), vsz 720x576, 4cc DX11, sar 16:15, msk r0x0 g0x0 b0x0
254
main debug: removing module "freetype"
255
main debug: VoutDisplayEvent 'resize' 768x576
256
main debug: looking for text renderer module matching "any": 3 candidates
257
direct3d11 debug: Detected size change 768x576
258
freetype debug: Using DWrite backend
259
freetype debug: DWrite_GetFamily(): family name: Arial
260
main debug: using text renderer module "freetype"
261
main debug: Buffering 85%
262
main debug: looking for hw decoder module matching "any": 2 candidates
263
d3d11va debug: ATI WDDM driver 23.20.15033.1003
264
d3d11va debug: Reusing Direct3D11 device
265
d3d11va debug: CreateDevice succeed
266
d3d11va debug: - 'MPEG-2 variable-length decoder' is supported
267
d3d11va debug: - 'MPEG-2 inverse discrete cosine transform' is supported
268
d3d11va debug: - 'H.264 variable-length decoder, no film grain technology' is supported
269
d3d11va debug: - 'H.264 variable-length decoder, no film grain technology, Flash' is supported
270
d3d11va debug: - 'Unknown decoder 0x6719b6fb-0x5cad-0x4acb-0xb00af3bfdec38727' is supported
271
d3d11va debug: - 'Unknown decoder 0x9901ccd3-0xca12-0x4b7e-0x867ae2223d9255c3' is supported
272
d3d11va debug: - 'H.264 stereo high profile, mbs flag set' is supported
273
d3d11va debug: - 'H.264 stereo high profile' is supported
274
d3d11va debug: - 'VC-1 variable-length decoder' is supported
275
d3d11va debug: - 'Unknown decoder 0xca15d19a-0x2b48-0x43d6-0x979e7a6e9c802ff8' is supported
276
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, Avivo' is supported
277
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, no GMC' is supported
278
d3d11va debug: - 'VC-1 inverse discrete cosine transform' is supported
279
d3d11va debug: - 'Windows Media Video 9 IDCT' is supported
280
d3d11va debug: - 'Unknown decoder 0x103473e4-0x10ea-0x11df-0x9a922ba055d89593' is supported
281
d3d11va debug: - 'Unknown decoder 0x84ad67f6-0x4c21-0x419a-0x9f0b24f0578906c1' is supported
282
d3d11va debug: - 'Unknown decoder 0x725ad240-0x786c-0x471e-0xad3c38f739936517' is supported
283
d3d11va debug: - 'Unknown decoder 0x95664ff5-0x9e03-0x4c74-0xbb4f9178d6035e58' is supported
284
d3d11va debug: - 'Unknown decoder 0x603a4756-0xa864-0x4f91-0xbb622c935b7a1391' is supported
285
d3d11va debug: Trying to use 'MPEG-2 variable-length decoder' as input
286
d3d11va debug: NV12 output is supported for decoder MPEG-2 variable-length decoder.
287
d3d11va debug: Using output format NV12 for decoder MPEG-2 variable-length decoder
288
d3d11va warning: surface dimensions (736x576) differ from avcodec dimensions (720x576)
289
d3d11va debug: va_pool_SetupDecoder id 2 720x576 count: 6
290
d3d11va debug: using external surface pool
291
d3d11va debug: ID3D11VideoDecoderOutputView succeed with 6 surfaces (736x576)
292
d3d11va debug: we got 2 decoder configurations
293
d3d11va debug: configuration[0] ConfigBitstreamRaw 1
294
d3d11va debug: configuration[1] ConfigBitstreamRaw 1
295
d3d11va debug: DxCreateDecoderSurfaces succeed
296
main debug: using hw decoder module "d3d11va"
297
avcodec info: Using D3D11VA (AMD Radeon R7 240, vendor 4098(ATI), device 26131, revision 0) for hardware decoding
298
main debug: Buffering 88%
299
qt debug: Logical video size: 768x576
300
main debug: resized to 768x576
301
main debug: VoutDisplayEvent 'resize' 768x576
302
main debug: Received first picture
303
main debug: Buffering 91%
304
direct3d11 debug: unhandled source transfer 0
305
main debug: Buffering 93%
306
main debug: Detected interlaced video
307
main debug: deinterlace -1, mode yadif2x, is_needed 1
308
main debug: Adding 'deinterlace' as static
309
main debug: looking for video filter module matching "deinterlace,chain": 46 candidates
310
d3d11_filters debug: ATI WDDM driver 23.20.15033.1003
311
main debug: using video filter module "d3d11_filters"
312
main debug: Filter 'deinterlace' (0a0dd858) appended to chain
313
main debug: Buffering 96%
314
ts debug: SDTCallBack called
315
ts debug: * pid=18 listening for EIT
316
ts debug: * pid=20 listening for TDT
317
ts debug: new SDT ts_id=12 version=7 current_next=1 network_id=200
318
ts debug: * service id=1 eit schedule=1 present=1 running=4 free_ca=0
319
ts debug: - type=1 provider=DVB-C name=CGTN
320
main debug: EsOutProgramMeta: number=1
321
main debug: Buffering 98%
322
main debug: Stream buffering done (1015 ms in 1088 ms)
323
main debug: Decoder wait done in 0 ms
324
main debug: inserting 9696 zeroes
325
mmdevice debug: state changed: 1
326
qt debug: Logical video size: 768x576
327
main debug: resized to 768x576
328
main debug: VoutDisplayEvent 'resize' 768x576
329
main warning: picture is too late to be displayed (missing 30 ms)
330
main debug: picture might be displayed late (missing 15 ms)
331
main error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 1000 ms)
332
main debug: ES_OUT_RESET_PCR called
333
mmdevice debug: state changed: 0
334
wasapi debug: reset
335
main debug: Buffering 0%
336
main debug: Buffering 2%
337
main debug: Buffering 5%
338
main debug: Buffering 7%
339
main debug: Buffering 10%
340
main debug: Buffering 13%
341
main debug: Buffering 15%
342
main debug: Buffering 18%
343
main debug: Received first picture
344
main debug: Buffering 21%
345
main debug: Buffering 23%
346
main debug: Buffering 26%
347
main debug: Buffering 28%
348
main debug: Buffering 31%
349
main debug: Buffering 34%
350
main debug: Buffering 36%
351
main debug: Buffering 39%
352
main debug: Buffering 42%
353
main debug: Buffering 44%
354
main debug: Buffering 46%
355
main debug: Buffering 49%
356
main debug: Buffering 51%
357
main debug: Buffering 54%
358
main debug: Buffering 57%
359
main debug: Buffering 59%
360
main debug: Buffering 62%
361
main debug: Buffering 65%
362
main debug: Buffering 67%
363
main debug: Buffering 70%
364
main debug: Buffering 73%
365
main debug: Buffering 75%
366
main debug: Buffering 78%
367
main debug: Buffering 80%
368
main debug: Buffering 83%
369
main debug: Buffering 86%
370
main debug: Buffering 88%
371
main debug: Buffering 91%
372
main debug: Buffering 94%
373
main debug: Buffering 97%
374
main debug: Stream buffering done (1001 ms in 1619 ms)
375
main debug: Decoder wait done in 0 ms
376
main warning: playback too early (-83932): down-sampling
377
mmdevice debug: state changed: 1
378
main warning: picture is too late to be displayed (missing 388 ms)
379
main warning: picture is too late to be displayed (missing 348 ms)
380
main warning: picture is too late to be displayed (missing 308 ms)
381
main warning: picture is too late to be displayed (missing 268 ms)
382
main warning: picture is too late to be displayed (missing 228 ms)
383
main warning: picture is too late to be displayed (missing 188 ms)
384
main warning: picture is too late to be displayed (missing 148 ms)
385
main warning: picture is too late to be displayed (missing 108 ms)
386
main warning: picture is too late to be displayed (missing 68 ms)
387
main warning: picture is too late to be displayed (missing 28 ms)
388
main warning: picture is too late to be displayed (missing 239 ms)
389
main warning: picture is too late to be displayed (missing 199 ms)
390
main warning: picture is too late to be displayed (missing 159 ms)
391
main warning: picture is too late to be displayed (missing 119 ms)
392
main warning: picture is too late to be displayed (missing 80 ms)
393
main warning: picture is too late to be displayed (missing 40 ms)
394
main debug: picture might be displayed late (missing 0 ms)
395
main warning: picture is too late to be displayed (missing 140 ms)
396
main warning: picture is too late to be displayed (missing 100 ms)
397
main warning: picture is too late to be displayed (missing 60 ms)
398
main warning: picture is too late to be displayed (missing 20 ms)
399
main warning: picture is too late to be displayed (missing 138 ms)
400
main warning: picture is too late to be displayed (missing 98 ms)
401
main warning: picture is too late to be displayed (missing 58 ms)
402
main debug: picture might be displayed late (missing 18 ms)
403
main warning: picture is too late to be displayed (missing 50 ms)
404
main debug: picture might be displayed late (missing 10 ms)
405
main warning: picture is too late to be displayed (missing 21 ms)
406
main debug: picture might be displayed late (missing 1 ms)
407
main debug: resampling stopped (drift: -10847 us)
408
qt debug: Using a general info windows
409
main debug: VoutDisplayEvent 'mouse button' 0 t=8
410
main debug: VoutDisplayEvent 'mouse button' 0 t=9
411
main debug: incoming request - stopping current input
412
main debug: incoming request - stopping current input
413
main debug: killing decoder fourcc `mpgv'
414
main debug: removing module "avcodec"
415
main debug: saving a free vout
416
main debug: reusing provided vout
417
main debug: removing module "mpegvideo"
418
main debug: incoming request - stopping current input
419
main debug: killing decoder fourcc `mpga'
420
main debug: removing module "mpg123"
421
mmdevice debug: state changed: 0
422
wasapi debug: reset
423
main debug: removing module "samplerate"
424
main debug: removing module "scaletempo"
425
main debug: removing module "float_mixer"
426
main debug: keeping audio output
427
main debug: removing module "mpegaudio"
428
main debug: incoming request - stopping current input
429
main debug: removing module "ts"
430
main debug: incoming request - stopping current input
431
main debug: Program doesn't contain anymore ES
432
main debug: removing module "record"
433
main debug: removing module "prefetch"
434
main debug: incoming request - stopping current input
435
prefetch debug: end of stream
436
main debug: removing module "access"
437
main debug: dead input
438
main debug: processing request item: 1122199661, node: ????????, skip: 0
439
main debug: rebuilding array of current - root ????????
440
main debug: rebuild done - 2 items, index 1
441
main debug: starting playback of new item
442
main debug: resyncing on 1122199661
443
main debug: 1122199661 is at 1
444
main debug: creating new input thread
445
main debug: Creating an input for '1122199661'
446
main debug: selected audio language[0] bg
447
main debug: requesting art for new input thread
448
main debug: using timeshift granularity of 50 MiB
449
main debug: using timeshift path: C:\Temp
450
main debug: `http://172.31.90.252:9981/stream/channelid/1122199661' gives access `http' demux `any' path `172.31.90.252:9981/stream/channelid/1122199661'
451
main debug: creating demux: access='http' demux='any' location='172.31.90.252:9981/stream/channelid/1122199661' file='\\172.31.90.252:9981\stream\channelid\1122199661'
452
main debug: looking for access_demux module matching "http": 15 candidates
453
main debug: no access_demux modules matched
454
main debug: creating access: http://172.31.90.252:9981/stream/channelid/1122199661
455
main debug: (path: \\172.31.90.252:9981\stream\channelid\1122199661)
456
main debug: looking for access module matching "http": 25 candidates
457
main debug: looking for meta fetcher module matching "any": 1 candidates
458
http debug: resolving 172.31.90.252 ...
459
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\meta\fetcher
460
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\fetcher
461
http debug: outgoing request: GET /stream/channelid/1122199661 HTTP/1.1 Host: 172.31.90.252:9981 Accept: */* Accept-Language: bg User-Agent: VLC/3.0.0 LibVLC/3.0.0 Range: bytes=0- 
462
main debug: no meta fetcher modules matched
463
main debug: looking for art finder module matching "any": 2 candidates
464
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\meta\art
465
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art
466
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
467
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
468
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
469
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
470
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
471
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
472
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
473
lua debug: skipping script (unmatched scope) C:\Program Files (x86)\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
474
main debug: no art finder modules matched
475
qt debug: IM: Deleting the input
476
qt debug: IM: Setting an input
477
qt debug: IM: Deleting the input
478
qt debug: IM: Setting an input
479
http debug: incoming response: HTTP/1.1 200 OK Server: HTS/tvheadend Cache-Control: no-cache Connection: Close Content-Type: video/mp2t 
480
main debug: using access module "access"
481
main debug: looking for stream_filter module matching "prefetch,cache_block": 24 candidates
482
prefetch debug: using 16777216 bytes buffer, 16384 bytes read
483
main debug: using stream_filter module "prefetch"
484
main debug: looking for stream_filter module matching "any": 24 candidates
485
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\playlist
486
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\playlist
487
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\anevia_streams.luac
488
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\anevia_xml.luac
489
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\appletrailers.luac
490
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\bbc_co_uk.luac
491
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\cue.luac
492
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\dailymotion.luac
493
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\jamendo.luac
494
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\koreus.luac
495
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\liveleak.luac
496
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\newgrounds.luac
497
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\rockbox_fm_presets.luac
498
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\soundcloud.luac
499
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\twitch.luac
500
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\vimeo.luac
501
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\vocaroo.luac
502
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\playlist\youtube.luac
503
main debug: no stream_filter modules matched
504
main debug: looking for stream_directory module matching "any": 1 candidates
505
main debug: no stream_directory modules matched
506
main debug: attachment of directory-extractor failed for http://172.31.90.252:9981/stream/channelid/1122199661
507
main debug: looking for stream_filter module matching "record": 24 candidates
508
main debug: using stream_filter module "record"
509
main debug: creating demux: access='http' demux='ts' location='172.31.90.252:9981/stream/channelid/1122199661' file='\\172.31.90.252:9981\stream\channelid\1122199661'
510
main debug: looking for demux module matching "ts": 55 candidates
511
ts debug: Standard set to Auto
512
main debug: using demux module "ts"
513
ts debug: DEMUX_SET_GROUP 0 00000000
514
main debug: looking for meta reader module matching "any": 2 candidates
515
lua debug: Trying Lua scripts in C:\Roaming\vlc\lua\meta\reader
516
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
517
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.luac
518
main debug: no meta reader modules matched
519
main debug: `http://172.31.90.252:9981/stream/channelid/1122199661' successfully opened
520
ts debug: pid[720] unknown
521
ts debug: first packet for pid=720 cc=0xb
522
ts debug: pid[519] unknown
523
ts debug: first packet for pid=519 cc=0xe
524
ts debug: PATCallBack called
525
ts debug: new PAT ts_id=12 version=27 current_next=1
526
ts debug: * number=1 pid=296
527
ts debug: temporary receiving program 1
528
ts debug: PMTCallBack called for program 1
529
ts debug: new PMT program number=1 version=28 pid_pcr=519
530
ts debug: * pid=519 type=0x2 ISO/IEC 13818-2 Video or ISO/IEC 11172-2 constrained parameter video stream
531
ts debug: - ES descriptor tag 0x2
532
ts debug: => pid 519 has now es fcc=mpgv
533
main debug: selecting program id=1
534
main debug: looking for packetizer module matching "any": 24 candidates
535
main debug: using packetizer module "mpegvideo"
536
main debug: looking for video decoder module matching "any": 17 candidates
537
avcodec debug: using ffmpeg Lavc58.6.103
538
avcodec debug: CPU flags: 0x000011db
539
avcodec debug: allowing 5 thread(s) for decoding
540
avcodec debug: codec (mpeg2video) started
541
main debug: using video decoder module "avcodec"
542
ts debug: Default program is 1
543
ts debug: * pid=720 type=0x4 ISO/IEC 13818-3 Audio
544
ts debug: - ES descriptor tag 0x3
545
ts debug: - ES descriptor tag 0xa
546
ts debug: found language: eng
547
ts debug: => pid 720 has now es fcc=mpga
548
main debug: looking for packetizer module matching "any": 24 candidates
549
main debug: using packetizer module "mpegaudio"
550
main debug: looking for audio decoder module matching "any": 23 candidates
551
mpg123 debug: mpga->f32l, bits per sample: 32
552
main debug: using audio decoder module "mpg123"
553
ts debug: * pid=17 listening for SDT
554
ts debug: enabling pid 519 from program 1
555
ts debug: enabling pid 720 from program 1
556
ts debug: enabling pcr pid 519 from program 1
557
ts debug: first packet for pid=720 cc=0x7
558
ts debug: first packet for pid=519 cc=0x1
559
main debug: Buffering 0%
560
main debug: Buffering 2%
561
main debug: Buffering 5%
562
main debug: Buffering 7%
563
mpegvideo debug: waiting for sequence start
564
main debug: Buffering 10%
565
mpegvideo debug: waiting for sequence start
566
main debug: Buffering 13%
567
mpegvideo debug: waiting for sequence start
568
main debug: Buffering 15%
569
mpegaudio debug: MPGA channels:2 samplerate:48000 bitrate:128
570
main debug: restarting module due to input format change
571
main debug: removing module "mpg123"
572
main debug: looking for audio decoder module matching "any": 23 candidates
573
mpg123 debug: mpga->f32l, bits per sample: 32
574
main debug: using audio decoder module "mpg123"
575
main debug: reusing audio output
576
main debug: looking for aout stream module matching "any": 2 candidates
577
main debug: Buffering 18%
578
wasapi debug: buffer size : 96000 frames
579
wasapi debug: maximum latency: 000 ns
580
wasapi debug: default period : 10000000 ns
581
wasapi debug: minimum period : 3000000 ns
582
main debug: using aout stream module "wasapi"
583
main debug: output 'f32l' 48000 Hz Dolby frame=1 samples/8 bytes
584
main debug: looking for audio volume module matching "any": 2 candidates
585
main debug: using audio volume module "float_mixer"
586
main debug: input 'f32l' 48000 Hz Dolby frame=1 samples/8 bytes
587
main debug: looking for audio filter module matching "scaletempo": 16 candidates
588
scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32
589
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
590
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
591
main debug: using audio filter module "scaletempo"
592
main debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Dolby->Dolby
593
main debug: conversion pipeline complete
594
main debug: conversion: 'f32l'->'f32l' 48000 Hz->48000 Hz Dolby->Dolby
595
main debug: conversion pipeline complete
596
main debug: looking for audio resampler module matching "any": 3 candidates
597
main debug: using audio resampler module "samplerate"
598
mpegvideo debug: waiting for sequence start
599
main debug: Buffering 21%
600
main debug: Buffering 23%
601
mpegvideo debug: waiting for sequence start
602
main debug: Buffering 26%
603
mpegvideo debug: waiting for sequence start
604
main debug: Buffering 28%
605
main debug: Buffering 31%
606
main debug: Buffering 34%
607
mpegvideo debug: waiting for sequence start
608
main debug: Buffering 36%
609
main debug: Buffering 39%
610
mpegvideo debug: waiting for sequence start
611
main debug: Buffering 42%
612
main debug: Buffering 43%
613
mpegvideo debug: waiting for sequence start
614
main debug: Buffering 45%
615
main debug: Buffering 48%
616
main debug: Buffering 51%
617
mpegvideo debug: waiting for sequence start
618
mpegvideo debug: size 720x576/720x576 fps=25.000
619
main debug: Buffering 53%
620
main debug: restarting module due to input format change
621
main debug: removing module "avcodec"
622
main debug: looking for video decoder module matching "any": 17 candidates
623
avcodec debug: using ffmpeg Lavc58.6.103
624
avcodec debug: CPU flags: 0x000011db
625
avcodec debug: allowing 5 thread(s) for decoding
626
main debug: Buffering 56%
627
avcodec debug: codec (mpeg2video) started
628
main debug: using video decoder module "avcodec"
629
avcodec debug: available hardware decoder output format 119 (cuda)
630
avcodec debug: available hardware decoder output format 53 (dxva2_vld)
631
avcodec debug: available hardware decoder output format 118 (d3d11va_vld)
632
avcodec debug: available hardware decoder output format 174 (d3d11)
633
avcodec debug: available software decoder output format 0 (yuv420p)
634
avcodec debug: trying format d3d11va_vld
635
main debug: trying to reuse free vout
636
main debug: removing module "freetype"
637
main debug: looking for text renderer module matching "any": 3 candidates
638
freetype debug: Using DWrite backend
639
freetype debug: DWrite_GetFamily(): family name: Arial
640
main debug: using text renderer module "freetype"
641
main debug: removing module "d3d11_filters"
642
main debug: reusing provided vout
643
main debug: looking for hw decoder module matching "any": 2 candidates
644
d3d11va debug: ATI WDDM driver 23.20.15033.1003
645
main debug: Filter 0a0dd858 removed from chain
646
d3d11va debug: Reusing Direct3D11 device
647
main debug: Adding 'deinterlace' as static
648
d3d11va debug: CreateDevice succeed
649
main debug: looking for video filter module matching "deinterlace,chain": 46 candidates
650
d3d11va debug: - 'MPEG-2 variable-length decoder' is supported
651
d3d11va debug: - 'MPEG-2 inverse discrete cosine transform' is supported
652
d3d11va debug: - 'H.264 variable-length decoder, no film grain technology' is supported
653
d3d11va debug: - 'H.264 variable-length decoder, no film grain technology, Flash' is supported
654
d3d11_filters debug: ATI WDDM driver 23.20.15033.1003
655
d3d11va debug: - 'Unknown decoder 0x6719b6fb-0x5cad-0x4acb-0xb00af3bfdec38727' is supported
656
d3d11va debug: - 'Unknown decoder 0x9901ccd3-0xca12-0x4b7e-0x867ae2223d9255c3' is supported
657
d3d11va debug: - 'H.264 stereo high profile, mbs flag set' is supported
658
main debug: using video filter module "d3d11_filters"
659
d3d11va debug: - 'H.264 stereo high profile' is supported
660
main debug: Filter 'deinterlace' (0a0dcb10) appended to chain
661
d3d11va debug: - 'VC-1 variable-length decoder' is supported
662
d3d11va debug: - 'Unknown decoder 0xca15d19a-0x2b48-0x43d6-0x979e7a6e9c802ff8' is supported
663
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, Avivo' is supported
664
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, no GMC' is supported
665
d3d11va debug: - 'VC-1 inverse discrete cosine transform' is supported
666
d3d11va debug: - 'Windows Media Video 9 IDCT' is supported
667
d3d11va debug: - 'Unknown decoder 0x103473e4-0x10ea-0x11df-0x9a922ba055d89593' is supported
668
d3d11va debug: - 'Unknown decoder 0x84ad67f6-0x4c21-0x419a-0x9f0b24f0578906c1' is supported
669
d3d11va debug: - 'Unknown decoder 0x725ad240-0x786c-0x471e-0xad3c38f739936517' is supported
670
d3d11va debug: - 'Unknown decoder 0x95664ff5-0x9e03-0x4c74-0xbb4f9178d6035e58' is supported
671
d3d11va debug: - 'Unknown decoder 0x603a4756-0xa864-0x4f91-0xbb622c935b7a1391' is supported
672
d3d11va debug: Trying to use 'MPEG-2 variable-length decoder' as input
673
d3d11va debug: NV12 output is supported for decoder MPEG-2 variable-length decoder.
674
d3d11va debug: Using output format NV12 for decoder MPEG-2 variable-length decoder
675
d3d11va warning: surface dimensions (736x576) differ from avcodec dimensions (720x576)
676
d3d11va debug: va_pool_SetupDecoder id 2 720x576 count: 6
677
d3d11va debug: using external surface pool
678
d3d11va debug: ID3D11VideoDecoderOutputView succeed with 6 surfaces (736x576)
679
d3d11va debug: we got 2 decoder configurations
680
d3d11va debug: configuration[0] ConfigBitstreamRaw 1
681
d3d11va debug: configuration[1] ConfigBitstreamRaw 1
682
d3d11va debug: DxCreateDecoderSurfaces succeed
683
main debug: using hw decoder module "d3d11va"
684
avcodec info: Using D3D11VA (AMD Radeon R7 240, vendor 4098(ATI), device 26131, revision 0) for hardware decoding
685
main debug: Received first picture
686
main debug: Buffering 59%
687
direct3d11 debug: unhandled source transfer 0
688
qt debug: Logical video size: 768x576
689
main debug: resized to 768x576
690
main debug: VoutDisplayEvent 'resize' 768x576
691
main debug: Buffering 61%
692
main debug: Buffering 64%
693
main debug: Buffering 67%
694
main debug: Buffering 69%
695
main debug: Buffering 72%
696
main debug: Buffering 74%
697
ts debug: pid[18] unknown
698
main debug: Buffering 77%
699
main debug: Buffering 80%
700
main debug: Buffering 82%
701
main debug: Buffering 85%
702
main debug: Buffering 88%
703
ts debug: SDTCallBack called
704
ts debug: * pid=18 listening for EIT
705
ts debug: * pid=20 listening for TDT
706
ts debug: new SDT ts_id=12 version=7 current_next=1 network_id=200
707
ts debug: * service id=1 eit schedule=1 present=1 running=4 free_ca=0
708
ts debug: - type=1 provider=DVB-C name=CGTN
709
main debug: EsOutProgramMeta: number=1
710
main debug: Buffering 90%
711
ts debug: first packet for pid=18 cc=0x1
712
main debug: Buffering 93%
713
main debug: Buffering 96%
714
main debug: Buffering 98%
715
main debug: Stream buffering done (1012 ms in 1270 ms)
716
main debug: Decoder wait done in 0 ms
717
main debug: inserting 9876 zeroes
718
mmdevice debug: state changed: 1
719
main warning: picture is too late to be displayed (missing 150 ms)
720
main warning: picture is too late to be displayed (missing 110 ms)
721
main warning: picture is too late to be displayed (missing 70 ms)
722
main warning: picture is too late to be displayed (missing 30 ms)
723
main warning: picture is too late to be displayed (missing 137 ms)
724
main warning: picture is too late to be displayed (missing 97 ms)
725
main warning: picture is too late to be displayed (missing 57 ms)
726
main debug: picture might be displayed late (missing 17 ms)
727
main warning: picture is too late to be displayed (missing 51 ms)
728
main debug: picture might be displayed late (missing 11 ms)
729
main warning: picture is too late to be displayed (missing 21 ms)
730
main debug: picture might be displayed late (missing 1 ms)
731
ts debug: EITCallBack called
732
ts debug: new EIT service_id=1 version=27 current_next=1 ts_id=12 network_id=200 segment_last_section_number=1 last_table_id=78
733
ts debug: * event id=3131 start_time:1523128500 duration=2700 running=4 free_ca=0
734
ts debug: - short event lang=bul 'Crossover' : '(null)'
735
ts debug: * event id=3132 start_time:1523131200 duration=1800 running=1 free_ca=0
736
ts debug: - short event lang=bul 'News Desk' : '(null)'
737
main debug: EsOutProgramEpg: number=1 name=CGTN [???????? 1]
(3-3/3)