

0:01:20.846429045 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer0> no buffer, going to wait
0:01:20.846438028 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.846442004 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13752 at time 0:00:49.744953650, discont 0
0:01:20.846450807 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.846472660 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.846499149 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13752, 0:00:49.803340859
0:01:20.846520289 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.846537749 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803340859 with sync time 2:06:35.321956621
0:01:20.846572876 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.000023222 rtptime 0:00:00.000000000, clock-rate 90000, diff 0:00:00.000023222, jitter: 0:00:00.000551721
0:01:20.846601435 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13752, got #13752, gap of 0
0:01:20.846614392 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13752<->#13752 gap 0
0:01:20.846626299 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.846635442 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13752->13753 to 0:00:49.803293613
0:01:20.846648650 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.846688409 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.846719008 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13752, now 1 packets, head: 1, percent -1
0:01:20.846733536 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.846744087 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.846756098 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13753, 0:00:49.803293613
0:01:20.846768351 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.846777894 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803293613 with sync time 2:06:35.321909375
0:01:20.846780651 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13753 at time 0:00:49.744978357, discont 0
0:01:20.846803949 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.846853224 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer0> Pushing buffer 13752, dts 0:00:49.744953650, pts 0:00:49.741980775
0:01:20.846855900 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.000024707 rtptime 0:00:00.000000000, clock-rate 90000, diff 0:00:00.000024707, jitter: 0:00:00.000518782
0:01:20.846940943 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13753, got #13753, gap of 0
0:01:20.846971139 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13753<->#13753 gap 0
0:01:20.846985812 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.846995459 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13753->13754 to 0:00:49.803252442
0:01:20.847006919 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.847039016 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13753, now 1 packets, head: 1, percent -1
0:01:20.847081041 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer0> Pushing buffer 13753, dts 0:00:49.744978357, pts 0:00:49.741980775
0:01:20.847118588 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer0> no buffer, going to wait
0:01:20.847125267 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13754 at time 0:00:49.745001286, discont 0
0:01:20.847137177 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.847170456 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.847182373 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.847193541 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13754, 0:00:49.803252442
0:01:20.847204906 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.847214532 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803252442 with sync time 2:06:35.321868204
0:01:20.847233306 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.000022929 rtptime 0:00:00.000000000, clock-rate 90000, diff 0:00:00.000022929, jitter: 0:00:00.000487791
0:01:20.847409825 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13754, got #13754, gap of 0
0:01:20.847430460 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13754<->#13754 gap 0
0:01:20.847447762 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.847463400 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13754->13755 to 0:00:49.803213389
0:01:20.847481426 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.847611896 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.847641399 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13754, now 1 packets, head: 1, percent -1


