Bug 740596 – uridecodebin with rtsp url does not work with version 1.x

After an evaluation, GNOME has moved from Bugzilla to GitLab. Learn more about GitLab.
No new issues can be reported in GNOME Bugzilla anymore.
To report an issue in a GNOME project, go to GNOME GitLab.
Do not go to GNOME Gitlab for: Bluefish, Doxygen, GnuCash, GStreamer, java-gnome, LDTP, NetworkManager, Tomboy.

Bug 740596 - uridecodebin with rtsp url does not work with version 1.x


Summary:	uridecodebin with rtsp url does not work with version 1.x


Status:	RESOLVED DUPLICATE of bug 635701

Product:	GStreamer
Classification:	Platform
Component:	gst-plugins-good
Version:	1.4.4
Hardware:	Other Windows

Importance:	Normal normal
Target Milestone:	git master
Assigned To:	GStreamer Maintainers
QA Contact:	GStreamer Maintainers

URL:
Whiteboard:

Depends on:
Blocks:

Reported:	2014-11-23 20:19 UTC by German Afanador
Modified:	2015-03-25 22:28 UTC

See Also:
GNOME target:	---
GNOME version:	---

Attachments
Wireshark captures (153.01 KB, application/octet-stream) 2014-11-23 20:19 UTC, German Afanador	Details
Debug output for not working attempt using gst-launch 1.4.4 (57.97 KB, text/plain) 2014-11-23 21:49 UTC, German Afanador	Details

Description German Afanador 2014-11-23 20:19:10 UTC

Created attachment 291325 [details]
Wireshark captures

Hi,

I have the following command using gst-launch-0.10 that works perfectly well when connecting to an ip camera:

gst-launch-0.10 uridecodebin uri="rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100" ! ffmpegcolorspace ! autovideosink


However when trying the following rtsp url using gst-launch-1.0 (of version 1.4.4) it doesn't work at all:

gst-launch-1.0 uridecodebin uri="rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100" ! videoconvert ! autovideosink

The ip address of the machine that is trying to connect to the camera is 192.168.1.153 and the ip address of the camera is 192.168.1.10.

I have also attached wireshark capture files for the working (gstreamer 0.10) and not working (gstreamer 1.4.4) attempts


Turning debugging on when running on version 1.0 I get the following output:

Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-
aching=100
Progress: (open) Retrieving server options
Progress: (open) Retrieving media info
Progress: (request) SETUP stream 0
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
New clock: GstSystemClock
0:00:00.175930403 Progress: (request) Sending PLAY request
 4892Progress: (request) Sending PLAY request
 0000000002FD7B80 WARN                  udpsrc gstudpsrc.c:573:gst_udpsrc_create:<udpsrc0> error: receive error -1: Err
r receiving message: An existing connection was forcibly closed by the remote host.
Progress: (request) Sent PLAY request
0:00:00.211708299 ERROR: from element /GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:
dpsrc0: Could not read from resource.
 4892Additional debug info:
gstudpsrc.c(573): gst_udpsrc_create (): /GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSr
:udpsrc0:
receive error -1: Error receiving message: An existing connection was forcibly closed by the remote host.
 0000000002FD7B80 Execution ended after 0:00:00.072867745
WARN    Setting pipeline to PAUSED ...
             basesrc gstbasesrc.c:2933:gst_base_src_loop:<udpsrc0>Setting pipeline to READY ...
 error: Internal data flow error.
0:00:00.269876638  4892 0000000002FD7B80 WARN                 basesrc gstbasesrc.c:2933:gst_base_src_loop:<udpsrc0> err
r: streaming task paused, reason error (-5)
Setting pipeline to NULL ...
0:00:00.287588503  4892 0000000003F39930 WARN            d3dvideosink d3dhelpers.c:1092:d3d_set_window_handle:<autovide
sink0-actual-sink-d3dvideo> Window HWND already set to: 0
Freeing pipeline ...

C:\gstreamer\1.0\x86_64\bin>gst-launch-1.0 -v uridecodebin uri="rtsp://192.168.1.10:554/user=admin&password=&channel=1&
tream=1.sdp?real_stream--rtp-caching=100" ! videoconvert ! autovideosink
Setting pipeline to PAUSED ...
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0: source = "\(GstRTSPSrc\)\ source"
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-
aching=100
Progress: (open) Retrieving server options
Progress: (open) Retrieving media info
Progress: (request) SETUP stream 0
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager: latency = 2000
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager: ntp-sync = false
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager: use-pipeline-clock = false
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager: drop-on-latency = false
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager: buffer-mode = Slave receiver
o sender clock
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1: timeout = 5000000000
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1: caps = "application/x-rtp\,\
edia\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\ profile-lev
l-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parameter-sets\=\(string\)\"Z00AFJWoWCWhAAADAAEAAAMA
oQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)7116"
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc2: caps = application/x-rtcp
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager: buffer-mode = Slave receiver
o sender clock
New clock: GstSystemClock
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1.GstPad:src: caps = "applicatio
/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\
rofile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parameter-sets\=\(string\)\"Z00AFJWoWCWhA
ADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)7116"
Progress: (request) Sending PLAY request
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1.GstPad:src: caps = "applicatio
/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\
rofile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parameter-sets\=\(string\)\"Z00AFJWoWCWhA
ADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)7116"
Progress: (request) Sending PLAY request
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager.GstGhostPad:recv_rtp_sink_0.Gs
ProxyPad:proxypad1: caps = "application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)900
0\,\ encoding-name\=\(string\)H264\,\ profile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-pa
ameter-sets\=\(string\)\"Z00AFJWoWCWhAAADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(ui
t\)7116"
Progress: (request) Sent PLAY request
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager/GstRtpSession:rtpsession0.GstP
d:recv_rtp_src: caps = "application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,
 encoding-name\=\(string\)H264\,\ profile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parame
er-sets\=\(string\)\"Z00AFJWoWCWhAAADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)
116"
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager/GstRtpSsrcDemux:rtpssrcdemux0.
stPad:sink: caps = "application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ en
oding-name\=\(string\)H264\,\ profile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parameter-
ets\=\(string\)\"Z00AFJWoWCWhAAADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)7116

/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager/GstRtpSession:rtpsession0.GstP
d:recv_rtp_sink: caps = "application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\
\ encoding-name\=\(string\)H264\,\ profile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-param
ter-sets\=\(string\)\"Z00AFJWoWCWhAAADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\
7116"
/GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstRtpBin:manager.GstGhostPad:recv_rtp_sink_0: c
ps = "application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\
string\)H264\,\ profile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parameter-sets\=\(string
)\"Z00AFJWoWCWhAAADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)7116"
0:00:00.860288917  5568 0000000002F6DB80 WARN                  udpsrc gstudpsrc.c:573:gst_udpsrc_create:<udpsrc1> error
 receive error -1: Error receiving message: An existing connection was forcibly closed by the remote host.
