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
