test-rtp-7[3336]: GLIB DEBUG farsight-rtp - Changing state of the pipeline to PAUSED
0:00:17.098297000  3336 0x13050 DEBUG             basesink gstbasesink.c:2900:gst_base_sink_change_state:<dsppcmsink0> PLAYING to PAUSED
0:00:17.107300000  3336 0x13050 DEBUG             basesink gstbasesink.c:2091:gst_base_sink_is_prerolled:<dsppcmsink0> have_preroll: 0, EOS: 0 => prerolled: 0
0:00:17.112427000  3336 0x13050 DEBUG             basesink gstbasesink.c:2924:gst_base_sink_change_state:<dsppcmsink0> PLAYING to PAUSED, need preroll
0:00:17.115845000  3336 0x13050 DEBUG             basesink gstbasesink.c:2933:gst_base_sink_change_state:<dsppcmsink0> rendered: 73, dropped: 0
0:00:17.111694000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<capsfilter2:sink> calling chainfunction &gst_base_transform_chain
0:00:17.123810000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<rtpilbcpay0:sink> calling chainfunction &0x401b59a0
0:00:17.148529000  3336 0x13050 DEBUG             basesink gstbasesink.c:2900:gst_base_sink_change_state:<rtpsinkelement> PLAYING to PAUSED
0:00:17.156250000  3336 0x13050 DEBUG             basesink gstbasesink.c:2091:gst_base_sink_is_prerolled:<rtpsinkelement> have_preroll: 0, EOS: 0 => prerolled: 0
0:00:17.157745000  3336 0x13050 DEBUG             basesink gstbasesink.c:2924:gst_base_sink_change_state:<rtpsinkelement> PLAYING to PAUSED, need preroll
0:00:17.160187000  3336 0x13050 DEBUG             basesink gstbasesink.c:2933:gst_base_sink_change_state:<rtpsinkelement> rendered: 73, dropped: 0
test-rtp-7[3336]: GLIB DEBUG farsight-rtp - Waiting for state to change to PAUSED - 3
test-rtp-7[3336]: GLIB DEBUG farsight-rtp - Changing state of the pipeline to PLAYING - 3
0:00:17.262909000  3336 0x13050 DEBUG             basesink gstbasesink.c:2091:gst_base_sink_is_prerolled:<dsppcmsink0> have_preroll: 0, EOS: 0 => prerolled: 0
0:00:17.264832000  3336 0x13050 DEBUG             basesink gstbasesink.c:2765:gst_base_sink_query:<dsppcmsink0> not prerolled yet, can't report latency
0:00:17.267120000  3336 0x13050 DEBUG             basesink gstbasesink.c:2091:gst_base_sink_is_prerolled:<rtpsinkelement> have_preroll: 0, EOS: 0 => prerolled: 0
0:00:17.269104000  3336 0x13050 DEBUG             basesink gstbasesink.c:2765:gst_base_sink_query:<rtpsinkelement> not prerolled yet, can't report latency
test-rtp-7[3336]: GLIB DEBUG farsight-rtp - Clock provider found
0:00:17.161835000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<src:proxypad7> calling chainfunction &gst_proxy_pad_do_chain
0:00:17.334625000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<rtpdtmfmux0:sink_01> calling chainfunction &0x401ea8c4
0:00:17.340912000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<rtpbin0:sink> calling chainfunction &gst_proxy_pad_do_chain
0:00:17.346405000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<rtpsend0:datasink> calling chainfunction &0x40186628
0:00:17.348145000  3348 0xf8bf0 DEBUG              rtpsend gstrtpsend.c:338:gst_rtpsend_datasink_chain:<rtpsend0> 1. Outgoing RTP packet going straight out without jrtplib 0xdab08 62
0:00:17.350861000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<rtpsrc:proxypad3> calling chainfunction &gst_proxy_pad_do_chain
0:00:17.352814000  3348 0xf8bf0 LOG         GST_SCHEDULING gstpad.c:3449:gst_pad_chain_unchecked:<rtpsinkelement:sink> calling chainfunction &gst_base_sink_chain
0:00:17.354767000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:2141:gst_base_sink_chain_unlocked:<rtpsinkelement> got times start: 99:99:99.999999999, end: 99:99:99.999999999
0:00:17.356354000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:1842:gst_base_sink_queue_object_unlocked:<rtpsinkelement> now 1 prerolled items
0:00:17.357849000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:1779:gst_base_sink_preroll_object:<rtpsinkelement> do preroll 0xe0228
0:00:17.359284000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:1787:gst_base_sink_preroll_object:<rtpsinkelement> preroll buffer 99:99:99.999999999
0:00:17.360748000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:940:gst_base_sink_commit_state:<rtpsinkelement> commiting state to PAUSED
0:00:17.364594000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:1089:gst_base_sink_get_sync_times:<rtpsinkelement> got times start: 99:99:99.999999999, stop: 99:99:99.999999999, do_sync 0
0:00:17.370422000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:1322:gst_base_sink_do_sync:<rtpsinkelement> prerolling object 0xe0228
0:00:17.371918000  3348 0xf8bf0 DEBUG             basesink gstbasesink.c:1238:gst_base_sink_wait_preroll:<rtpsinkelement> wait for preroll...