search

awslabs / amazon-kinesis-video-streams-producer-sdk-cpp

Amazon Kinesis Video Streams Producer SDK for C++ is for developers to install and customize for their connected camera and other devices to securely stream video, audio, and time-encoded data to Kinesis Video Streams.
Apache License 2.0
378 stars 336 forks source link

Error connecting to RTSP stream #754

Closed daveisfera closed 3 years ago

daveisfera commented 3 years ago

I can connect to the RTSP stream with VLC, but it errors out when using the example in a docker container

Logging Here's the command:

gst-launch-1.0 rtspsrc location="rtsp://<username>:<password>@192.168.4.246/live1s1.sdp" short-header=TRUE ! rtph264depay ! video/x-h264, format=avc,alignment=au ! kvssink stream-name="KvsTest" storage-size=512 access-key="<access_key>" secret-key="<secret_key>" aws-region="us-east-1"

Here's the output:

Setting pipeline to PAUSED ...
log4cplus:ERROR could not open file ./kvs_log_configuration
INFO - createKinesisVideoClient(): Creating Kinesis Video Client
2021-09-13 23:39:26 [140513556444992] INFO - heapInitialize(): Initializing native heap with limit size 536870912, spill ratio 0% and flags 0x00000001
2021-09-13 23:39:26 [140513556444992] INFO - heapInitialize(): Creating AIV heap.
2021-09-13 23:39:26 [140513556444992] INFO - heapInitialize(): Heap is initialized OK
2021-09-13 23:39:26 [140513556444992] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000001, Next state: 0x0000000000000002
2021-09-13 23:39:26 [140513556444992] DEBUG - getSecurityTokenHandler invoked
2021-09-13 23:39:26 [140513556444992] DEBUG - Refreshing credentials. Force refreshing: 0 Now time is: 1631576366088534600 Expiration: 0
2021-09-13 23:39:26 [140513556444992] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000002, Next state: 0x0000000000000010
2021-09-13 23:39:26 [140513556444992] INFO - createDeviceResultEvent(): Create device result event.
2021-09-13 23:39:26 [140513556444992] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000010, Next state: 0x0000000000000040
2021-09-13 23:39:26 [140513556444992] DEBUG - clientReadyHandler invoked
2021-09-13 23:39:26 [140513556444992] INFO - try creating stream
2021-09-13 23:39:26 [140513556444992] INFO - Creating Kinesis Video Stream DaveTest
2021-09-13 23:39:26 [140513556444992] INFO - createKinesisVideoStream(): Creating Kinesis Video Stream.
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo(): Kinesis Video Stream Info
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Stream name: DaveTest 
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Streaming type: STREAMING_TYPE_REALTIME 
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Content type: video/h264 
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Max latency (100ns): 600000000
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Fragment duration (100ns): 20000000
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Key frame fragmentation: Yes
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Use frame timecode: Yes
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Absolute frame timecode: Yes
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Nal adaptation flags: 0
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Average bandwith (bps): 4194304
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Framerate: 25
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Buffer duration (100ns): 1200000000
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Replay duration (100ns): 400000000
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Connection Staleness duration (100ns): 600000000
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Store Pressure Policy: 1
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  View Overflow Policy: 1
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Segment UUID: NULL
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Frame ordering mode: 0
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo(): Track list
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Track id: 1
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Track name: kinesis_video
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Codec id: V_MPEG4/ISO/AVC
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Track type: TRACK_INFO_TYPE_VIDEO
2021-09-13 23:39:26 [140513556444992] DEBUG - logStreamInfo():  Track cpd: NULL
2021-09-13 23:39:26 [140513556444992] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000001, Next state: 0x0000000000000002
2021-09-13 23:39:26 [140513556444992] INFO - writeHeaderCallback(): RequestId: 2102de0f-a6dc-4dfb-8c1f-0c405562e7c9
2021-09-13 23:39:26 [140512910898944] DEBUG - describeStreamCurlHandler(): DescribeStream API response: {"StreamInfo":{"CreationTime":1.631568461072E9,"DataRetentionInHours":720,"DeviceName":null,"IngestionConfiguration":null,"KmsKeyId":"arn:aws:kms:us-east-1:123456789012:alias/aws/kinesisvideo","MediaType":null,"Status":"ACTIVE","StreamARN":"arn:aws:kinesisvideo:us-east-1:234567890123:stream/KvsTest/3456789012345","StreamName":"KvsTest","Version":"45678901234567890123"}}
2021-09-13 23:39:26 [140512910898944] INFO - describeStreamResultEvent(): Describe stream result event.
2021-09-13 23:39:26 [140512910898944] WARN - describeStreamResult(): Retention period returned from the DescribeStream call doesn't match the one specified in the StreamInfo
2021-09-13 23:39:26 [140512910898944] WARN - describeStreamResult(): Content type returned from the DescribeStream call doesn't match the one specified in the StreamInfo
2021-09-13 23:39:26 [140512910898944] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000002, Next state: 0x0000000000000020
2021-09-13 23:39:26 [140512910898944] INFO - writeHeaderCallback(): RequestId: a08797d6-fd69-44e0-9ac3-8c63c02c2601
2021-09-13 23:39:26 [140512902506240] DEBUG - getStreamingEndpointCurlHandler(): GetStreamingEndpoint API response: {"DataEndpoint":"https://s-12345678.kinesisvideo.us-east-1.amazonaws.com"}
2021-09-13 23:39:26 [140512902506240] INFO - getStreamingEndpointResultEvent(): Get streaming endpoint result event.
2021-09-13 23:39:26 [140512902506240] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000020, Next state: 0x0000000000000010
2021-09-13 23:39:26 [140512902506240] DEBUG - getStreamingTokenHandler invoked
2021-09-13 23:39:26 [140512902506240] DEBUG - Refreshing credentials. Force refreshing: 1 Now time is: 1631576366907135100 Expiration: 18446744073709551615
2021-09-13 23:39:26 [140512902506240] INFO - getStreamingTokenResultEvent(): Get streaming token result event.
2021-09-13 23:39:26 [140512902506240] DEBUG - stepStateMachine(): State Machine - Current state: 0x0000000000000010, Next state: 0x0000000000000040
2021-09-13 23:39:26 [140512902506240] DEBUG - streamReadyHandler invoked
2021-09-13 23:39:26 [140512902506240] Stream is ready
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://<username>:<password>@192.168.4.246/live1s1.sdp
Progress: (open) Retrieving server options
Progress: (open) Retrieving media info
Progress: (request) SETUP stream 0
Progress: (request) SETUP stream 1
Progress: (request) SETUP stream 2
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
New clock: GstSystemClock
Progress: (request) Sending PLAY request
Progress: (request) Sending PLAY request
Progress: (request) Sent PLAY request
WARNING: from element /GstPipeline:pipeline0/GstRTSPSrc:rtspsrc0: Could not read from resource.
Additional debug info:
gstrtspsrc.c(4754): gst_rtspsrc_loop_udp (): /GstPipeline:pipeline0/GstRTSPSrc:rtspsrc0:
The server closed the connection.