0:01:20.847714272 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13755 at time 0:00:49.745023998, discont 0
0:01:20.847733432 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.847763722 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.847781697 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13755, 0:00:49.803213389
0:01:20.847800985 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.847818578 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803213389 with sync time 2:06:35.321829151
0:01:20.847843914 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.847864612 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer0> Pushing buffer 13754, dts 0:00:49.745001286, pts 0:00:49.741980775
0:01:20.847869372 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.000022712 rtptime 0:00:00.000000000, clock-rate 90000, diff 0:00:00.000022712, jitter: 0:00:00.000458723
0:01:20.848013592 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13755, got #13755, gap of 0
0:01:20.848026692 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13755<->#13755 gap 0
0:01:20.848048630 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.848056635 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13755->13756 to 0:00:49.803177965
0:01:20.848068597 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.848161133 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13755, now 1 packets, head: 1, percent -1
0:01:20.848268716 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer0> Pushing buffer 13755, dts 0:00:49.745023998, pts 0:00:49.741980775
0:01:20.848304052 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer0> no buffer, going to wait
0:01:20.848308269 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13756 at time 0:00:49.745046477, discont 0
0:01:20.848314262 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.848351606 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.848368452 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.848386329 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13756, 0:00:49.803177965
0:01:20.848404729 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.848420877 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803177965 with sync time 2:06:35.321793727
0:01:20.848448224 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.000022479 rtptime 0:00:00.000000000, clock-rate 90000, diff 0:00:00.000022479, jitter: 0:00:00.000431457
0:01:20.848477407 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13756, got #13756, gap of 0
0:01:20.848490682 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13756<->#13756 gap 0
0:01:20.848503005 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.848512517 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13756->13757 to 0:00:49.803145912
0:01:20.848526085 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.848568478 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.848600140 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13756, now 1 packets, head: 1, percent -1
0:01:20.848627878 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.848651203 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.848648848 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer0> Pushing buffer 13756, dts 0:00:49.745046477, pts 0:00:49.741980775
0:01:20.848690476 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.848703610 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13757, 0:00:49.803145912
0:01:20.848711326 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13757 at time 0:00:49.745068228, discont 0
0:01:20.848716030 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.848743138 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803145912 with sync time 2:06:35.321761674
0:01:20.848763428 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer0> no buffer, going to wait
0:01:20.848772711 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.848798723 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.000021751 rtptime 0:00:00.000000000, clock-rate 90000, diff 0:00:00.000021751, jitter: 0:00:00.000405850
0:01:20.848829787 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13757, got #13757, gap of 0
0:01:20.848841135 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13757<->#13757 gap 0
0:01:20.848851687 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.848860847 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13757->13758 to 0:00:49.803116449
0:01:20.848873913 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.848905655 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.848940534 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13757, now 1 packets, head: 1, percent -1
0:01:20.848959785 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer0> sync unscheduled
0:01:20.848972247 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.686681327
0:01:20.848981964 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.803116449
0:01:20.849001816 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.849012197 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.803116449 with sync time 2:06:35.321732211
0:01:20.849037773 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.849067279 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer0> Pushing buffer 13757, dts 0:00:49.745068228, pts 0:00:49.741980775
0:01:20.849173812 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer0> no buffer, going to wait
0:01:20.849236754 10727 0x7f35a8002a30 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.856155403 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Received packet #20957 at time 0:00:49.757382359, discont 0
0:01:20.856196695 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer1> dtsdiff 0:00:00.020658332 rtptime 0:00:00.020000000, clock-rate 8000, diff 0:00:00.000658332, jitter: 0:00:00.001354842
0:01:20.856222088 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> expected #20957, got #20957, gap of 0
0:01:20.856234432 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1930:calculate_packet_spacing:<rtpjitterbuffer1> new packet spacing 0:00:00.020658332
0:01:20.856247634 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:464:calculate_skew: extrtp 4127176640, gstrtp 143:18:17.080000000, base 143:17:29.440000000, send_diff 0:00:47.640000000
0:01:20.856265475 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:487:calculate_skew: time 0:00:49.757382359, base 0:00:02.112685063, recv_diff 0:00:47.644697296, slope 7
0:01:20.856281639 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:566:calculate_skew: delta 4697296, new min: -648270
0:01:20.856292714 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:619:calculate_skew: skew -987811, out 0:00:49.751697252
0:01:20.856306371 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1690:add_timer:<rtpjitterbuffer1> add timer 0 for seqnum 20958 to 0:00:49.778040691, delay 0:00:00.002709684
0:01:20.856321910 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1708:add_timer: signal timer
0:01:20.856336093 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.856355547 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Pushed packet #20957, now 1 packets, head: 1, percent -1
0:01:20.856381917 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2997:wait_next_timeout: waiting timer done
0:01:20.856403225 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer1> now 0:00:49.735616191
0:01:20.856435412 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer1> 0, 0, 20958, 0:00:49.780750375
0:01:20.856444956 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer1> new best 0
0:01:20.856451297 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer1> sync to timestamp 0:00:49.780750375 with sync time 2:06:35.299366137
0:01:20.856466800 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.856483176 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer1> Pushing buffer 20957, dts 0:00:49.770781142, pts 0:00:49.765096035
0:01:20.856568033 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer1> no buffer, going to wait
0:01:20.856585208 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.868149740 10727 0x7f35c400f400 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpssrcdemux5:rtcp_src_26330237> type: 200
0:01:20.868199432 10727 0x7f35c400f400 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpssrcdemux5:rtcp_src_26330237> type: 202
0:01:20.868354028 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3175:gst_rtp_jitter_buffer_chain_rtcp:<rtpjitterbuffer0> received RTCP of SSRC 0191c47d
0:01:20.868389340 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3085:do_handle_sync:<rtpjitterbuffer0> ext SR 2012867052, base 2008578282, clock-rate 90000, clock-base 18446744073709551615, last-rtptime 2012863722
0:01:20.868422295 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3111:do_handle_sync:<rtpjitterbuffer0> ext last 2012863722, diff 3330
0:01:20.868450143 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3127:do_handle_sync:<rtpjitterbuffer0> signaling sync
0:01:20.871377043 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Received packet #20958 at time 0:00:49.772590191, discont 0
0:01:20.871416476 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer1> dtsdiff 0:00:00.015207832 rtptime 0:00:00.020000000, clock-rate 8000, diff 0:00:00.004792168, jitter: 0:00:00.001569674
0:01:20.871441590 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> expected #20958, got #20958, gap of 0
0:01:20.871455699 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1930:calculate_packet_spacing:<rtpjitterbuffer1> new packet spacing 0:00:00.015207832
0:01:20.871470997 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:464:calculate_skew: extrtp 4127176800, gstrtp 143:18:17.100000000, base 143:17:29.440000000, send_diff 0:00:47.660000000
0:01:20.871491689 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:487:calculate_skew: time 0:00:49.772590191, base 0:00:02.112685063, recv_diff 0:00:47.659905128, slope 8
0:01:20.871510862 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:566:calculate_skew: delta -94872, new min: -648270
0:01:20.871522621 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:619:calculate_skew: skew -985094, out 0:00:49.771699969
0:01:20.871536968 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer1> 0, 0, #20958<->#20958 gap 0
0:01:20.871549894 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.871560174 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer1> replace timer for seqnum 20958->20959 to 0:00:49.790937371
0:01:20.871574467 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer1> unschedule current timer
0:01:20.871637009 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.871673331 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Pushed packet #20958, now 1 packets, head: 1, percent -1
0:01:20.871717644 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer1> sync unscheduled
0:01:20.871745139 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer1> now 0:00:49.735616191
0:01:20.871769220 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer1> 0, 0, 20959, 0:00:49.790937371
0:01:20.871785013 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer1> new best 0
0:01:20.871796332 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer1> sync to timestamp 0:00:49.790937371 with sync time 2:06:35.309553133
0:01:20.871825425 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.871857455 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer1> Pushing buffer 20958, dts 0:00:49.785988974, pts 0:00:49.785098752
0:01:20.871944593 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer1> no buffer, going to wait
0:01:20.871964012 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.889673481 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer1> sync done, 0, #20959, -17762068
0:01:20.889728912 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer1> now 0:00:49.790937371
0:01:20.889753513 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer1> 0, 0, 20959, 0:00:49.790937371
0:01:20.889775505 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer1> new best 0
0:01:20.889790889 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer1> expected 20959 didn't arrive, now 0:00:49.790937371
0:01:20.889811102 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer1> timeout 0:00:00.004454168, period 0:00:00.195545832
0:01:20.889862659 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer1> base 0:00:49.787798023, delay 0:00:00.003139348, retry 0:00:00.004454168, num_retry 1
0:01:20.889891320 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer1> replace timer for seqnum 20959->20959 to 0:00:49.795391539
0:01:20.889995327 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer1> now 0:00:49.790937371
0:01:20.890029291 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer1> 0, 0, 20959, 0:00:49.795391539
0:01:20.890064138 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer1> new best 0
0:01:20.890089453 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer1> sync to timestamp 0:00:49.795391539 with sync time 2:06:35.314007301
0:01:20.890132758 10727 0x7f35c400f000 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_0> type: 201
0:01:20.890152789 10727 0x7f35c400f000 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_0> type: 202
0:01:20.890163253 10727 0x7f35c400f000 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_0> type: 205
0:01:20.890173333 10727 0x7f35c400f000 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 326135639 about 480175297 with FCI of length 4
0:01:20.890188424 10727 0x7f35c400f000 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bfffe6d0 NACK (1)
0:01:20.892058383 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Received packet #20959 at time 0:00:49.793199245, discont 0
0:01:20.892113939 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer1> dtsdiff 0:00:00.020609054 rtptime 0:00:00.020000000, clock-rate 8000, diff 0:00:00.000609054, jitter: 0:00:00.001509635
0:01:20.892168127 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> expected #20959, got #20959, gap of 0
0:01:20.892198170 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1930:calculate_packet_spacing:<rtpjitterbuffer1> new packet spacing 0:00:00.020609054
0:01:20.892228041 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:464:calculate_skew: extrtp 4127176960, gstrtp 143:18:17.120000000, base 143:17:29.440000000, send_diff 0:00:47.680000000
0:01:20.892272145 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:487:calculate_skew: time 0:00:49.793199245, base 0:00:02.112685063, recv_diff 0:00:47.680514182, slope 7
0:01:20.892313362 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:566:calculate_skew: delta 514182, new min: -648270
0:01:20.892338823 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:619:calculate_skew: skew -982399, out 0:00:49.791702664
0:01:20.892368140 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer1> 0, 0, #20959<->#20959 gap 0
0:01:20.892395759 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.892417729 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:1877:update_timers:<rtpjitterbuffer1> RTX success 475, failed 101, requests 576, dups 0, avg-num 1.6983, delay 0:00:00.001983928, avg-rtt 0:00:00.001398458
0:01:20.892468965 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer1> unschedule current timer
0:01:20.892590302 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1781:remove_timer:<rtpjitterbuffer1> removed index 0
0:01:20.892613691 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.892631442 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Pushed packet #20959, now 1 packets, head: 1, percent -1
0:01:20.892647007 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.892659903 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer1> Pushing buffer 20959, dts 0:00:49.806598028, pts 0:00:49.805101447
0:01:20.892754365 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer1> no buffer, going to wait
0:01:20.892782287 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.892810762 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2990:wait_next_timeout:<rtpjitterbuffer1> sync unscheduled
0:01:20.892841209 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer1> now 0:00:49.790937371
0:01:20.892872029 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2997:wait_next_timeout: waiting timer
0:01:20.901841107 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -52729105
0:01:20.901879223 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.803116449
0:01:20.901893688 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.803116449
0:01:20.901907916 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.901919016 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.803116449
0:01:20.901932688 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.901965414 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.001609643, num_retry 1
0:01:20.901984412 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.804726092
0:01:20.902136663 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.902151834 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.902160705 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.902170526 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.902185032 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.902600266 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.803116449
0:01:20.902615605 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.804726092
0:01:20.902628329 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.902637091 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.804726092 with sync time 2:06:35.323341854
0:01:20.903436726 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -719995
0:01:20.903455988 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.804726092
0:01:20.903467476 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.804726092
0:01:20.903479718 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.903488950 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.804726092
0:01:20.903500671 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.903523744 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.003219286, num_retry 2
0:01:20.903539072 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.806335735
0:01:20.903690197 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.903713339 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.903729528 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.903744932 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.903764688 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.903860084 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.804726092
0:01:20.903882407 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.806335735
0:01:20.903901403 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.903916456 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.806335735 with sync time 2:06:35.324951497
0:01:20.905048479 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -1044364
0:01:20.905077567 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.806335735
0:01:20.905097588 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.806335735
0:01:20.905117901 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.905136006 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.806335735
0:01:20.905155617 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.905189484 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.004828929, num_retry 3
0:01:20.905212421 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.807945378
0:01:20.905351149 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.905374372 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.905390616 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.905406237 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.905426695 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.905526246 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.806335735
0:01:20.905547606 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.807945378
0:01:20.905566727 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.905581704 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.807945378 with sync time 2:06:35.326561140
0:01:20.906703920 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -987718
0:01:20.906735171 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.807945378
0:01:20.906753984 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.807945378
0:01:20.906773381 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.906790863 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.807945378
0:01:20.906810465 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.906846515 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.006438572, num_retry 4
0:01:20.906872098 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.809555021
0:01:20.907028089 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.907053088 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.907071533 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.907090787 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.907114129 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.907226521 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.807945378
0:01:20.907249702 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.809555021
0:01:20.907270964 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.907289610 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.809555021 with sync time 2:06:35.328170783
0:01:20.908268740 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -888306
0:01:20.908295409 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.809555021
0:01:20.908313071 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.809555021
0:01:20.908331196 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.908347368 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.809555021
0:01:20.908366417 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.908403697 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.008048215, num_retry 5
0:01:20.908429638 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.811164664
0:01:20.908582737 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.908603894 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.908619576 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.908634554 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.908655683 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.908768782 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.809555021
0:01:20.908791577 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.811164664
0:01:20.908812583 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.908829907 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.811164664 with sync time 2:06:35.329780426
0:01:20.909889363 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -957618
0:01:20.909919711 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.811164664
0:01:20.909935842 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.811164664
0:01:20.909950884 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.909962184 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.811164664
0:01:20.909977852 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.910020521 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.009657858, num_retry 6
0:01:20.910040890 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.812774307
0:01:20.910277428 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.910296756 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.910307779 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.910320497 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.910336546 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.910525971 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.811164664
0:01:20.910554598 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.812774307
0:01:20.910569630 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.910581072 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.812774307 with sync time 2:06:35.331390069
0:01:20.911497012 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -821683
0:01:20.911536615 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.812774307
0:01:20.911558427 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.812774307
0:01:20.911580597 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.911599386 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.812774307
0:01:20.911622396 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.911666083 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.011267501, num_retry 7
0:01:20.911694496 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.814383950
0:01:20.911808632 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.812774307
0:01:20.911846728 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.814383950
0:01:20.911887392 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.911908468 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.814383950 with sync time 2:06:35.332999712
0:01:20.911894796 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.911944344 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.911956017 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.911964555 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.911974558 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.913104834 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2988:wait_next_timeout:<rtpjitterbuffer0> sync done, 0, #13758, -1079934
0:01:20.913128731 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.814383950
0:01:20.913140159 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.814383950
0:01:20.913151936 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.913161331 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2681:do_expected_timeout:<rtpjitterbuffer0> expected 13758 didn't arrive, now 0:00:49.814383950
0:01:20.913172669 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2704:do_expected_timeout:<rtpjitterbuffer0> timeout 0:00:00.001609643, period 0:00:00.198390357
0:01:20.913201721 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2737:do_expected_timeout:<rtpjitterbuffer0> base 0:00:49.802304749, delay 0:00:00.000811700, retry 0:00:00.012877144, num_retry 8
0:01:20.913219903 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1731:reschedule_timer:<rtpjitterbuffer0> replace timer for seqnum 13758->13758 to 0:00:49.815993593
0:01:20.913342611 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 201
0:01:20.913383416 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 202
0:01:20.913403495 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1105:rtcp_probe:<rtpbin:send_rtcp_src_1> type: 205
0:01:20.913422534 10727 0x7f35c400f370 DEBUG         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1055:rtp_session_process_feedback: received/sent feedback 205:1 from 256279768 about 26330237 with FCI of length 4
0:01:20.913444583 10727 0x7f35c400f370 ERROR         webrtcendpoint /home/mparis/eclipse_workspaces/kurento-workspace/kms-elements/src/gst-plugins/kmswebrtcendpoint.c:1073:rtp_session_process_feedback:0x7f35bf7fd6d0 NACK (1)
0:01:20.913553244 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer0> now 0:00:49.814383950
0:01:20.913575167 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer0> 0, 0, 13758, 0:00:49.815993593
0:01:20.913594850 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer0> new best 0
0:01:20.913611231 10727 0x7f35a8002ad0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer0> sync to timestamp 0:00:49.815993593 with sync time 2:06:35.334609355
0:01:20.913915713 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Received packet #20960 at time 0:00:49.815172017, discont 0
0:01:20.913941111 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer1> dtsdiff 0:00:00.021972772 rtptime 0:00:00.020000000, clock-rate 8000, diff 0:00:00.001972772, jitter: 0:00:00.001538581
0:01:20.913963638 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> expected #20960, got #20960, gap of 0
0:01:20.913976281 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1930:calculate_packet_spacing:<rtpjitterbuffer1> new packet spacing 0:00:00.021972772
0:01:20.913989428 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:464:calculate_skew: extrtp 4127177120, gstrtp 143:18:17.140000000, base 143:17:29.440000000, send_diff 0:00:47.700000000
0:01:20.914007020 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:487:calculate_skew: time 0:00:49.815172017, base 0:00:02.112685063, recv_diff 0:00:47.702486954, slope 7
0:01:20.914018947 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:566:calculate_skew: delta 2486954, new min: -648270
0:01:20.914025652 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:619:calculate_skew: skew -979725, out 0:00:49.811705338
0:01:20.914034945 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1690:add_timer:<rtpjitterbuffer1> add timer 0 for seqnum 20961 to 0:00:49.837144789, delay 0:00:00.003077162
0:01:20.914044557 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1708:add_timer: signal timer
0:01:20.914055747 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.914075957 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer1> Pushed packet #20960, now 1 packets, head: 1, percent -1
0:01:20.914093199 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event done
0:01:20.914110228 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2997:wait_next_timeout: waiting timer done
0:01:20.914125507 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2894:wait_next_timeout:<rtpjitterbuffer1> now 0:00:49.790937371
0:01:20.914138056 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2903:wait_next_timeout:<rtpjitterbuffer1> 0, 0, 20961, 0:00:49.840221951
0:01:20.914151097 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2928:wait_next_timeout:<rtpjitterbuffer1> new best 0
0:01:20.914160324 10727 0x7f35a8002800 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2965:wait_next_timeout:<rtpjitterbuffer1> sync to timestamp 0:00:49.840221951 with sync time 2:06:35.358837713
0:01:20.914107816 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2543:pop_and_push_next:<rtpjitterbuffer1> Pushing buffer 20960, dts 0:00:49.828570800, pts 0:00:49.825104121
0:01:20.914177304 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2125:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Received packet #13758 at time 0:00:49.815430721, discont 0
0:01:20.914203058 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:2064:calculate_jitter:<rtpjitterbuffer0> dtsdiff 0:00:00.070362493 rtptime 0:00:00.068000000, clock-rate 90000, diff 0:00:00.002362493, jitter: 0:00:00.000528140
0:01:20.914222020 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2173:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> expected #13758, got #13758, gap of 0
0:01:20.914232684 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1930:calculate_packet_spacing:<rtpjitterbuffer0> new packet spacing 0:00:00.070687336
0:01:20.914247960 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:464:calculate_skew: extrtp 2012869842, gstrtp 6:12:45.220466666, base 6:11:57.536466666, send_diff 0:00:47.684000000
0:01:20.914263370 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:487:calculate_skew: time 0:00:49.815430721, base 0:00:02.131945371, recv_diff 0:00:47.683485350, slope 8
0:01:20.914277938 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:566:calculate_skew: delta -514650, new min: -6620013
0:01:20.914290571 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer rtpjitterbuffer.c:619:calculate_skew: skew -5969839, out 0:00:49.809975532
0:01:20.914302730 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1823:update_timers:<rtpjitterbuffer0> 0, 0, #13758<->#13758 gap 0
0:01:20.914314815 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1826:update_timers: found timer for current seqnum
0:01:20.914238984 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2659:handle_next_buffer:<rtpjitterbuffer1> no buffer, going to wait
0:01:20.914331897 10727 0x7f35a8002ca0 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:3025:gst_rtp_jitter_buffer_loop: waiting event
0:01:20.914324413 10727 0x7f35c400f400 LOG          rtpjitterbuffer gstrtpjitterbuffer.c:1877:update_timers:<rtpjitterbuffer0> RTX success 290, failed 992, requests 1282, dups 0, avg-num 5.9491, delay 0:00:00.000875368, avg-rtt 0:00:00.000807621
0:01:20.914458611 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1639:unschedule_current_timer:<rtpjitterbuffer0> unschedule current timer
0:01:20.914496079 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:1781:remove_timer:<rtpjitterbuffer0> removed index 0
0:01:20.914522328 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2295:gst_rtp_jitter_buffer_chain: signal event
0:01:20.914548503 10727 0x7f35c400f400 DEBUG        rtpjitterbuffer gstrtpjitterbuffer.c:2307:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0> Pushed packet #13758, now 1 packets, head: 1, percent -1