Why does this not link?

Ian Davidson id012c3076 at blueyonder.co.uk
Sat Dec 5 05:14:38 PST 2015


I am trying to link decodebin to a pipeline handling both the video and 
audio.  Sections of my (not very beautiful) code are below.  The video 
links OK but the audio fails for some reason.  What am I doing wrong - 
or what could I try?

I have included a trace from the time that I receive a pad-added signal 
for the audio until the time that it reports failure.

static void pad_added_handler (GstElement *src, GstPad *new_pad, guint 
*data) {
   GstPad *asink_pad = gst_element_get_static_pad (queue4, "sink");
   GstPad *vsink_pad = gst_element_get_static_pad (queue1a, "sink");
   GstPadLinkReturn ret;
   GstCaps *new_pad_caps = NULL;
   GstStructure *new_pad_struct = NULL;
   const gchar *new_pad_type = NULL;

   g_print ("Received new pad '%s' from '%s':\n", GST_PAD_NAME 
(new_pad), GST_ELEMENT_NAME (src));
   g_printerr ("Received new pad '%s' from '%s':\n", GST_PAD_NAME 
(new_pad), GST_ELEMENT_NAME (src));

   /* If our converter is already linked, we have nothing to do here */
   if (gst_pad_is_linked (asink_pad) && gst_pad_is_linked (vsink_pad)) {
     g_print ("  We are already linked. Ignoring.\n");
     goto exit;
   }

   /* Check the new pad's type */
   new_pad_caps = gst_pad_query_caps (new_pad, NULL);
   new_pad_struct = gst_caps_get_structure (new_pad_caps, 0);
   new_pad_type = gst_structure_get_name (new_pad_struct);
   if (g_str_has_prefix (new_pad_type, "audio/x-raw")) {
       g_print ("  Type is '%s' link to Queue4.\n", new_pad_type);
       g_printerr ("  Type is '%s' link to Queue4.\n", new_pad_type);
     ret = gst_pad_link (new_pad, asink_pad);
     if (GST_PAD_LINK_FAILED (ret)) {
       g_print ("  Type is '%s' but link failed.\n", new_pad_type);
       g_printerr ("  Type is '%s' but link failed.\n", new_pad_type);
     } else {
       g_print ("  Link succeeded (type '%s').\n", new_pad_type);
       g_printerr ("  Link succeeded (type '%s').\n", new_pad_type);
     }
   goto exit;
   }
   if (g_str_has_prefix (new_pad_type, "video/x-raw")) {
     ret = gst_pad_link (new_pad, vsink_pad);
     if (GST_PAD_LINK_FAILED (ret)) {
       g_print ("  Type is '%s' but link failed.\n", new_pad_type);
       g_printerr ("  Type is '%s' but link failed.\n", new_pad_type);
     } else {
       g_print ("  Link succeeded (type '%s').\n", new_pad_type);
       g_printerr ("  Link succeeded (type '%s').\n", new_pad_type);
     }
   goto exit;
   }

     g_print ("  It has type '%s' which is not raw audio. Ignoring.\n", 
new_pad_type);
     g_printerr ("  It has type '%s' which is not raw audio. 
Ignoring.\n", new_pad_type);
     goto exit;



exit:
   /* Unreference the new pad's caps, if we got them */
   if (new_pad_caps != NULL)
     gst_caps_unref (new_pad_caps);

   /* Unreference the sink pad */
   gst_object_unref (asink_pad);
   gst_object_unref (vsink_pad);
}

...

if (!pipeline || !filesrc || !decodebin || !vidrate || !vcapsfilter || 
!queue1 || !videnc || !audconvert || !acapsfilter || !queue2 || !audrate 
|| !queue3 || !lameenc || !mux || !sink) {
     g_printerr ("One element could not be created. Exiting.\n");
     return;
   }

   /* Set up the pipeline */


   g_object_set (G_OBJECT (filesrc), "location", 
"/home/ian/superRecord/MOV05E.Mpg", NULL);
   g_object_set (G_OBJECT (sink), "location", 
"/home/ian/superRecord/testVid.Mp4", NULL);

   g_object_set (G_OBJECT (videnc), "tune", 0x0004, NULL); /*zerolatency*/

  g_printerr ("Properties set.\n");
     /* we set the video capabilities on the vidcaps element */

/* SET THE VIDEO CAPS    */
   caps = 
gst_caps_from_string("video/x-raw,format=(string)I420,width=720,height=576,framerate=(fraction)25/1");
   g_object_set (G_OBJECT (vcapsfilter), "caps", caps, NULL);
   gst_caps_unref (caps);

/* we set the audio capabilities on the audiocaps element */
   caps = gst_caps_from_string("audio/x-raw,rate=44100,channels=1");
   g_object_set (G_OBJECT (acapsfilter), "caps", caps, NULL);
   gst_caps_unref (caps);


   /* we add a message handler */
   bus = gst_pipeline_get_bus (GST_PIPELINE (pipeline));
   bus_watch_id = gst_bus_add_watch (bus, bus_call, loop);
   gst_object_unref (bus);

   /* we add all elements into the pipeline */
      gst_bin_add_many (GST_BIN (pipeline),
                         filesrc, decodebin, queue1a, vidrate, vidscale, 
vcapsfilter, queue1, vidconvert, videnc,
                         queue4, audconvert, queue2, audrate, queue3, 
acapsfilter, lameenc,
                         mux, sink,  NULL);

   /* we link the elements together */

     if (!gst_element_link_many (filesrc, decodebin, NULL)) {
       g_printerr ("Failed to link filesrc, decodebin. Exiting.\n");
       return;
     }
     if (!gst_element_link_many (queue1a, vidrate, vidscale, 
vcapsfilter, queue1, videnc, mux, NULL)) {
       g_printerr ("Failed to link queue1a, vidrate, vidscale, 
vcapsfilter, queue1, videnc, mux. Exiting.\n");
       return;
     }
     if (!gst_element_link_many (queue4, audconvert, queue2, audrate, 
queue3, acapsfilter, lameenc, mux, NULL)) {
       g_printerr ("Failed to link queue4, audconvert, queue2, audrate, 
queue3, acapsfilter, lameenc, mux. Exiting.\n");
       return;
     }


g_print ("Most Links Linked!\n");
g_printerr ("Most Links Linked!\n");

     if (!gst_element_link_many (mux, sink, NULL)) {
       g_printerr ("Failed to link mux to sink. Exiting.\n");
       return;
     }

g_print ("All Links Linked!\n");
g_printerr ("All Links Linked!\n");

g_print ("Add the callback\n");
g_printerr ("Add the callback\n");
   g_signal_connect(decodebin, "pad-added", 
G_CALLBACK(pad_added_handler), NULL);

   /* Set the pipeline to "playing" state*/
   gst_debug_set_default_threshold(9);
g_print ("Set the pipeline to Playing\n");
g_printerr ("Set the pipeline to Playing\n");
   gst_element_set_state (pipeline, GST_STATE_PLAYING);



   /* Iterate */
   g_main_loop_run (loop);
...

Received new pad 'src_1' from 'decodebin-element':
0:00:10.333876431  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2761:gst_pad_query_caps:<decodebin-element:src_1> get pad 
caps with filter (NULL)
0:00:10.333900918  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e040
0:00:10.333925325  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a080f0 caps
0:00:10.333947640  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<decodebin-element:src_1> doing query 
0xb6a080f0 (caps)
0:00:10.333968605  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<decodebin-element:src_1> do probes 
cookie 21
0:00:10.333989810  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<decodebin-element:src_1> hook 1, 
cookie 21 with flags 0x00003040 does not match 00001402
0:00:10.334015815  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3266:do_probe_callbacks:<decodebin-element:src_1> data is passed
0:00:10.334031500  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<decodebin-element:src_1> do probes 
cookie 22
0:00:10.334048535  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<decodebin-element:src_1> hook 1, 
cookie 22 with flags 0x00003040 does not match 00001400
0:00:10.334071232  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<decodebin-element> 0x9911060 ref 4->5
0:00:10.334091289  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<decodebin-element:src_1> query 
caps caps query: 0xb6a080f0, GstQueryCaps, filter=(GstCaps)NULL, 
caps=(GstCaps)NULL;
0:00:10.334134472  2080 0xb5c04d80 DEBUG               GST_PADS 
gstutils.c:2523:gst_pad_proxy_query_caps:<decodebin-element:src_1> 
proxying caps query for decodebin-element:src_1
0:00:10.334155427  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a113b8
0:00:10.334174533  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<decodebin-element> 0x9911060 ref 5->6
0:00:10.334197326  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<src_1:proxypad3> 0x970aad0 ref 1->2
0:00:10.334224281  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<decodebin-element> 0x9911060 unref 6->5
0:00:10.334248869  2080 0xb5c04d80 LOG                 GST_PADS 
gstpad.c:2728:gst_pad_forward:<decodebin-element:src_1> calling forward 
function on pad src_1:proxypad3
0:00:10.334270128  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<src_1:proxypad3> peer query 0xb6a080f0 
(caps)
0:00:10.334290403  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<a52dec0:src> 0xb5c31158 ref 1->2
0:00:10.334309955  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<a52dec0:src> doing query 0xb6a080f0 (caps)
0:00:10.334328905  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<a52dec0:src> do probes cookie 11
0:00:10.334346195  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<a52dec0:src> hook 1, cookie 11 with 
flags 0x00003272 does not match 00001402
0:00:10.334367661  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3266:do_probe_callbacks:<a52dec0:src> data is passed
0:00:10.334411756  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<a52dec0:src> do probes cookie 12
0:00:10.334429906  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<a52dec0:src> hook 1, cookie 12 with 
flags 0x00003272 does not match 00001400
0:00:10.334478720  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<a52dec0> 0xb5c2a170 ref 2->3
0:00:10.334501426  2080 0xb5c04d80 LOG             audiodecoder 
gstaudiodecoder.c:2482:gst_audio_decoder_src_query:<a52dec0> handling 
query: caps query: 0xb6a080f0, GstQueryCaps, filter=(GstCaps)NULL, 
caps=(GstCaps)NULL;
0:00:10.334531819  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<a52dec0:src> query caps caps 
query: 0xb6a080f0, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.334560615  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2906:gst_pad_query_caps_default:<a52dec0:src> fixed pad caps: 
trying pad caps
0:00:10.334579146  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2942:gst_pad_query_caps_default:<a52dec0:src> using caps 
0xb5c28118 audio/x-raw, format=(string)F32LE, 
layout=(string)interleaved, rate=(int)48000, channels=(int)2, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.334625678  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 3->4
0:00:10.334646222  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 4->5
0:00:10.334665484  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 5->4
0:00:10.334685205  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<a52dec0:src> not forwarding 
0xb6a080f0 (caps) query
0:00:10.334704749  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<a52dec0> 0xb5c2a170 unref 3->2
0:00:10.334723811  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<a52dec0:src> sent query 0xb6a080f0 (caps), 
result 1
0:00:10.334743596  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<a52dec0:src> do probes cookie 13
0:00:10.334760785  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<a52dec0:src> hook 1, cookie 13 with 
flags 0x00003272 does not match 00002400
0:00:10.334782265  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<a52dec0:src> 0xb5c31158 unref 2->1
0:00:10.334803786  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2485:query_caps_func:<src_1:proxypad3> intersect with result 
audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.334838233  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 4->5
0:00:10.334858651  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2487:query_caps_func:<src_1:proxypad3> intersected 
audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.334890361  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a113b8 unref 1->0
0:00:10.334909640  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a113b8
0:00:10.334933110  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f1740 ref 4->5
0:00:10.334951800  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 5->6
0:00:10.334969040  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 6->5
0:00:10.334986078  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f1740 unref 5->4
0:00:10.335003593  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 5->6
0:00:10.335021973  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 6->5
0:00:10.335039192  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 5->4
0:00:10.335074856  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<decodebin-element:src_1> not 
forwarding 0xb6a080f0 (caps) query
0:00:10.335095625  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<decodebin-element> 0x9911060 unref 5->4
0:00:10.335115139  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<decodebin-element:src_1> sent query 
0xb6a080f0 (caps), result 1
0:00:10.335135405  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<decodebin-element:src_1> do probes 
cookie 23
0:00:10.335152865  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<decodebin-element:src_1> hook 1, 
cookie 23 with flags 0x00003040 does not match 00002400
0:00:10.335173652  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 4->5
0:00:10.335193414  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2768:gst_pad_query_caps:<decodebin-element:src_1> query 
returned audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.335225932  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a080f0 unref 1->0
0:00:10.335244684  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 5->4
0:00:10.335263126  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e040
   Type is 'audio/x-raw' link to Queue4.
0:00:10.335508427  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<decodebin-element> 0x9911060 ref 4->5
0:00:10.335536229  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985df00
0:00:10.335566395  2080 0xb5c04d80 LOG              GST_MESSAGE 
gstmessage.c:284:gst_message_new_custom: source sink: creating new 
message 0xb165a950 structure-change
0:00:10.335588812  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue4:sink> 0x992e768 ref 2->3
0:00:10.335611399  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<bus0> 0x97c44c0 ref 19->20
0:00:10.335632110  2080 0xb5c04d80 DEBUG                GST_BUS 
gstbus.c:312:gst_bus_post:<bus0> [msg 0xb165a950] posting on bus 
structure-change message: 0xb165a950, time 99:99:99.999999999, seq-num 
258, element 'sink', GstMessageStructureChange, 
type=(GstStructureChangeType)GST_STRUCTURE_CHANGE_TYPE_PAD_LINK, 
owner=(GstElement)"\(GstDecodeBin\)\ decodebin-element", busy=(boolean)true;
0:00:10.335713428  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:3396:gst_bin_handle_message_func:<av-recorder> [msg 0xb165a950] 
handling child sink message of type structure-change
0:00:10.335739967  2080 0xb5c04d80 LOG                      bin 
gstbin.c:863:message_check: looking at message 0xb6a06e80: 0
0:00:10.335758614  2080 0xb5c04d80 LOG                      bin 
gstbin.c:863:message_check: looking at message 0x993ee30: 0
0:00:10.335777015  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:885:find_message:<av-recorder> no message found matching types 
00001000
0:00:10.335795438  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:892:find_message:<av-recorder>   structure-change
0:00:10.335814182  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:932:bin_replace_message:<av-recorder> got new message 
0xb165a950, structure-change from sink
0:00:10.335835151  2080 0xb5c04d80 DEBUG                GST_BUS 
gstbus.c:343:gst_bus_post:<bus0> [msg 0xb165a950] dropped
0:00:10.335853362  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<bus0> 0x97c44c0 unref 20->19
0:00:10.335873336  2080 0xb5c04d80 INFO                GST_PADS 
gstpad.c:2120:gst_pad_link_prepare: trying to link 
decodebin-element:src_1 and queue4:sink
0:00:10.335897970  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2761:gst_pad_query_caps:<decodebin-element:src_1> get pad 
caps with filter (NULL)
0:00:10.335945442  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e060
0:00:10.335967550  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a08060 caps
0:00:10.335989075  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<decodebin-element:src_1> doing query 
0xb6a08060 (caps)
0:00:10.336010321  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<decodebin-element:src_1> do probes 
cookie 24
0:00:10.336029746  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<decodebin-element:src_1> hook 1, 
cookie 24 with flags 0x00003040 does not match 00001402
0:00:10.336053375  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3266:do_probe_callbacks:<decodebin-element:src_1> data is passed
0:00:10.336069274  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<decodebin-element:src_1> do probes 
cookie 25
0:00:10.336086589  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<decodebin-element:src_1> hook 1, 
cookie 25 with flags 0x00003040 does not match 00001400
0:00:10.336108762  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<decodebin-element> 0x9911060 ref 6->7
0:00:10.336129421  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<decodebin-element:src_1> query 
caps caps query: 0xb6a08060, GstQueryCaps, filter=(GstCaps)NULL, 
caps=(GstCaps)NULL;
0:00:10.336161353  2080 0xb5c04d80 DEBUG               GST_PADS 
gstutils.c:2523:gst_pad_proxy_query_caps:<decodebin-element:src_1> 
proxying caps query for decodebin-element:src_1
0:00:10.336181057  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11390
0:00:10.336200246  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<decodebin-element> 0x9911060 ref 7->8
0:00:10.336222366  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<src_1:proxypad3> 0x970aad0 ref 1->2
0:00:10.336247721  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<decodebin-element> 0x9911060 unref 8->7
0:00:10.336271411  2080 0xb5c04d80 LOG                 GST_PADS 
gstpad.c:2728:gst_pad_forward:<decodebin-element:src_1> calling forward 
function on pad src_1:proxypad3
0:00:10.336292064  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<src_1:proxypad3> peer query 0xb6a08060 
(caps)
0:00:10.336312361  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<a52dec0:src> 0xb5c31158 ref 1->2
0:00:10.336331653  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<a52dec0:src> doing query 0xb6a08060 (caps)
0:00:10.336350160  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<a52dec0:src> do probes cookie 14
0:00:10.336367079  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<a52dec0:src> hook 1, cookie 14 with 
flags 0x00003272 does not match 00001402
0:00:10.336421050  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3266:do_probe_callbacks:<a52dec0:src> data is passed
0:00:10.336436652  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<a52dec0:src> do probes cookie 15
0:00:10.336453560  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<a52dec0:src> hook 1, cookie 15 with 
flags 0x00003272 does not match 00001400
0:00:10.336475483  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<a52dec0> 0xb5c2a170 ref 2->3
0:00:10.336496514  2080 0xb5c04d80 LOG             audiodecoder 
gstaudiodecoder.c:2482:gst_audio_decoder_src_query:<a52dec0> handling 
query: caps query: 0xb6a08060, GstQueryCaps, filter=(GstCaps)NULL, 
caps=(GstCaps)NULL;
0:00:10.347504876  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<a52dec0:src> query caps caps 
query: 0xb6a08060, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.347759909  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2906:gst_pad_query_caps_default:<a52dec0:src> fixed pad caps: 
trying pad caps
0:00:10.347789719  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2942:gst_pad_query_caps_default:<a52dec0:src> using caps 
0xb5c28118 audio/x-raw, format=(string)F32LE, 
layout=(string)interleaved, rate=(int)48000, channels=(int)2, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.347854841  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 4->5
0:00:10.347881632  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 5->6
0:00:10.347905121  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 6->5
0:00:10.347925390  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<a52dec0:src> not forwarding 
0xb6a08060 (caps) query
0:00:10.347948669  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<a52dec0> 0xb5c2a170 unref 3->2
0:00:10.347969927  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<a52dec0:src> sent query 0xb6a08060 (caps), 
result 1
0:00:10.347991582  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<a52dec0:src> do probes cookie 16
0:00:10.348011925  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<a52dec0:src> hook 1, cookie 16 with 
flags 0x00003272 does not match 00002400
0:00:10.348037159  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<a52dec0:src> 0xb5c31158 unref 2->1
0:00:10.348061369  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2485:query_caps_func:<src_1:proxypad3> intersect with result 
audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.348099503  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 5->6
0:00:10.348119603  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2487:query_caps_func:<src_1:proxypad3> intersected 
audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.348152853  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11390 unref 1->0
0:00:10.348174812  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11390
0:00:10.348203368  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f1740 ref 4->5
0:00:10.348222403  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 6->7
0:00:10.348240517  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 7->6
0:00:10.348258234  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f1740 unref 5->4
0:00:10.348276555  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 6->7
0:00:10.348295193  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 7->6
0:00:10.348312868  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 6->5
0:00:10.348333370  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<decodebin-element:src_1> not 
forwarding 0xb6a08060 (caps) query
0:00:10.348354953  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<decodebin-element> 0x9911060 unref 7->6
0:00:10.348392118  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<decodebin-element:src_1> sent query 
0xb6a08060 (caps), result 1
0:00:10.348417147  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3190:do_probe_callbacks:<decodebin-element:src_1> do probes 
cookie 26
0:00:10.348452546  2080 0xb5c04d80 LOG           GST_SCHEDULING 
gstpad.c:3134:probe_hook_marshal:<decodebin-element:src_1> hook 1, 
cookie 26 with flags 0x00003040 does not match 00002400
0:00:10.348476711  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb5c28118 ref 5->6
0:00:10.348497116  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2768:gst_pad_query_caps:<decodebin-element:src_1> query 
returned audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.348531749  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a08060 unref 1->0
0:00:10.348550808  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 6->5
0:00:10.348569546  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e060
0:00:10.348590201  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2761:gst_pad_query_caps:<queue4:sink> get pad caps with 
filter (NULL)
0:00:10.348609293  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e210
0:00:10.348629895  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a07fb0 caps
0:00:10.348652027  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<queue4:sink> doing query 0xb6a07fb0 (caps)
0:00:10.348672312  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue4> 0x99169a8 ref 1->2
0:00:10.348694259  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<queue4:sink> query caps caps 
query: 0xb6a07fb0, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.348724189  2080 0xb5c04d80 DEBUG               GST_PADS 
gstutils.c:2523:gst_pad_proxy_query_caps:<queue4:sink> proxying caps 
query for queue4:sink
0:00:10.348744058  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a115b8
0:00:10.348763139  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue4> 0x99169a8 ref 2->3
0:00:10.348783334  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue4> 0x99169a8 ref 3->4
0:00:10.348802070  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:2618:gst_pad_iterate_internal_links_default:<queue4:sink> 
Making iterator
0:00:10.348820799  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue4> 0x99169a8 unref 5->4
0:00:10.348839511  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue4> 0x99169a8 unref 4->3
0:00:10.348863312  2080 0xb5c04d80 LOG                 GST_PADS 
gstpad.c:2728:gst_pad_forward:<queue4:sink> calling forward function on 
pad queue4:src
0:00:10.348884501  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<queue4:src> peer query 0xb6a07fb0 (caps)
0:00:10.348905154  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audio-conv:sink> 0x991e638 ref 1->2
0:00:10.348925113  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<audio-conv:sink> doing query 0xb6a07fb0 (caps)
0:00:10.348944882  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audio-conv> 0x9926d78 ref 1->2
0:00:10.348967419  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x9853ef0 ref 2->3
0:00:10.348986544  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x9853f90 ref 2->3
0:00:10.349007149  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2807:gst_pad_peer_query_caps:<audio-conv:src> get pad peer 
caps with filter (NULL)
0:00:10.349024685  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985d320
0:00:10.349061579  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a08000 caps
0:00:10.349082174  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<audio-conv:src> peer query 0xb6a08000 
(caps)
0:00:10.349102399  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue2:sink> 0x991eb18 ref 1->2
0:00:10.349122420  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<queue2:sink> doing query 0xb6a08000 (caps)
0:00:10.349141670  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue2> 0x9916548 ref 1->2
0:00:10.349161985  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<queue2:sink> query caps caps 
query: 0xb6a08000, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.349190615  2080 0xb5c04d80 DEBUG               GST_PADS 
gstutils.c:2523:gst_pad_proxy_query_caps:<queue2:sink> proxying caps 
query for queue2:sink
0:00:10.349208891  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11540
0:00:10.349227100  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue2> 0x9916548 ref 2->3
0:00:10.349247222  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue2> 0x9916548 ref 3->4
0:00:10.349266769  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:2618:gst_pad_iterate_internal_links_default:<queue2:sink> 
Making iterator
0:00:10.349283203  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue2> 0x9916548 unref 5->4
0:00:10.349302371  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue2> 0x9916548 unref 4->3
0:00:10.349323885  2080 0xb5c04d80 LOG                 GST_PADS 
gstpad.c:2728:gst_pad_forward:<queue2:sink> calling forward function on 
pad queue2:src
0:00:10.349343291  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<queue2:src> peer query 0xb6a08000 (caps)
0:00:10.349362692  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audrate:sink> 0x991ed88 ref 1->2
0:00:10.349399720  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<audrate:sink> doing query 0xb6a08000 (caps)
0:00:10.349420906  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audrate> 0x992c278 ref 1->2
0:00:10.349441542  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<audrate:sink> query caps caps 
query: 0xb6a08000, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.349469815  2080 0xb5c04d80 DEBUG               GST_PADS 
gstutils.c:2523:gst_pad_proxy_query_caps:<audrate:sink> proxying caps 
query for audrate:sink
0:00:10.349488360  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11518
0:00:10.349506703  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audrate> 0x992c278 ref 2->3
0:00:10.349526542  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audrate> 0x992c278 ref 3->4
0:00:10.349546057  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:2618:gst_pad_iterate_internal_links_default:<audrate:sink> 
Making iterator
0:00:10.349562041  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audrate> 0x992c278 unref 5->4
0:00:10.349581428  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audrate> 0x992c278 unref 4->3
0:00:10.349602558  2080 0xb5c04d80 LOG                 GST_PADS 
gstpad.c:2728:gst_pad_forward:<audrate:sink> calling forward function on 
pad audrate:src
0:00:10.349622354  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<audrate:src> peer query 0xb6a08000 (caps)
0:00:10.349641935  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue3:sink> 0x992e018 ref 1->2
0:00:10.349680136  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<queue3:sink> doing query 0xb6a08000 (caps)
0:00:10.349701105  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue3> 0x9916778 ref 1->2
0:00:10.349721534  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<queue3:sink> query caps caps 
query: 0xb6a08000, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.349749808  2080 0xb5c04d80 DEBUG               GST_PADS 
gstutils.c:2523:gst_pad_proxy_query_caps:<queue3:sink> proxying caps 
query for queue3:sink
0:00:10.349768316  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11568
0:00:10.349786537  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue3> 0x9916778 ref 2->3
0:00:10.349806381  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue3> 0x9916778 ref 3->4
0:00:10.349826071  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:2618:gst_pad_iterate_internal_links_default:<queue3:sink> 
Making iterator
0:00:10.349841972  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue3> 0x9916778 unref 5->4
0:00:10.349860679  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue3> 0x9916778 unref 4->3
0:00:10.349881366  2080 0xb5c04d80 LOG                 GST_PADS 
gstpad.c:2728:gst_pad_forward:<queue3:sink> calling forward function on 
pad queue3:src
0:00:10.349900998  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<queue3:src> peer query 0xb6a08000 (caps)
0:00:10.349920754  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audio-caps:sink> 0x991e8a8 ref 1->2
0:00:10.349940756  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<audio-caps:sink> doing query 0xb6a08000 (caps)
0:00:10.349960689  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<audio-caps> 0x991c3c8 ref 1->2
0:00:10.349981006  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x9853f40 ref 2->3
0:00:10.350001292  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x9853878 ref 2->3
0:00:10.350020769  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2807:gst_pad_peer_query_caps:<audio-caps:src> get pad peer 
caps with filter (NULL)
0:00:10.350037603  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985da80
0:00:10.350056578  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a08030 caps
0:00:10.350075447  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3619:gst_pad_peer_query:<audio-caps:src> peer query 0xb6a08030 
(caps)
0:00:10.350095121  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<lameenc:sink> 0x992e288 ref 1->2
0:00:10.350114725  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<lameenc:sink> doing query 0xb6a08030 (caps)
0:00:10.350134984  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<lameenc> 0x9932208 ref 1->2
0:00:10.350157819  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f3db8 ref 2->3
0:00:10.350180595  2080 0xb5c04d80 DEBUG         GST_PROPERTIES 
gstpad.c:2549:gst_pad_get_allowed_caps:<lameenc:src> getting allowed caps
0:00:10.350196579  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<mp4-mux:audio_0> 0x992eb10 ref 2->3
0:00:10.350215547  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2761:gst_pad_query_caps:<lameenc:src> get pad caps with 
filter (NULL)
0:00:10.350232397  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985d140
0:00:10.350250862  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a07f80 caps
0:00:10.350287742  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<lameenc:src> doing query 0xb6a07f80 (caps)
0:00:10.350307448  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<lameenc> 0x9932208 ref 2->3
0:00:10.350328367  2080 0xb5c04d80 LOG             audioencoder 
gstaudioencoder.c:1801:gst_audio_encoder_src_query:<lameenc> handling 
query: caps query: 0xb6a07f80, GstQueryCaps, filter=(GstCaps)NULL, 
caps=(GstCaps)NULL;
0:00:10.350359237  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<lameenc:src> query caps caps 
query: 0xb6a07f80, GstQueryCaps, filter=(GstCaps)NULL, caps=(GstCaps)NULL;
0:00:10.350521715  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2906:gst_pad_query_caps_default:<lameenc:src> fixed pad caps: 
trying pad caps
0:00:10.350539427  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2912:gst_pad_query_caps_default:<lameenc:src> trying pad 
template caps
0:00:10.350554694  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2942:gst_pad_query_caps_default:<lameenc:src> using caps 
0x98f3d90 audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 
8000, 11025, 12000, 16000, 22050, 24000, 32000, 44100, 48000 }, 
channels=(int)[ 1, 2 ]
0:00:10.350614705  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f3d90 ref 2->3
0:00:10.350634454  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f3d90 ref 3->4
0:00:10.350654448  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f3d90 unref 4->3
0:00:10.350674406  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<lameenc:src> not forwarding 
0xb6a07f80 (caps) query
0:00:10.350695064  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<lameenc> 0x9932208 unref 3->2
0:00:10.350714538  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<lameenc:src> sent query 0xb6a07f80 (caps), 
result 1
0:00:10.350734167  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f3d90 ref 3->4
0:00:10.350753647  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2768:gst_pad_query_caps:<lameenc:src> query returned 
audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 8000, 11025, 
12000, 16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)[ 1, 2 ]
0:00:10.350801380  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a07f80 unref 1->0
0:00:10.350820672  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f3d90 unref 4->3
0:00:10.350839427  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985d140
0:00:10.350857626  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2761:gst_pad_query_caps:<mp4-mux:audio_0> get pad caps with 
filter audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 8000, 
11025, 12000, 16000, 22050, 24000, 32000, 44100, 48000 }, 
channels=(int)[ 1, 2 ]
0:00:10.350905716  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e220
0:00:10.350921905  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f3d90 ref 3->4
0:00:10.350941777  2080 0xb5c04d80 DEBUG                  query 
gstquery.c:680:gst_query_new_custom: creating new query 0xb6a07f50 caps
0:00:10.350961215  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3494:gst_pad_query:<mp4-mux:audio_0> doing query 0xb6a07f50 (caps)
0:00:10.350981147  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<mp4-mux> 0x9939070 ref 1->2
0:00:10.351002701  2080 0xb5c04d80 DEBUG            collectpads 
gstcollectpads.c:1902:gst_collect_pads_query:<mp4-mux:audio_0> Got caps 
query on sink pad
0:00:10.351025187  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2889:gst_pad_query_caps_default:<mp4-mux:audio_0> query caps 
caps query: 0xb6a07f50, GstQueryCaps, filter=(GstCaps)audio/mpeg, 
mpegversion=(int)1, layer=(int)3, rate=(int){ 8000, 11025, 12000, 16000, 
22050, 24000, 32000, 44100, 48000 }, channels=(int)[ 1, 2 ], 
caps=(GstCaps)NULL;
0:00:10.351112105  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2912:gst_pad_query_caps_default:<mp4-mux:audio_0> trying pad 
template caps
0:00:10.351130216  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2936:gst_pad_query_caps_default:<mp4-mux:audio_0> using caps 
0x99286c8 audio/mpeg, mpegversion=(int)1, layer=(int)3, channels=(int)[ 
1, 2 ], rate=(int)[ 1, 2147483647 ]; audio/mpeg, mpegversion=(int)4, 
stream-format=(string)raw, channels=(int)[ 1, 8 ], rate=(int)[ 1, 
2147483647 ]; audio/x-alac, channels=(int)[ 1, 2 ], rate=(int)[ 1, 
2147483647 ] with filter 0x98f3d90 audio/mpeg, mpegversion=(int)1, 
layer=(int)3, rate=(int){ 8000, 11025, 12000, 16000, 22050, 24000, 
32000, 44100, 48000 }, channels=(int)[ 1, 2 ]
0:00:10.351228857  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11590
0:00:10.351249392  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e0c0
0:00:10.351294801  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e0d0
0:00:10.351314727  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e0d0
0:00:10.351333568  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2939:gst_pad_query_caps_default:<mp4-mux:audio_0> result 
0xb6a11590 audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 
8000, 11025, 12000, 16000, 22050, 24000, 32000, 44100, 48000 }, 
channels=(int)[ 1, 2 ]
0:00:10.351403218  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11590 ref 1->2
0:00:10.351423408  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11590 unref 2->1
0:00:10.351443581  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<mp4-mux:audio_0> not forwarding 
0xb6a07f50 (caps) query
0:00:10.351464178  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<mp4-mux> 0x9939070 unref 2->1
0:00:10.351484034  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<mp4-mux:audio_0> sent query 0xb6a07f50 
(caps), result 1
0:00:10.351503051  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11590 ref 1->2
0:00:10.351522367  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2768:gst_pad_query_caps:<mp4-mux:audio_0> query returned 
audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 8000, 11025, 
12000, 16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)[ 1, 2 ]
0:00:10.351568469  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a07f50 unref 1->0
0:00:10.351586885  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f3d90 unref 4->3
0:00:10.351604702  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11590 unref 2->1
0:00:10.351622418  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e220
0:00:10.351639953  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<mp4-mux:audio_0> 0x992eb10 unref 3->2
0:00:10.351657518  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f3d90 unref 3->2
0:00:10.351676638  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2561:gst_pad_get_allowed_caps:<lameenc:src> allowed caps 
audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 8000, 11025, 
12000, 16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)[ 1, 2 ]
0:00:10.351726329  2080 0xb5c04d80 LOG             audioencoder 
gstaudioencoder.c:1362:gst_audio_encoder_proxy_getcaps:<lameenc> 
template caps audio/x-raw, format=(string)S16LE, 
layout=(string)interleaved, rate=(int){ 8000, 11025, 12000, 16000, 
22050, 24000, 32000, 44100, 48000 }, channels=(int)1; audio/x-raw, 
format=(string)S16LE, layout=(string)interleaved, rate=(int){ 8000, 
11025, 12000, 16000, 22050, 24000, 32000, 44100, 48000 }, 
channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.351821830  2080 0xb5c04d80 LOG             audioencoder 
gstaudioencoder.c:1363:gst_audio_encoder_proxy_getcaps:<lameenc> allowed 
caps audio/mpeg, mpegversion=(int)1, layer=(int)3, rate=(int){ 8000, 
11025, 12000, 16000, 22050, 24000, 32000, 44100, 48000 }, 
channels=(int)[ 1, 2 ]
0:00:10.351870863  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a114c8
0:00:10.351890876  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e190
0:00:10.351924879  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985daa0
0:00:10.351958577  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985daa0
0:00:10.351979015  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11428
0:00:10.351996916  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e0a0
0:00:10.352062980  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e0b0
0:00:10.352129208  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114c8 unref 1->0
0:00:10.352150944  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e190
0:00:10.352167613  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a114c8
0:00:10.352183633  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f3db8 unref 3->2
0:00:10.352201793  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:495:gst_mini_object_replace: replace 0xb6a11590 (1) with 
(nil) (0)
0:00:10.352221962  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11590 unref 1->0
0:00:10.352241283  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e0c0
0:00:10.352257944  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11590
0:00:10.352275451  2080 0xb5c04d80 LOG             audioencoder 
gstaudioencoder.c:1407:gst_audio_encoder_proxy_getcaps:<lameenc> proxy 
caps audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 22050, 24000, 
32000, 44100, 48000 }, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int){ 8000, 11025, 12000, 
16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)2, 
format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.352348067  2080 0xb5c04d80 LOG             audioencoder 
gstaudioencoder.c:1418:gst_audio_encoder_getcaps_default:<lameenc> 
returning caps audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 
22050, 24000, 32000, 44100, 48000 }, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved; audio/x-raw, 
rate=(int){ 8000, 11025, 12000, 16000, 22050, 24000, 32000, 44100, 48000 
}, channels=(int)2, format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.352439203  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11428 ref 1->2
0:00:10.352459835  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11428 unref 2->1
0:00:10.352479471  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<lameenc> 0x9932208 unref 2->1
0:00:10.352499632  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<lameenc:sink> sent query 0xb6a08030 (caps), 
result 1
0:00:10.352539274  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<lameenc:sink> 0x992e288 unref 2->1
0:00:10.352560688  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11428 ref 1->2
0:00:10.352580767  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2814:gst_pad_peer_query_caps:<audio-caps:src> peer query 
returned audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 22050, 
24000, 32000, 44100, 48000 }, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int){ 8000, 11025, 12000, 
16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)2, 
format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.352650988  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a08030 unref 1->0
0:00:10.352670115  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11428 unref 2->1
0:00:10.352688763  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985da80
0:00:10.352707810  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:721:gst_base_transform_query_caps:<audio-caps:sink> 
peer caps  audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 22050, 
24000, 32000, 44100, 48000 }, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int){ 8000, 11025, 12000, 
16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)2, 
format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.352779225  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:724:gst_base_transform_query_caps:<audio-caps:sink> 
our template  ANY
0:00:10.352797615  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11428 ref 1->2
0:00:10.352817017  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:726:gst_base_transform_query_caps:<audio-caps:sink> 
intersected audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 22050, 
24000, 32000, 44100, 48000 }, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int){ 8000, 11025, 12000, 
16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)2, 
format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.352887570  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:520:gst_base_transform_transform_caps:<audio-caps> 
transform caps (direction = 1)
0:00:10.352906397  2080 0xb5c04d80 LOG            basetransform 
gstbasetransform.c:522:gst_base_transform_transform_caps:<audio-caps> 
from: audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 22050, 24000, 
32000, 44100, 48000 }, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int){ 8000, 11025, 12000, 
16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)2, 
format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.352978256  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x99288c8 ref 1->2
0:00:10.352999993  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11368
0:00:10.353017534  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985cd60
0:00:10.353046765  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985df90
0:00:10.353070869  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985df90
0:00:10.353089977  2080 0xb5c04d80 DEBUG             capsfilter 
gstcapsfilter.c:227:gst_capsfilter_transform_caps:<audio-caps> 
input:     audio/x-raw, rate=(int){ 8000, 11025, 12000, 16000, 22050, 
24000, 32000, 44100, 48000 }, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int){ 8000, 11025, 12000, 
16000, 22050, 24000, 32000, 44100, 48000 }, channels=(int)2, 
format=(string)S16LE, layout=(string)interleaved, 
channel-mask=(bitmask)0x0000000000000003
0:00:10.353179058  2080 0xb5c04d80 DEBUG             capsfilter 
gstcapsfilter.c:228:gst_capsfilter_transform_caps:<audio-caps> 
filter:    (NULL)
0:00:10.353199605  2080 0xb5c04d80 DEBUG             capsfilter 
gstcapsfilter.c:230:gst_capsfilter_transform_caps:<audio-caps> caps 
filter:    audio/x-raw, rate=(int)44100, channels=(int)1
0:00:10.353226889  2080 0xb5c04d80 DEBUG             capsfilter 
gstcapsfilter.c:231:gst_capsfilter_transform_caps:<audio-caps> 
intersect: audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.353255881  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x99288c8 unref 2->1
0:00:10.353275904  2080 0xb5c04d80 LOG            basetransform 
gstbasetransform.c:524:gst_base_transform_transform_caps:<audio-caps> 
to: audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.353306096  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:547:gst_base_transform_transform_caps:<audio-caps> 
to: audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.353334720  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:734:gst_base_transform_query_caps:<audio-caps:sink> 
transformed  audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.353363451  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11428 unref 2->1
0:00:10.353400710  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:741:gst_base_transform_query_caps:<audio-caps:sink> 
our template  ANY
0:00:10.353418506  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11368 ref 1->2
0:00:10.353437862  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:744:gst_base_transform_query_caps:<audio-caps:sink> 
intersected audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.353467204  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 2->1
0:00:10.353487085  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:768:gst_base_transform_query_caps:<audio-caps> 
returning  audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.353515205  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11428 unref 1->0
0:00:10.353535666  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e0a0
0:00:10.353553463  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e0b0
0:00:10.353569662  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11428
0:00:10.353585244  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x9853f40 unref 3->2
0:00:10.353603202  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x9853878 unref 3->2
0:00:10.353621164  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11368 ref 1->2
0:00:10.353639203  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 2->1
0:00:10.353658218  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audio-caps> 0x991c3c8 unref 2->1
0:00:10.353678248  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<audio-caps:sink> sent query 0xb6a08000 
(caps), result 1
0:00:10.353698313  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audio-caps:sink> 0x991e8a8 unref 2->1
0:00:10.353735915  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2485:query_caps_func:<queue3:src> intersect with result 
audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.353767180  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11368 ref 1->2
0:00:10.353787305  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2487:query_caps_func:<queue3:src> intersected audio/x-raw, 
rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.353815133  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11568 unref 1->0
0:00:10.353833362  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11568
0:00:10.353860102  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f1850 ref 7->8
0:00:10.353878840  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11368 ref 2->3
0:00:10.353896287  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 3->2
0:00:10.353913152  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f1850 unref 8->7
0:00:10.353930511  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11368 ref 2->3
0:00:10.353948059  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 3->2
0:00:10.353965388  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 2->1
0:00:10.353984210  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<queue3:sink> not forwarding 
0xb6a08000 (caps) query
0:00:10.354004200  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue3> 0x9916778 unref 2->1
0:00:10.354022840  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<queue3:sink> sent query 0xb6a08000 (caps), 
result 1
0:00:10.354042503  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue3:sink> 0x992e018 unref 2->1
0:00:10.354063321  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2485:query_caps_func:<audrate:src> intersect with result 
audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.354092869  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a11368 ref 1->2
0:00:10.354111988  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2487:query_caps_func:<audrate:src> intersected audio/x-raw, 
rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.354140330  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11518 unref 1->0
0:00:10.354158936  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11518
0:00:10.354178828  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f3d40 ref 3->4
0:00:10.354198899  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a114a0
0:00:10.354216071  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985d140
0:00:10.354255148  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 2->1
0:00:10.354274738  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f3d40 unref 4->3
0:00:10.354292503  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a114a0 ref 1->2
0:00:10.354310365  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11368 unref 1->0
0:00:10.354328868  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985cd60
0:00:10.354362403  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11368
0:00:10.358605626  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114a0 unref 2->1
0:00:10.358677681  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<audrate:sink> not forwarding 
0xb6a08000 (caps) query
0:00:10.358709996  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audrate> 0x992c278 unref 2->1
0:00:10.358734973  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<audrate:sink> sent query 0xb6a08000 (caps), 
result 1
0:00:10.358760944  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audrate:sink> 0x991ed88 unref 2->1
0:00:10.358788786  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2485:query_caps_func:<queue2:src> intersect with result 
audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.358856734  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a114a0 ref 1->2
0:00:10.358877792  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2487:query_caps_func:<queue2:src> intersected audio/x-raw, 
rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved
0:00:10.358909216  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11540 unref 1->0
0:00:10.358930137  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11540
0:00:10.358959936  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f1850 ref 7->8
0:00:10.358979125  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a114a0 ref 2->3
0:00:10.358996748  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114a0 unref 3->2
0:00:10.359013851  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f1850 unref 8->7
0:00:10.359033861  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a114a0 ref 2->3
0:00:10.359053300  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114a0 unref 3->2
0:00:10.359071219  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114a0 unref 2->1
0:00:10.359090112  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<queue2:sink> not forwarding 
0xb6a08000 (caps) query
0:00:10.359111712  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue2> 0x9916548 unref 2->1
0:00:10.359131008  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<queue2:sink> sent query 0xb6a08000 (caps), 
result 1
0:00:10.359151120  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue2:sink> 0x991eb18 unref 2->1
0:00:10.359171499  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a114a0 ref 1->2
0:00:10.359191730  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2814:gst_pad_peer_query_caps:<audio-conv:src> peer query 
returned audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.359223560  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a08000 unref 1->0
0:00:10.359242956  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114a0 unref 2->1
0:00:10.359262301  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985d320
0:00:10.359289011  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:721:gst_base_transform_query_caps:<audio-conv:sink> 
peer caps  audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.359321203  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:724:gst_base_transform_query_caps:<audio-conv:sink> 
our template  audio/x-raw, format=(string){ S8, U8, S16LE, S16BE, U16LE, 
U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, U32LE, 
U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, S18LE, 
S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, rate=(int)[ 1, 
2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved
0:00:10.359454126  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a114f0
0:00:10.359478169  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985df40
0:00:10.359528047  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:726:gst_base_transform_query_caps:<audio-conv:sink> 
intersected audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.359563219  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:520:gst_base_transform_transform_caps:<audio-conv> 
transform caps (direction = 1)
0:00:10.359582306  2080 0xb5c04d80 LOG            basetransform 
gstbasetransform.c:522:gst_base_transform_transform_caps:<audio-conv> 
from: audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved
0:00:10.359614075  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11650
0:00:10.359633646  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985df60
0:00:10.359652798  2080 0xb5c04d80 TRACE        GST_PERFORMANCE 
gststructure.c:353:gst_structure_copy: doing copy 0x985df40 -> 0x985df60
0:00:10.359679842  2080 0xb5c04d80 INFO               structure 
gststructure.c:2854:gst_structure_get_valist: Expected field 
'channel-mask' in structure: audio/x-raw, rate=(int)44100, 
channels=(int)1, layout=(string)interleaved;
0:00:10.359718174  2080 0xb5c04d80 DEBUG           audioconvert 
gstaudioconvert.c:333:gst_audio_convert_transform_caps:<audio-conv> 
transformed audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved into audio/x-raw, 
rate=(int)44100, layout=(string)interleaved
0:00:10.359756423  2080 0xb5c04d80 LOG            basetransform 
gstbasetransform.c:524:gst_base_transform_transform_caps:<audio-conv> 
to: audio/x-raw, rate=(int)44100, layout=(string)interleaved
0:00:10.359783848  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:547:gst_base_transform_transform_caps:<audio-conv> 
to: audio/x-raw, rate=(int)44100, layout=(string)interleaved
0:00:10.359809355  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:734:gst_base_transform_query_caps:<audio-conv:sink> 
transformed  audio/x-raw, rate=(int)44100, layout=(string)interleaved
0:00:10.359834314  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114f0 unref 1->0
0:00:10.359853721  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985df40
0:00:10.359870502  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a114f0
0:00:10.359887729  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:741:gst_base_transform_query_caps:<audio-conv:sink> 
our template  audio/x-raw, format=(string){ S8, U8, S16LE, S16BE, U16LE, 
U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, U32LE, 
U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, S18LE, 
S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, rate=(int)[ 1, 
2147483647 ], channels=(int)[ 1, 2147483647 ], layout=(string)interleaved
0:00:10.359939796  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a11678
0:00:10.359957241  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985df50
0:00:10.360008074  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:744:gst_base_transform_query_caps:<audio-conv:sink> 
intersected audio/x-raw, rate=(int)44100, layout=(string)interleaved, 
format=(string){ S8, U8, S16LE, S16BE, U16LE, U16BE, S24_32LE, S24_32BE, 
U24_32LE, U24_32BE, S32LE, S32BE, U32LE, U32BE, S24LE, S24BE, U24LE, 
U24BE, S20LE, S20BE, U20LE, U20BE, S18LE, S18BE, U18LE, U18BE, F32LE, 
F32BE, F64LE, F64BE }, channels=(int)[ 1, 2147483647 ]
0:00:10.360076048  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11650 unref 1->0
0:00:10.360097358  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985df60
0:00:10.360114400  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11650
0:00:10.360131954  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:241:gst_caps_new_empty: created caps 0xb6a116c8
0:00:10.360148823  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985e1b0
0:00:10.360191177  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a11678 unref 1->0
0:00:10.360211289  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a11678
0:00:10.360230254  2080 0xb5c04d80 DEBUG          basetransform 
gstbasetransform.c:768:gst_base_transform_query_caps:<audio-conv> 
returning  audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved; audio/x-raw, 
rate=(int)44100, layout=(string)interleaved, format=(string){ S8, U8, 
S16LE, S16BE, U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, 
S32LE, S32BE, U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, 
U20LE, U20BE, S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, 
channels=(int)[ 1, 2147483647 ]
0:00:10.360289137  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a114a0 unref 1->0
0:00:10.360309954  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985d140
0:00:10.360326254  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a114a0
0:00:10.360342161  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x9853ef0 unref 3->2
0:00:10.360359389  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x9853f90 unref 3->2
0:00:10.360394653  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a116c8 ref 1->2
0:00:10.360416016  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a116c8 unref 2->1
0:00:10.360436434  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audio-conv> 0x9926d78 unref 2->1
0:00:10.360456801  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<audio-conv:sink> sent query 0xb6a07fb0 
(caps), result 1
0:00:10.360477551  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<audio-conv:sink> 0x991e638 unref 2->1
0:00:10.360498810  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2485:query_caps_func:<queue4:src> intersect with result 
audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int)44100, 
layout=(string)interleaved, format=(string){ S8, U8, S16LE, S16BE, 
U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, 
U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, 
S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, 
channels=(int)[ 1, 2147483647 ]
0:00:10.364731214  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a116c8 ref 1->2
0:00:10.364774502  2080 0xb5c04d80 DEBUG                default 
gstutils.c:2487:query_caps_func:<queue4:src> intersected audio/x-raw, 
rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int)44100, 
layout=(string)interleaved, format=(string){ S8, U8, S16LE, S16BE, 
U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, 
U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, 
S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, 
channels=(int)[ 1, 2147483647 ]
0:00:10.364895271  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a115b8 unref 1->0
0:00:10.364923115  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a115b8
0:00:10.364957455  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0x98f1850 ref 7->8
0:00:10.364977925  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a116c8 ref 2->3
0:00:10.364995668  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a116c8 unref 3->2
0:00:10.365013464  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0x98f1850 unref 8->7
0:00:10.365033942  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a116c8 ref 2->3
0:00:10.365054720  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a116c8 unref 3->2
0:00:10.365073196  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a116c8 unref 2->1
0:00:10.365094380  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3020:gst_pad_query_default:<queue4:sink> not forwarding 
0xb6a07fb0 (caps) query
0:00:10.365118364  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue4> 0x99169a8 unref 2->1
0:00:10.365138417  2080 0xb5c04d80 DEBUG               GST_PADS 
gstpad.c:3516:gst_pad_query:<queue4:sink> sent query 0xb6a07fb0 (caps), 
result 1
0:00:10.365160446  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:360:gst_mini_object_ref: 0xb6a116c8 ref 1->2
0:00:10.365180127  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstutils.c:2768:gst_pad_query_caps:<queue4:sink> query returned 
audio/x-raw, rate=(int)44100, channels=(int)1, format=(string)S16LE, 
layout=(string)interleaved; audio/x-raw, rate=(int)44100, 
layout=(string)interleaved, format=(string){ S8, U8, S16LE, S16BE, 
U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, S32LE, S32BE, 
U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, U20LE, U20BE, 
S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, 
channels=(int)[ 1, 2147483647 ]
0:00:10.365236230  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a07fb0 unref 1->0
0:00:10.365256578  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a116c8 unref 2->1
0:00:10.365275455  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e210
0:00:10.365297298  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2020:gst_pad_link_check_compatible_unlocked:<decodebin-element:src_1> 
src caps audio/x-raw, format=(string)F32LE, layout=(string)interleaved, 
rate=(int)48000, channels=(int)2, channel-mask=(bitmask)0x0000000000000003
0:00:10.365336714  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2022:gst_pad_link_check_compatible_unlocked:<queue4:sink> sink 
caps audio/x-raw, rate=(int)44100, channels=(int)1, 
format=(string)S16LE, layout=(string)interleaved; audio/x-raw, 
rate=(int)44100, layout=(string)interleaved, format=(string){ S8, U8, 
S16LE, S16BE, U16LE, U16BE, S24_32LE, S24_32BE, U24_32LE, U24_32BE, 
S32LE, S32BE, U32LE, U32BE, S24LE, S24BE, U24LE, U24BE, S20LE, S20BE, 
U20LE, U20BE, S18LE, S18BE, U18LE, U18BE, F32LE, F32BE, F64LE, F64BE }, 
channels=(int)[ 1, 2147483647 ]
0:00:10.365440716  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb5c28118 unref 5->4
0:00:10.365461667  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb6a116c8 unref 1->0
0:00:10.365481175  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985e1b0
0:00:10.365523589  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985df50
0:00:10.365541432  2080 0xb5c04d80 TRACE               GST_CAPS 
gstcaps.c:200:_gst_caps_free: freeing caps 0xb6a116c8
0:00:10.365557535  2080 0xb5c04d80 DEBUG               GST_CAPS 
gstpad.c:2040:gst_pad_link_check_compatible_unlocked: caps are notcompatible
0:00:10.365573773  2080 0xb5c04d80 INFO                GST_PADS 
gstpad.c:2176:gst_pad_link_prepare: caps are incompatible
0:00:10.365593146  2080 0xb5c04d80 TRACE              structure 
gststructure.c:148:gst_structure_new_id_empty_with_size: created 
structure 0x985df90
0:00:10.365622314  2080 0xb5c04d80 LOG              GST_MESSAGE 
gstmessage.c:284:gst_message_new_custom: source sink: creating new 
message 0xb165a778 structure-change
0:00:10.365645291  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<queue4:sink> 0x992e768 ref 3->4
0:00:10.365668655  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:247:gst_object_ref:<bus0> 0x97c44c0 ref 19->20
0:00:10.365689661  2080 0xb5c04d80 DEBUG                GST_BUS 
gstbus.c:312:gst_bus_post:<bus0> [msg 0xb165a778] posting on bus 
structure-change message: 0xb165a778, time 99:99:99.999999999, seq-num 
259, element 'sink', GstMessageStructureChange, 
type=(GstStructureChangeType)GST_STRUCTURE_CHANGE_TYPE_PAD_LINK, 
owner=(GstElement)"\(GstDecodeBin\)\ decodebin-element", 
busy=(boolean)false;
0:00:10.365762967  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:3396:gst_bin_handle_message_func:<av-recorder> [msg 0xb165a778] 
handling child sink message of type structure-change
0:00:10.365787550  2080 0xb5c04d80 LOG                      bin 
gstbin.c:863:message_check: looking at message 0xb165a950: 1
0:00:10.365807450  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:960:bin_remove_messages:<queue4:sink> deleting message 
0xb165a950 of types 0x00001000
0:00:10.365826438  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb165a950 unref 1->0
0:00:10.365844577  2080 0xb5c04d80 LOG              GST_MESSAGE 
gstmessage.c:186:_gst_message_free: finalize message 0xb165a950, 
structure-change from sink
0:00:10.365864577  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue4:sink> 0x992e768 unref 4->3
0:00:10.365885123  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985df00
0:00:10.365902276  2080 0xb5c04d80 LOG                      bin 
gstbin.c:863:message_check: looking at message 0xb6a06e80: 0
0:00:10.365921157  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:966:bin_remove_messages:<decodebin-element> not deleting 
message 0xb6a06e80 of type 0x00100000
0:00:10.365939438  2080 0xb5c04d80 LOG                      bin 
gstbin.c:863:message_check: looking at message 0x993ee30: 0
0:00:10.365959140  2080 0xb5c04d80 DEBUG                    bin 
gstbin.c:966:bin_remove_messages:<file-output> not deleting message 
0x993ee30 of type 0x00100000
0:00:10.365977960  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstminiobject.c:440:gst_mini_object_unref: 0xb165a778 unref 1->0
0:00:10.365995564  2080 0xb5c04d80 LOG              GST_MESSAGE 
gstmessage.c:186:_gst_message_free: finalize message 0xb165a778, 
structure-change from sink
0:00:10.366015388  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<queue4:sink> 0x992e768 unref 3->2
0:00:10.366034666  2080 0xb5c04d80 TRACE              structure 
gststructure.c:386:gst_structure_free: free structure 0x985df90
0:00:10.366053549  2080 0xb5c04d80 DEBUG                GST_BUS 
gstbus.c:343:gst_bus_post:<bus0> [msg 0xb165a778] dropped
0:00:10.366071210  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<bus0> 0x97c44c0 unref 20->19
0:00:10.366090371  2080 0xb5c04d80 TRACE        GST_REFCOUNTING 
gstobject.c:273:gst_object_unref:<decodebin-element> 0x9911060 unref 5->4
   Type is 'audio/x-raw' but link failed.



More information about the gstreamer-devel mailing list