Here's the output with GST_DEBUG=4 after Progress: (open) Opened Stream:

Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
0:00:03.102761200   101      0x1ff5a00 INFO               GST_EVENT gstevent.c:1382:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:03.102872700   101      0x1ff5a00 INFO        GST_ELEMENT_PADS gstelement.c:1564:gst_element_default_send_event: can't send latency event on element kvssink0
0:00:03.102887400   101      0x1ff5a00 WARN                     bin gstbin.c:2802:gst_bin_do_latency_func:<pipeline0> did not really configure latency of 0:00:00.000000000
0:00:03.102997000   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<kvssink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.103111900   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<kvssink0> completed state change to PLAYING
0:00:03.103131200   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<kvssink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.103172700   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<pipeline0> child 'kvssink0' changed state to 4(PLAYING) successfully
0:00:03.103240600   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<capsfilter0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.103250500   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<capsfilter0> completed state change to PLAYING
0:00:03.103255800   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<capsfilter0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.103271900   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<pipeline0> child 'capsfilter0' changed state to 4(PLAYING) successfully
0:00:03.103293800   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtph264depay0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.103384100   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtph264depay0> completed state change to PLAYING
0:00:03.103397200   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtph264depay0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.103413000   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<pipeline0> child 'rtph264depay0' changed state to 4(PLAYING) successfully
0:00:03.103821300   101      0x2167680 INFO                    task gsttask.c:318:gst_task_func:<task0> Task going to paused
0:00:03.103926400   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsink5' changed state to 4(PLAYING) successfully
0:00:03.103951700   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsink4' changed state to 4(PLAYING) successfully
0:00:03.103967900   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsink3' changed state to 4(PLAYING) successfully
0:00:03.104067800   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsink2' changed state to 4(PLAYING) successfully
0:00:03.104085300   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsink1' changed state to 4(PLAYING) successfully
0:00:03.104097600   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsink0' changed state to 4(PLAYING) successfully
0:00:03.104108800   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'fakesrc2' changed state to 4(PLAYING) successfully
0:00:03.104117100   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'fakesrc1' changed state to 4(PLAYING) successfully
0:00:03.104126800   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'manager' changed state to 4(PLAYING) successfully
0:00:03.104136000   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'fakesrc0' changed state to 4(PLAYING) successfully
0:00:03.104144100   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsrc0' changed state to 4(PLAYING) successfully
0:00:03.104153100   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsrc1' changed state to 4(PLAYING) successfully
0:00:03.104160200   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsrc3' changed state to 4(PLAYING) successfully
0:00:03.104170200   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsrc4' changed state to 4(PLAYING) successfully
0:00:03.104179300   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsrc5' changed state to 4(PLAYING) successfully
0:00:03.104191300   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<rtspsrc0> child 'udpsrc6' changed state to 4(PLAYING) successfully
0:00:03.104238600   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtspsrc0> completed state change to PLAYING
0:00:03.104254500   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtspsrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.104288400   101      0x1ff5a00 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<pipeline0> child 'rtspsrc0' changed state to 4(PLAYING) successfully
0:00:03.104347900   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:03.104358900   101      0x1ff5a00 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
New clock: GstSystemClock
Progress: (request) Sending PLAY request
0:00:03.104978300   101      0x2167680 INFO                    task gsttask.c:320:gst_task_func:<task0> Task resume from paused
0:00:03.105004700   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsink0> completed state change to NULL
0:00:03.105040400   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<fakesrc0> completed state change to NULL
0:00:03.105084600   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsink0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.105128800   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.105317700   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsink0> continue state change READY to PAUSED, final PLAYING
0:00:03.105360800   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsink0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.105425400   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.105486300   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsink0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.105525300   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsink0> completed state change to PLAYING
0:00:03.105539200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.105552300   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<fakesrc0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.105566200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.105625400   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<fakesrc0> continue state change READY to PAUSED, final PLAYING
0:00:03.105953900   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343: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:03.106003500   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880be770 on task 0x21445f0
0:00:03.106020900   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<fakesrc0:src> created task 0x21445f0
0:00:03.106183400   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<fakesrc0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.106222000   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.106270400   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<fakesrc0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.106341800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<fakesrc0> completed state change to PLAYING
0:00:03.106359400   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.106372900   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsink2> completed state change to NULL
0:00:03.106381500   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<fakesrc1> completed state change to NULL
0:00:03.106372900   101 0x7f0f880ad770 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "fakesrc0"
0:00:03.106406800   101 0x7f0f880ad770 FIXME                default gstutils.c:3826:gst_pad_create_stream_id_internal:<fakesrc0:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:03.106469600   101 0x7f0f880ad770 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.106518300   101 0x7f0f880ad770 INFO                 basesrc gstbasesrc.c:2847:gst_base_src_loop:<fakesrc0> marking pending DISCONT
0:00:03.106780200   101 0x7f0f880ad770 INFO                 basesrc gstbasesrc.c:2743:gst_base_src_loop:<fakesrc0> pausing after gst_base_src_get_range() = eos
0:00:03.106907400   101 0x7f0f880ad770 INFO                    task gsttask.c:318:gst_task_func:<fakesrc0:src> Task going to paused
0:00:03.106406800   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsink2> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.107099300   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink2> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.107394900   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsink2> continue state change READY to PAUSED, final PLAYING
0:00:03.107507000   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsink2> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.107527400   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink2> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.107595400   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsink2> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.107677700   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsink2> completed state change to PLAYING
0:00:03.107920700   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.108083800   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<fakesrc1> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.108329800   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc1> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.108397600   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<fakesrc1> continue state change READY to PAUSED, final PLAYING
0:00:03.108441500   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<fakesrc1> 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:03.108473600   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880d6310 on task 0x2144710
0:00:03.108492500   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<fakesrc1:src> created task 0x2144710
0:00:03.108873200   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<fakesrc1> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.108913200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc1> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.108968800   101 0x7f0f880e0000 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "fakesrc1"
0:00:03.109052600   101 0x7f0f880e0000 FIXME                default gstutils.c:3826:gst_pad_create_stream_id_internal:<fakesrc1:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:03.108961200   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<fakesrc1> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.109107800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<fakesrc1> completed state change to PLAYING
0:00:03.109122500   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.109149500   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsink4> completed state change to NULL
0:00:03.109162200   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<fakesrc2> completed state change to NULL
0:00:03.109173700   101 0x7f0f880e0000 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.109219800   101 0x7f0f880e0000 INFO                 basesrc gstbasesrc.c:2847:gst_base_src_loop:<fakesrc1> marking pending DISCONT
0:00:03.109326100   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsink4> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.109468600   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink4> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.109598200   101 0x7f0f880e0000 INFO                 basesrc gstbasesrc.c:2743:gst_base_src_loop:<fakesrc1> pausing after gst_base_src_get_range() = eos
0:00:03.109672300   101 0x7f0f880e0000 INFO                    task gsttask.c:318:gst_task_func:<fakesrc1:src> Task going to paused
0:00:03.109514300   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsink4> continue state change READY to PAUSED, final PLAYING
0:00:03.110096800   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsink4> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.110171100   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink4> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.110221600   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsink4> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.110280400   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsink4> completed state change to PLAYING
0:00:03.110291200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsink4> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.110346700   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<fakesrc2> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.110576700   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc2> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.110602500   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<fakesrc2> continue state change READY to PAUSED, final PLAYING
0:00:03.110624600   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<fakesrc2> 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:03.110693900   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880d7e10 on task 0x2144830
0:00:03.110758400   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<fakesrc2:src> created task 0x2144830
0:00:03.111032500   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<fakesrc2> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.111146500   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc2> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.111217700   101 0x7f0f880e0050 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "fakesrc2"
0:00:03.111385800   101 0x7f0f880e0050 FIXME                default gstutils.c:3826:gst_pad_create_stream_id_internal:<fakesrc2:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:03.111531300   101 0x7f0f880e0050 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.111567700   101 0x7f0f880e0050 INFO                 basesrc gstbasesrc.c:2847:gst_base_src_loop:<fakesrc2> marking pending DISCONT
0:00:03.111416900   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<fakesrc2> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.111865100   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<fakesrc2> completed state change to PLAYING
0:00:03.112064600   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<fakesrc2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
Progress: (request) Sending PLAY request
0:00:03.112686800   101 0x7f0f880e0050 INFO                 basesrc gstbasesrc.c:2743:gst_base_src_loop:<fakesrc2> pausing after gst_base_src_get_range() = eos
0:00:03.112815900   101 0x7f0f880e0050 INFO                    task gsttask.c:318:gst_task_func:<fakesrc2:src> Task going to paused
0:00:03.137566400   101      0x2167680 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtpssrcdemux2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.137635500   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpssrcdemux2> completed state change to PLAYING
0:00:03.137657300   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpssrcdemux2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.137724700   101      0x2167680 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<manager> child 'rtpssrcdemux2' changed state to 4(PLAYING) successfully
0:00:03.137774900   101      0x2167680 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtpsession2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.138124800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpsession2> completed state change to PLAYING
0:00:03.138174900   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpsession2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.138236200   101      0x2167680 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<manager> child 'rtpsession2' changed state to 4(PLAYING) successfully
0:00:03.138451700   101      0x2167680 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtpssrcdemux1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.138485800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpssrcdemux1> completed state change to PLAYING
0:00:03.138514600   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpssrcdemux1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.138698000   101      0x2167680 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<manager> child 'rtpssrcdemux1' changed state to 4(PLAYING) successfully
0:00:03.139025600   101      0x2167680 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtpsession1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.139156000   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpsession1> completed state change to PLAYING
0:00:03.139180200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpsession1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.139212400   101      0x2167680 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<manager> child 'rtpsession1' changed state to 4(PLAYING) successfully
0:00:03.139314100   101      0x2167680 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtpssrcdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.139356500   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpssrcdemux0> completed state change to PLAYING
0:00:03.139371300   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.139525500   101      0x2167680 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<manager> child 'rtpssrcdemux0' changed state to 4(PLAYING) successfully
0:00:03.139563600   101      0x2167680 INFO              GST_STATES gstbin.c:2521:gst_bin_element_set_state:<rtpsession0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:03.140076800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpsession0> completed state change to PLAYING
0:00:03.140117800   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.140166000   101      0x2167680 INFO              GST_STATES gstbin.c:2969:gst_bin_change_state_func:<manager> child 'rtpsession0' changed state to 4(PLAYING) successfully
0:00:03.140239100   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<manager> completed state change to PLAYING
0:00:03.140552100   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<manager> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.140591800   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<udpsrc0> 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:03.140685000   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x21432d0 on task 0x2144950
0:00:03.140889800   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<udpsrc0:src> created task 0x2144950
0:00:03.141416400   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsrc0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.141454300   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.141530900   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsrc0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.141616000   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsrc0> completed state change to PLAYING
0:00:03.141654100   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.141682600   101 0x7f0f880e0280 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc0"
0:00:03.141871400   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343: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:03.141937600   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x2143510 on task 0x2144a70
0:00:03.141955200   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<udpsrc1:src> created task 0x2144a70
0:00:03.141852400   101 0x7f0f880e0280 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtp, media=(string)video, payload=(int)98, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)4d4032, sprop-parameter-sets=(string)"Z01AMpY1AFQBf03BQYFQAAA+gAAOpgC+oA\=\=\,aO4Ecg\=\=", a-charset=(string)Shift_JIS, a-etag=(string)1234567890, x-onvif-track=(string)"trackID\=2", a-recvonly=(string)"", ssrc=(uint)427918956, clock-base=(uint)0, seqnum-base=(uint)0, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1
0:00:03.142471500   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsrc1> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.142493700   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.142656200   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsrc1> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.142679900   101 0x7f0f880e02d0 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc1"
0:00:03.143066800   101 0x7f0f880e02d0 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtcp
0:00:03.143096400   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsrc1> completed state change to PLAYING
0:00:03.143211400   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.143270000   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<udpsrc3> 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:03.143368700   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880bf070 on task 0x2144b90
0:00:03.143393400   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<udpsrc3:src> created task 0x2144b90
0:00:03.143674800   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsrc3> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.143709500   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc3> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.143820900   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsrc3> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.143832300   101 0x7f0f880e0320 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc3"
0:00:03.143943200   101 0x7f0f880e0320 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtp, media=(string)audio, payload=(int)0, clock-rate=(int)8000, encoding-name=(string)PCMU, a-charset=(string)Shift_JIS, a-etag=(string)1234567890, x-onvif-track=(string)"trackID\=0", a-recvonly=(string)"", ssrc=(uint)628909789, clock-base=(uint)0, seqnum-base=(uint)0, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1
0:00:03.143958800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsrc3> completed state change to PLAYING
0:00:03.144065200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc3> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.144127200   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<udpsrc4> 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:03.144152900   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880bf2b0 on task 0x2144cb0
0:00:03.144164900   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<udpsrc4:src> created task 0x2144cb0
0:00:03.144266500   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsrc4> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.144366500   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc4> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.144391000   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsrc4> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.144454600   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsrc4> completed state change to PLAYING
0:00:03.144478900   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc4> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.144539300   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<udpsrc5> 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:03.144599200   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880d69d0 on task 0x2144dd0
0:00:03.144613700   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<udpsrc5:src> created task 0x2144dd0
0:00:03.144552700   101 0x7f0f880e0370 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc4"
0:00:03.144691000   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsrc5> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.144708000   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc5> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.144691000   101 0x7f0f880e0370 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtcp
0:00:03.144799200   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsrc5> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.144941800   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc5"
0:00:03.145182000   101 0x7f0f880e0800 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtp, media=(string)application, payload=(int)107, clock-rate=(int)90000, encoding-name=(string)VND.ONVIF.METADATA, a-charset=(string)Shift_JIS, a-etag=(string)1234567890, x-onvif-track=(string)"trackID\=1", a-recvonly=(string)"", ssrc=(uint)13568361, clock-base=(uint)0, seqnum-base=(uint)0, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1
0:00:03.145192700   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsrc5> completed state change to PLAYING
0:00:03.145286200   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc5> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.145300900   101      0x2167680 INFO                 basesrc gstbasesrc.c:1343:gst_base_src_do_seek:<udpsrc6> 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:03.145342500   101      0x2167680 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880d6c10 on task 0x2144ef0
0:00:03.145351800   101      0x2167680 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<udpsrc6:src> created task 0x2144ef0
0:00:03.145512100   101      0x2167680 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<udpsrc6> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.145546500   101 0x7f0f880e0800 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.145582100   101 0x7f0f880e0800 INFO                 basesrc gstbasesrc.c:2847:gst_base_src_loop:<udpsrc5> marking pending DISCONT
0:00:03.145551400   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc6> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.145628200   101      0x2167680 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<udpsrc6> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.145653800   101      0x2167680 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<udpsrc6> completed state change to PLAYING
0:00:03.145661900   101      0x2167680 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<udpsrc6> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
Progress: (request) Sent PLAY request
0:00:03.145858700   101 0x7f0f880e0850 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc6"
0:00:03.145910500   101 0x7f0f880e0850 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtcp
0:00:03.218994000   101 0x7f0f880e0800 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtp, media=(string)application, payload=(int)107, clock-rate=(int)90000, encoding-name=(string)VND.ONVIF.METADATA, a-charset=(string)Shift_JIS, a-etag=(string)1234567890, x-onvif-track=(string)"trackID\=1", a-recvonly=(string)"", ssrc=(uint)13568361, clock-base=(uint)0, seqnum-base=(uint)0, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1
0:00:03.219074100   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<rtpssrcdemux2> adding pad 'src_13568361'
0:00:03.219091700   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<rtpssrcdemux2> adding pad 'rtcp_src_13568361'
0:00:03.219117600   101 0x7f0f880e0800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "rtpjitterbuffer"
0:00:03.219269600   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<GstRtpJitterBuffer@0x7f0f400302e0> adding pad 'src'
0:00:03.219365500   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<GstRtpJitterBuffer@0x7f0f400302e0> adding pad 'sink'
0:00:03.219399500   101 0x7f0f880e0800 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:364:gst_element_factory_create: creating element "rtpptdemux"
0:00:03.219481600   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<GstRtpPtDemux@0x7f0f88050120> adding pad 'sink'
0:00:03.219669100   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstutils.c:1633:gst_element_link_pads_full: trying to link element rtpjitterbuffer0:src to element rtpptdemux0:sink
0:00:03.219715700   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:916:gst_element_get_static_pad: found pad rtpjitterbuffer0:src
0:00:03.219751100   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:916:gst_element_get_static_pad: found pad rtpptdemux0:sink
0:00:03.219802300   101 0x7f0f880e0800 INFO                GST_PADS gstutils.c:1447:prepare_link_maybe_ghosting: rtpjitterbuffer0 and rtpptdemux0 in same bin, no need for ghost pads
0:00:03.219862500   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link rtpjitterbuffer0:src and rtpptdemux0:sink
0:00:03.219888600   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:2523:gst_pad_link_full: linked rtpjitterbuffer0:src and rtpptdemux0:sink, successful
0:00:03.219916100   101 0x7f0f880e0800 INFO               GST_EVENT gstevent.c:1511:gst_event_new_reconfigure: creating reconfigure event
0:00:03.219933500   101 0x7f0f880e0800 INFO               GST_EVENT gstpad.c:5652:gst_pad_send_event_unchecked:<rtpjitterbuffer0:src> Received event on flushing pad. Discarding
0:00:03.220025800   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<rtpptdemux0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.220065900   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpptdemux0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.220120800   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<rtpptdemux0> continue state change READY to PAUSED, final PLAYING
0:00:03.220177900   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<rtpptdemux0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.220263800   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpptdemux0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.220344100   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<rtpptdemux0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.220360300   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpptdemux0> completed state change to PLAYING
0:00:03.220391600   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpptdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.220440800   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<rtpjitterbuffer0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:03.220511500   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpjitterbuffer0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:03.220554100   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<rtpjitterbuffer0> continue state change READY to PAUSED, final PLAYING
0:00:03.220718800   101 0x7f0f880e0800 INFO                    task gsttask.c:456:gst_task_set_lock: setting stream lock 0x7f0f880e29a0 on task 0x7f0f88050290
0:00:03.220754600   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:5998:gst_pad_start_task:<rtpjitterbuffer0:src> created task 0x7f0f88050290
0:00:03.220898500   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2394:gst_element_continue_state:<rtpjitterbuffer0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:03.220913500   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpjitterbuffer0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:03.221043300   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2401:gst_element_continue_state:<rtpjitterbuffer0> continue state change PAUSED to PLAYING, final PLAYING
0:00:03.221066500   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2419:gst_element_continue_state:<rtpjitterbuffer0> completed state change to PLAYING
0:00:03.221083500   101 0x7f0f880e0800 INFO              GST_STATES gstelement.c:2324:_priv_gst_element_state_changed:<rtpjitterbuffer0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:03.221124900   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:916:gst_element_get_static_pad: found pad rtpssrcdemux2:src_13568361
0:00:03.221141000   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:916:gst_element_get_static_pad: found pad rtpjitterbuffer0:sink
0:00:03.221156900   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link rtpssrcdemux2:src_13568361 and rtpjitterbuffer0:sink
0:00:03.221210700   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:2523:gst_pad_link_full: linked rtpssrcdemux2:src_13568361 and rtpjitterbuffer0:sink, successful
0:00:03.221265200   101 0x7f0f880e0800 INFO               GST_EVENT gstevent.c:1511:gst_event_new_reconfigure: creating reconfigure event
0:00:03.221292000   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:916:gst_element_get_static_pad: found pad rtpssrcdemux2:rtcp_src_13568361
0:00:03.221376400   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'sink_rtcp' in element "rtpjitterbuffer0"
0:00:03.221410500   101 0x7f0f880e0800 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<rtpjitterbuffer0> adding pad 'sink_rtcp'
0:00:03.221427300   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link rtpssrcdemux2:rtcp_src_13568361 and rtpjitterbuffer0:sink_rtcp
0:00:03.221438400   101 0x7f0f880e0800 INFO                GST_PADS gstpad.c:2523:gst_pad_link_full: linked rtpssrcdemux2:rtcp_src_13568361 and rtpjitterbuffer0:sink_rtcp, successful
0:00:03.221522200   101 0x7f0f880e0800 INFO               GST_EVENT gstevent.c:1511:gst_event_new_reconfigure: creating reconfigure event
0:00:03.221659500   101 0x7f0f880e0800 INFO         rtpjitterbuffer rtpjitterbuffer.c:778:rtp_jitter_buffer_calculate_pts: resync to time 0:00:00.112645500, rtptime 0:00:00.000000000
0:00:03.222157700   101 0x7f0f40004ca0 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtp, media=(string)application, payload=(int)107, clock-rate=(int)90000, encoding-name=(string)VND.ONVIF.METADATA, a-charset=(string)Shift_JIS, a-etag=(string)1234567890, x-onvif-track=(string)"trackID\=1", a-recvonly=(string)"", ssrc=(uint)13568361, clock-base=(uint)0, seqnum-base=(uint)0, npt-start=(guint64)0, play-speed=(double)1, play-scale=(double)1
0:00:03.222199300   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<rtpptdemux0> adding pad 'src_107'
0:00:03.222629500   101 0x7f0f40004ca0 INFO                GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link rtpptdemux0:src_107 and recv_rtp_src_2_13568361_107:proxypad9
0:00:03.222665100   101 0x7f0f40004ca0 INFO                GST_PADS gstpad.c:2523:gst_pad_link_full: linked rtpptdemux0:src_107 and recv_rtp_src_2_13568361_107:proxypad9, successful
0:00:03.222680700   101 0x7f0f40004ca0 INFO               GST_EVENT gstevent.c:1511:gst_event_new_reconfigure: creating reconfigure event
0:00:03.222828000   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<manager> adding pad 'recv_rtp_src_2_13568361_107'
0:00:03.223168800   101 0x7f0f40004ca0 INFO                GST_PADS gstpad.c:2315:gst_pad_link_prepare: trying to link manager:recv_rtp_src_2_13568361_107 and recv_rtp_src_2_13568361_107:proxypad10
0:00:03.223190200   101 0x7f0f40004ca0 INFO                GST_PADS gstpad.c:2523:gst_pad_link_full: linked manager:recv_rtp_src_2_13568361_107 and recv_rtp_src_2_13568361_107:proxypad10, successful
0:00:03.223200300   101 0x7f0f40004ca0 INFO               GST_EVENT gstevent.c:1511:gst_event_new_reconfigure: creating reconfigure event
0:00:03.223284700   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstelement.c:668:gst_element_add_pad:<rtspsrc0> adding pad 'recv_rtp_src_2_13568361_107'
0:00:03.223345400   101 0x7f0f40004ca0 INFO            GST_PIPELINE grammar.y:518:gst_parse_found_pad: trying delayed linking some pad of GstRTSPSrc named rtspsrc0 to some pad of GstRtpH264Depay named rtph264depay0
0:00:03.223457000   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstutils.c:1633:gst_element_link_pads_full: trying to link element rtspsrc0:(any) to element rtph264depay0:(any)
0:00:03.223476100   101 0x7f0f40004ca0 INFO                GST_PADS gstutils.c:933:gst_pad_check_link: trying to link rtspsrc0:recv_rtp_src_2_13568361_107 and rtph264depay0:sink
0:00:03.223589700   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstutils.c:1125:gst_element_get_compatible_pad:<rtph264depay0> Could not find a compatible pad to link to rtspsrc0:recv_rtp_src_2_13568361_107
0:00:03.223713800   101 0x7f0f40004ca0 INFO                GST_PADS gstutils.c:933:gst_pad_check_link: trying to link rtspsrc0:recv_rtp_src_2_13568361_107 and rtph264depay0:sink
0:00:03.223822400   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstelement.c:913:gst_element_get_static_pad: no such pad 'stream_%u' in element "rtspsrc0"
0:00:03.223906700   101 0x7f0f40004ca0 INFO        GST_ELEMENT_PADS gstutils.c:1125:gst_element_get_compatible_pad:<rtspsrc0> Could not find a compatible pad to link to rtph264depay0:sink
0:00:03.224049400   101 0x7f0f40004ca0 INFO                 default gstutils.c:2022:gst_element_link_pads_filtered: Could not link pads: rtspsrc0:(null) - rtph264depay0:(null)
0:00:03.224218400   101 0x7f0f40004ca0 INFO                GST_PADS gstpad.c:4112:gst_pad_peer_query:<rtspsrc0:recv_rtp_src_2_13568361_107> pad has no peer
0:00:03.224255200   101 0x7f0f40004ca0 INFO                GST_PADS gstpad.c:4112:gst_pad_peer_query:<rtspsrc0:recv_rtp_src_2_13568361_107> pad has no peer
0:00:03.224356900   101 0x7f0f40004ca0 INFO                    task gsttask.c:318:gst_task_func:<rtpjitterbuffer0:src> Task going to paused
0:00:03.225958900   101 0x7f0f880e0800 INFO                 basesrc gstbasesrc.c:2860:gst_base_src_loop:<udpsrc5> pausing after gst_pad_push() = not-linked
0:00:03.226006200   101 0x7f0f880e0800 WARN                 basesrc gstbasesrc.c:2950:gst_base_src_loop:<udpsrc5> error: Internal data stream error.
0:00:03.226017100   101 0x7f0f880e0800 WARN                 basesrc gstbasesrc.c:2950:gst_base_src_loop:<udpsrc5> error: streaming stopped, reason not-linked (-1)
0:00:03.226037700   101 0x7f0f880e0800 INFO        GST_ERROR_SYSTEM gstelement.c:1890:gst_element_message_full_with_details:<udpsrc5> posting message: Internal data stream error.
0:00:03.226064000   101 0x7f0f880e0800 INFO        GST_ERROR_SYSTEM gstelement.c:1917:gst_element_message_full_with_details:<udpsrc5> posted error message: Internal data stream error.
0:00:03.226114800   101 0x7f0f880e0800 INFO                    task gsttask.c:318:gst_task_func:<udpsrc5:src> Task going to paused
0:00:03.230993000   101 0x7f0f880e0230 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtcp
0:00:03.231055500   101 0x7f0f880e0230 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.246577900   101 0x7f0f880e01e0 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtcp
0:00:03.246651300   101 0x7f0f880e01e0 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.274443500   101 0x7f0f880e0190 INFO               GST_EVENT gstevent.c:808:gst_event_new_caps: creating caps event application/x-rtcp
0:00:03.274660500   101 0x7f0f880e0190 INFO               GST_EVENT gstevent.c:889:gst_event_new_segment: creating segment event 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:03.460218000   101      0x2167680 WARN                 rtspsrc gstrtspsrc.c:4754:gst_rtspsrc_loop_udp:<rtspsrc0> warning: The server closed the connection.
0:00:03.460288000   101      0x2167680 INFO        GST_ERROR_SYSTEM gstelement.c:1890:gst_element_message_full_with_details:<rtspsrc0> posting message: Could not read from resource.
0:00:03.460346500   101      0x2167680 INFO        GST_ERROR_SYSTEM gstelement.c:1917:gst_element_message_full_with_details:<rtspsrc0> posted warning message: Could not read from resource.
WARNING: from element /GstPipeline:pipeline0/GstRTSPSrc:rtspsrc0: Could not read from resource.
Additional debug info:
gstrtspsrc.c(4754): gst_rtspsrc_loop_udp (): /GstPipeline:pipeline0/GstRTSPSrc:rtspsrc0:
The server closed the connection.

Any design considerations/constraints We're looking to use KVS with existing cameras that can't send video directly to KVS but that can stream the video using RTSP, so this is doing the mapping for us.

vbbandeira commented 3 years ago

hello there, try use h264parse after ! rtph264depay !

daveisfera commented 3 years ago

That worked! Thanks!