Issue getting Streams w/ Security Cameras

binkis · January 29, 2021, 10:17pm

I'm having an issue getting streams going to Channels DVR from my 3 POE IP Security Cameras. I can use the below URLs (user/password redacted) to view the stream successfully with VLC, but when I try to go through the web-client, I never get the stream to load.

VLC doesn't report anything funky with the codec parameters (for sub-stream):
Codec: H264 - MPEG-4 AVC (part 10) (h264)
Type:Video
Video Resolution:704x480
Buffer Dimensions:704x480
Frame Rate: 29.97

Main Stream is the same except indicates normal 4k video resolution (3840 x 2160)

There is no audio for the camera, so I don't know if that could be causing an issue?

I triple-checked that I was setup for MPEG-TS in the configuration. I tried both subtype=0 and subtype=1, thinking it might be a 4k h.264 high encoding issue (on the main feed), but no change in results.

Here is my .m3u file:

#EXTM3U
#EXTINF:-1 channel-id="GarageDoor" channel-number="2001" tvg-name="GarageDoor" tvc-guide-title="Garage Door" tvc-guide-description="Security Camera for Garage Door" tvc-stream-vcodec="h264",GarageDoor
rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1
#EXTINF:-1 channel-id="FrontDoor" channel-number="2002" tvg-name="FrontDoor" tvc-guide-title="Front Door" tvc-guide-description="Security Camera for Front Door" tvc-stream-vcodec="h264",FrontDoor
rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1
#EXTINF:-1 channel-id="BackDoor" channel-number="2003" tvg-name="BackDoor" tvc-guide-title="Back Door" tvc-guide-description="Security Camera for Back Door" tvc-stream-vcodec="h264",BackDoor
rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1

Here is the log messaging I get:

2021/01/29 16:59:53.053418 [TNR] Opened connection to M3U-IPCameras for ch2001 GarageDoor
2021/01/29 16:59:53.054955 [HLS] Starting transcoder for channel 2001 from 10.221.158.21 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 16:59:54.375929 [HLS] ffmpeg: rtsp-GarageDoor: [mpegts @ 0x58ae300] Timestamps are unset in a packet for stream 0. This is deprecated and will stop working in the future. Fix your code to set the timestamps properly
2021/01/29 16:59:54.375976 [HLS] ffmpeg: rtsp-GarageDoor: [mpegts @ 0x58ae300] first pts value must be set
2021/01/29 16:59:54.375988 [HLS] ffmpeg: rtsp-GarageDoor: av_interleaved_write_frame(): Invalid data found when processing input
2021/01/29 16:59:54.378490 [ERR] Error during stream M3U-IPCameras ch2001 GarageDoor: exit status 1
2021/01/29 16:59:54.378852 [HLS] ffmpeg: ch2001-dANY-ip10.221.158.21-remux: pipe:: could not find codec parameters
2021/01/29 16:59:54.419268 [HLS] Couldn't generate stream playlist for ch2001-dANY-ip10.221.158.21: Stream stopped
2021/01/29 16:59:54.419331 [HLS] Stopping transcoder session ch2001-dANY-ip10.221.158.21
2021/01/29 16:59:55.059532 [TNR] Sharing existing connection to M3U-IPCameras for ch2001 GarageDoor (clients=2, len=0)
2021/01/29 16:59:55.060604 [HLS] Starting transcoder for channel 2001 from 10.221.158.21 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 16:59:55.070354 [HLS] ffmpeg: ch2001-dANY-ip10.221.158.21-remux: pipe:: could not find codec parameters
2021/01/29 16:59:55.114630 [HLS] Couldn't generate stream playlist for ch2001-dANY-ip10.221.158.21: Stream stopped
2021/01/29 16:59:55.114697 [HLS] Stopping transcoder session ch2001-dANY-ip10.221.158.21
2021/01/29 16:59:56.125327 [TNR] Sharing existing connection to M3U-IPCameras for ch2001 GarageDoor (clients=3, len=0)
2021/01/29 16:59:56.126440 [HLS] Starting transcoder for channel 2001 from 10.221.158.21 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 16:59:56.136159 [HLS] ffmpeg: ch2001-dANY-ip10.221.158.21-remux: pipe:: could not find codec parameters
2021/01/29 16:59:56.175134 [HLS] Couldn't generate stream playlist for ch2001-dANY-ip10.221.158.21: Stream stopped
2021/01/29 16:59:56.175202 [HLS] Stopping transcoder session ch2001-dANY-ip10.221.158.21
2021/01/29 16:59:57.067426 [TNR] Sharing existing connection to M3U-IPCameras for ch2001 GarageDoor (clients=4, len=0)
2021/01/29 16:59:57.068564 [HLS] Starting transcoder for channel 2001 from 10.221.158.21 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 16:59:57.078311 [HLS] ffmpeg: ch2001-dANY-ip10.221.158.21-remux: pipe:: could not find codec parameters
2021/01/29 16:59:57.122153 [HLS] Couldn't generate stream playlist for ch2001-dANY-ip10.221.158.21: Stream stopped
2021/01/29 16:59:57.122220 [HLS] Stopping transcoder session ch2001-dANY-ip10.221.158.21
2021/01/29 16:59:58.131501 [TNR] Sharing existing connection to M3U-IPCameras for ch2001 GarageDoor (clients=5, len=0)
2021/01/29 16:59:58.132657 [HLS] Starting transcoder for channel 2001 from 10.221.158.21 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 16:59:58.152165 [HLS] ffmpeg: ch2001-dANY-ip10.221.158.21-remux: pipe:: could not find codec parameters
2021/01/29 16:59:58.181297 [HLS] Couldn't generate stream playlist for ch2001-dANY-ip10.221.158.21: Stream stopped
2021/01/29 16:59:58.181403 [HLS] Stopping transcoder session ch2001-dANY-ip10.221.158.21
2021/01/29 16:59:59.077067 [TNR] Sharing existing connection to M3U-IPCameras for ch2001 GarageDoor (clients=6, len=0)
2021/01/29 16:59:59.078154 [HLS] Starting transcoder for channel 2001 from 10.221.158.21 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 16:59:59.099621 [HLS] ffmpeg: ch2001-dANY-ip10.221.158.21-remux: pipe:: could not find codec parameters
2021/01/29 16:59:59.131742 [HLS] Couldn't generate stream playlist for ch2001-dANY-ip10.221.158.21: Stream stopped
2021/01/29 16:59:59.131814 [HLS] Stopping transcoder session ch2001-dANY-ip10.221.158.21