ERROR: from element /GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1: Could not
read from resource.
0:00:00.883191102 Additional debug info:
gstudpsrc.c(573): gst_udpsrc_create (): /GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSr
:udpsrc1:
receive error -1: Error receiving message: An existing connection was forcibly closed by the remote host.
 5568Execution ended after 0:00:00.520574959
 0000000002F6DB80 Setting pipeline to PAUSED ...
WARN    Setting pipeline to READY ...
             basesrc gstbasesrc.c:2933:gst_base_src_loop:<udpsrc1> error: Internal data flow error.
0:00:00.944349316  5568 0000000002F6D2C0 WARN                 rtspsrc gstrtspsrc.c:5482:gst_rtspsrc_try_send:<source> r
ceive interrupted
0:00:00.954447137  5568 0000000002F6DB80 WARN                 basesrc gstbasesrc.c:2933:gst_base_src_loop:<udpsrc1> err
r: streaming task paused, reason error (-5)
0:00:00.961791194  5568 0000000002F6D2C0 WARN                 rtspsrc gstrtspsrc.c:7596:gst_rtspsrc_pause:<source> PAUS
 interrupted
Setting pipeline to NULL ...
0:00:00.988704689  5568 0000000003EB97B0 WARN            d3dvideosink d3dhelpers.c:1092:d3d_set_window_handle:<autovide
sink0-actual-sink-d3dvideo> Window HWND already set to: 0
Freeing pipeline ...

Comment 1 Tim-Philipp Müller 2014-11-23 20:40:09 UTC

Could you provide a GST_DEBUG=*src:6,rtsp*:6 log for the failing 1.x case please?

Am I right in assuming this is on Windows? Have you tried with a Linux machine?

Comment 2 German Afanador 2014-11-23 20:56:40 UTC

