0:00:00.117595796 [334m26637[00m      0x1dcb010 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2771:gst_decode_bin_plugin_init:[00m binding text domain gst-plugins-base-0.10 to locale dir /usr/share/locale
Setting pipeline to PAUSED ...
0:00:00.685915922 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2771:gst_decode_bin_plugin_init:[00m binding text domain gst-plugins-base-0.10 to locale dir /usr/share/locale
Pipeline is PREROLLING ...
0:00:07.096833522 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:858:gst_decode_bin_set_subs_encoding:<decodebin20>[00m Setting new encoding: (NULL)
0:00:07.098312932 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:836:gst_decode_bin_set_sink_caps:<decodebin20>[00m Setting new caps: application/ogg
0:00:07.099758519 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2704:gst_decode_bin_change_state:<decodebin20>[00m dynlocking from thread 0x247ec90
0:00:07.099963845 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2704:gst_decode_bin_change_state:<decodebin20>[00m dynlocked from thread 0x247ec90
0:00:07.100274602 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2705:gst_decode_bin_change_state:<decodebin20>[00m clearing shutdown flag
0:00:07.100627995 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2707:gst_decode_bin_change_state:<decodebin20>[00m dynunlocking from thread 0x247ec90
0:00:07.101728048 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1505:type_found:<decodebin20>[00m typefind found caps application/ogg
0:00:07.103007946 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad typefind:src caps:application/ogg
0:00:07.103670840 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:07.103995054 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps application/ogg
0:00:07.104311843 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are not final caps
0:00:07.113448381 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:972:gst_decode_bin_autoplug_sort:<decodebin20>[00m autoplug-sort returns 0x2454ba0
0:00:07.113715724 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1090:analyze_new_pad:<typefind:src>[00m Let's continue discovery on this pad
0:00:07.113916573 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1191:connect_pad:<decodebin20>[00m pad typefind:src , group:(nil)
0:00:07.114354968 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1228:connect_pad:<decodebin20>[00m autoplug select requested try
0:00:07.118123706 [333m26636[00m      0x247ec90 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1294:connect_pad:<decodebin20>[00m linked on pad typefind:src
0:00:07.118399197 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1348:connect_element:<decodebin20>[00m Attempting to connect element oggdemux0 [group:(nil)] further
0:00:07.118567547 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1361:connect_element:<decodebin20>[00m got a source pad template src_%d
0:00:07.118743769 [333m26636[00m      0x247ec90 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1396:connect_element:<decodebin20>[00m did not get the sometimes pad of template src_%d
buffering... 0%  
buffering... 0%  
buffering... 0%  
buffering... 0%  
[333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1505:type_found:<decodebin20>[00m typefind found caps application/ogg
buffering... 5%  
buffering... 0%  
buffering... 5%  
buffering... 0%  
buffering... 5%  
buffering... 0%  
buffering... 4%  
buffering... 0%  
0:00:07.680102026 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1593:pad_added_cb:<oggdemux0:serial_000053f1>[00m Pad added to non-grouped element
0:00:07.680665992 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad oggdemux0:serial_000053f1 caps:audio/x-vorbis
0:00:07.681296531 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:07.681717872 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps audio/x-vorbis
0:00:07.682179033 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are not final caps
0:00:07.683071323 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:972:gst_decode_bin_autoplug_sort:<decodebin20>[00m autoplug-sort returns 0x2410200
0:00:07.683434560 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1090:analyze_new_pad:<oggdemux0:serial_000053f1>[00m Let's continue discovery on this pad
0:00:07.683759770 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1191:connect_pad:<decodebin20>[00m pad oggdemux0:serial_000053f1 , group:(nil)
0:00:07.684207134 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1195:connect_pad:<oggdemux0>[00m is a demuxer, connecting the pad through multiqueue
0:00:07.684568109 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locking from thread 0x2573340
0:00:07.684942208 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locked from thread 0x2573340
0:00:07.685309091 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1797:gst_decode_group_new:<decodebin20>[00m Creating new group
0:00:07.686652972 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1835:gst_decode_group_new:<decodebin20>[00m Returning new group 0x250f010
0:00:07.686654106 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1875:get_current_group:<decodebin20>[00m added group 0x250f010, demux 1
0:00:07.686942555 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1883:get_current_group:<decodebin20>[00m unlocking from thread 0x2573340
0:00:07.687266301 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1885:get_current_group:<decodebin20>[00m Returning group 0x250f010
0:00:07.687767673 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1906:gst_decode_group_control_demuxer_pad:<decodebin20>[00m group:0x250f010 pad oggdemux0:serial_000053f1
0:00:07.689315906 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1927:gst_decode_group_control_demuxer_pad:<decodebin20>[00m locking group 0x250f010 from thread 0x2573340
0:00:07.689770737 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1927:gst_decode_group_control_demuxer_pad:<decodebin20>[00m locked group 0x250f010 from thread 0x2573340
0:00:07.690147037 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1936:gst_decode_group_control_demuxer_pad:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2573340
0:00:07.690553842 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1228:connect_pad:<decodebin20>[00m autoplug select requested try
0:00:07.691108778 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1294:connect_pad:<decodebin20>[00m linked on pad multiqueue0:src0
0:00:07.691479492 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1348:connect_element:<decodebin20>[00m Attempting to connect element vorbisdec1 [group:0x250f010] further
0:00:07.691865403 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1361:connect_element:<decodebin20>[00m got a source pad template src
0:00:07.692966971 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1372:connect_element:<decodebin20>[00m got the pad for always template src
0:00:07.693427891 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad vorbisdec1:src caps:audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 256 ], endianness=(int)1234, width=(int)32
0:00:07.694387828 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:07.694474498 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 256 ], endianness=(int)1234, width=(int)32
0:00:07.694657441 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are final caps
0:00:07.694915424 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1100:analyze_new_pad:<decodebin20>[00m Pad is final. autoplug-continue:1
0:00:07.695209101 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1467:expose_pad:<decodebin20>[00m pad vorbisdec1:src, group:0x250f010
0:00:07.695511032 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1949:gst_decode_group_control_source_pad:<'':decodepad2>[00m adding decode pad to group 0x250f010
0:00:07.695813588 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocking from thread 0x2573340
0:00:07.696091916 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocked from thread 0x2573340
0:00:07.696398498 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2559:gst_decode_pad_set_blocked:<decodebin20>[00m dynunlocking from thread 0x2573340
0:00:07.696817558 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1954:gst_decode_group_control_source_pad:<decodebin20>[00m locking group 0x250f010 from thread 0x2573340
0:00:07.697100739 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1954:gst_decode_group_control_source_pad:<decodebin20>[00m locked group 0x250f010 from thread 0x2573340
0:00:07.697579531 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1956:gst_decode_group_control_source_pad:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2573340
0:00:07.698317566 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1612:no_more_pads_cb:<oggdemux0>[00m No more pads, setting current group to complete
0:00:07.698621362 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locking from thread 0x2573340
0:00:07.699127705 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locked from thread 0x2573340
0:00:07.699500992 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1862:get_current_group:<decodebin20>[00m locking group 0x250f010 from thread 0x2573340
0:00:07.699796782 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1862:get_current_group:<decodebin20>[00m locked group 0x250f010 from thread 0x2573340
0:00:07.700087163 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1863:get_current_group:<decodebin20>[00m group 0x250f010, complete:0
0:00:07.700380039 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1867:get_current_group:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2573340
0:00:07.700680989 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1883:get_current_group:<decodebin20>[00m unlocking from thread 0x2573340
0:00:07.701034106 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1885:get_current_group:<decodebin20>[00m Returning group 0x250f010
0:00:07.701419753 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2449:gst_decode_group_set_complete:<decodebin20>[00m Setting group 0x250f010 to COMPLETE
0:00:07.701799611 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2451:gst_decode_group_set_complete:<decodebin20>[00m locking group 0x250f010 from thread 0x2573340
0:00:07.702294386 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2451:gst_decode_group_set_complete:<decodebin20>[00m locked group 0x250f010 from thread 0x2573340
0:00:07.702621042 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1981:gst_decode_group_check_if_blocked:<decodebin20>[00m group : 0x250f010 , ->complete:1 , ->nbdynamic:0
0:00:07.702940112 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2000:gst_decode_group_check_if_blocked:<decodebin20>[00m group is blocked:0
0:00:07.703255383 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2456:gst_decode_group_set_complete:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2573340
0:00:07.707778407 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2496:source_pad_blocked_cb:<'':decodepad2>[00m blocked:1, dpad->group:0x250f010
0:00:07.708287350 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locking group 0x250f010 from thread 0x2511f50
0:00:07.708483537 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locked group 0x250f010 from thread 0x2511f50
0:00:07.708651864 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1981:gst_decode_group_check_if_blocked:<decodebin20>[00m group : 0x250f010 , ->complete:1 , ->nbdynamic:0
0:00:07.708912332 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2000:gst_decode_group_check_if_blocked:<decodebin20>[00m group is blocked:1
0:00:07.709084190 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2503:source_pad_blocked_cb:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2511f50
0:00:07.709252035 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2506:source_pad_blocked_cb:<decodebin20>[00m locking from thread 0x2511f50
0:00:07.709424264 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2506:source_pad_blocked_cb:<decodebin20>[00m locked from thread 0x2511f50
0:00:07.709885162 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2164:gst_decode_group_expose:<decodebin20>[00m going to expose group 0x250f010
0:00:07.710516986 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2186:gst_decode_group_expose:<decodebin20>[00m Disconnecting overrun
0:00:07.710855560 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2205:gst_decode_group_expose:<decodebin20>[00m Exposing group 0x250f010
0:00:07.711180672 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2221:gst_decode_group_expose:<decodebin20>[00m About to expose dpad decodepad2 as src0
0:00:07.711510651 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2234:gst_decode_group_expose:<decodebin20>[00m emitting new-decoded-pad
0:00:07.712868317 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2238:gst_decode_group_expose:<decodebin20>[00m emitted new-decoded-pad
0:00:07.713213468 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2243:gst_decode_group_expose:<decodebin20>[00m signalling no-more-pads
0:00:07.961635078 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2253:gst_decode_group_expose:<decodebin20:src0>[00m unblocking
0:00:07.961635985 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocking from thread 0x2511f50
0:00:07.961672263 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocked from thread 0x2511f50
0:00:07.961927891 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2559:gst_decode_pad_set_blocked:<decodebin20>[00m dynunlocking from thread 0x2511f50
0:00:07.962078796 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2255:gst_decode_group_expose:<decodebin20:src0>[00m unblocked
0:00:07.962250575 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2262:gst_decode_group_expose:<decodebin20>[00m removed group 0x250f010
0:00:07.962656875 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2272:gst_decode_group_expose:<decodebin20>[00m Group 0x250f010 exposed
0:00:07.962830723 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2509:source_pad_blocked_cb:<decodebin20>[00m unlocking from thread 0x2511f50
0:00:07.963002948 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2496:source_pad_blocked_cb:<decodebin20:src0>[00m blocked:0, dpad->group:0x250f010
0:00:07.963172214 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locking group 0x250f010 from thread 0x2511f50
0:00:07.963343843 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locked group 0x250f010 from thread 0x2511f50
0:00:07.963513721 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2503:source_pad_blocked_cb:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2511f50
0:00:07.963727520 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m newsegment dpad:0x2580990
0:00:07.964132828 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m tag dpad:0x2580990
0:00:07.964438072 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m tag dpad:0x2580990
Prerolled, waiting for buffering to finish...
buffering... 5%  
buffering... 0%  
buffering... 5%  
buffering... 0%  
buffering... 5%  
buffering... 0%  
buffering... 5%  
buffering... 11%  
buffering... 16%  
buffering... 22%  
buffering... 28%  
buffering... 34%  
buffering... 38%  
buffering... 43%  
buffering... 49%  
buffering... 49%  
buffering... 57%  
buffering... 63%  
buffering... 68%  
buffering... 74%  
buffering... 80%  
buffering... 86%  
buffering... 86%  
buffering... 93%  
buffering... 100%  
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
0:00:12.136999567 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m latency dpad:0x2580990
New clock: GstPulseSinkClock
buffering... 6%  
Buffering, setting pipeline to PAUSED ...
buffering... 0%  
Prerolled, waiting for buffering to finish...
buffering... 6%  
buffering... 12%  
buffering... 18%  
buffering... 25%  
buffering... 32%  
buffering... 38%  
buffering... 46%  
buffering... 52%  
buffering... 59%  
buffering... 67%  
buffering... 74%  
buffering... 81%  
buffering... 81%  
buffering... 90%  
buffering... 97%  
buffering... 100%  
Done buffering, setting pipeline to PLAYING ...
0:00:20.905352144 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m latency dpad:0x2580990
buffering... 5%  
Buffering, setting pipeline to PAUSED ...
buffering... 0%  
Prerolled, waiting for buffering to finish...
buffering... 6%  
buffering... 13%  
buffering... 19%  
buffering... 26%  
buffering... 33%  
buffering... 39%  
buffering... 47%  
buffering... 54%  
buffering... 61%  
buffering... 69%  
buffering... 76%  
buffering... 83%  
buffering... 83%  
buffering... 94%  
buffering... 100%  
Done buffering, setting pipeline to PLAYING ...
0:00:27.800930428 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m latency dpad:0x2580990
buffering... 7%  
Buffering, setting pipeline to PAUSED ...
buffering... 0%  
buffering... 0%  
Prerolled, waiting for buffering to finish...
buffering... 1%  
buffering... 1%  
buffering... 8%  
buffering... 15%  
buffering... 22%  
buffering... 23%  
buffering... 31%  
buffering... 38%  
buffering... 45%  
buffering... 46%  
buffering... 52%  
buffering... 59%  
buffering... 66%  
buffering... 76%  
buffering... 83%  
buffering... 90%  
buffering... 97%  
buffering... 100%  
Done buffering, setting pipeline to PLAYING ...
0:00:34.001215274 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m latency dpad:0x2580990
0:00:34.805590969 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1593:pad_added_cb:<oggdemux0:serial_00004dab>[00m Pad added to non-grouped element
0:00:34.806152875 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad oggdemux0:serial_00004dab caps:audio/x-vorbis
0:00:34.807090554 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:34.807159089 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps audio/x-vorbis
0:00:34.807352483 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are not final caps
0:00:34.809058413 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:972:gst_decode_bin_autoplug_sort:<decodebin20>[00m autoplug-sort returns 0x2459780
0:00:34.809287358 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1090:analyze_new_pad:<oggdemux0:serial_00004dab>[00m Let's continue discovery on this pad
0:00:34.809573621 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1191:connect_pad:<decodebin20>[00m pad oggdemux0:serial_00004dab , group:(nil)
0:00:34.809882450 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1195:connect_pad:<oggdemux0>[00m is a demuxer, connecting the pad through multiqueue
0:00:34.810137238 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locking from thread 0x2573340
0:00:34.810375662 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locked from thread 0x2573340
0:00:34.810611065 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1797:gst_decode_group_new:<decodebin20>[00m Creating new group
0:00:34.811244897 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1835:gst_decode_group_new:<decodebin20>[00m Returning new group 0x2533af0
0:00:34.811500016 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1875:get_current_group:<decodebin20>[00m added group 0x2533af0, demux 1
0:00:34.811739904 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1883:get_current_group:<decodebin20>[00m unlocking from thread 0x2573340
0:00:34.811977141 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1885:get_current_group:<decodebin20>[00m Returning group 0x2533af0
0:00:34.812224254 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1906:gst_decode_group_control_demuxer_pad:<decodebin20>[00m group:0x2533af0 pad oggdemux0:serial_00004dab
0:00:34.816471205 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1927:gst_decode_group_control_demuxer_pad:<decodebin20>[00m locking group 0x2533af0 from thread 0x2573340
0:00:34.816555283 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1927:gst_decode_group_control_demuxer_pad:<decodebin20>[00m locked group 0x2533af0 from thread 0x2573340
0:00:34.816743447 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1936:gst_decode_group_control_demuxer_pad:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2573340
0:00:34.816975096 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1228:connect_pad:<decodebin20>[00m autoplug select requested try
0:00:34.817340715 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1294:connect_pad:<decodebin20>[00m linked on pad multiqueue1:src0
0:00:34.817525176 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1348:connect_element:<decodebin20>[00m Attempting to connect element vorbisdec3 [group:0x2533af0] further
0:00:34.817714030 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1361:connect_element:<decodebin20>[00m got a source pad template src
0:00:34.817921842 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1372:connect_element:<decodebin20>[00m got the pad for always template src
0:00:34.818283981 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad vorbisdec3:src caps:audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 256 ], endianness=(int)1234, width=(int)32
0:00:34.818912276 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:34.818965427 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 256 ], endianness=(int)1234, width=(int)32
0:00:34.819093457 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are final caps
0:00:34.819238682 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1100:analyze_new_pad:<decodebin20>[00m Pad is final. autoplug-continue:1
0:00:34.819405590 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1467:expose_pad:<decodebin20>[00m pad vorbisdec3:src, group:0x2533af0
0:00:34.819577529 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1949:gst_decode_group_control_source_pad:<'':decodepad4>[00m adding decode pad to group 0x2533af0
0:00:34.819771800 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocking from thread 0x2573340
0:00:34.819931051 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocked from thread 0x2573340
0:00:34.820106702 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2559:gst_decode_pad_set_blocked:<decodebin20>[00m dynunlocking from thread 0x2573340
0:00:34.820289190 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1954:gst_decode_group_control_source_pad:<decodebin20>[00m locking group 0x2533af0 from thread 0x2573340
0:00:34.820437220 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1954:gst_decode_group_control_source_pad:<decodebin20>[00m locked group 0x2533af0 from thread 0x2573340
0:00:34.820605898 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1956:gst_decode_group_control_source_pad:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2573340
0:00:34.821117951 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1604:pad_removed_cb:<oggdemux0:serial_000053f1>[00m Pad removed from non-grouped element
0:00:34.821495464 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1612:no_more_pads_cb:<oggdemux0>[00m No more pads, setting current group to complete
0:00:34.821683050 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locking from thread 0x2573340
0:00:34.821915669 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locked from thread 0x2573340
0:00:34.822211218 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1862:get_current_group:<decodebin20>[00m locking group 0x2533af0 from thread 0x2573340
0:00:34.822398451 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1862:get_current_group:<decodebin20>[00m locked group 0x2533af0 from thread 0x2573340
0:00:34.822563225 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1863:get_current_group:<decodebin20>[00m group 0x2533af0, complete:0
0:00:34.822730123 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1867:get_current_group:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2573340
0:00:34.822895879 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1883:get_current_group:<decodebin20>[00m unlocking from thread 0x2573340
0:00:34.823060306 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1885:get_current_group:<decodebin20>[00m Returning group 0x2533af0
0:00:34.823257142 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2449:gst_decode_group_set_complete:<decodebin20>[00m Setting group 0x2533af0 to COMPLETE
0:00:34.824680016 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2451:gst_decode_group_set_complete:<decodebin20>[00m locking group 0x2533af0 from thread 0x2573340
0:00:34.824872672 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2451:gst_decode_group_set_complete:<decodebin20>[00m locked group 0x2533af0 from thread 0x2573340
0:00:34.825038045 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1981:gst_decode_group_check_if_blocked:<decodebin20>[00m group : 0x2533af0 , ->complete:1 , ->nbdynamic:0
0:00:34.825203398 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2000:gst_decode_group_check_if_blocked:<decodebin20>[00m group is blocked:0
0:00:34.825369609 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2456:gst_decode_group_set_complete:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2573340
0:00:34.827776711 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2496:source_pad_blocked_cb:<'':decodepad4>[00m blocked:1, dpad->group:0x2533af0
0:00:34.827986014 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locking group 0x2533af0 from thread 0x2530f50
0:00:34.828207656 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locked group 0x2533af0 from thread 0x2530f50
0:00:34.828397174 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1981:gst_decode_group_check_if_blocked:<decodebin20>[00m group : 0x2533af0 , ->complete:1 , ->nbdynamic:0
0:00:34.828583786 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2000:gst_decode_group_check_if_blocked:<decodebin20>[00m group is blocked:1
0:00:34.828770426 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2503:source_pad_blocked_cb:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2530f50
0:00:34.828957038 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2506:source_pad_blocked_cb:<decodebin20>[00m locking from thread 0x2530f50
0:00:34.829167277 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2506:source_pad_blocked_cb:<decodebin20>[00m locked from thread 0x2530f50
0:00:34.829353645 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2164:gst_decode_group_expose:<decodebin20>[00m going to expose group 0x2533af0
0:00:34.829555490 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2186:gst_decode_group_expose:<decodebin20>[00m Disconnecting overrun
0:00:34.829894260 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2195:gst_decode_group_expose:<decodebin20>[00m another group 0x250f010 is already exposed, waiting for EOS
0:00:34.830141883 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2509:source_pad_blocked_cb:<decodebin20>[00m unlocking from thread 0x2530f50
0:00:34.925418261 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src0>[00m eos dpad:0x2580990
0:00:34.925565815 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2519:source_pad_event_probe:<decodebin20:src0>[00m we received EOS
0:00:34.925762873 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2051:gst_decode_pad_handle_eos:<decodebin20>[00m group : 0x250f010, pad 0x2580990
0:00:34.925891905 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2053:gst_decode_pad_handle_eos:<decodebin20>[00m locking group 0x250f010 from thread 0x2511f50
0:00:34.926057643 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2053:gst_decode_pad_handle_eos:<decodebin20>[00m locked group 0x250f010 from thread 0x2511f50
0:00:34.926229142 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2064:gst_decode_pad_handle_eos:<decodebin20>[00m testing dpad 0x2580990 1
0:00:34.926423286 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2072:gst_decode_pad_handle_eos:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2511f50
0:00:34.926616322 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2015:gst_decode_bin_activate_next_group:<decodebin20>[00m locking from thread 0x2511f50
0:00:34.926809305 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2015:gst_decode_bin_activate_next_group:<decodebin20>[00m locked from thread 0x2511f50
0:00:34.927001984 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2023:gst_decode_bin_activate_next_group:<decodebin20>[00m Switching to new group
0:00:34.927194391 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2285:gst_decode_group_hide:<decodebin20>[00m Hiding group 0x250f010
0:00:34.927386922 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2292:gst_decode_group_hide:<decodebin20>[00m locking group 0x250f010 from thread 0x2511f50
0:00:34.927579646 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2292:gst_decode_group_hide:<decodebin20>[00m locked group 0x250f010 from thread 0x2511f50
0:00:34.927887366 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2302:gst_decode_group_hide:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2511f50
0:00:34.928092422 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2164:gst_decode_group_expose:<decodebin20>[00m going to expose group 0x2533af0
0:00:34.928306466 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2205:gst_decode_group_expose:<decodebin20>[00m Exposing group 0x2533af0
0:00:34.928429205 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2221:gst_decode_group_expose:<decodebin20>[00m About to expose dpad decodepad4 as src1
0:00:34.928559318 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2234:gst_decode_group_expose:<decodebin20>[00m emitting new-decoded-pad
0:00:34.929620708 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2238:gst_decode_group_expose:<decodebin20>[00m emitted new-decoded-pad
0:00:34.929752033 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2243:gst_decode_group_expose:<decodebin20>[00m signalling no-more-pads
0:00:34.929876041 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2253:gst_decode_group_expose:<decodebin20:src1>[00m unblocking
0:00:34.930000336 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocking from thread 0x2511f50
0:00:34.930122755 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocked from thread 0x2511f50
0:00:34.930273082 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2559:gst_decode_pad_set_blocked:<decodebin20>[00m dynunlocking from thread 0x2511f50
0:00:34.930397898 [333m26636[00m      0x2511f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2255:gst_decode_group_expose:<decodebin20:src1>[00m unblocked
0:00:34.930520080 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2262:gst_decode_group_expose:<decodebin20>[00m removed group 0x2533af0
0:00:34.930642356 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2272:gst_decode_group_expose:<decodebin20>[00m Group 0x2533af0 exposed
0:00:34.930763490 [333m26636[00m      0x2511f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2033:gst_decode_bin_activate_next_group:<decodebin20>[00m unlocking from thread 0x2511f50
0:00:34.930933735 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2496:source_pad_blocked_cb:<decodebin20:src1>[00m blocked:0, dpad->group:0x2533af0
0:00:34.931052689 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locking group 0x2533af0 from thread 0x2530f50
0:00:34.931175109 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locked group 0x2533af0 from thread 0x2530f50
0:00:34.931296620 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2503:source_pad_blocked_cb:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2530f50
0:00:34.931424985 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src1>[00m newsegment dpad:0x2580d50
0:00:34.931734588 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src1>[00m tag dpad:0x2580d50
0:00:34.931782314 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src1>[00m tag dpad:0x2580d50
0:00:55.583608574 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1593:pad_added_cb:<oggdemux0:serial_00000551>[00m Pad added to non-grouped element
0:00:55.584250850 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad oggdemux0:serial_00000551 caps:audio/x-vorbis
0:00:55.584479303 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:55.584589519 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps audio/x-vorbis
0:00:55.584750046 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are not final caps
0:00:55.585125904 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:972:gst_decode_bin_autoplug_sort:<decodebin20>[00m autoplug-sort returns 0x253ef80
0:00:55.585328519 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1090:analyze_new_pad:<oggdemux0:serial_00000551>[00m Let's continue discovery on this pad
0:00:55.585522943 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1191:connect_pad:<decodebin20>[00m pad oggdemux0:serial_00000551 , group:(nil)
0:00:55.585721165 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1195:connect_pad:<oggdemux0>[00m is a demuxer, connecting the pad through multiqueue
0:00:55.585853455 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locking from thread 0x2573340
0:00:55.586006330 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locked from thread 0x2573340
0:00:55.586231907 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1797:gst_decode_group_new:<decodebin20>[00m Creating new group
0:00:55.586599381 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1835:gst_decode_group_new:<decodebin20>[00m Returning new group 0x24aa4c0
0:00:55.586801664 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1875:get_current_group:<decodebin20>[00m added group 0x24aa4c0, demux 1
0:00:55.586976699 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1883:get_current_group:<decodebin20>[00m unlocking from thread 0x2573340
0:00:55.587080762 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1885:get_current_group:<decodebin20>[00m Returning group 0x24aa4c0
0:00:55.587253514 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1906:gst_decode_group_control_demuxer_pad:<decodebin20>[00m group:0x24aa4c0 pad oggdemux0:serial_00000551
0:00:55.590297770 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1927:gst_decode_group_control_demuxer_pad:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x2573340
0:00:55.590383895 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1927:gst_decode_group_control_demuxer_pad:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x2573340
0:00:55.590563984 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1936:gst_decode_group_control_demuxer_pad:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x2573340
0:00:55.590785392 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1228:connect_pad:<decodebin20>[00m autoplug select requested try
0:00:55.591211630 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1294:connect_pad:<decodebin20>[00m linked on pad multiqueue2:src0
0:00:55.591486348 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1348:connect_element:<decodebin20>[00m Attempting to connect element vorbisdec5 [group:0x24aa4c0] further
0:00:55.591686489 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1361:connect_element:<decodebin20>[00m got a source pad template src
0:00:55.591858076 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1372:connect_element:<decodebin20>[00m got the pad for always template src
0:00:55.593569804 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1039:analyze_new_pad:<decodebin20>[00m Pad vorbisdec5:src caps:audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 256 ], endianness=(int)1234, width=(int)32
0:00:55.594192658 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:941:gst_decode_bin_autoplug_continue:<decodebin20>[00m autoplug-continue returns TRUE
0:00:55.594244086 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1727:are_raw_caps:<decodebin20>[00m Checking with caps audio/x-raw-float, rate=(int)[ 1, 2147483647 ], channels=(int)[ 1, 256 ], endianness=(int)1234, width=(int)32
0:00:55.599206437 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1738:are_raw_caps:<decodebin20>[00m Caps are final caps
0:00:55.600052456 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1100:analyze_new_pad:<decodebin20>[00m Pad is final. autoplug-continue:1
0:00:55.600457283 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1467:expose_pad:<decodebin20>[00m pad vorbisdec5:src, group:0x24aa4c0
0:00:55.600654550 [333m26636[00m      0x2573340 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:1949:gst_decode_group_control_source_pad:<'':decodepad6>[00m adding decode pad to group 0x24aa4c0
0:00:55.600827255 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocking from thread 0x2573340
0:00:55.600981686 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocked from thread 0x2573340
0:00:55.601155192 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2559:gst_decode_pad_set_blocked:<decodebin20>[00m dynunlocking from thread 0x2573340
0:00:55.601379941 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1954:gst_decode_group_control_source_pad:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x2573340
0:00:55.603202645 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1954:gst_decode_group_control_source_pad:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x2573340
0:00:55.603535738 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1956:gst_decode_group_control_source_pad:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x2573340
0:00:55.611018052 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1604:pad_removed_cb:<oggdemux0:serial_00004dab>[00m Pad removed from non-grouped element
0:00:55.611883162 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1612:no_more_pads_cb:<oggdemux0>[00m No more pads, setting current group to complete
0:00:55.611934618 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locking from thread 0x2573340
0:00:55.612038661 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1858:get_current_group:<decodebin20>[00m locked from thread 0x2573340
0:00:55.612207913 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1862:get_current_group:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x2573340
0:00:55.612374395 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1862:get_current_group:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x2573340
0:00:55.612641297 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1863:get_current_group:<decodebin20>[00m group 0x24aa4c0, complete:0
0:00:55.612815138 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1867:get_current_group:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x2573340
0:00:55.613000994 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1883:get_current_group:<decodebin20>[00m unlocking from thread 0x2573340
0:00:55.613166062 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1885:get_current_group:<decodebin20>[00m Returning group 0x24aa4c0
0:00:55.613332109 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2449:gst_decode_group_set_complete:<decodebin20>[00m Setting group 0x24aa4c0 to COMPLETE
0:00:55.613498320 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2451:gst_decode_group_set_complete:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x2573340
0:00:55.613665265 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2451:gst_decode_group_set_complete:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x2573340
0:00:55.613870680 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1981:gst_decode_group_check_if_blocked:<decodebin20>[00m group : 0x24aa4c0 , ->complete:1 , ->nbdynamic:0
0:00:55.614070175 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2000:gst_decode_group_check_if_blocked:<decodebin20>[00m group is blocked:0
0:00:55.614255524 [333m26636[00m      0x2573340 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2456:gst_decode_group_set_complete:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x2573340
0:00:55.616714626 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2496:source_pad_blocked_cb:<'':decodepad6>[00m blocked:1, dpad->group:0x24aa4c0
0:00:55.616963427 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x24aa760
0:00:55.617154180 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x24aa760
0:00:55.617348111 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1981:gst_decode_group_check_if_blocked:<decodebin20>[00m group : 0x24aa4c0 , ->complete:1 , ->nbdynamic:0
0:00:55.617536503 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2000:gst_decode_group_check_if_blocked:<decodebin20>[00m group is blocked:1
0:00:55.617722985 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2503:source_pad_blocked_cb:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x24aa760
0:00:55.617950280 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2506:source_pad_blocked_cb:<decodebin20>[00m locking from thread 0x24aa760
0:00:55.618137979 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2506:source_pad_blocked_cb:<decodebin20>[00m locked from thread 0x24aa760
0:00:55.618324823 [333m26636[00m      0x24aa760 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2164:gst_decode_group_expose:<decodebin20>[00m going to expose group 0x24aa4c0
0:00:55.618532351 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2186:gst_decode_group_expose:<decodebin20>[00m Disconnecting overrun
0:00:55.618722606 [333m26636[00m      0x24aa760 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2195:gst_decode_group_expose:<decodebin20>[00m another group 0x2533af0 is already exposed, waiting for EOS
0:00:55.618909692 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2509:source_pad_blocked_cb:<decodebin20>[00m unlocking from thread 0x24aa760
0:00:55.734344304 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src1>[00m eos dpad:0x2580d50
0:00:55.734618800 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2519:source_pad_event_probe:<decodebin20:src1>[00m we received EOS
0:00:55.734866018 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2051:gst_decode_pad_handle_eos:<decodebin20>[00m group : 0x2533af0, pad 0x2580d50
0:00:55.735044004 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2053:gst_decode_pad_handle_eos:<decodebin20>[00m locking group 0x2533af0 from thread 0x2530f50
0:00:55.735238889 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2053:gst_decode_pad_handle_eos:<decodebin20>[00m locked group 0x2533af0 from thread 0x2530f50
0:00:55.735479138 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2064:gst_decode_pad_handle_eos:<decodebin20>[00m testing dpad 0x2580d50 1
0:00:55.735673271 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2072:gst_decode_pad_handle_eos:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2530f50
0:00:55.735998562 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2015:gst_decode_bin_activate_next_group:<decodebin20>[00m locking from thread 0x2530f50
0:00:55.736174486 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2015:gst_decode_bin_activate_next_group:<decodebin20>[00m locked from thread 0x2530f50
0:00:55.737305654 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2023:gst_decode_bin_activate_next_group:<decodebin20>[00m Switching to new group
0:00:55.737569191 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2285:gst_decode_group_hide:<decodebin20>[00m Hiding group 0x2533af0
0:00:55.737768061 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2292:gst_decode_group_hide:<decodebin20>[00m locking group 0x2533af0 from thread 0x2530f50
0:00:55.737891440 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2292:gst_decode_group_hide:<decodebin20>[00m locked group 0x2533af0 from thread 0x2530f50
0:00:55.738164254 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2302:gst_decode_group_hide:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2530f50
0:00:55.738341597 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2164:gst_decode_group_expose:<decodebin20>[00m going to expose group 0x24aa4c0
0:00:55.738557024 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2205:gst_decode_group_expose:<decodebin20>[00m Exposing group 0x24aa4c0
0:00:55.738754480 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2221:gst_decode_group_expose:<decodebin20>[00m About to expose dpad decodepad6 as src2
0:00:55.738953766 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2234:gst_decode_group_expose:<decodebin20>[00m emitting new-decoded-pad
0:00:55.740343258 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2238:gst_decode_group_expose:<decodebin20>[00m emitted new-decoded-pad
0:00:55.740551832 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2243:gst_decode_group_expose:<decodebin20>[00m signalling no-more-pads
0:00:55.740774371 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2253:gst_decode_group_expose:<decodebin20:src2>[00m unblocking
0:00:55.740880729 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocking from thread 0x2530f50
0:00:55.741029343 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2536:gst_decode_pad_set_blocked:<decodebin20>[00m dynlocked from thread 0x2530f50
0:00:55.741225928 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2559:gst_decode_pad_set_blocked:<decodebin20>[00m dynunlocking from thread 0x2530f50
0:00:55.741435590 [333m26636[00m      0x2530f50 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:2255:gst_decode_group_expose:<decodebin20:src2>[00m unblocked
0:00:55.741623598 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2262:gst_decode_group_expose:<decodebin20>[00m removed group 0x24aa4c0
0:00:55.741965021 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2272:gst_decode_group_expose:<decodebin20>[00m Group 0x24aa4c0 exposed
0:00:55.742143801 [333m26636[00m      0x2530f50 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2033:gst_decode_bin_activate_next_group:<decodebin20>[00m unlocking from thread 0x2530f50
0:00:55.741342106 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2496:source_pad_blocked_cb:<decodebin20:src2>[00m blocked:0, dpad->group:0x24aa4c0
0:00:55.742558614 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x24aa760
0:00:55.742753557 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2498:source_pad_blocked_cb:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x24aa760
0:00:55.742946944 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2503:source_pad_blocked_cb:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x24aa760
0:00:55.743147211 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src2>[00m newsegment dpad:0x25807b0
0:00:55.743398936 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src2>[00m tag dpad:0x25807b0
0:00:55.743613570 [333m26636[00m      0x24aa760 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2516:source_pad_event_probe:<decodebin20:src2>[00m tag dpad:0x25807b0
Caught interrupt -- handling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 53532734289 ns.
Setting pipeline to PAUSED ...
Setting pipeline to READY ...
0:01:05.686354955 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2713:gst_decode_bin_change_state:<decodebin20>[00m dynlocking from thread 0x2294080
0:01:05.688012591 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2713:gst_decode_bin_change_state:<decodebin20>[00m dynlocked from thread 0x2294080
0:01:05.688167436 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2714:gst_decode_bin_change_state:<decodebin20>[00m setting shutdown flag
0:01:05.688308048 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2717:gst_decode_bin_change_state:<decodebin20>[00m dynunlocking from thread 0x2294080
0:01:05.688949682 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1604:pad_removed_cb:<oggdemux0:serial_00000551>[00m Pad removed from non-grouped element
0:01:05.690036156 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:669:gst_decode_bin_remove_groups:<decodebin20>[00m cleaning up
0:01:05.690230918 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:672:gst_decode_bin_remove_groups:<decodebin20>[00m free active group 0x24aa4c0
0:01:05.690418614 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2385:gst_decode_group_free:<decodebin20>[00m group 0x24aa4c0
0:01:05.690604551 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2387:gst_decode_group_free:<decodebin20>[00m locking group 0x24aa4c0 from thread 0x2294080
0:01:05.691111094 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2387:gst_decode_group_free:<decodebin20>[00m locked group 0x24aa4c0 from thread 0x2294080
0:01:05.692532023 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2318:deactivate_free_recursive:<decodebin20>[00m element:multiqueue2
0:01:05.693053257 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2422:gst_decode_group_free:<decodebin20>[00m unlocking group 0x24aa4c0 from thread 0x2294080
0:01:05.693240882 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:690:gst_decode_bin_remove_groups:<decodebin20>[00m free old group 0x2533af0
0:01:05.693422183 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2385:gst_decode_group_free:<decodebin20>[00m group 0x2533af0
0:01:05.693635989 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2387:gst_decode_group_free:<decodebin20>[00m locking group 0x2533af0 from thread 0x2294080
0:01:05.693866941 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2387:gst_decode_group_free:<decodebin20>[00m locked group 0x2533af0 from thread 0x2294080
0:01:05.694083871 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2318:deactivate_free_recursive:<decodebin20>[00m element:multiqueue1
0:01:05.694286628 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2422:gst_decode_group_free:<decodebin20>[00m unlocking group 0x2533af0 from thread 0x2294080
0:01:05.694468404 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:690:gst_decode_bin_remove_groups:<decodebin20>[00m free old group 0x250f010
0:01:05.694647832 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2385:gst_decode_group_free:<decodebin20>[00m group 0x250f010
0:01:05.694827215 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2387:gst_decode_group_free:<decodebin20>[00m locking group 0x250f010 from thread 0x2294080
0:01:05.695021552 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2387:gst_decode_group_free:<decodebin20>[00m locked group 0x250f010 from thread 0x2294080
0:01:05.695233453 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2318:deactivate_free_recursive:<decodebin20>[00m element:multiqueue0
0:01:05.695434645 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:2422:gst_decode_group_free:<decodebin20>[00m unlocking group 0x250f010 from thread 0x2294080
0:01:05.695616625 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:696:gst_decode_bin_remove_groups:<decodebin20>[00m removing last elements
0:01:05.695798261 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:722:gst_decode_bin_remove_groups:<decodebin20>[00m remove element vorbisdec5
0:01:05.696012745 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:722:gst_decode_bin_remove_groups:<decodebin20>[00m remove element vorbisdec3
0:01:05.696226741 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:722:gst_decode_bin_remove_groups:<decodebin20>[00m remove element vorbisdec1
0:01:05.696440483 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:722:gst_decode_bin_remove_groups:<decodebin20>[00m remove element oggdemux0
0:01:05.697787387 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:669:gst_decode_bin_remove_groups:<decodebin20>[00m cleaning up
0:01:05.697917976 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:696:gst_decode_bin_remove_groups:<decodebin20>[00m removing last elements
Setting pipeline to NULL ...
0:01:05.699013941 [333m26636[00m      0x2294080 [37mLOG    [00m [00m          decodebin2 gstdecodebin2.c:1604:pad_removed_cb:<oggdemux0:sink>[00m Pad removed from non-grouped element
0:01:05.699336656 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:669:gst_decode_bin_remove_groups:<decodebin20>[00m cleaning up
0:01:05.699393131 [333m26636[00m      0x2294080 [36mDEBUG  [00m [00m          decodebin2 gstdecodebin2.c:696:gst_decode_bin_remove_groups:<decodebin20>[00m removing last elements
Freeing pipeline ...