I also went to the commandline of the linux box running channels-dvr and ran ffplay in verbose mode, and was able to view the video, and grabbed a screenshot of the output (in case that's helpful).

On a side-note, 10.221.158.21 is an internal address for the router, not any machine involved in this transaction. Could it be a problem where the IP camera footage is coming in through 1 sub-net on a separate VLAN from the connection it's going out to the rest of the network (on a different sub-net)?

reneg · January 30, 2021, 2:40am

I don't see anything obvious. I have a Dahua NVR & cameras. Your configuration looks very similar to mine. I do have audio enabled on the cameras and I use the lower resolution sub-stream. I access the cameras through the NVR IP address. Here's my working configuration for comparison.

#EXTM3U

#EXTINF:-1 channel-id="All Cams" channel-number="8000" tvg-name="All Cams" tvc-guide-title="All Cams view" tvc-guide-description="Live view All Cameras" tvc-stream-vcodec="h264" tvc-stream-acodec="aac" tvc-guide-art="http://192.168.1.23:8000/images/rtsp/AllCams.jpg",All Cams
rtsp://user:[email protected]:554/cam/realmonitor?channel=17&subtype=0

#EXTINF:-1 channel-id="Garage1" channel-number="8001" tvg-name="Garage1" tvc-guide-title="Garage view" tvc-guide-description="Live view from garage" tvc-stream-vcodec="h264" tvc-stream-acodec="aac" tvc-guide-art="http://192.168.1.23:8000/images/rtsp/Garage1.jpg",Garage1
rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1

#EXTINF:-1 channel-id="Study2" channel-number="8002" tvg-name="Study2" tvc-guide-title="Study view" tvc-guide-description="Live view from study" tvc-stream-vcodec="h264" tvc-stream-acodec="aac" tvc-guide-art="http://192.168.1.23:8000/images/rtsp/Study2.jpg",Study2
rtsp://user:[email protected]:554/cam/realmonitor?channel=2&subtype=1

Logs from when I start up view a camera from Channels
2021/01/29 20:22:56.196986 [TNR] Opened connection to M3U-IPCameras for ch8001 Garage1
2021/01/29 20:22:56.202988 [HLS] Starting transcoder for channel 8001 from 192.168.1.254 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2021/01/29 20:22:59.838717 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 20880, current: 3600; changing to 20881. This may result in incorrect timestamps in the output file.
2021/01/29 20:22:59.838717 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 20881, current: 6840; changing to 20882. This may result in incorrect timestamps in the output file.
2021/01/29 20:22:59.838717 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 20882, current: 10080; changing to 20883. This may result in incorrect timestamps in the output file.
2021/01/29 20:22:59.838717 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 20883, current: 13680; changing to 20884. This may result in incorrect timestamps in the output file.
2021/01/29 20:22:59.838717 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 20884, current: 17280; changing to 20885. This may result in incorrect timestamps in the output file.
2021/01/29 20:22:59.839782 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 67320, current: 67320; changing to 67321. This may result in incorrect timestamps in the output file.
2021/01/29 20:23:00.056767 [HLS] Probed live stream in 3.8238386s: h264 704x576 progressive 1366857bps
2021/01/29 20:23:00.900689 [HLS] Session ch8001-dANY-ip192.168.1.254 started in 4.7257293s
2021/01/29 20:23:01.996630 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 463320, current: 463320; changing to 463321. This may result in incorrect timestamps in the output file.
2021/01/29 20:23:02.296591 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 492120, current: 492120; changing to 492121. This may result in incorrect timestamps in the output file.
2021/01/29 20:23:02.427567 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 502920, current: 502920; changing to 502921. This may result in incorrect timestamps in the output file.
2021/01/29 20:23:02.912537 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 546120, current: 546120; changing to 546121. This may result in incorrect timestamps in the output file.
2021/01/29 20:23:06.316337 [HLS] ffmpeg: rtsp-Garage1: [mpegts @ 000000000185e8c0] Non-monotonous DTS in output stream 0:0; previous: 857250, current: 857250; changing to 857251. This may result in incorrect timestamps in the output file.
2021/01/29 20:23:09.294163 [HLS] Stopping transcoder session ch8001-dANY-ip192.168.1.254 (out: 12.288s, finished: false)

tmm1 · January 30, 2021, 2:45am

Can you submit diagnostics from the dvr so we can take a closer look?

Were the logs above from the stable or prerelease dvr?

binkis · January 30, 2021, 4:25am

Logs were from pre-release. I tried to update to the latest pre-release after it didn't work initially.

I just attempted another stream to the web-client, then submitted diagnostic logs.
e5696eec-4363-4a8e-a9ef-5ca169318145 was the uid given.

binkis · January 31, 2021, 1:35am

Also, as another point of data, I was running with Hardware Transcoding enabled (QSV is the hardware available). I figured VLC might not be configured to utilize that, so I tried disabling Hardware Transcoder in Channels DVR, but got the same result.

tmm1 · January 31, 2021, 1:47am

Can you use the ffmpeg that comes with channels-dvr and see if these work:

./channels-dvr/latest/ffmpeg -i "rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1" -c copy -f mpegts -y /dev/null

./channels-dvr/latest/ffmpeg -fflags genpts -i "rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1" -c copy -f mpegts -y /dev/null

binkis · January 31, 2021, 2:29am

For
./channels-dvr/latest/ffmpeg -i "rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1" -c copy -f mpegts -y /dev/null:

Input #0, rtsp, from 'rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1':
  Metadata:
    title           : Media Server
  Duration: N/A, start: 0.040000, bitrate: N/A
    Stream #0:0: Video: h264 (High), yuv420p(progressive), 704x480, 100 tbr, 90k tbn, 180k tbc
Output #0, mpegts, to '/dev/null':
  Metadata:
    title           : Media Server
    encoder         : Lavf58.29.100
    Stream #0:0: Video: h264 (High), yuv420p(progressive), 704x480, q=2-31, 100 tbr, 90k tbn, 90k tbc
Stream mapping:
  Stream #0:0 -> #0:0 (copy)
Press [q] to stop, [?] for help
[mpegts @ 0x7563c00] Timestamps are unset in a packet for stream 0. This is deprecated and will stop working in the future. Fix your code to set the timestamps properly
[mpegts @ 0x7563c00] first pts value must be set
av_interleaved_write_frame(): Invalid data found when processing input
frame=    1 fps=0.0 q=-1.0 Lsize=       0kB time=00:00:00.00 bitrate=   0.0kbits/s speed=0.393x    
video:50kB audio:0kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
Conversion failed!

For
./channels-dvr/latest/ffmpeg -fflags genpts -i "rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1" -c copy -f mpegts -y /dev/null

Input #0, rtsp, from 'rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1':
  Metadata:
    title           : Media Server
  Duration: N/A, start: 0.060000, bitrate: N/A
    Stream #0:0: Video: h264 (High), yuv420p(progressive), 704x480, 100 tbr, 90k tbn, 180k tbc
Output #0, mpegts, to '/dev/null':
  Metadata:
    title           : Media Server
    encoder         : Lavf58.29.100
    Stream #0:0: Video: h264 (High), yuv420p(progressive), 704x480, q=2-31, 100 tbr, 90k tbn, 90k tbc
Stream mapping:
  Stream #0:0 -> #0:0 (copy)
Press [q] to stop, [?] for help
[mpegts @ 0x68b1cc0] Timestamps are unset in a packet for stream 0. This is deprecated and will stop working in the future. Fix your code to set the timestamps properly
[mpegts @ 0x68b1cc0] first pts value must be set
av_interleaved_write_frame(): Invalid data found when processing input
frame=    1 fps=0.0 q=-1.0 Lsize=       0kB time=00:00:00.00 bitrate=   0.0kbits/s speed=0.324x    
video:32kB audio:0kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
Conversion failed!

Now, what's interesting is that if I change the output video codec from copy to libx264, ffmpeg is just happy to ingest and send the data along. If I change the output video_codec to h264_vaapi, it chokes again. But if I alter your command to the following (run from the channels-dvr/latest folder):

./ffmpeg -hwaccel vaapi -hwaccel_output_format vaapi -fflags genpts -i "rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1" -c:v h264_vaapi -f mpegts -y /dev/null

ffmpeg chugs along just fine:

Input #0, rtsp, from 'rtsp://user:[email protected]:554/cam/realmonitor?channel=1&subtype=1':
  Metadata:
    title           : Media Server
  Duration: N/A, start: 0.030000, bitrate: N/A
    Stream #0:0: Video: h264 (High), yuv420p(progressive), 704x480, 100 tbr, 90k tbn, 180k tbc
Stream mapping:
  Stream #0:0 -> #0:0 (h264 (native) -> h264 (h264_vaapi))
Press [q] to stop, [?] for help
[h264_vaapi @ 0x5f8adc0] No quality level set; using default (20).
Output #0, mpegts, to '/dev/null':
  Metadata:
    title           : Media Server
    encoder         : Lavf58.29.100
    Stream #0:0: Video: h264 (h264_vaapi) (High), vaapi_vld, 704x480, q=-1--1, 100 fps, 90k tbn, 100 tbc
    Metadata:
      encoder         : Lavc58.54.100 h264_vaapi
frame=  221 fps= 34 q=-0.0 Lsize=     386kB time=00:00:07.26 bitrate= 436.1kbits/s speed=1.11x    
video:332kB audio:0kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: 16.360579%

binkis · January 31, 2021, 2:31am

Also, another note, the ffmpeg I had been using was
ffmpeg version 4.2.4-1ubuntu0.1 Copyright (c) 2000-2020 the FFmpeg developers

And I get the same result as with the packaged ffmpeg for channels-dvr in all cases noted above.

binkis · February 2, 2021, 2:45pm

I've seen a few pre-release updates get pushed, but I'm still having this issue. Any more information needed to identify?

tmm1 · February 2, 2021, 4:19pm

This is a long-standing ffmpeg bug which affects certain cameras: https://trac.ffmpeg.org/ticket/1598

I can take a stab at fixing it but I would need access to the camera via SSH or VPN for testing.

TerryD · June 10, 2021, 12:49am

Any advise for this strange issue with my custom Cam channel?

#EXTM3U

#EXTINF:-1 channel-id="TestCAM" channel-number="01" tvg-name="TestSetUp" tvc-guide-title="Test1" tvc-guide-description="Live test camera feed" tvc-stream-vcodec="h264",TestSetUp
rtsp://9j8NDfUI:[email protected]:554/live/ch1?token=72ed10260a2e1bf3df403f71065c8c1e

when running in Channels this works except for filling the log with multiple DTS error messages and the broken pipe messages upon exiting the channel.

2021/06/07 13:26:57.860261 [HLS] ffmpeg: rtsp-TestCAM: [mpegts @ 0x804eea400] Non-monotonous DTS in output stream 0:1; previous: 19573740, current: 19540440; changing to 19573741. This may result in incorrect timestamps in the output file.
2021/06/07 13:26:57.860318 [HLS] ffmpeg: rtsp-TestCAM: av_interleaved_write_frame(): Broken pipe
2021/06/07 13:26:57.860351 [HLS] ffmpeg: rtsp-TestCAM: Error writing trailer of pipe:: Broken pipe

When running the rtsp link in VLC it runs for 30 seconds and closes.
The following is the error logs from VLC.

have to break this post up as it's to large.Sorry for the long winded post but thought the information would be of use.

TerryD · June 10, 2021, 12:50am

main debug: processing request item: rtsp://192.168.50.237:554/live/ch1, node: Playlist, skip: 0
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 1 items, index 0
main debug: starting playback of new item
main debug: resyncing on rtsp://192.168.50.237:554/live/ch1
main debug: rtsp://192.168.50.237:554/live/ch1 is at 0
main debug: creating new input thread
main debug: Creating an input for 'rtsp://192.168.50.237:554/live/ch1'
main debug: requesting art for new input thread
main debug: using timeshift granularity of 50 MiB
main debug: looking for meta fetcher module matching "any": 1 candidates
main debug: using timeshift path: C:\Users\doyle\AppData\Local\Temp
main debug: rtsp://9j8NDfUI:[email protected]:554/live/ch1?token=72ed10260a2e1bf3df403f71065c8c1e' gives access rtsp' demux any' path 9j8NDfUI:[email protected]:554/live/ch1?token=72ed10260a2e1bf3df403f71065c8c1e'
main debug: creating demux: access='rtsp' demux='any' location='9j8NDfUI:[email protected]:554/live/ch1?token=72ed10260a2e1bf3df403f71065c8c1e' file='\9j8NDfUI:[email protected]:554\live\ch1'
main debug: looking for access_demux module matching "rtsp": 15 candidates
live555 debug: version 2016.11.28
lua debug: Trying Lua scripts in C:\Users\doyle\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
main warning: Password in a URI is DEPRECATED
main debug: no meta fetcher modules matched
main debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\doyle\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
main debug: no art finder modules matched
main debug: looking for meta fetcher module matching "any": 1 candidates
lua debug: Trying Lua scripts in C:\Users\doyle\AppData\Roaming\vlc\lua\meta\fetcher
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher
main debug: no meta fetcher modules matched
main debug: looking for art finder module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\doyle\AppData\Roaming\vlc\lua\meta\art
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac
main debug: no art finder modules matched
qt debug: IM: Setting an input
live555 debug: RTP subsession 'video/H264'
main debug: selecting program id=0
live555 debug: RTP subsession 'audio/MPEG4-GENERIC'
live555 debug: setup start: 0.000000 stop:0.000000
live555 debug: We have a timeout of 60 seconds
live555 debug: play start: 0.000000 stop:0.000000
main debug: using access_demux module "live555"
main debug: looking for packetizer module matching "any": 25 candidates
h264 debug: found NAL_SPS (sps_id=0)
h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
main debug: using packetizer module "h264"
main debug: looking for video decoder module matching "any": 19 candidates
avcodec debug: using ffmpeg Lavc58.6.103
avcodec debug: CPU flags: 0x0007d3db
avcodec debug: allowing 6 thread(s) for decoding
avcodec debug: codec (h264) started
avcodec debug: using frame thread mode with 6 threads
main debug: using video decoder module "avcodec"
main debug: looking for audio decoder module matching "any": 23 candidates
main debug: using audio decoder module "faad"
main debug: looking for meta reader module matching "any": 2 candidates
lua debug: Trying Lua scripts in C:\Users\doyle\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac
main debug: no meta reader modules matched
main debug: `rtsp://9j8NDfUI:[email protected]:554/live/ch1?token=72ed10260a2e1bf3df403f71065c8c1e' successfully opened
main debug: Buffering 0%
main debug: Buffering 1%
main debug: Buffering 3%
main debug: restarting module due to input format change
main debug: removing module "avcodec"
main debug: looking for video decoder module matching "any": 19 candidates
avcodec debug: using ffmpeg Lavc58.6.103
avcodec debug: CPU flags: 0x0007d3db
avcodec debug: allowing 6 thread(s) for decoding
avcodec debug: codec (h264) started
avcodec debug: using frame thread mode with 6 threads
main debug: using video decoder module "avcodec"
avcodec debug: available hardware decoder output format 119 (cuda)
avcodec debug: available hardware decoder output format 53 (dxva2_vld)
avcodec debug: available hardware decoder output format 118 (d3d11va_vld)
avcodec debug: available hardware decoder output format 174 (d3d11)
avcodec debug: available software decoder output format 0 (yuv420p)
main debug: Buffering 4%
main debug: Buffering 8%
main debug: Buffering 11%
avcodec debug: trying format d3d11va_vld
main debug: looking for text renderer module matching "any": 3 candidates
freetype debug: Using DWrite backend
freetype debug: DWrite_GetFamily(): family name: Arial
freetype debug: DWrite_ParseFamily(): using font at index 0 with weight 400 for bold: 0, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 1 with weight 700 for bold: 1, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 2 with weight 400 for bold: 0, italic: 1
freetype debug: DWrite_ParseFamily(): using font at index 3 with weight 700 for bold: 1, italic: 1
main debug: using text renderer module "freetype"
main debug: looking for video converter module matching "any": 24 candidates
swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video converter module "swscale"
main debug: looking for video converter module matching "any": 24 candidates
main debug: Buffering 14%
yuvp debug: YUVP to YUVA converter
main debug: using video converter module "yuvp"
main debug: Deinterlacing available
main debug: deinterlace -1, mode auto, is_needed 0
main debug: looking for vout window module matching "qt,any": 3 candidates
qt debug: requesting video window...
main debug: resized to 646x300
main debug: resized to 768x432
main debug: using vout window module "qt"
main debug: looking for inhibit module matching "any": 1 candidates
main debug: using inhibit module "inhibit"
main debug: Opening vout display wrapper
main debug: looking for vout display module matching "any": 12 candidates
direct3d11 debug: creating Vout EventThread
direct3d11 debug: Win32VoutCreateWindow
main debug: VoutDisplayEvent 'resize' 768x432
qt debug: Logical video size: 768x432
main debug: resized to 768x432
main debug: VoutDisplayEvent 'resize' 768x432
direct3d11 debug: inclinometer not found. (hr=0x80070490)
direct3d11 debug: created video sub-window
direct3d11 debug: Vout EventThread running
direct3d11 debug: Created the D3D11 device type 1 level b100.
direct3d11 debug: NVIDIA WDDM driver 27.21.14.6663
direct3d11 debug: supports colorspace RGB Rec.709 gamma:22 range:FULL
direct3d11 debug: supports colorspace RGB Rec.709 gamma:22 range:STUDIO
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:22 range:STUDIO
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:22 range:FULL
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:2084 range:FULL
direct3d11 debug: supports colorspace RGB Rec.2020 gamma:2084 range:STUDIO
direct3d11 debug: Output max luminance: 270.0, colorspace RGB Rec.709 gamma:22 range:FULL, bits per pixel 8
direct3d11 debug: using colorspace RGB Rec.709 gamma:22 range:FULL
direct3d11 debug: Using pixel format VA_NV12 for chroma DX11
main debug: VoutDisplayEvent 'resize' 784x471
main debug: Buffering 18%
direct3d11 debug: Direct3D11 resources created
direct3d11 debug: Direct3D11 device adapter successfully initialized
direct3d11 debug: Direct3D11 Open Succeeded
main debug: using vout display module "direct3d11"
direct3d11 debug: D3D11 pool succeed with 40 surfaces (768x512) context 0x0000027ddea274a0
main debug: original format sz 768x432, of (0,0), vsz 768x432, 4cc DX11, sar 1:1, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: VoutDisplayEvent 'resize' 768x432
direct3d11 debug: Detected size change 768x432
main debug: looking for text renderer module matching "any": 3 candidates
freetype debug: Using DWrite backend
freetype debug: DWrite_GetFamily(): family name: Arial
freetype debug: DWrite_ParseFamily(): using font at index 0 with weight 400 for bold: 0, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 1 with weight 700 for bold: 1, italic: 0
freetype debug: DWrite_ParseFamily(): using font at index 2 with weight 400 for bold: 0, italic: 1
freetype debug: DWrite_ParseFamily(): using font at index 3 with weight 700 for bold: 1, italic: 1
main debug: using text renderer module "freetype"
main debug: looking for hw decoder module matching "any": 2 candidates
d3d11va debug: NVIDIA WDDM driver 27.21.14.6663
d3d11va debug: Reusing Direct3D11 device
d3d11va debug: CreateDevice succeed
d3d11va debug: - 'MPEG-2 & MPEG-1 variable-length decoder' is supported
d3d11va debug: - 'MPEG-2 variable-length decoder' is supported
d3d11va debug: - 'MPEG-1 variable-length decoder, no D pictures' is supported
d3d11va debug: - 'VC-1 variable-length decoder' is supported
d3d11va debug: - 'VC-1 variable-length decoder' is supported
d3d11va debug: - 'Unknown decoder 0x32fcfe3f-0xde46-0x4a49-0x861bac71110649d5' is supported
d3d11va debug: - 'H.264 stereo high profile, mbs flag set' is supported
d3d11va debug: - 'H.264 stereo high profile' is supported
d3d11va debug: - 'H.264 variable-length decoder, no film grain technology' is supported
d3d11va debug: - 'HEVC Main profile' is supported
d3d11va debug: - 'HEVC Main 10 profile' is supported
d3d11va debug: - 'Unknown decoder 0x20bb8b0a-0x97aa-0x4571-0x8e9964e60606c1a6' is supported
d3d11va debug: - 'Unknown decoder 0x15df9b21-0x06c4-0x47f1-0x841ea67c97d7f312' is supported
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple Profile' is supported
d3d11va debug: - 'MPEG-4 Part 2 variable-length decoder, Simple&Advanced Profile, no GMC' is supported
d3d11va debug: - 'MPEG-4 Part 2 nVidia bitstream decoder' is supported
d3d11va debug: - 'Unknown decoder 0x33fcfe41-0xde46-0x4a49-0x861bac71110649d5' is supported
d3d11va debug: - 'VP9 profile 0' is supported
d3d11va debug: - 'VP9 profile 2' is supported
d3d11va debug: - 'Unknown decoder 0xdda19dc7-0x93b5-0x49f5-0xa9b32bda28a2ce6e' is supported
d3d11va debug: - 'Unknown decoder 0x6affd11e-0x1d96-0x42b1-0xa21593a31f09a53d' is supported
d3d11va debug: - 'Unknown decoder 0x914c84a3-0x4078-0x4fa9-0x984ce2f262cb5c9c' is supported
d3d11va debug: - 'Unknown decoder 0x8a1a1031-0x29bc-0x46d0-0xa007e9b092ca6767' is supported
d3d11va debug: Trying to use 'H.264 variable-length decoder, no film grain technology' as input
d3d11va debug: NV12 output is supported for decoder H.264 variable-length decoder, no film grain technology.
d3d11va debug: Using output format NV12 for decoder H.264 variable-length decoder, no film grain technology
d3d11va debug: va_pool_SetupDecoder id 27 768x432 count: 24
d3d11va warning: not enough decoding slices in the texture (6/24)
qt debug: Logical video size: 768x432
main debug: resized to 768x432
main debug: VoutDisplayEvent 'resize' 768x432
d3d11va debug: ID3D11VideoDecoderOutputView succeed with 24 surfaces (768x432)
d3d11va debug: we got 3 decoder configurations
d3d11va debug: configuration[0] ConfigBitstreamRaw 1
d3d11va debug: configuration[1] ConfigBitstreamRaw 2
d3d11va debug: configuration[2] ConfigBitstreamRaw 2
d3d11va debug: DxCreateDecoderSurfaces succeed
main debug: using hw decoder module "d3d11va"
avcodec info: Using D3D11VA (NVIDIA GeForce RTX 2070 SUPER, vendor 10de(NVIDIA), device 1e84, revision a1) for hardware decoding
main debug: Buffering 41%
main debug: Buffering 48%
main debug: Buffering 55%
main debug: Buffering 61%
main debug: Received first picture
main debug: Buffering 68%
main debug: Buffering 75%
main debug: Buffering 81%
main debug: Buffering 88%
faad warning: decoded zero sample
main debug: Stream buffering done (1271 ms in 1470 ms)
main debug: reusing audio output
main debug: looking for aout stream module matching "any": 2 candidates
wasapi debug: modified format
wasapi debug: buffer size : 96000 frames
wasapi debug: maximum latency: 000 ns
wasapi debug: default period : 10000000 ns
wasapi debug: minimum period : 3000000 ns
main debug: using aout stream module "wasapi"
main debug: output 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio volume module matching "any": 2 candidates
main debug: using audio volume module "float_mixer"
main debug: input 'f32l' 16000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module matching "scaletempo": 16 candidates
scaletempo debug: format: 16000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 480.000 stride_in, 480 stride_out, 384 standing, 96 overlap, 224 search, 800 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: conversion: 'f32l'->'f32l' 16000 Hz->16000 Hz Stereo->Stereo
main debug: conversion pipeline complete
main debug: conversion: 'f32l'->'f32l' 16000 Hz->16000 Hz Stereo->Stereo
main debug: conversion pipeline complete
main debug: looking for audio resampler module matching "any": 3 candidates
main debug: using audio resampler module "samplerate"
main debug: Decoder wait done in 22 ms
main debug: inserting 73456 zeroes
mmdevice debug: state changed: 1
main warning: playback way too late (409084): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 59903 zeroes
mmdevice debug: state changed: 1
main warning: playback way too late (501980): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 41951 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-499020): playing silence
main debug: inserting 23952 zeroes
main warning: playback way too late (516959): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 49871 zeroes
mmdevice debug: state changed: 1
main warning: picture is too late to be displayed (missing 341 ms)
main warning: picture is too late to be displayed (missing 274 ms)
main warning: picture is too late to be displayed (missing 208 ms)
main warning: picture is too late to be displayed (missing 141 ms)
main warning: picture is too late to be displayed (missing 74 ms)
main debug: picture might be displayed late (missing 8 ms)
main warning: playback way too early (-217860): playing silence
main debug: inserting 10457 zeroes
main debug: auto hiding mouse cursor
main warning: playback way too late (446141): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36105 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-471448): playing silence
main debug: inserting 22629 zeroes
main warning: playback way too late (493907): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36744 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-476225): playing silence
main debug: inserting 22858 zeroes
main warning: picture is too late to be displayed (missing 198 ms)
main warning: picture is too late to be displayed (missing 131 ms)
main warning: picture is too late to be displayed (missing 64 ms)
main warning: playback way too late (500525): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 33850 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-507036): playing silence
main debug: inserting 24337 zeroes
main warning: playback way too early (-509098): playing silence
main debug: inserting 24436 zeroes
main warning: playback way too late (509235): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 58298 zeroes
mmdevice debug: state changed: 1
main warning: playback too late (64230): up-sampling
main debug: resampling stopped (drift: -6990 us)
main warning: playback way too late (492852): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 40563 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-433625): playing silence
main debug: inserting 20814 zeroes
main warning: picture is too late to be displayed (missing 484 ms)
main warning: picture is too late to be displayed (missing 418 ms)
main warning: picture is too late to be displayed (missing 351 ms)
main warning: picture is too late to be displayed (missing 214 ms)
main warning: picture is too late to be displayed (missing 148 ms)
main warning: picture is too late to be displayed (missing 81 ms)
main debug: picture might be displayed late (missing 14 ms)
main warning: playback way too late (518552): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36439 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-506254): playing silence
main debug: inserting 24300 zeroes
main warning: playback way too late (498851): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35184 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-459446): playing silence
main debug: inserting 22053 zeroes
main warning: playback way too late (500156): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 39421 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-476131): playing silence
main debug: inserting 22854 zeroes
main warning: playback way too late (499826): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 33749 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-489353): playing silence
main debug: inserting 23488 zeroes
main warning: playback way too late (460941): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset

TerryD · June 10, 2021, 12:50am

main debug: inserting 35459 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-457781): playing silence
main debug: inserting 21973 zeroes
main warning: playback way too late (500011): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37132 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-420849): playing silence
main debug: inserting 20200 zeroes
main warning: playback too early (-60824): down-sampling
main warning: playback way too early (-136355): playing silence
main debug: inserting 6545 zeroes
main warning: playback way too late (498812): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37999 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-436281): playing silence
main debug: inserting 20941 zeroes
main warning: playback way too late (500407): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35770 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-470652): playing silence
main debug: inserting 22591 zeroes
main warning: playback too early (-41898): down-sampling
main warning: picture is too late to be displayed (missing 357 ms)
main warning: picture is too late to be displayed (missing 290 ms)
main warning: picture is too late to be displayed (missing 224 ms)
main warning: picture is too late to be displayed (missing 157 ms)
main warning: picture is too late to be displayed (missing 90 ms)
main debug: picture might be displayed late (missing 24 ms)
main debug: picture might be displayed late (missing 4 ms)
main warning: playback way too late (456644): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 34880 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-441659): playing silence
main debug: inserting 21199 zeroes
main warning: playback way too late (506506): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37051 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-473242): playing silence
main debug: inserting 22715 zeroes
main warning: playback way too late (498633): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 34542 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-453565): playing silence
main debug: inserting 21771 zeroes
main warning: playback too early (-113060): down-sampling
main warning: playback way too early (-613005): playing silence
main debug: inserting 29424 zeroes
main warning: playback way too late (499732): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 63425 zeroes
mmdevice debug: state changed: 1
main warning: playback way too late (464130): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 41283 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-386662): playing silence
main debug: inserting 18559 zeroes
main warning: playback way too late (500359): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37087 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-505378): playing silence
main debug: inserting 24258 zeroes
main warning: playback way too late (501681): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 40700 zeroes
mmdevice debug: state changed: 1
main warning: picture is too late to be displayed (missing 381 ms)
main warning: picture is too late to be displayed (missing 314 ms)
main warning: picture is too late to be displayed (missing 247 ms)
main warning: picture is too late to be displayed (missing 181 ms)
main warning: picture is too late to be displayed (missing 114 ms)
main warning: picture is too late to be displayed (missing 47 ms)
main warning: picture is too late to be displayed (missing 74 ms)
main debug: picture might be displayed late (missing 8 ms)
main warning: playback way too early (-427512): playing silence
main debug: inserting 20520 zeroes
main warning: playback way too late (500592): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 32230 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-328847): playing silence
main debug: inserting 15784 zeroes
main warning: playback way too early (-296998): playing silence
main debug: inserting 14255 zeroes
main warning: playback way too late (499232): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 44308 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-379842): playing silence
main debug: inserting 18232 zeroes
main warning: playback way too late (500304): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 33040 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-458274): playing silence
main debug: inserting 21997 zeroes
main warning: playback way too late (498330): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37628 zeroes
mmdevice debug: state changed: 1
main warning: picture is too late to be displayed (missing 138 ms)
main warning: picture is too late to be displayed (missing 71 ms)
main debug: picture might be displayed late (missing 5 ms)
main warning: playback way too early (-494317): playing silence
main debug: inserting 23727 zeroes
main warning: picture is too late to be displayed (missing 36 ms)
main warning: playback way too late (500643): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36465 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-491248): playing silence
main debug: inserting 23579 zeroes
main warning: playback way too late (499482): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35748 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-346707): playing silence
main debug: inserting 16641 zeroes
main warning: playback too early (-104670): down-sampling
main warning: playback way too early (-135422): playing silence
main debug: inserting 6500 zeroes
main warning: playback too early (-41954): down-sampling
main warning: playback way too early (-541932): playing silence
main debug: inserting 26012 zeroes
main warning: playback way too late (498337): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 65255 zeroes
mmdevice debug: state changed: 1
main warning: playback too early (-81473): down-sampling
main warning: playback way too late (418319): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 40912 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-359805): playing silence
main debug: inserting 17270 zeroes
main warning: playback way too late (499882): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 39615 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-470014): playing silence
main debug: inserting 22560 zeroes
main warning: playback way too late (500215): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37012 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-474281): playing silence
main debug: inserting 22765 zeroes
main warning: playback way too late (499803): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36012 zeroes
mmdevice debug: state changed: 1
main warning: picture is too late to be displayed (missing 319 ms)
main warning: picture is too late to be displayed (missing 253 ms)
main warning: picture is too late to be displayed (missing 186 ms)
main warning: picture is too late to be displayed (missing 119 ms)
main warning: picture is too late to be displayed (missing 53 ms)
main warning: picture is too late to be displayed (missing 109 ms)
main warning: picture is too late to be displayed (missing 42 ms)
main warning: playback way too early (-449788): playing silence
main debug: inserting 21589 zeroes
main warning: playback too early (-42047): down-sampling
main warning: playback way too late (420918): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 40264 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-447806): playing silence
main debug: inserting 21494 zeroes
main warning: playback way too late (498236): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36790 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-476846): playing silence
main debug: inserting 22888 zeroes
main warning: playback way too late (500591): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37894 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-486572): playing silence
main debug: inserting 23355 zeroes
main warning: playback way too late (501095): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37285 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-492310): playing silence
main debug: inserting 23630 zeroes
main warning: playback way too late (499670): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37594 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-271825): playing silence
main debug: inserting 13047 zeroes
main warning: playback way too early (-500826): playing silence
main debug: inserting 24039 zeroes
main warning: playback way too late (498966): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 49690 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-282453): playing silence
main debug: inserting 13557 zeroes
main warning: playback way too late (499339): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 39805 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-441879): playing silence
main debug: inserting 21210 zeroes
main warning: playback way too late (534135): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 34834 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-499010): playing silence
main debug: inserting 23952 zeroes
main warning: picture is too late to be displayed (missing 133 ms)
main warning: picture is too late to be displayed (missing 66 ms)
main warning: playback way too late (555972): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35043 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-493481): playing silence
main debug: inserting 23687 zeroes
main warning: playback way too late (500602): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35218 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-196619): playing silence
main debug: inserting 9437 zeroes
main warning: playback way too early (-403783): playing silence
main debug: inserting 19381 zeroes
main warning: playback way too late (499967): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 40931 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-408663): playing silence
main debug: inserting 19615 zeroes
main warning: playback way too late (501919): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 36177 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-227606): playing silence
main debug: inserting 10925 zeroes
main debug: picture might be displayed late (missing 31 ms)
main warning: playback too early (-102636): down-sampling
main debug: picture might be displayed late (missing 17 ms)
main warning: playback way too early (-603157): playing silence
main debug: inserting 28951 zeroes
main warning: playback way too late (190771): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 69928 zeroes
mmdevice debug: state changed: 1
main warning: playback too late (111995): up-sampling
main warning: playback way too late (611370): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 40445 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-442584): playing silence
main debug: inserting 21244 zeroes
main warning: playback way too late (500206): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37670 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-498388): playing silence
main debug: inserting 23922 zeroes
main warning: playback way too late (499571): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 37569 zeroes
mmdevice debug: state changed: 1
main warning: picture is too late to be displayed (missing 125 ms)
main warning: picture is too late to be displayed (missing 59 ms)
main debug: picture might be displayed late (missing 29 ms)
main warning: playback way too early (-473957): playing silence
main debug: inserting 22749 zeroes
main warning: playback way too late (498816): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 34813 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-484304): playing silence
main debug: inserting 23246 zeroes
main warning: playback way too late (500884): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35256 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-461205): playing silence
main debug: inserting 22137 zeroes
main warning: playback way too late (499357): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 39281 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-478909): playing silence
main debug: inserting 22987 zeroes
main warning: playback way too late (495481): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 35044 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-169899): playing silence
main debug: inserting 8155 zeroes
main warning: playback way too early (-499920): playing silence
main debug: inserting 23996 zeroes
main warning: playback way too late (499663): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 41265 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-417389): playing silence
main debug: inserting 20034 zeroes
main warning: playback way too late (517423): flushing buffers
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: inserting 42915 zeroes
mmdevice debug: state changed: 1
main warning: playback way too early (-128794): playing silence
main debug: inserting 6182 zeroes
main debug: picture might be displayed late (missing 17 ms)
live555 warning: no data received in 10s, eof ?
main debug: EOF reached
main debug: waiting decoder fifos to empty
main warning: picture is too late to be displayed (missing 9381 ms)
main warning: picture is too late to be displayed (missing 9315 ms)
main warning: picture is too late to be displayed (missing 9249 ms)
main warning: picture is too late to be displayed (missing 9182 ms)
main warning: picture is too late to be displayed (missing 9115 ms)
main debug: killing decoder fourcc h264' main debug: removing module "avcodec" main debug: saving a free vout main debug: reusing provided vout main debug: removing module "h264" main debug: killing decoder fourcc mp4a'
main debug: removing module "faad"
mmdevice debug: state changed: 0
wasapi debug: reset
main debug: removing module "samplerate"
main debug: removing module "scaletempo"
main debug: removing module "float_mixer"
main debug: keeping audio output
main debug: removing module "live555"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: changing item without a request (current 0/1)
main debug: nothing to play
main debug: destroying useless vout
main debug: removing module "direct3d11"
direct3d11 debug: Direct3D11 resources destroyed
qt debug: IM: Deleting the input
direct3d11 debug: Direct3D11 device adapter closed
direct3d11 error: SetThumbNailClip failed: 0x800706f4
direct3d11 debug: Win32 Vout EventThread terminating
direct3d11 debug: Win32VoutCloseWindow
direct3d11 debug: WinProc WM_DESTROY
main debug: removing module "inhibit"
qt debug: releasing video...
qt debug: Video is not needed anymore
qt warning: orphaned video window
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"