(In reply to comment #1)
> Could you provide a GST_DEBUG=*src:6,rtsp*:6 log for the failing 1.x case
> please?
> 
> Am I right in assuming this is on Windows? Have you tried with a Linux machine?

I'm running on Windows. I have not tried in a Linux machine.

Here is the output:

0:00:00.260083193  3176 00000000040C9630 DEBUG                rtspext gstrtspext.c:102:gst_rtsp_ext_list_get: added extension interface for 'rtspwms0'
0:00:00.264135051  3176 00000000040C9630 DEBUG                rtspext gstrtspext.c:102:gst_rtsp_ext_list_get: added extension interface for 'rtspreal0'
0:00:00.264194079  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7954:gst_rtspsrc_uri_set_uri:<source> parsing URI
0:00:00.264222822  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7961:gst_rtspsrc_uri_set_uri:<source> configuring URI
0:00:00.264248487  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7977:gst_rtspsrc_uri_set_uri:<source> set uri: rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100
0:00:00.264270044  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7979:gst_rtspsrc_uri_set_uri:<source> request uri is: rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100
0:00:00.264369108  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7741:gst_rtspsrc_start:<source> starting
0:00:00.264424029  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 1
0:00:00.264448154  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5017:gst_rtspsrc_loop_send_cmd:<source> not interrupting busy cmd 0
0:00:00.267311761  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7696:gst_rtspsrc_thread:<source> got command 1
0:00:00.267347178  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 0
0:00:00.267367196  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4135:gst_rtsp_conninfo_connect:<source> creating connection (rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100)...
0:00:00.269057437  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4143:gst_rtsp_conninfo_connect:<source> sanitized uri rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100
0:00:00.269107739  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4171:gst_rtsp_conninfo_connect:<source> connecting (rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp?real_stream--rtp-caching=100)...
0:00:00.279765553  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6767:gst_rtspsrc_retrieve_sdp:<source> create options...
0:00:00.279880015  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6775:gst_rtspsrc_retrieve_sdp:<source> send options...
0:00:00.279926724  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:45:gst_rtsp_wms_before_send:<rtspwms0> before send
0:00:00.279948282  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5381:gst_rtspsrc_try_send:<source> sending message
0:00:00.297555437  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5410:gst_rtspsrc_try_send:<source> received response message
0:00:00.297593933  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5425:gst_rtspsrc_try_send:<source> got response message 200
0:00:00.297615491  3176 000000000337A2C0 DEBUG               rtspreal rtspreal.c:191:rtsp_ext_real_after_send:<rtspreal0> Could not find challenge tag.
0:00:00.297634996  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:66:gst_rtsp_wms_after_send:<rtspwms0> after send
0:00:00.297658094  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6790:gst_rtspsrc_retrieve_sdp:<source> create describe...
0:00:00.297679138  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6802:gst_rtspsrc_retrieve_sdp:<source> send describe...
0:00:00.297708909  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:45:gst_rtsp_wms_before_send:<rtspwms0> before send
0:00:00.297726874  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5381:gst_rtspsrc_try_send:<source> sending message
0:00:00.339251489  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5410:gst_rtspsrc_try_send:<source> received response message
0:00:00.339299738  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5425:gst_rtspsrc_try_send:<source> got response message 200
0:00:00.339324889  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:66:gst_rtsp_wms_after_send:<rtspwms0> after send
0:00:00.339344907  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6844:gst_rtspsrc_retrieve_sdp:<source> parse SDP...
0:00:00.339399828  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6508:gst_rtspsrc_parse_range:<source> parsed range npt=0-
0:00:00.339420873  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6519:gst_rtspsrc_parse_range:<source> range: type 0, min 0.000000 - type 2,  max 0.000000 
0:00:00.339452183  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6529:gst_rtspsrc_parse_range:<source> range: min 0:00:00.000000000
0:00:00.339472714  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6544:gst_rtspsrc_parse_range:<source> range: max 99:99:99.999999999
0:00:00.339995237  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1360:gst_rtspsrc_collect_payloads:<source>  looking at 0 pt: 96
0:00:00.340044512  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1376:gst_rtspsrc_collect_payloads: mapping sdp session level attributes to caps
0:00:00.340066583  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1378:gst_rtspsrc_collect_payloads: mapping sdp media level attributes to caps
0:00:00.340087114  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1892:gst_rtspsrc_sdp_attributes_to_caps: adding caps: a-framerate=0S
0:00:00.340106619  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1892:gst_rtspsrc_sdp_attributes_to_caps: adding caps: a-framerate=25
0:00:00.340127664  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1477:gst_rtspsrc_create_stream:<source> stream 0, (00000000033B66D0)
0:00:00.340146142  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1478:gst_rtspsrc_create_stream:<source>  port: 0
0:00:00.340163080  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1479:gst_rtspsrc_create_stream:<source>  container: 0
0:00:00.340181045  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1480:gst_rtspsrc_create_stream:<source>  control: trackID=3
0:00:00.340208763  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1510:gst_rtspsrc_create_stream:<source>  setup: rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp/trackID=3
0:00:00.340231860  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:144:gst_rtsp_wms_configure_stream:<rtspwms0> application/x-unknown, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, profile-level-id=(string)4d0014, packetization-mode=(string)1, sprop-parameter-sets=(string)"Z00AFJWoWCWhAAADAAEAAAMAMoQ\=\,aO48gA\=\=", a-framerate=(string)25 encoding-name: H264
0:00:00.340274976  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:342:default_select_stream:<source> default handler
0:00:00.340293454  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:353:select_stream_accum: accum 1
0:00:00.340311932  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:342:default_select_stream:<source> default handler
0:00:00.340330924  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6188:gst_rtspsrc_setup_streams:<source> doing setup of stream 00000000033B66D0 with rtsp://192.168.1.10:554/user=admin&password=&channel=1&stream=1.sdp/trackID=3
0:00:00.340351455  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6203:gst_rtspsrc_setup_streams:<source> protocols = 0x7, protocol mask = 0x1
0:00:00.340370960  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5750:gst_rtspsrc_create_transports_string:<source> got transports (NULL)
0:00:00.340398164  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5780:gst_rtspsrc_create_transports_string:<source> adding UDP unicast
0:00:00.340416642  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5807:gst_rtspsrc_create_transports_string:<source> prepared transports RTP/AVP;unicast;client_port=%%u1-%%u2
0:00:00.340435120  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6218:gst_rtspsrc_setup_streams:<source> replace ports in RTP/AVP;unicast;client_port=%%u1-%%u2
0:00:00.344496731  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:445:gst_base_src_init:<GstBaseSrc@00000000040D8390> creating src pad
0:00:00.344585529  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:448:gst_base_src_init:<GstBaseSrc@00000000040D8390> setting functions on src pad
0:00:00.344608113  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:456:gst_base_src_init:<GstBaseSrc@00000000040D8390> adding src pad
0:00:00.344629158  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:473:gst_base_src_init:<GstBaseSrc@00000000040D8390> init done
0:00:00.344671247  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:830:gst_udpsrc_open:<udpsrc0> allocating socket for 0.0.0.0:0
0:00:00.344705124  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:803:gst_udpsrc_resolve:<udpsrc0> IP address for host 0.0.0.0 is 0.0.0.0
0:00:00.344770824  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:843:gst_udpsrc_open:<udpsrc0> got socket 00000000034E3300
0:00:00.344798541  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:850:gst_udpsrc_open:<udpsrc0> binding on port 0
0:00:00.344837038  3176 000000000337A2C0 INFO                  udpsrc gstudpsrc.c:898:gst_udpsrc_open:<udpsrc0> setting udp buffer of 524288 bytes
0:00:00.344859109  3176 000000000337A2C0 INFO                  udpsrc gstudpsrc.c:918:gst_udpsrc_open:<udpsrc0> have udp buffer of 524288 bytes
0:00:00.344901198  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:991:gst_udpsrc_open:<udpsrc0> bound, on port 51389
0:00:00.344920190  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:994:gst_udpsrc_open:<udpsrc0> notifying port 51389
0:00:00.344947394  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2124:gst_rtspsrc_alloc_udp_ports:<source> got RTP port 51389
0:00:00.344965872  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2132:gst_rtspsrc_alloc_udp_ports:<source> RTP port not even
0:00:00.344982810  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2134:gst_rtspsrc_alloc_udp_ports:<source> free RTP udpsrc
0:00:00.345004368  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:1071:gst_udpsrc_close: closing sockets
0:00:00.345057236  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2139:gst_rtspsrc_alloc_udp_ports:<source> retry 1
0:00:00.345147061  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:445:gst_base_src_init:<GstBaseSrc@00000000040D8390> creating src pad
0:00:00.345174778  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:448:gst_base_src_init:<GstBaseSrc@00000000040D8390> setting functions on src pad
0:00:00.345194796  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:456:gst_base_src_init:<GstBaseSrc@00000000040D8390> adding src pad
0:00:00.345688575  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:473:gst_base_src_init:<GstBaseSrc@00000000040D8390> init done
0:00:00.345731690  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:830:gst_udpsrc_open:<udpsrc1> allocating socket for 0.0.0.0:51390
0:00:00.345765567  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:803:gst_udpsrc_resolve:<udpsrc1> IP address for host 0.0.0.0 is 0.0.0.0
0:00:00.345821515  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:843:gst_udpsrc_open:<udpsrc1> got socket 00000000034E3480
0:00:00.345849232  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:850:gst_udpsrc_open:<udpsrc1> binding on port 51390
0:00:00.345895428  3176 000000000337A2C0 INFO                  udpsrc gstudpsrc.c:898:gst_udpsrc_open:<udpsrc1> setting udp buffer of 524288 bytes
0:00:00.345919039  3176 000000000337A2C0 INFO                  udpsrc gstudpsrc.c:918:gst_udpsrc_open:<udpsrc1> have udp buffer of 524288 bytes
0:00:00.345951889  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:991:gst_udpsrc_open:<udpsrc1> bound, on port 51390
0:00:00.345979606  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2124:gst_rtspsrc_alloc_udp_ports:<source> got RTP port 51390
0:00:00.346078157  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:445:gst_base_src_init:<GstBaseSrc@00000000040D8790> creating src pad
0:00:00.346108440  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:448:gst_base_src_init:<GstBaseSrc@00000000040D8790> setting functions on src pad
0:00:00.346129485  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:456:gst_base_src_init:<GstBaseSrc@00000000040D8790> adding src pad
0:00:00.346151043  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:473:gst_base_src_init:<GstBaseSrc@00000000040D8790> init done
0:00:00.346192619  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2156:gst_rtspsrc_alloc_udp_ports:<source> starting RTCP on port 51391
0:00:00.346214690  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:830:gst_udpsrc_open:<udpsrc2> allocating socket for 0.0.0.0:51391
0:00:00.346243947  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:803:gst_udpsrc_resolve:<udpsrc2> IP address for host 0.0.0.0 is 0.0.0.0
0:00:00.346293736  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:843:gst_udpsrc_open:<udpsrc2> got socket 00000000034E3600
0:00:00.346320940  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:850:gst_udpsrc_open:<udpsrc2> binding on port 51391
0:00:00.346352763  3176 000000000337A2C0 INFO                  udpsrc gstudpsrc.c:918:gst_udpsrc_open:<udpsrc2> have udp buffer of 65536 bytes
0:00:00.346478518  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:991:gst_udpsrc_open:<udpsrc2> bound, on port 51391
0:00:00.346512394  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6229:gst_rtspsrc_setup_streams:<source> transport is now RTP/AVP;unicast;client_port=51390-51391
0:00:00.346549864  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:45:gst_rtsp_wms_before_send:<rtspwms0> before send
0:00:00.346571422  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5381:gst_rtspsrc_try_send:<source> sending message
0:00:00.361338581  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5410:gst_rtspsrc_try_send:<source> received response message
0:00:00.361461256  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5425:gst_rtspsrc_try_send:<source> got response message 200
0:00:00.361499239  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:66:gst_rtsp_wms_after_send:<rtspwms0> after send
0:00:00.361550054  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6347:gst_rtspsrc_setup_streams:<source> stream 00000000033B66D0 as UDP unicast
0:00:00.361581877  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3799:gst_rtspsrc_stream_configure_transport:<source> configuring transport for stream 00000000033B66D0
0:00:00.361611135  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3808:gst_rtspsrc_stream_configure_transport:<source> setting media type to application/x-rtp
0:00:00.361647064  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3118:gst_rtspsrc_stream_configure_manager:<source> using manager rtpbin
0:00:00.369908358  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2982:set_manager_buffer_mode:<source> auto buffering mode, have clock (NULL)
0:00:00.369954041  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3002:set_manager_buffer_mode:<source> auto buffering mode
0:00:00.370055157  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3007:set_manager_buffer_mode:<source> selected slave
0:00:00.370081335  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3185:gst_rtspsrc_stream_configure_manager:<source> connect to signals on session manager, stream 00000000033B66D0
0:00:00.370798392  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3022:request_rtp_decoder: decoder session 0, stream 00000000033B66D0, 0
0:00:00.370895915  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2782:new_manager_pad:<source> got new manager pad <manager:recv_rtp_sink_0>
0:00:00.370925172  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2843:new_manager_pad:<source> ignoring unknown stream
0:00:00.371002678  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3022:request_rtp_decoder: decoder session 0, stream 00000000033B66D0, 0
0:00:00.371065812  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2782:new_manager_pad:<source> got new manager pad <manager:recv_rtcp_sink_0>
0:00:00.371096609  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2843:new_manager_pad:<source> ignoring unknown stream
0:00:00.371122273  3176 000000000337A2C0 INFO                 rtspsrc gstrtspsrc.c:3223:gst_rtspsrc_stream_configure_manager:<source> configure bandwidth in session 00000000040DE7F0
0:00:00.371158716  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3553:gst_rtspsrc_stream_configure_udp:<source> setting up UDP source
0:00:00.371195159  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3579:gst_rtspsrc_stream_configure_udp:<source> connecting UDP source 0 to manager
0:00:00.371245975  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3610:gst_rtspsrc_stream_configure_udp:<source> connecting UDP source 1 to manager
0:00:00.371273692  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3654:gst_rtspsrc_stream_configure_udp_sinks:<source> configure RTP UDP sink for 192.168.1.10:40000
0:00:00.376672918  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3674:gst_rtspsrc_stream_configure_udp_sinks:<source> RTP UDP src has sock 00000000034E3480
0:00:00.376798160  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:445:gst_base_src_init:<GstBaseSrc@00000000034EE150> creating src pad
0:00:00.376830496  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:448:gst_base_src_init:<GstBaseSrc@00000000034EE150> setting functions on src pad
0:00:00.376851541  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:456:gst_base_src_init:<GstBaseSrc@00000000034EE150> adding src pad
0:00:00.376872072  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:473:gst_base_src_init:<GstBaseSrc@00000000034EE150> init done
0:00:00.376933666  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3708:gst_rtspsrc_stream_configure_udp_sinks:<source> configure RTCP UDP sink for 192.168.1.10:40001
0:00:00.377280133  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3729:gst_rtspsrc_stream_configure_udp_sinks:<source> RTCP UDP src has sock 00000000034E3600
0:00:00.377380223  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3050:request_rtcp_encoder: decoder session 0, stream 00000000033B66D0, 0
0:00:00.377440790  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2782:new_manager_pad:<source> got new manager pad <manager:send_rtcp_src_0>
0:00:00.377465428  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:2843:new_manager_pad:<source> ignoring unknown stream
0:00:00.377509570  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7696:gst_rtspsrc_thread:<source> got command 64
0:00:00.377531128  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 0
0:00:00.377550119  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4677:gst_rtspsrc_loop_udp:<source> doing receive with timeout 54 seconds
0:00:00.386106038  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:2982:set_manager_buffer_mode:<source> auto buffering mode, have clock (NULL)
0:00:00.386148127  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:3002:set_manager_buffer_mode:<source> auto buffering mode
0:00:00.386167632  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:3007:set_manager_buffer_mode:<source> selected slave
0:00:00.386205102  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 16
0:00:00.386225633  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5006:gst_rtspsrc_loop_send_cmd:<source> cancel previous request 64
0:00:00.386244111  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5013:gst_rtspsrc_loop_send_cmd:<source> connection flush busy 64
0:00:00.386262590  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 1
0:00:00.386280041  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4244:gst_rtspsrc_connection_flush:<source> connection flush
0:00:00.386345741  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4764:gst_rtspsrc_loop_udp:<source> got interrupted
0:00:00.386371919  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5055:gst_rtspsrc_loop:<source> pausing task, reason flushing
0:00:00.386390910  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 16
0:00:00.386412468  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5013:gst_rtspsrc_loop_send_cmd:<source> connection flush busy 64
0:00:00.386431460  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 1
0:00:00.386475602  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 2
0:00:00.386495107  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5017:gst_rtspsrc_loop_send_cmd:<source> not interrupting busy cmd 16
0:00:00.386515638  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7696:gst_rtspsrc_thread:<source> got command 2
0:00:00.386534630  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 0
0:00:00.386552081  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4244:gst_rtspsrc_connection_flush:<source> connection flush
0:00:00.386569533  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7277:gst_rtspsrc_play:<source> PLAY...
0:00:00.386588524  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3906:gst_rtspsrc_send_dummy_packets:<source> sending dummy packet to stream 00000000033B66D0
0:00:00.386651658  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3757:gst_base_src_activate_mode:<fakesrc0:src> activating in mode 1
0:00:00.386672703  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3683:gst_base_src_activate_push:<fakesrc0> Activating in push mode
0:00:00.386692208  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3364:gst_base_src_start_complete:<fakesrc0> starting source
0:00:00.386709659  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3377:gst_base_src_start_complete:<fakesrc0> setting size 18446744073709551615
0:00:00.386729677  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3388:gst_base_src_start_complete:<fakesrc0> format: bytes, have size: 0, size: 18446744073709551615, duration: -1
0:00:00.386751748  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3391:gst_base_src_start_complete:<fakesrc0> is seekable: 1
0:00:00.386769200  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3396:gst_base_src_start_complete:<fakesrc0> is random_access: 1
0:00:00.386786652  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3559:gst_base_src_set_flushing:<fakesrc0> flushing 0, live_play 0
0:00:00.386811803  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1565:gst_base_src_perform_seek:<fakesrc0> doing seek: (NULL)
0:00:00.386831821  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1621:gst_base_src_perform_seek:<fakesrc0> seek with seqnum 68
0:00:00.386849786  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1658:gst_base_src_perform_seek:<fakesrc0> segment configured from 0 to -1, position 0
0:00:00.386868264  3176 000000000337A2C0 INFO                 basesrc gstbasesrc.c:1328:gst_base_src_do_seek:<fakesrc0> seeking: bytes segment start=0, offset=0, stop=-1, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0, base=0, position 0, duration -1
0:00:00.386965274  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3505:gst_base_src_start_wait:<fakesrc0> got ok
0:00:00.387026355  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3792:gst_base_src_change_state:<fakesrc0> PAUSED->PLAYING
0:00:00.387117206  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3757:gst_base_src_activate_mode:<udpsrc1:src> activating in mode 1
0:00:00.387142357  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3683:gst_base_src_activate_push:<udpsrc1> Activating in push mode
0:00:00.387160835  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3364:gst_base_src_start_complete:<udpsrc1> starting source
0:00:00.387178287  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3388:gst_base_src_start_complete:<udpsrc1> format: time, have size: 0, size: 18446744073709551615, duration: -1
0:00:00.387233208  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3391:gst_base_src_start_complete:<udpsrc1> is seekable: 0
0:00:00.387252200  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3396:gst_base_src_start_complete:<udpsrc1> is random_access: 0
0:00:00.387269651  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3559:gst_base_src_set_flushing:<udpsrc1> flushing 0, live_play 0
0:00:00.387289156  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1565:gst_base_src_perform_seek:<udpsrc1> doing seek: (NULL)
0:00:00.387307634  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1621:gst_base_src_perform_seek:<udpsrc1> seek with seqnum 76
0:00:00.387325086  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1658:gst_base_src_perform_seek:<udpsrc1> segment configured from 0 to -1, position 0
0:00:00.387344077  3176 000000000337A2C0 INFO                 basesrc gstbasesrc.c:1328:gst_base_src_do_seek:<udpsrc1> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.387433389  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3505:gst_base_src_start_wait:<udpsrc1> got ok
0:00:00.387465212  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3792:gst_base_src_change_state:<udpsrc1> PAUSED->PLAYING
0:00:00.387483690  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3638:gst_base_src_set_playing:<udpsrc1> unschedule clock
0:00:00.387502169  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3645:gst_base_src_set_playing:<udpsrc1> live running 1
0:00:00.387519620  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3652:gst_base_src_set_playing:<udpsrc1> unlock stop
0:00:00.387537585  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1062:gst_udpsrc_unlock_stop:<udpsrc1> No longer flushing
0:00:00.387556063  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3666:gst_base_src_set_playing:<udpsrc1> signal
0:00:00.387581214  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3757:gst_base_src_activate_mode:<udpsrc2:src> activating in mode 1
0:00:00.387604312  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3683:gst_base_src_activate_push:<udpsrc2> Activating in push mode
0:00:00.387623303  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3364:gst_base_src_start_complete:<udpsrc2> starting source
0:00:00.387649994  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3388:gst_base_src_start_complete:<udpsrc2> format: time, have size: 0, size: 18446744073709551615, duration: -1
0:00:00.387668986  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3391:gst_base_src_start_complete:<udpsrc2> is seekable: 0
0:00:00.387685924  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3396:gst_base_src_start_complete:<udpsrc2> is random_access: 0
0:00:00.387702862  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3559:gst_base_src_set_flushing:<udpsrc2> flushing 0, live_play 0
0:00:00.387720314  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1565:gst_base_src_perform_seek:<udpsrc2> doing seek: (NULL)
0:00:00.387738792  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1621:gst_base_src_perform_seek:<udpsrc2> seek with seqnum 81
0:00:00.387756244  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:1658:gst_base_src_perform_seek:<udpsrc2> segment configured from 0 to -1, position 0
0:00:00.387774209  3176 000000000337A2C0 INFO                 basesrc gstbasesrc.c:1328:gst_base_src_do_seek:<udpsrc2> seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:00.387839396  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3505:gst_base_src_start_wait:<udpsrc2> got ok
0:00:00.387867626  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3792:gst_base_src_change_state:<udpsrc2> PAUSED->PLAYING
0:00:00.387886104  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3638:gst_base_src_set_playing:<udpsrc2> unschedule clock
0:00:00.387902530  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3645:gst_base_src_set_playing:<udpsrc2> live running 1
0:00:00.387920494  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3652:gst_base_src_set_playing:<udpsrc2> unlock stop
0:00:00.387936919  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1062:gst_udpsrc_unlock_stop:<udpsrc2> No longer flushing
0:00:00.387954371  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3666:gst_base_src_set_playing:<udpsrc2> signal
0:00:00.387989788  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:45:gst_rtsp_wms_before_send:<rtspwms0> before send
0:00:00.388007753  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5381:gst_rtspsrc_try_send:<source> sending message
0:00:00.388632932  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:1280:gst_base_src_default_query:<fakesrc0> query uri returns 0
0:00:00.388852104  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:1280:gst_base_src_default_query:<udpsrc1> query uri returns 1
0:00:00.389080001  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:883:gst_base_src_send_stream_start:<udpsrc1> Pushing STREAM_START
0:00:00.389117984  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3253:gst_base_src_negotiate:<udpsrc1> starting negotiation
0:00:00.389142622  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:1280:gst_base_src_default_query:<udpsrc1> query caps returns 1
0:00:00.389162127  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3183:gst_base_src_default_negotiate:<udpsrc1> caps of src: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, profile-level-id=(string)4d0014, packetization-mode=(string)1, sprop-parameter-sets=(string)"Z00AFJWoWCWhAAADAAEAAAMAMoQ\=\,aO48gA\=\=", a-framerate=(string)25, ssrc=(uint)7116
0:00:00.389274536  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3193:gst_base_src_default_negotiate:<udpsrc1> caps of peer: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, profile-level-id=(string)4d0014, packetization-mode=(string)1, sprop-parameter-sets=(string)"Z00AFJWoWCWhAAADAAEAAAMAMoQ\=\,aO48gA\=\=", a-framerate=(string)25, ssrc=(uint)7116
0:00:00.389309952  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3204:gst_base_src_default_negotiate:<udpsrc1> have caps: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, profile-level-id=(string)4d0014, packetization-mode=(string)1, sprop-parameter-sets=(string)"Z00AFJWoWCWhAAADAAEAAAMAMoQ\=\,aO48gA\=\=", a-framerate=(string)25, ssrc=(uint)7116
0:00:00.389337156  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:953:gst_base_src_default_fixate:<udpsrc1> using default caps fixate function
0:00:00.389356148  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3212:gst_base_src_default_negotiate:<udpsrc1> fixated to: application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, profile-level-id=(string)4d0014, packetization-mode=(string)1, sprop-parameter-sets=(string)"Z00AFJWoWCWhAAADAAEAAAMAMoQ\=\,aO48gA\=\=", a-framerate=(string)25, ssrc=(uint)7116
0:00:00.389460857  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3123:gst_base_src_prepare_allocation:<udpsrc1> peer ALLOCATION query failed
0:00:00.389501920  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:3130:gst_base_src_prepare_allocation:<udpsrc1> ALLOCATION (1) params: allocation query: 00000000034E0DC0, GstQueryAllocation, caps=(GstCaps)"application/x-rtp\,\ media\=\(string\)video\,\ payload\=\(int\)96\,\ clock-rate\=\(int\)90000\,\ encoding-name\=\(string\)H264\,\ profile-level-id\=\(string\)4d0014\,\ packetization-mode\=\(string\)1\,\ sprop-parameter-sets\=\(string\)\"Z00AFJWoWCWhAAADAAEAAAMAMoQ\\\=\\\,aO48gA\\\=\\\=\"\,\ a-framerate\=\(string\)25\,\ ssrc\=\(uint\)7116", need-pool=(boolean)true, allocator=(GArray)NULL, pool=(GArray)NULL;
0:00:00.389550682  3176 000000000337AB80 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<udpsrc1> next_ts 99:99:99.999999999 size 4096
0:00:00.389571727  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<udpsrc1> calling create offset 18446744073709551615 length 4096, time 0
0:00:00.389638453  3176 000000000337AB80 LOG                   udpsrc gstudpsrc.c:425:gst_udpsrc_create:<udpsrc1> doing select, timeout 5000000
0:00:00.388958353  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:1280:gst_base_src_default_query:<udpsrc2> query uri returns 1
0:00:00.389699534  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:883:gst_base_src_send_stream_start:<udpsrc2> Pushing STREAM_START
0:00:00.389732384  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3253:gst_base_src_negotiate:<udpsrc2> starting negotiation
0:00:00.389752402  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:1280:gst_base_src_default_query:<udpsrc2> query caps returns 1
0:00:00.389771907  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3183:gst_base_src_default_negotiate:<udpsrc2> caps of src: application/x-rtcp
0:00:00.389799111  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3193:gst_base_src_default_negotiate:<udpsrc2> caps of peer: application/x-rtcp
0:00:00.389817589  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3204:gst_base_src_default_negotiate:<udpsrc2> have caps: application/x-rtcp
0:00:00.389835041  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:953:gst_base_src_default_fixate:<udpsrc2> using default caps fixate function
0:00:00.389853519  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3212:gst_base_src_default_negotiate:<udpsrc2> fixated to: application/x-rtcp
0:00:00.389915626  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3123:gst_base_src_prepare_allocation:<udpsrc2> peer ALLOCATION query failed
0:00:00.389936158  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:3130:gst_base_src_prepare_allocation:<udpsrc2> ALLOCATION (1) params: allocation query: 00000000040F5160, GstQueryAllocation, caps=(GstCaps)application/x-rtcp, need-pool=(boolean)true, allocator=(GArray)NULL, pool=(GArray)NULL;
0:00:00.389966955  3176 000000000337ABC0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<udpsrc2> next_ts 99:99:99.999999999 size 4096
0:00:00.389986460  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<udpsrc2> calling create offset 18446744073709551615 length 4096, time 0
0:00:00.390013664  3176 000000000337ABC0 LOG                   udpsrc gstudpsrc.c:425:gst_udpsrc_create:<udpsrc2> doing select, timeout -1
0:00:00.390043947  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:883:gst_base_src_send_stream_start:<fakesrc0> Pushing STREAM_START
0:00:00.390076797  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:3253:gst_base_src_negotiate:<fakesrc0> starting negotiation
0:00:00.390096815  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:1280:gst_base_src_default_query:<fakesrc0> query caps returns 1
0:00:00.390115294  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:3183:gst_base_src_default_negotiate:<fakesrc0> caps of src: ANY
0:00:00.390133259  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:3229:gst_base_src_default_negotiate:<fakesrc0> no negotiation needed
0:00:00.390152763  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:3123:gst_base_src_prepare_allocation:<fakesrc0> peer ALLOCATION query failed
0:00:00.390170728  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:3130:gst_base_src_prepare_allocation:<fakesrc0> ALLOCATION (1) params: allocation query: 00000000034E0A00, GstQueryAllocation, caps=(GstCaps)"NULL", need-pool=(boolean)true, allocator=(GArray)NULL, pool=(GArray)NULL;
0:00:00.390210251  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<fakesrc0> next_ts 0:00:00.000000000 size 4096
0:00:00.390230269  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2341:gst_base_src_update_length:<fakesrc0> reading offset 0, length 4096, size -1, segment.stop -1, maxsize -1
0:00:00.390250800  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<fakesrc0> calling create offset 0 length 4096, time 0
0:00:00.390286217  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2486:gst_base_src_get_range:<fakesrc0> setting first timestamp to 0
0:00:00.390305722  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2262:gst_base_src_do_sync:<fakesrc0> created PTS 0:00:00.000000000
0:00:00.390323687  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2300:gst_base_src_do_sync:<fakesrc0> no sync needed
0:00:00.390340625  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2505:gst_base_src_get_range:<fakesrc0> buffer ok
0:00:00.390362696  3176 000000000337A9C0 INFO                 basesrc gstbasesrc.c:2828:gst_base_src_loop:<fakesrc0> marking pending DISCONT
0:00:00.390437636  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:7621:gst_rtspsrc_handle_message:<source> timeout on UDP port
0:00:00.390460220  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 32
0:00:00.390477158  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:5006:gst_rtspsrc_loop_send_cmd:<source> cancel previous request 64
0:00:00.390494097  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:5017:gst_rtspsrc_loop_send_cmd:<source> not interrupting busy cmd 2
0:00:00.390513088  3176 000000000337AB80 LOG                   udpsrc gstudpsrc.c:425:gst_udpsrc_create:<udpsrc1> doing select, timeout 5000000
0:00:00.390645002  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<fakesrc0> next_ts 0:00:00.000000200 size 4096
0:00:00.390688118  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2341:gst_base_src_update_length:<fakesrc0> reading offset 200, length 4096, size -1, segment.stop -1, maxsize -1
0:00:00.390709676  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<fakesrc0> calling create offset 200 length 4096, time 0
0:00:00.390752278  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2262:gst_base_src_do_sync:<fakesrc0> created PTS 99:99:99.999999999
0:00:00.390772296  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2300:gst_base_src_do_sync:<fakesrc0> no sync needed
0:00:00.390789748  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2505:gst_base_src_get_range:<fakesrc0> buffer ok
0:00:00.390885219  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<fakesrc0> next_ts 0:00:00.000000400 size 4096
0:00:00.390925255  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2341:gst_base_src_update_length:<fakesrc0> reading offset 400, length 4096, size -1, segment.stop -1, maxsize -1
0:00:00.390955538  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<fakesrc0> calling create offset 400 length 4096, time 0
0:00:00.391006353  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2262:gst_base_src_do_sync:<fakesrc0> created PTS 99:99:99.999999999
0:00:00.391026371  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2300:gst_base_src_do_sync:<fakesrc0> no sync needed
0:00:00.391045363  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2505:gst_base_src_get_range:<fakesrc0> buffer ok
0:00:00.391142887  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<fakesrc0> next_ts 0:00:00.000000600 size 4096
0:00:00.391174710  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2341:gst_base_src_update_length:<fakesrc0> reading offset 600, length 4096, size -1, segment.stop -1, maxsize -1
0:00:00.391201914  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<fakesrc0> calling create offset 600 length 4096, time 0
0:00:00.391245030  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2262:gst_base_src_do_sync:<fakesrc0> created PTS 99:99:99.999999999
0:00:00.391265048  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2300:gst_base_src_do_sync:<fakesrc0> no sync needed
0:00:00.391291739  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2505:gst_base_src_get_range:<fakesrc0> buffer ok
0:00:00.391372324  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<fakesrc0> next_ts 0:00:00.000000800 size 4096
0:00:00.391414414  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2341:gst_base_src_update_length:<fakesrc0> reading offset 800, length 4096, size -1, segment.stop -1, maxsize -1
0:00:00.391434945  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2441:gst_base_src_get_range:<fakesrc0> calling create offset 800 length 4096, time 0
0:00:00.391467282  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2262:gst_base_src_do_sync:<fakesrc0> created PTS 99:99:99.999999999
0:00:00.391497052  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2300:gst_base_src_do_sync:<fakesrc0> no sync needed
0:00:00.391514504  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2505:gst_base_src_get_range:<fakesrc0> buffer ok
0:00:00.391629479  3176 000000000337A9C0 LOG                  basesrc gstbasesrc.c:2719:gst_base_src_loop:<fakesrc0> next_ts 0:00:00.000001000 size 4096
0:00:00.391658736  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2341:gst_base_src_update_length:<fakesrc0> reading offset 1000, length 4096, size -1, segment.stop -1, maxsize -1
0:00:00.391688507  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2583:gst_base_src_get_range:<fakesrc0> sent all buffers
0:00:00.391706985  3176 000000000337A9C0 INFO                 basesrc gstbasesrc.c:2724:gst_base_src_loop:<fakesrc0> pausing after gst_base_src_get_range() = eos
0:00:00.391725977  3176 000000000337A9C0 DEBUG                basesrc gstbasesrc.c:2884:gst_base_src_loop:<fakesrc0> pausing task, reason eos
0:00:00.392921413  3176 000000000337AB80 LOG                   udpsrc gstudpsrc.c:473:gst_udpsrc_create:<udpsrc1> ioctl says -1 bytes available
0:00:00.393775003  3176 000000000337AB80 WARN                  udpsrc gstudpsrc.c:573:gst_udpsrc_create:<udpsrc1> error: receive error -1: Error receiving message: An existing connection was forcibly closed by the remote host.
0:00:00.393855075  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:7648:gst_rtspsrc_handle_message:<source> got error from udpsrc1
0:00:00.393878173  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:7662:gst_rtspsrc_handle_message:<source> combined flows: not-linked
0:00:00.393904864  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:2553:gst_base_src_get_range:<udpsrc1> create returned -5 (error)
0:00:00.393923855  3176 000000000337AB80 INFO                 basesrc gstbasesrc.c:2724:gst_base_src_loop:<udpsrc1> pausing after gst_base_src_get_range() = error
0:00:00.393941307  3176 000000000337AB80 DEBUG                basesrc gstbasesrc.c:2884:gst_base_src_loop:<udpsrc1> pausing task, reason error
0:00:00.393980317  3176 000000000337AB80 WARN                 basesrc gstbasesrc.c:2933:gst_base_src_loop:<udpsrc1> error: Internal data flow error.
0:00:00.393999308  3176 000000000337AB80 WARN                 basesrc gstbasesrc.c:2933:gst_base_src_loop:<udpsrc1> error: streaming task paused, reason error (-5)
0:00:00.394029592  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:7648:gst_rtspsrc_handle_message:<source> got error from udpsrc1
0:00:00.394048583  3176 000000000337AB80 DEBUG                rtspsrc gstrtspsrc.c:7662:gst_rtspsrc_handle_message:<source> combined flows: not-linked
ERROR: from element /GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1: Could not read from resource.
Additional debug info:
gstudpsrc.c(573): gst_udpsrc_create (): /GstPipeline:pipeline0/GstURIDecodeBin:uridecodebin0/GstRTSPSrc:source/GstUDPSrc:udpsrc1:
receive error -1: Error receiving message: An existing connection was forcibly closed by the remote host.
0:00:00.422138010  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 16
0:00:00.422190364  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4999:gst_rtspsrc_loop_send_cmd:<source> ignore, we were reconnecting
0:00:00.422231940  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5006:gst_rtspsrc_loop_send_cmd:<source> cancel previous request 32
0:00:00.422250419  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5017:gst_rtspsrc_loop_send_cmd:<source> not interrupting busy cmd 2
0:00:00.422296614  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 4
0:00:00.422318172  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4999:gst_rtspsrc_loop_send_cmd:<source> ignore, we were reconnecting
0:00:00.422335110  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5006:gst_rtspsrc_loop_send_cmd:<source> cancel previous request 32
0:00:00.422352562  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5017:gst_rtspsrc_loop_send_cmd:<source> not interrupting busy cmd 2
0:00:00.423595734  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5410:gst_rtspsrc_try_send:<source> received response message
0:00:00.423651169  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5425:gst_rtspsrc_try_send:<source> got response message 200
0:00:00.423689665  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:66:gst_rtsp_wms_after_send:<rtspwms0> after send
0:00:00.423714302  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6508:gst_rtspsrc_parse_range:<source> parsed range npt=now-
0:00:00.423735347  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6519:gst_rtspsrc_parse_range:<source> range: type 1, min 0.000000 - type 2,  max 0.000000 
0:00:00.423762551  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6529:gst_rtspsrc_parse_range:<source> range: min 0:00:00.000000000
0:00:00.423783596  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6544:gst_rtspsrc_parse_range:<source> range: max 99:99:99.999999999
0:00:00.423808747  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:3981:gst_rtspsrc_configure_caps:<source> configuring stream caps
0:00:00.423841083  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4019:gst_rtspsrc_configure_caps:<source> stream 00000000033B66D0, pt 96, caps application/x-rtp, media=(string)video, payload=(int)96, clock-rate=(int)90000, encoding-name=(string)H264, profile-level-id=(string)4d0014, packetization-mode=(string)1, sprop-parameter-sets=(string)"Z00AFJWoWCWhAAADAAEAAAMAMoQ\=\,aO48gA\=\=", a-framerate=(string)25, ssrc=(uint)7116, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1
0:00:00.423917563  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4027:gst_rtspsrc_configure_caps:<source> clear session
0:00:00.423966838  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7430:gst_rtspsrc_play:<source> mark DISCONT, we did a seek to another position
0:00:00.424022272  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7696:gst_rtspsrc_thread:<source> got command 32
0:00:00.424045370  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 0
0:00:00.424070008  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4825:gst_rtspsrc_reconnect:<source> doing reconnect
0:00:00.424091566  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:6967:gst_rtspsrc_close:<source> TEARDOWN...
0:00:00.427154327  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3809:gst_base_src_change_state:<udpsrc1> PLAYING->PAUSED
0:00:00.427242098  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3629:gst_base_src_set_playing:<udpsrc1> unlock
0:00:00.427265196  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1049:gst_udpsrc_unlock:<udpsrc1> Flushing
0:00:00.427290860  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3638:gst_base_src_set_playing:<udpsrc1> unschedule clock
0:00:00.427317551  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3645:gst_base_src_set_playing:<udpsrc1> live running 0
0:00:00.427345781  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3757:gst_base_src_activate_mode:<udpsrc1:src> activating in mode 1
0:00:00.427364260  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3691:gst_base_src_activate_push:<udpsrc1> Deactivating in push mode
0:00:00.427381198  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3516:gst_base_src_stop:<udpsrc1> stopping source
0:00:00.427398650  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3559:gst_base_src_set_flushing:<udpsrc1> flushing 1, live_play 0
0:00:00.427416615  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1049:gst_udpsrc_unlock:<udpsrc1> Flushing
0:00:00.427433040  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1062:gst_udpsrc_unlock_stop:<udpsrc1> No longer flushing
0:00:00.427479748  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3809:gst_base_src_change_state:<udpsrc2> PLAYING->PAUSED
0:00:00.427499253  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3629:gst_base_src_set_playing:<udpsrc2> unlock
0:00:00.427523377  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1049:gst_udpsrc_unlock:<udpsrc2> Flushing
0:00:00.427680442  3176 000000000337ABC0 DEBUG                 udpsrc gstudpsrc.c:545:gst_udpsrc_create: stop called
0:00:00.427706106  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:2553:gst_base_src_get_range:<udpsrc2> create returned -2 (flushing)
0:00:00.427725611  3176 000000000337ABC0 INFO                 basesrc gstbasesrc.c:2724:gst_base_src_loop:<udpsrc2> pausing after gst_base_src_get_range() = flushing
0:00:00.427747169  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3638:gst_base_src_set_playing:<udpsrc2> unschedule clock
0:00:00.427764621  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3645:gst_base_src_set_playing:<udpsrc2> live running 0
0:00:00.427790798  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3757:gst_base_src_activate_mode:<udpsrc2:src> activating in mode 1
0:00:00.427808763  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3691:gst_base_src_activate_push:<udpsrc2> Deactivating in push mode
0:00:00.427825701  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3516:gst_base_src_stop:<udpsrc2> stopping source
0:00:00.427842126  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3559:gst_base_src_set_flushing:<udpsrc2> flushing 1, live_play 0
0:00:00.427859578  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1049:gst_udpsrc_unlock:<udpsrc2> Flushing
0:00:00.427877030  3176 000000000337A2C0 LOG                   udpsrc gstudpsrc.c:1062:gst_udpsrc_unlock_stop:<udpsrc2> No longer flushing
0:00:00.427898588  3176 000000000337ABC0 DEBUG                basesrc gstbasesrc.c:2884:gst_base_src_loop:<udpsrc2> pausing task, reason flushing
0:00:00.427940677  3176 000000000337A2C0 DEBUG                rtspwms gstrtspwms.c:45:gst_rtsp_wms_before_send:<rtspwms0> before send
0:00:00.427959668  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5381:gst_rtspsrc_try_send:<source> sending message
0:00:00.431724088  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 8
0:00:00.431766690  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5006:gst_rtspsrc_loop_send_cmd:<source> cancel previous request 64
0:00:00.431787221  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5017:gst_rtspsrc_loop_send_cmd:<source> not interrupting busy cmd 32
0:00:00.431909383  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7772:gst_rtspsrc_stop:<source> stopping
0:00:00.431936074  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4994:gst_rtspsrc_loop_send_cmd:<source> sending cmd 16
0:00:00.431956092  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5006:gst_rtspsrc_loop_send_cmd:<source> cancel previous request 8
0:00:00.431979189  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:5013:gst_rtspsrc_loop_send_cmd:<source> connection flush busy 32
0:00:00.432070554  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4241:gst_rtspsrc_connection_flush:<source> set flushing 1
0:00:00.432089545  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:4244:gst_rtspsrc_connection_flush:<source> connection flush
0:00:00.432167564  3176 000000000337A2C0 WARN                 rtspsrc gstrtspsrc.c:5482:gst_rtspsrc_try_send:<source> receive interrupted
0:00:00.432188096  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:5580:gst_rtspsrc_send:<source> got error -3
0:00:00.432209140  3176 000000000337A2C0 WARN                 rtspsrc gstrtspsrc.c:7068:gst_rtspsrc_close:<source> TEARDOWN interrupted
0:00:00.432237371  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:7032:gst_rtspsrc_close:<source> closing connection...
0:00:00.432344647  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4209:gst_rtsp_conninfo_close:<source> closing connection...
0:00:00.435599889  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:4215:gst_rtsp_conninfo_close:<source> freeing connection...
0:00:00.435638386  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1576:gst_rtspsrc_cleanup:<source> cleanup
0:00:00.435659944  3176 000000000337A2C0 DEBUG                rtspsrc gstrtspsrc.c:1525:gst_rtspsrc_stream_free:<source> free stream 00000000033B66D0
0:00:00.435710245  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:1071:gst_udpsrc_close: closing sockets
0:00:00.435853965  3176 000000000337A2C0 DEBUG                 udpsrc gstudpsrc.c:1071:gst_udpsrc_close: closing sockets
0:00:00.436019755  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3809:gst_base_src_change_state:<fakesrc0> PLAYING->PAUSED
0:00:00.436050039  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3757:gst_base_src_activate_mode:<fakesrc0:src> activating in mode 1
0:00:00.436070570  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3691:gst_base_src_activate_push:<fakesrc0> Deactivating in push mode
0:00:00.436088022  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3516:gst_base_src_stop:<fakesrc0> stopping source
0:00:00.436107013  3176 000000000337A2C0 DEBUG                basesrc gstbasesrc.c:3559:gst_base_src_set_flushing:<fakesrc0> flushing 1, live_play 0
0:00:00.436239954  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:6967:gst_rtspsrc_close:<source> TEARDOWN...
0:00:00.436265105  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:6972:gst_rtspsrc_close:<source> not ready, doing cleanup
0:00:00.436284096  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:7032:gst_rtspsrc_close:<source> closing connection...
0:00:00.436302061  3176 00000000040C9630 DEBUG                rtspsrc gstrtspsrc.c:1576:gst_rtspsrc_cleanup:<source> cleanup

Comment 3 Tim-Philipp Müller 2014-11-23 21:40:43 UTC

Please *attach* it next time instead of dumping it into the comments.

Comment 4 Luis de Bethencourt 2014-11-23 21:43:25 UTC

Hello German,

Thank you very much for the bug report:)

In the future it would be better if you shared the log as a plain text file attachment to the bug or paste it into a pastebin website. Bugzilla breaks the formatting and it is a bit harder to read.

Cheers,
Luis

Comment 5 German Afanador 2014-11-23 21:49:43 UTC

Created attachment 291329 [details]
Debug output for not working attempt using gst-launch 1.4.4

Hi Luis,

I apologize for copy/pasting the output here. I will be sure to remember to not do it next time. I'm attaching the same debug output.

Thanks,
German

Comment 6 Luis de Bethencourt 2014-11-23 21:57:15 UTC

No worries. Just a note for future reference. It increases your chances of a developer helping.

Comment 7 Nicolas Dufresne (ndufresne) 2015-03-25 22:28:10 UTC


*** This bug has been marked as a duplicate of bug 635701 ***