0:00:01.166598000 10372 0x80cafe8 DEBUG      rtpjitterbuffer gstrtpjitterbuffer.c:311:gst_rtp_jitter_buffer_loop:<rtpjitterbuffer0> Poped buffer #28834 from queue 7
0:00:01.167580000 10372 0x80cafe8 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<audioclock0> internal time 0:00:00.000000000
0:00:01.167640000 10372 0x80cafe8 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<audioclock0> internal time 0:00:00.000000000
0:00:01.167675000 10372 0x80cafe8 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<GstSystemClock> internal time 325434:41:44.687776000
0:00:01.167705000 10372 0x80cafe8 DEBUG            GST_CLOCK gstclock.c:829:gst_clock_get_time:<GstSystemClock> adjusted time 325434:41:44.687776000
0:00:01.167741000 10372 0x80cafe8 DEBUG            GST_CLOCK gstclock.c:170:gst_clock_entry_new:<GstSystemClock> created entry 0x8111568, time 325434:41:44.687776000
0:00:01.167772000 10372 0x80cafe8 DEBUG            GST_CLOCK gstsystemclock.c:472:gst_system_clock_id_wait_async: adding entry 0x8111568
0:00:01.167807000 10372 0x80cafe8 DEBUG            GST_CLOCK gstsystemclock.c:485:gst_system_clock_id_wait_async: send signal
0:00:01.168077000 10372 0x80cafe8 DEBUG      rtpjitterbuffer gstrtpjitterbuffer.c:311:gst_rtp_jitter_buffer_loop:<rtpjitterbuffer0> Poped buffer #28835 from queue 6
0:00:01.168492000 10372 0x80cafe8 DEBUG      rtpjitterbuffer gstrtpjitterbuffer.c:311:gst_rtp_jitter_buffer_loop:<rtpjitterbuffer0> Poped buffer #28836 from queue 5
0:00:01.169589000 10372 0x8101000 DEBUG            GST_CLOCK gstsystemclock.c:261:gst_system_clock_async_thread: got signal
0:00:01.169628000 10372 0x8101000 DEBUG            GST_CLOCK gstsystemclock.c:397:gst_system_clock_id_wait_jitter_unlocked: entry 0x8111568 target 325434:41:44.687778000 entry 325434:41:44.687776000 now 325434:41:44.689730000 real 325434:41:44.689730000 diff -1954000
0:00:01.169655000 10372 0x8101000 DEBUG            GST_CLOCK gstsystemclock.c:292:gst_system_clock_async_thread: async entry 0x8111568 unlocked
0:00:01.169697000 10372 0x810cec8 DEBUG            GST_CLOCK gstsystemclock.c:431:gst_system_clock_id_wait_jitter_unlocked: entry 0x8111050 unlocked with signal
0:00:01.169755000 10372 0x810cec8 DEBUG            GST_CLOCK gstclock.c:414:gst_clock_id_wait:<GstSystemClock> done waiting entry 0x8111050, res: 3
0:00:01.169786000 10372 0x810cec8 DEBUG            GST_CLOCK gstclock.c:209:_gst_clock_id_free: freed entry 0x8111050
0:00:01.169813000 10372 0x810cec8 LOG                basesrc gstbasesrc.c:1266:gst_base_src_do_sync:<audiotestsrc0> clock entry done: 3
0:00:01.169837000 10372 0x810cec8 DEBUG              basesrc gstbasesrc.c:1406:gst_base_src_get_range:<audiotestsrc0> clock returned 3, not returning
0:00:01.169867000 10372 0x810cec8 INFO               basesrc gstbasesrc.c:1552:gst_base_src_loop:<audiotestsrc0> pausing after gst_base_src_get_range() = -2
0:00:01.169894000 10372 0x810cec8 DEBUG              basesrc gstbasesrc.c:1623:gst_base_src_loop:<audiotestsrc0> pausing task, reason wrong-state
0:00:01.171734000 10372 0x8101000 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<audioclock0> internal time 0:00:00.001000000
0:00:01.171791000 10372 0x8101000 DEBUG            GST_CLOCK gstclock.c:788:gst_clock_get_internal_time:<GstSystemClock> internal time 325434:41:44.691887000
0:00:01.171813000 10372 0x8101000 DEBUG            GST_CLOCK gstclock.c:829:gst_clock_get_time:<GstSystemClock> adjusted time 325434:41:44.691887000
0:00:01.171834000 10372 0x8101000 DEBUG            GST_CLOCK gstclock.c:932:gst_clock_slave_callback:<audioclock0> master 325434:41:44.691887000, slave 0:00:00.001000000
0:00:01.171871000 10372 0x8101000 DEBUG            GST_CLOCK gstsystemclock.c:397:gst_system_clock_id_wait_jitter_unlocked: entry 0x8111568 target 325434:41:44.787777000 entry 325434:41:44.787776000 now 325434:41:44.691973000 real 325434:41:44.691973000 diff 95803000