We are building an application that needs to dynamically apply an overlay on a stream from a Red5 Server.
We have created a pipeline that includes the appsink/appsrc plugins to retrieve the individual frames, so we can stamp the overlay image on the frames. We are using the Java wrapper by @neilcsmith. The code is below. When we run a sample file into the application, we get the following error: basesrc gstbasesrc.c:2843:void gst_base_src_loop(GstPad *):<appsrc>[00m marking pending DISCONT (more of the log file below) From what we can google, we need to add a queue element to the pipeline, but we are not sure where that queue should be placed in the pipeline, or if that is indeed the solution to this problem. Please let us know what we are missing, or how we can change our pipeline to get this to work. Thanks, Per Thomsen CODEpackage com.bumebox.core.red5.transcoder; import java.awt.Graphics2D; import java.awt.image.BufferedImage; import java.io.ByteArrayInputStream; import java.io.File; import java.util.Date; import javax.imageio.ImageIO; import org.freedesktop.gstreamer.Buffer; import org.freedesktop.gstreamer.Bus; import org.freedesktop.gstreamer.Caps; import org.freedesktop.gstreamer.Element; import org.freedesktop.gstreamer.ElementFactory; import org.freedesktop.gstreamer.Gst; import org.freedesktop.gstreamer.GstObject; import org.freedesktop.gstreamer.Pad; import org.freedesktop.gstreamer.Pipeline; import org.freedesktop.gstreamer.Sample; import org.freedesktop.gstreamer.State; import org.freedesktop.gstreamer.Structure; import org.freedesktop.gstreamer.elements.AppSink; import org.freedesktop.gstreamer.elements.AppSrc; import com.bumebox.core.red5.EnvConfig; public class GStreamerOverlay { private boolean isFirstRead; private long initialDelayMs = 4000; private long overlayDurationMs = 5000; private long startOverlayMs; private boolean showOverlay = false; private BufferedImage waterMarkImage; public void startOverlay() throws Exception { String waterMarkFileName = EnvConfig.getFilePath("top_overlay.png"); waterMarkImage = ImageIO.read(new File(waterMarkFileName)); boolean enableAppSink = true; Gst.init("Test", new String[] { "--gst-debug-level=4" }); // Gst.init("Test1", new String[] { }); String srcLocation = "src/test/resources/video/iphone-video.MOV"; // String srcLocation = "rtmp://192.168.1.6:1935/livetest/yyy"; String rtmpSink = "rtmp://localhost:1935/livetest/yyy live=1"; final Pipeline pipe = new Pipeline("First pipeline"); Element source = ElementFactory.make("filesrc", "filesink"); // Element source = ElementFactory.make("rtmpsrc", "filesink"); Element demux = ElementFactory.make("qtdemux", "t"); final Element h264decode = ElementFactory.make("avdec_h264", "h264"); Element queue1 = ElementFactory.make("queue", "queue1"); Element rgbConverter = ElementFactory.make("videoconvert", "rgbconverter"); Element rgbCapsFilter = ElementFactory.make("capsfilter", "rgbcaps"); String rgbCapsStr = "video/x-raw,format=RGB,width=1280,height=720"; rgbCapsFilter.setCaps(new Caps(rgbCapsStr)); final Element h264Converter = ElementFactory.make("videoconvert", "h264converter"); Element h264CapsFilter = ElementFactory.make("capsfilter", "h264caps"); String h264CapsStr = "video/x-raw,format=I420,width=1280,height=720"; h264CapsFilter.setCaps(new Caps(h264CapsStr)); Element queue2 = ElementFactory.make("queue", "queue2"); Element x264enc = ElementFactory.make("x264enc", "x264"); x264enc.set("tune", 0x00000004); // Element queue3 = ElementFactory.make("queue", "queue3"); Element flvMux = ElementFactory.make("flvmux", "mux"); final Element aacparse = ElementFactory.make("aacparse", "aac"); Element queue4 = ElementFactory.make("queue", "queue4"); Element rtmpsink = ElementFactory.make("rtmpsink", "rtmpSink"); source.set("location", srcLocation); rtmpsink.set("location", rtmpSink); pipe.addMany(source, demux, h264decode, queue1, rgbConverter, rgbCapsFilter, h264Converter, h264CapsFilter, queue2, x264enc, flvMux, aacparse, queue4, rtmpsink); source.link(demux); h264decode.link(queue1); queue1.link(rgbConverter); rgbConverter.link(rgbCapsFilter); AppSink appSink = new AppSink("appSink"); appSink.set("emit-signals", true); // appSink.set("sync", true); // appSink.set("sync", false); /* * StringBuilder appSinkCaps = new StringBuilder("video/x-raw, "); // * JNA creates ByteBuffer using native byte order, set masks according * to that. if (ByteOrder.nativeOrder() == ByteOrder.LITTLE_ENDIAN) { * appSinkCaps.append("format=BGRx"); } else { * appSinkCaps.append("format=xRGB"); } * appSinkCaps.append(",width=1280,height=720"); */ appSink.setCaps(new Caps(rgbCapsStr)); // Element tee = ElementFactory.make("tee", "split"); // pipe.add(tee); final AppSrc appSrc = new AppSrc("appsrc") { }; appSrc.setCaps(new Caps(rgbCapsStr)); appSrc.set("is-live", true); appSrc.set("format", 3); appSrc.set("do-timestamp", true); appSrc.set("block", true); if (enableAppSink) { pipe.add(appSink); pipe.add(appSrc); // Element tQ1 = ElementFactory.make("queue", "tq1"); // Element tQ2 = ElementFactory.make("queue", "tq2"); // pipe.add(tQ1); // pipe.add(tQ2); // rgbCapsFilter.link(tee); // tee.link(tQ1); // tee.link(tQ2); // tQ1.link(h264Converter); // tQ2.link(appSink); // tQ1.link(appSink); rgbCapsFilter.link(appSink); // tQ1.link(appSink); appSrc.link(h264Converter); /* * Bus.ERROR errorSignal = new Bus.ERROR() { * * @Override public void errorMessage(GstObject source, int code, * String message) { System.out.println("AppSrc error source:" + * source + ", code:" + code + ", msg:" + message); } }; */ // appSrc.getBus().connect(errorSignal); // appSrc.getBus().disconnect(errorSignal); } else { rgbCapsFilter.link(h264Converter); } h264Converter.link(h264CapsFilter); h264CapsFilter.link(queue2); queue2.link(x264enc); // x264enc.link(queue3); aacparse.link(queue4); // queue3.link(flvMux); x264enc.link(flvMux); queue4.link(flvMux); flvMux.link(rtmpsink); // pipe.debugToDotFile(5, "src/test/resources/temp.txt"); demux.connect(new Element.PAD_ADDED() { @Override public void padAdded(Element ele, Pad pad) { System.out.println("Ele :" + ele + ", pad:" + pad); if (pad.getName().startsWith("audio")) { boolean success = Element.linkPads(ele, pad.getName(), aacparse, "sink"); System.out.println("Audio link success :" + success); } else if (pad.getName().startsWith("video")) { boolean success = Element.linkPads(ele, pad.getName(), h264decode, "sink"); System.out.println("Video link success :" + success); } } }); appSink.connect(new AppSink.NEW_SAMPLE() { @Override public void newBuffer(AppSink appsink) { Sample sample = appsink.pullSample(); Structure capsStruct = sample.getCaps().getStructure(0); int w = capsStruct.getInteger("width"); int h = capsStruct.getInteger("height"); String format = capsStruct.getString("format"); Buffer buffer = sample.getBuffer(); // ByteBuffer bBuffer = buffer.map(false); // byte[] arr = new byte[bBuffer.remaining()]; // bBuffer.get(arr); // createImageFromBytes(arr); // System.out.println("Sample caps : [w=" + w + ",h=" + h + // ", format=" // + format + "]"); if (buffer != null) { appSrc.pushBuffer(buffer); } // buffer.unmap(); } }); /* * appSink.connect(new AppSink.EOS() { * * @Override public void eos(AppSink appsink) { * System.out.println("EOS on " + appsink); } }); */ appSink.connect(new AppSink.NEW_PREROLL() { @Override public void newPreroll(AppSink appsink) { System.out.println("NewPreroll on " + appsink); Sample sample = appsink.pullPreroll(); Structure capsStruct = sample.getCaps().getStructure(0); int w = capsStruct.getInteger("width"); int h = capsStruct.getInteger("height"); String format = capsStruct.getString("format"); Buffer buffer = sample.getBuffer(); System.out.println("On PreRoll Sample caps : [w=" + w + ",h=" + h + ", format=" + format + "]"); // ByteBuffer bBuffer = buffer.map(false); // byte[] arr = new byte[bBuffer.remaining()]; // bBuffer.get(arr); // createImageFromBytes(arr); // Pad pad = h264Converter.getPads().get(0); // System.out.println(pad); // h264Converter.emit("push-buffer", buffer);; if (buffer != null) { appSrc.pushBuffer(buffer); System.out.println("Push buffer to appSrc in preroll"); } else { System.out .println("Push buffer to appSrc in preroll is null"); } // buffer.unmap(); } }); /* * appSrc.connect(new AppSrc.PUSH_BUFFER() { * * @Override public FlowReturn pushBuffer(AppSrc ele, Buffer buffer) { * System.out.println("AppSrc pushbuffer:" + buffer); return * FlowReturn.OK; } }); */ /* * appSrc.getBus().connect(new Bus.EOS() { * * @Override public void endOfStream(GstObject obj) { * System.out.println("AppSrc EOS:" + obj); } }); */ /* * appSrc.getBus().connect(new Bus.MESSAGE() { * * @Override public void busMessage(Bus bus, Message msg) { * System.out.println("AppSrc bus:" + bus + ", msg:" + msg); * //h264Converter.postMessage(msg); } }); */ System.out.println(pipe); pipe.play(); pipe.getBus().connect(new Bus.EOS() { @Override public void endOfStream(GstObject gst) { System.out.println("Reached EOS:" + gst); Gst.quit(); } }); Gst.main(); pipe.setState(State.NULL); Gst.deinit(); } private BufferedImage createImageFromBytes(byte[] imageData) { BufferedImage image = null; if (imageData != null && imageData.length > 0) { ByteArrayInputStream bais = new ByteArrayInputStream(imageData); try { image = ImageIO.read(bais); long current = System.currentTimeMillis(); if (!isFirstRead) { startOverlayMs = current + initialDelayMs; isFirstRead = true; } setOverlayState(current); if (showOverlay) { Graphics2D g = image.createGraphics(); g.drawImage(waterMarkImage, 0, 0, null); } } catch (Exception e) { e.printStackTrace(); } } return image; } private void setOverlayState(long current) { if ((startOverlayMs - current) <= 0) { showOverlay = !showOverlay; startOverlayMs = current + overlayDurationMs; if (showOverlay) { System.out.println("Overlay started at :" + new Date(current)); } else { System.out.println("Overlay stopped at :" + new Date(current)); } } } public static void main(String[] args) { System.setProperty("jna.library.path", "/Library/Frameworks/GStreamer.framework/Versions/Current/lib"); GStreamerOverlay overlay = new GStreamerOverlay(); try { overlay.startOverlay(); } catch (Exception e) { e.printStackTrace(); } } } LOG0:00:00.000556000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_INIT gstmessage.c:119:void _priv_gst_message_initialize():[00m init messages 0:00:00.001311000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_INIT gstcontext.c:83:void _priv_gst_context_initialize():[00m init contexts 0:00:00.001719000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:316:void _priv_gst_plugin_initialize():[00m registering 0 static plugins 0:00:00.001863000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:224:gboolean gst_plugin_register_static(gint, gint, const gchar *, const gchar *, GstPluginInitFunc, const gchar *, const gchar *, const gchar *, const gchar *, const gchar *):[00m registered static plugin "staticelements" 0:00:00.001877000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:226:gboolean gst_plugin_register_static(gint, gint, const gchar *, const gchar *, GstPluginInitFunc, const gchar *, const gchar *, const gchar *, const gchar *, const gchar *):[00m added static plugin "staticelements", result: 1 0:00:00.001901000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m GST_REGISTRY gstregistry.c:1723:gboolean ensure_current_registry(GError **):[00m reading registry cache: /Users/mallik/.cache/gstreamer-1.0/registry.x86_64.bin 0:00:00.028774000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m GST_REGISTRY gstregistrybinary.c:619:gboolean priv_gst_registry_binary_read_cache(GstRegistry *, const char *):[00m loaded /Users/mallik/.cache/gstreamer-1.0/registry.x86_64.bin in 0.026846 seconds 0:00:00.028842000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m GST_REGISTRY gstregistry.c:1579:GstRegistryScanAndUpdateResult scan_and_update_registry(GstRegistry *, const gchar *, gboolean, GError **):[00m Validating plugins from registry cache: /Users/mallik/.cache/gstreamer-1.0/registry.x86_64.bin 0:00:00.034905000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m GST_REGISTRY gstregistry.c:1681:GstRegistryScanAndUpdateResult scan_and_update_registry(GstRegistry *, const gchar *, gboolean, GError **):[00m Registry cache has not changed 0:00:00.034919000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m GST_REGISTRY gstregistry.c:1758:gboolean ensure_current_registry(GError **):[00m registry reading and updating done, result = 1 0:00:00.034930000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_INIT gst.c:724:gboolean init_post(GOptionContext *, GOptionGroup *, gpointer, GError **):[00m GLib runtime version: 2.46.2 0:00:00.034937000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_INIT gst.c:726:gboolean init_post(GOptionContext *, GOptionGroup *, gpointer, GError **):[00m GLib headers version: 2.46.2 0:00:00.034942000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_INIT gst.c:727:gboolean init_post(GOptionContext *, GOptionGroup *, gpointer, GError **):[00m initialized GStreamer successfully 0:00:00.245095000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "pipeline" named "First pipeline" 0:00:00.269978000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstcoreelements.so" loaded 0:00:00.270008000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "filesrc" named "filesink" 0:00:00.270186000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseSrc@0x7fa8b9aac100>[00m adding pad 'src' 0:00:00.280188000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstisomp4.so" loaded 0:00:00.280218000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "qtdemux" named "t" 0:00:00.280648000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQTDemux@0x7fa8bb8d5800>[00m adding pad 'sink' 0:00:00.309024000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_avs 0:00:00.309161000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_daud 0:00:00.309418000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_ea 0:00:00.309464000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_ffm 0:00:00.309504000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_4xm 0:00:00.309552000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_gxf 0:00:00.309590000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_idcin 0:00:00.309636000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_ipmovie 0:00:00.310019000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_mm 0:00:00.310081000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_mmf 0:00:00.311006000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_nsv 0:00:00.311049000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_nut 0:00:00.311314000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_film_cpk 0:00:00.311369000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_smk 0:00:00.311405000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_sol 0:00:00.311445000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_psxstr 0:00:00.311496000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_vmd 0:00:00.311545000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_wc3movie 0:00:00.311582000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_wsaud 0:00:00.311613000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_wsvqa 0:00:00.311648000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;32m GST_TYPEFIND gsttypefind.c:71:gboolean gst_type_find_register(GstPlugin *, const gchar *, guint, GstTypeFindFunction, const gchar *, GstCaps *, gpointer, GDestroyNotify):[00m registering typefind function for avtype_yuv4mpegpipe 0:00:00.312481000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstlibav.so" loaded 0:00:00.312500000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "avdec_h264" named "h264" 0:00:00.313103000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstVideoDecoder@0x7fa8ba18e5a0>[00m adding pad 'sink' 0:00:00.313131000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstVideoDecoder@0x7fa8ba18e5a0>[00m adding pad 'src' 0:00:00.316407000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "queue" named "queue1" 0:00:00.316593000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQueue@0x7fa8b9aae100>[00m adding pad 'sink' 0:00:00.316632000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQueue@0x7fa8b9aae100>[00m adding pad 'src' 0:00:00.319531000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstvideoconvert.so" loaded 0:00:00.319559000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "videoconvert" named "rgbconverter" 0:00:00.319868000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8b9a732d0>[00m adding pad 'sink' 0:00:00.319900000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8b9a732d0>[00m adding pad 'src' 0:00:00.321083000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "capsfilter" named "rgbcaps" 0:00:00.321208000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8bd888150>[00m adding pad 'sink' 0:00:00.321235000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8bd888150>[00m adding pad 'src' 0:00:00.323415000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.323542000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "videoconvert" named "h264converter" 0:00:00.323586000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8bd8842d0>[00m adding pad 'sink' 0:00:00.323612000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8bd8842d0>[00m adding pad 'src' 0:00:00.324378000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "capsfilter" named "h264caps" 0:00:00.324419000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8bd888480>[00m adding pad 'sink' 0:00:00.324453000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseTransform@0x7fa8bd888480>[00m adding pad 'src' 0:00:00.325313000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.325411000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "queue" named "queue2" 0:00:00.325460000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQueue@0x7fa8b9aae3f0>[00m adding pad 'sink' 0:00:00.325492000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQueue@0x7fa8b9aae3f0>[00m adding pad 'src' 0:00:00.328878000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m x264enc gstx264enc.c:2638:gboolean plugin_init(GstPlugin *):[00m x264 build: 148 0:00:00.328925000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstx264.so" loaded 0:00:00.328941000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "x264enc" named "x264" 0:00:00.329264000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstVideoEncoder@0x7fa8bd8850f0>[00m adding pad 'sink' 0:00:00.329292000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstVideoEncoder@0x7fa8bd8850f0>[00m adding pad 'src' 0:00:00.349502000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstflv.so" loaded 0:00:00.349529000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "flvmux" named "mux" 0:00:00.349721000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstFlvMux@0x7fa8b9ab00a0>[00m adding pad 'src' 0:00:00.351734000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstaudioparsers.so" loaded 0:00:00.351756000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "aacparse" named "aac" 0:00:00.351863000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseParse@0x7fa8b99c7460>[00m adding pad 'sink' 0:00:00.351891000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseParse@0x7fa8b99c7460>[00m adding pad 'src' 0:00:00.352960000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "queue" named "queue4" 0:00:00.353016000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQueue@0x7fa8b9aae6e0>[00m adding pad 'sink' 0:00:00.353048000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstQueue@0x7fa8b9aae6e0>[00m adding pad 'src' 0:00:00.357621000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstrtmp.so" loaded 0:00:00.357645000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "rtmpsink" named "rtmpSink" 0:00:00.357759000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseSink@0x7fa8bc0aea10>[00m adding pad 'sink' 0:00:00.362046000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m filesrc gstfilesrc.c:262:gboolean gst_file_src_set_location(GstFileSrc *, const gchar *):[00m filename : src/test/resources/video/iphone-video.MOV 0:00:00.362061000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m filesrc gstfilesrc.c:263:gboolean gst_file_src_set_location(GstFileSrc *, const gchar *):[00m uri : file:///Users/mallik/workspace/red5live/src/test/resources/video/iphone-video.MOV 0:00:00.367632000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element filesink:(any) to element t:(any) 0:00:00.367662000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link filesink:src and t:sink 0:00:00.367710000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m filesink and t in same bin, no need for ghost pads 0:00:00.367750000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link filesink:src and t:sink 0:00:00.367767000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked filesink:src and t:sink, successful 0:00:00.367782000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.367796000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<filesink:src>[00m Received event on flushing pad. Discarding 0:00:00.367873000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element h264:(any) to element queue1:(any) 0:00:00.367888000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link h264:src and queue1:sink 0:00:00.367918000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<queue1:src>[00m pad has no peer 0:00:00.367933000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m h264 and queue1 in same bin, no need for ghost pads 0:00:00.367950000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link h264:src and queue1:sink 0:00:00.367969000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<queue1:src>[00m pad has no peer 0:00:00.367981000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked h264:src and queue1:sink, successful 0:00:00.367991000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.367999000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<h264:src>[00m Received event on flushing pad. Discarding 0:00:00.368059000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element queue1:(any) to element rgbconverter:(any) 0:00:00.368072000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link queue1:src and rgbconverter:sink 0:00:00.368101000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<rgbconverter:src>[00m pad has no peer 0:00:00.368637000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m queue1 and rgbconverter in same bin, no need for ghost pads 0:00:00.368652000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link queue1:src and rgbconverter:sink 0:00:00.368675000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<rgbconverter:src>[00m pad has no peer 0:00:00.369168000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked queue1:src and rgbconverter:sink, successful 0:00:00.369177000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.369194000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<queue1:src>[00m Received event on flushing pad. Discarding 0:00:00.369262000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element rgbconverter:(any) to element rgbcaps:(any) 0:00:00.369277000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link rgbconverter:src and rgbcaps:sink 0:00:00.369730000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<rgbcaps:src>[00m pad has no peer 0:00:00.369758000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m rgbconverter and rgbcaps in same bin, no need for ghost pads 0:00:00.369770000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link rgbconverter:src and rgbcaps:sink 0:00:00.370211000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<rgbcaps:src>[00m pad has no peer 0:00:00.370233000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked rgbconverter:src and rgbcaps:sink, successful 0:00:00.370240000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.370245000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<rgbconverter:src>[00m Received event on flushing pad. Discarding 0:00:00.373554000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;36m GST_PLUGIN_LOADING gstplugin.c:842:GstPlugin *_priv_gst_plugin_load_file_for_registry(const gchar *, GstRegistry *, GError **):[00m plugin "/Library/Frameworks/GStreamer.framework/Versions/1.0/lib/gstreamer-1.0/libgstapp.so" loaded 0:00:00.373572000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "appsink" named "appSink" 0:00:00.373669000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseSink@0x7fa8ba19cbb0>[00m adding pad 'sink' 0:00:00.382824000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_FACTORY gstelementfactory.c:362:GstElement *gst_element_factory_create(GstElementFactory *, const gchar *):[00m creating element "appsrc" named "appsrc" 0:00:00.382949000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<GstBaseSrc@0x7fa8bc89f3b0>[00m adding pad 'src' 0:00:00.399619000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element rgbcaps:(any) to element appSink:(any) 0:00:00.399649000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link rgbcaps:src and appSink:sink 0:00:00.400176000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m rgbcaps and appSink in same bin, no need for ghost pads 0:00:00.400193000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link rgbcaps:src and appSink:sink 0:00:00.400661000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked rgbcaps:src and appSink:sink, successful 0:00:00.400670000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.400677000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<rgbcaps:src>[00m Received event on flushing pad. Discarding 0:00:00.400740000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element appsrc:(any) to element h264converter:(any) 0:00:00.400753000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link appsrc:src and h264converter:sink 0:00:00.400765000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<h264converter:src>[00m pad has no peer 0:00:00.401257000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m appsrc and h264converter in same bin, no need for ghost pads 0:00:00.401268000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link appsrc:src and h264converter:sink 0:00:00.401278000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<h264converter:src>[00m pad has no peer 0:00:00.401762000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked appsrc:src and h264converter:sink, successful 0:00:00.401770000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.401775000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<appsrc:src>[00m Received event on flushing pad. Discarding 0:00:00.401827000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element h264converter:(any) to element h264caps:(any) 0:00:00.401838000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link h264converter:src and h264caps:sink 0:00:00.402319000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<h264caps:src>[00m pad has no peer 0:00:00.402342000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m h264converter and h264caps in same bin, no need for ghost pads 0:00:00.402352000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link h264converter:src and h264caps:sink 0:00:00.402829000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<h264caps:src>[00m pad has no peer 0:00:00.402851000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked h264converter:src and h264caps:sink, successful 0:00:00.402858000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.402863000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<h264converter:src>[00m Received event on flushing pad. Discarding 0:00:00.402918000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element h264caps:(any) to element queue2:(any) 0:00:00.402929000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link h264caps:src and queue2:sink 0:00:00.403433000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<queue2:src>[00m pad has no peer 0:00:00.403442000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m h264caps and queue2 in same bin, no need for ghost pads 0:00:00.403451000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link h264caps:src and queue2:sink 0:00:00.403963000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<queue2:src>[00m pad has no peer 0:00:00.403972000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked h264caps:src and queue2:sink, successful 0:00:00.403979000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.403983000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<h264caps:src>[00m Received event on flushing pad. Discarding 0:00:00.404034000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element queue2:(any) to element x264:(any) 0:00:00.404045000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link queue2:src and x264:sink 0:00:00.404571000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.404589000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m queue2 and x264 in same bin, no need for ghost pads 0:00:00.404599000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link queue2:src and x264:sink 0:00:00.405106000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.405119000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked queue2:src and x264:sink, successful 0:00:00.405126000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.405131000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<queue2:src>[00m Received event on flushing pad. Discarding 0:00:00.405182000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element aac:(any) to element queue4:(any) 0:00:00.405194000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link aac:src and queue4:sink 0:00:00.405210000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<queue4:src>[00m pad has no peer 0:00:00.405217000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m aac and queue4 in same bin, no need for ghost pads 0:00:00.405226000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link aac:src and queue4:sink 0:00:00.405236000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<queue4:src>[00m pad has no peer 0:00:00.405242000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked aac:src and queue4:sink, successful 0:00:00.405248000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.405252000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<aac:src>[00m Received event on flushing pad. Discarding 0:00:00.405298000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element x264:(any) to element mux:(any) 0:00:00.405349000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<mux>[00m adding pad 'video' 0:00:00.405360000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m x264 and mux in same bin, no need for ghost pads 0:00:00.405369000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link x264:src and mux:video 0:00:00.405385000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked x264:src and mux:video, successful 0:00:00.405391000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.405396000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<x264:src>[00m Received event on flushing pad. Discarding 0:00:00.405444000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element queue4:(any) to element mux:(any) 0:00:00.405483000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<mux>[00m adding pad 'audio' 0:00:00.405492000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m queue4 and mux in same bin, no need for ghost pads 0:00:00.405501000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link queue4:src and mux:audio 0:00:00.405516000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked queue4:src and mux:audio, successful 0:00:00.405521000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.405526000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<queue4:src>[00m Received event on flushing pad. Discarding 0:00:00.405573000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element mux:(any) to element rtmpSink:(any) 0:00:00.405584000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:932:gboolean gst_pad_check_link(GstPad *, GstPad *):[00m trying to link mux:src and rtmpSink:sink 0:00:00.405595000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m mux and rtmpSink in same bin, no need for ghost pads 0:00:00.405604000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link mux:src and rtmpSink:sink 0:00:00.405613000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked mux:src and rtmpSink:sink, successful 0:00:00.405618000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 0:00:00.405623000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstpad.c:5622:GstFlowReturn gst_pad_send_event_unchecked(GstPad *, GstEvent *, GstPadProbeType):<mux:src>[00m Received event on flushing pad. Discarding 2016-06-13 19:06:55,078 [INFO] [main] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:500 - Pipeline: [First pipeline] 0:00:00.420455000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appSink>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.420484000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<appSink>[00m completed state change to READY 0:00:00.420491000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<appSink>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.423215000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appSink' changed state to 2(READY) successfully 0:00:00.423247000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rtmpSink>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.423340000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rtmpSink>[00m completed state change to READY 0:00:00.423353000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rtmpSink>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.423954000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rtmpSink' changed state to 2(READY) successfully 0:00:00.423999000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.424012000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rgbcaps>[00m completed state change to READY 0:00:00.424021000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rgbcaps>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.424542000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbcaps' changed state to 2(READY) successfully 0:00:00.424586000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.424599000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<mux>[00m completed state change to READY 0:00:00.424607000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<mux>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.425095000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'mux' changed state to 2(READY) successfully 0:00:00.425118000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.425131000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rgbconverter>[00m completed state change to READY 0:00:00.425139000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rgbconverter>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.425611000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbconverter' changed state to 2(READY) successfully 0:00:00.425636000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.425658000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<x264>[00m completed state change to READY 0:00:00.425669000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<x264>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.426029000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'x264' changed state to 2(READY) successfully 0:00:00.426047000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.426060000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue4>[00m completed state change to READY 0:00:00.426070000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue4>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.426439000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue4' changed state to 2(READY) successfully 0:00:00.426458000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.426471000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue1>[00m completed state change to READY 0:00:00.426480000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue1>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.426834000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue1' changed state to 2(READY) successfully 0:00:00.426853000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.426866000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue2>[00m completed state change to READY 0:00:00.426875000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue2>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.427226000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue2' changed state to 2(READY) successfully 0:00:00.427243000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.427256000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<aac>[00m completed state change to READY 0:00:00.427265000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<aac>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.427752000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'aac' changed state to 2(READY) successfully 0:00:00.427775000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.427795000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264>[00m completed state change to READY 0:00:00.427805000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.428773000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264' changed state to 2(READY) successfully 0:00:00.428792000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264caps>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.428801000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264caps>[00m completed state change to READY 0:00:00.428806000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264caps>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.429175000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264caps' changed state to 2(READY) successfully 0:00:00.429200000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264converter>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.429213000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264converter>[00m completed state change to READY 0:00:00.429223000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264converter>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.429571000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264converter' changed state to 2(READY) successfully 0:00:00.429587000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appsrc>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.429599000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<appsrc>[00m completed state change to READY 0:00:00.429608000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<appsrc>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.429938000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appsrc' changed state to 2(READY) successfully 0:00:00.429957000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<t>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.430401000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<t>[00m completed state change to READY 0:00:00.430423000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<t>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.431396000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 't' changed state to 2(READY) successfully 0:00:00.431415000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<filesink>[00m current NULL pending VOID_PENDING, desired next READY 0:00:00.431424000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<filesink>[00m completed state change to READY 0:00:00.431430000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<filesink>[00m notifying about state-changed NULL to READY (VOID_PENDING pending) 0:00:00.432492000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'filesink' changed state to 2(READY) successfully 0:00:00.432558000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2347:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<First pipeline>[00m committing state from NULL to READY, pending PLAYING, next PAUSED 0:00:00.432577000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<First pipeline>[00m notifying about state-changed NULL to READY (PLAYING pending) 0:00:00.432929000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2354:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<First pipeline>[00m continue state change READY to PAUSED, final PLAYING 0:00:00.432974000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appSink>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.433000000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appSink' is changing state asynchronously to PAUSED 0:00:00.433010000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rtmpSink>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.433021000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rtmpSink' is changing state asynchronously to PAUSED 0:00:00.433029000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.433041000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rgbcaps>[00m completed state change to PAUSED 0:00:00.433047000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rgbcaps>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.433357000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbcaps' changed state to 3(PAUSED) successfully 0:00:00.433372000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.433391000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<mux>[00m completed state change to PAUSED 0:00:00.433396000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<mux>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.433705000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'mux' changed state to 3(PAUSED) successfully 0:00:00.433720000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.433732000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rgbconverter>[00m completed state change to PAUSED 0:00:00.433737000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rgbconverter>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.434044000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbconverter' changed state to 3(PAUSED) successfully 0:00:00.434058000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.434078000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<x264>[00m completed state change to PAUSED 0:00:00.434084000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<x264>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.434389000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'x264' changed state to 3(PAUSED) successfully 0:00:00.434403000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.434428000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m task gsttask.c:451:void gst_task_set_lock(GstTask *, GRecMutex *):[00m setting stream lock 0x7fa8bd88b2d0 on task 0x7fa8ba9af050 0:00:00.434435000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:5968:gboolean gst_pad_start_task(GstPad *, GstTaskFunction, gpointer, GDestroyNotify):<queue4:src>[00m created task 0x7fa8ba9af050 0:00:00.434803000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue4>[00m completed state change to PAUSED 0:00:00.434812000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue4>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.435133000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue4' changed state to 3(PAUSED) successfully 0:00:00.435148000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.435163000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m task gsttask.c:451:void gst_task_set_lock(GstTask *, GRecMutex *):[00m setting stream lock 0x7fa8b99e2c20 on task 0x7fa8ba9af170 0:00:00.435169000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:5968:gboolean gst_pad_start_task(GstPad *, GstTaskFunction, gpointer, GDestroyNotify):<queue1:src>[00m created task 0x7fa8ba9af170 0:00:00.435569000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue1>[00m completed state change to PAUSED 0:00:00.435584000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue1>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.435901000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue1' changed state to 3(PAUSED) successfully 0:00:00.435916000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.435931000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m task gsttask.c:451:void gst_task_set_lock(GstTask *, GRecMutex *):[00m setting stream lock 0x7fa8bd88a310 on task 0x7fa8ba9af290 0:00:00.435938000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:5968:gboolean gst_pad_start_task(GstPad *, GstTaskFunction, gpointer, GDestroyNotify):<queue2:src>[00m created task 0x7fa8ba9af290 0:00:00.436280000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue2>[00m completed state change to PAUSED 0:00:00.436291000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue2>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.436751000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue2' changed state to 3(PAUSED) successfully 0:00:00.436779000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.436893000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:4086:gboolean gst_pad_peer_query(GstPad *, GstQuery *):<aac:sink>[00m pad has no peer 0:00:00.436914000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<aac>[00m completed state change to PAUSED 0:00:00.436924000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<aac>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.437332000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'aac' changed state to 3(PAUSED) successfully 0:00:00.437348000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.437850000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264>[00m completed state change to PAUSED 0:00:00.437863000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.438200000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264' changed state to 3(PAUSED) successfully 0:00:00.438218000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264caps>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.438232000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264caps>[00m completed state change to PAUSED 0:00:00.438238000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264caps>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.438558000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264caps' changed state to 3(PAUSED) successfully 0:00:00.438574000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264converter>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.438586000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264converter>[00m completed state change to PAUSED 0:00:00.438591000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264converter>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.438916000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264converter' changed state to 3(PAUSED) successfully 0:00:00.438930000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appsrc>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.438952000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m basesrc gstbasesrc.c:1344:gboolean gst_base_src_do_seek(GstBaseSrc *, GstSegment *):<appsrc>[00m seeking: time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999 0:00:00.438974000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m task gsttask.c:451:void gst_task_set_lock(GstTask *, GRecMutex *):[00m setting stream lock 0x7fa8bd88b990 on task 0x7fa8ba9af3b0 0:00:00.438981000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:5968:gboolean gst_pad_start_task(GstPad *, GstTaskFunction, gpointer, GDestroyNotify):<appsrc:src>[00m created task 0x7fa8ba9af3b0 0:00:00.439334000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<appsrc>[00m completed state change to PAUSED 0:00:00.439344000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<appsrc>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.439666000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2807:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appsrc' changed state to 3(PAUSED) successfully without preroll 0:00:00.439684000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<t>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.439699000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m filesrc gstfilesrc.c:465:gboolean gst_file_src_start(GstBaseSrc *):<filesink>[00m opening file src/test/resources/video/iphone-video.MOV 0:00:00.439735000 [334m25989[00m 0x7fa8b9587f20 [33;01mWARN [00m [00m basesrc gstbasesrc.c:3486:void gst_base_src_start_complete(GstBaseSrc *, GstFlowReturn):<filesink>[00m pad not activated yet 0:00:00.439768000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m filesrc gstfilesrc.c:465:gboolean gst_file_src_start(GstBaseSrc *):<filesink>[00m opening file src/test/resources/video/iphone-video.MOV 0:00:00.439788000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00m task gsttask.c:451:void gst_task_set_lock(GstTask *, GRecMutex *):[00m setting stream lock 0x7fa8b99e2320 on task 0x7fa8ba9af4d0 0:00:00.439795000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:5968:gboolean gst_pad_start_task(GstPad *, GstTaskFunction, gpointer, GDestroyNotify):<t:sink>[00m created task 0x7fa8ba9af4d0 0:00:00.440155000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<t>[00m completed state change to PAUSED 0:00:00.440164000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<t>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.440222000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:904:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m no such pad 'sink' in element "appsrc" 0:00:00.440262000 [334m25989[00m 0x7fa8bc0c98a0 [32;01mFIXME [00m [00;04m default gstutils.c:3764:gchar *gst_pad_create_stream_id_internal(GstPad *, GstElement *, const gchar *):<appsrc:src>[00m Creating random stream-id, consider implementing a deterministic way of creating a stream-id 0:00:00.440496000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 't' changed state to 3(PAUSED) successfully 0:00:00.440511000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<filesink>[00m current READY pending VOID_PENDING, desired next PAUSED 0:00:00.440522000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<filesink>[00m completed state change to PAUSED 0:00:00.440527000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<filesink>[00m notifying about state-changed READY to PAUSED (VOID_PENDING pending) 0:00:00.440850000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'filesink' changed state to 3(PAUSED) successfully 0:00:00.440867000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2347:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<First pipeline>[00m committing state from READY to PAUSED, pending PLAYING, next PLAYING 0:00:00.440874000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<First pipeline>[00m notifying about state-changed READY to PAUSED (PLAYING pending) 0:00:00.441193000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2354:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<First pipeline>[00m continue state change PAUSED to PLAYING, final PLAYING 0:00:00.441737000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1253:GstEvent *gst_event_new_latency(GstClockTime):[00m creating latency event 0:00:00.000000000 0:00:00.441784000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01m bin gstbin.c:2593:gboolean gst_bin_do_latency_func(GstBin *):<First pipeline>[00m configured latency of 0:00:00.000000000 0:00:00.441817000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appSink>[00m current READY pending PAUSED, desired next PLAYING 0:00:00.441825000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appSink' is changing state asynchronously to PLAYING 0:00:00.441833000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rtmpSink>[00m current READY pending PAUSED, desired next PLAYING 0:00:00.441839000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rtmpSink' is changing state asynchronously to PLAYING 0:00:00.441846000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.441853000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rgbcaps>[00m completed state change to PLAYING 0:00:00.441858000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rgbcaps>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.442035000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux_types.c:222:qtdemux_type_get:[00m unknown QuickTime node type chan 0:00:00.442190000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbcaps' changed state to 4(PLAYING) successfully 0:00:00.442209000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.442217000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<mux>[00m completed state change to PLAYING 0:00:00.442222000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<mux>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.442238000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux_types.c:222:qtdemux_type_get:[00m unknown QuickTime node type tapt 0:00:00.442258000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux_types.c:222:qtdemux_type_get:[00m unknown QuickTime node type .swr 0:00:00.442270000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux.c:6488:qtdemux_parse_container:<t>[00m length too long (1578439 > 28) 0:00:00.442279000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux_types.c:222:qtdemux_type_get:[00m unknown QuickTime node type .xyz 0:00:00.442289000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux_types.c:222:qtdemux_type_get:[00m unknown QuickTime node type .mod 0:00:00.442298000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux_types.c:222:qtdemux_type_get:[00m unknown QuickTime node type .mak 0:00:00.442308000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux.c:6488:qtdemux_parse_container:<t>[00m length too long (1751411826 > 430) 0:00:00.442360000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m qtdemux qtdemux.c:11941:qtdemux_parse_tree:<t>[00m timescale: 600 0:00:00.442374000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m qtdemux qtdemux.c:11942:qtdemux_parse_tree:<t>[00m duration: 18076 0:00:00.442599000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux.c:2642:qtdemux_parse_trex:<t>[00m failed to find fragment defaults for stream 1 0:00:00.442658000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'mux' changed state to 4(PLAYING) successfully 0:00:00.442675000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.442685000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<rgbconverter>[00m completed state change to PLAYING 0:00:00.442694000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<rgbconverter>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.442876000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m qtdemux qtdemux.c:10269:qtdemux_parse_trak:<t>[00m type mp4a caps audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)1208 0:00:00.442943000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m qtdemux qtdemux.c:2642:qtdemux_parse_trex:<t>[00m failed to find fragment defaults for stream 2 0:00:00.442994000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m qtdemux qtdemux.c:9651:qtdemux_parse_trak:<t>[00m type avc1 caps video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)3.1, profile=(string)high, codec_data=(buffer)0164001fffe1000f2764001fac56c05005ba6a0202020401000428ee3cb0fdf8f800 0:00:00.443072000 [334m25989[00m 0x7fa8bc0c9370 [33;01mWARN [00m [00m basesrc gstbasesrc.c:2396:gboolean gst_base_src_update_length(GstBaseSrc *, guint64, guint *, gboolean):<filesink>[00m processing at or past EOS 0:00:00.443081000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;35m GST_SCHEDULING gstpad.c:4745:GstFlowReturn gst_pad_get_range_unchecked(GstPad *, guint64, guint, GstBuffer **):<filesink:src>[00m getrange failed, flow: eos 0:00:00.443081000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbconverter' changed state to 4(PLAYING) successfully 0:00:00.443111000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;35m GST_SCHEDULING gstpad.c:4960:GstFlowReturn gst_pad_pull_range(GstPad *, guint64, guint, GstBuffer **):<t:sink>[00m pullrange failed, flow: eos 0:00:00.443120000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.443134000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<x264>[00m completed state change to PLAYING 0:00:00.443142000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<x264>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.443393000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:907:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m found pad t:sink 0:00:00.443451000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)1208, rate=(int)44100, channels=(int)1 0:00:00.443484000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<t>[00m adding pad 'audio_0' 0:00:00.443573000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'x264' changed state to 4(PLAYING) successfully 0:00:00.443596000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.443608000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue4>[00m completed state change to PLAYING 0:00:00.443617000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue4>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.444091000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue4' changed state to 4(PLAYING) successfully 0:00:00.444117000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.444131000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue1>[00m completed state change to PLAYING 0:00:00.444140000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue1>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.444611000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue1' changed state to 4(PLAYING) successfully 0:00:00.444635000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.444649000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<queue2>[00m completed state change to PLAYING 0:00:00.444659000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<queue2>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.445132000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue2' changed state to 4(PLAYING) successfully 0:00:00.445155000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.445168000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<aac>[00m completed state change to PLAYING 0:00:00.445177000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<aac>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 2016-06-13 19:06:55,107 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:399 - Ele :Element: [t], pad:Pad: [audio_0] 0:00:00.445652000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'aac' changed state to 4(PLAYING) successfully 0:00:00.445675000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.445690000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264>[00m completed state change to PLAYING 0:00:00.445700000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.445715000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element t:audio_0 to element aac:sink 0:00:00.445734000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:907:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m found pad t:audio_0 0:00:00.445745000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:907:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m found pad aac:sink 0:00:00.445754000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m t and aac in same bin, no need for ghost pads 0:00:00.445773000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link t:audio_0 and aac:sink 0:00:00.445885000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked t:audio_0 and aac:sink, successful 0:00:00.445901000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 2016-06-13 19:06:55,108 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:403 - Audio link success :true 0:00:00.446184000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264' changed state to 4(PLAYING) successfully 0:00:00.446242000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appSink>[00m current READY pending PAUSED, desired next PLAYING 0:00:00.446257000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appSink' is changing state asynchronously to PLAYING 0:00:00.446271000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rtmpSink>[00m current READY pending PAUSED, desired next PLAYING 0:00:00.446282000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rtmpSink' is changing state asynchronously to PLAYING 0:00:00.446290000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:907:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m found pad t:sink 0:00:00.446296000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446350000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m skipping transition from PLAYING to PLAYING 0:00:00.446370000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbcaps' changed state to 4(PLAYING) successfully 0:00:00.446367000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)3.1, profile=(string)high, codec_data=(buffer)0164001fffe1000f2764001fac56c05005ba6a0202020401000428ee3cb0fdf8f800, width=(int)1280, height=(int)720, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1 0:00:00.446390000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446401000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:659:gboolean gst_element_add_pad(GstElement *, GstPad *):<t>[00m adding pad 'video_0' 0:00:00.446402000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m skipping transition from PLAYING to PLAYING 0:00:00.446441000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'mux' changed state to 4(PLAYING) successfully 0:00:00.446464000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446476000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m skipping transition from PLAYING to PLAYING 0:00:00.446484000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbconverter' changed state to 4(PLAYING) successfully 0:00:00.446497000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446507000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m skipping transition from PLAYING to PLAYING 0:00:00.446514000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'x264' changed state to 4(PLAYING) successfully 0:00:00.446525000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446535000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m skipping transition from PLAYING to PLAYING 0:00:00.446543000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue4' changed state to 4(PLAYING) successfully 0:00:00.446557000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446567000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m skipping transition from PLAYING to PLAYING 0:00:00.446575000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue1' changed state to 4(PLAYING) successfully 0:00:00.446586000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446597000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m skipping transition from PLAYING to PLAYING 0:00:00.446606000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue2' changed state to 4(PLAYING) successfully 0:00:00.446615000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446621000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m skipping transition from PLAYING to PLAYING 0:00:00.446625000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'aac' changed state to 4(PLAYING) successfully 0:00:00.446632000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.446638000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m skipping transition from PLAYING to PLAYING 0:00:00.446643000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264' changed state to 4(PLAYING) successfully 0:00:00.446651000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264caps>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.446658000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264caps>[00m completed state change to PLAYING 0:00:00.446663000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264caps>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.447065000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264caps' changed state to 4(PLAYING) successfully 0:00:00.447084000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<t>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.447096000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<t>[00m completed state change to PLAYING 0:00:00.447114000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<t>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 2016-06-13 19:06:55,109 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:399 - Ele :Element: [t], pad:Pad: [video_0] 0:00:00.447511000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 't' changed state to 4(PLAYING) successfully 0:00:00.447532000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264converter>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.447545000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<h264converter>[00m completed state change to PLAYING 0:00:00.447554000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<h264converter>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.447705000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstutils.c:1571:gboolean gst_element_link_pads_full(GstElement *, const gchar *, GstElement *, const gchar *, GstPadLinkCheck):[00m trying to link element t:video_0 to element h264:sink 0:00:00.447722000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:907:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m found pad t:video_0 0:00:00.447730000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;37;41m GST_ELEMENT_PADS gstelement.c:907:GstPad *gst_element_get_static_pad(GstElement *, const gchar *):[00m found pad h264:sink 0:00:00.447735000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;31;41m GST_PADS gstutils.c:1444:gboolean prepare_link_maybe_ghosting(GstPad **, GstPad **, GSList **):[00m t and h264 in same bin, no need for ghost pads 0:00:00.447748000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2312:GstPadLinkReturn gst_pad_link_prepare(GstPad *, GstPad *, GstPadLinkCheck):[00m trying to link t:video_0 and h264:sink 0:00:00.447947000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264converter' changed state to 4(PLAYING) successfully 0:00:00.447965000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<filesink>[00m current PAUSED pending VOID_PENDING, desired next PLAYING 0:00:00.447979000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<filesink>[00m completed state change to PLAYING 0:00:00.447989000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<filesink>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.448041000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;31;41m GST_PADS gstpad.c:2518:GstPadLinkReturn gst_pad_link_full(GstPad *, GstPad *, GstPadLinkCheck):[00m linked t:video_0 and h264:sink, successful 0:00:00.448052000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:1382:GstEvent *gst_event_new_reconfigure():[00m creating reconfigure event 2016-06-13 19:06:55,110 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:407 - Video link success :true 0:00:00.448328000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:760:GstEvent *gst_event_new_segment(const GstSegment *):[00m creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=0:00:30.126666666, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999 0:00:00.448382000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'filesink' changed state to 4(PLAYING) successfully 0:00:00.448426000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)1208, rate=(int)44100, channels=(int)1 0:00:00.448440000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<appSink>[00m current READY pending PAUSED, desired next PLAYING 0:00:00.448455000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appSink' is changing state asynchronously to PLAYING 0:00:00.448467000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m baseparse gstbaseparse.c:3902:void gst_base_parse_set_passthrough(GstBaseParse *, gboolean):<aac>[00m passthrough: yes 0:00:00.448470000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rtmpSink>[00m current READY pending PAUSED, desired next PLAYING 0:00:00.448481000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2770:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rtmpSink' is changing state asynchronously to PLAYING 0:00:00.448495000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448501000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbcaps>[00m skipping transition from PLAYING to PLAYING 0:00:00.448521000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbcaps' changed state to 4(PLAYING) successfully 0:00:00.448528000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m baseparse gstbaseparse.c:4737:void gst_base_parse_set_upstream_tags(GstBaseParse *, GstTagList *):<aac>[00m upstream tags: taglist, audio-codec=(string)"MPEG-4\ AAC\ audio", maximum-bitrate=(uint)64000, bitrate=(uint)64000; 0:00:00.448540000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448551000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<mux>[00m skipping transition from PLAYING to PLAYING 0:00:00.448558000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'mux' changed state to 4(PLAYING) successfully 0:00:00.448571000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448580000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<rgbconverter>[00m skipping transition from PLAYING to PLAYING 0:00:00.448588000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'rgbconverter' changed state to 4(PLAYING) successfully 0:00:00.448601000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448610000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<x264>[00m skipping transition from PLAYING to PLAYING 0:00:00.448617000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'x264' changed state to 4(PLAYING) successfully 0:00:00.448634000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448644000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue4>[00m skipping transition from PLAYING to PLAYING 0:00:00.448653000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue4' changed state to 4(PLAYING) successfully 0:00:00.448666000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448677000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue1>[00m skipping transition from PLAYING to PLAYING 0:00:00.448673000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:760:GstEvent *gst_event_new_segment(const GstSegment *):[00m creating segment event time segment start=0:00:00.033333333, offset=0:00:00.000000000, stop=0:00:30.159999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.033333333, duration 99:99:99.999999999 0:00:00.448701000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue1' changed state to 4(PLAYING) successfully 0:00:00.448716000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448725000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<queue2>[00m skipping transition from PLAYING to PLAYING 0:00:00.448742000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'queue2' changed state to 4(PLAYING) successfully 0:00:00.448767000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.448787000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<aac>[00m skipping transition from PLAYING to PLAYING 0:00:00.448796000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'aac' changed state to 4(PLAYING) successfully 0:00:00.454063000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.454094000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264>[00m skipping transition from PLAYING to PLAYING 0:00:00.454104000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264' changed state to 4(PLAYING) successfully 0:00:00.454122000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264caps>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.454132000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264caps>[00m skipping transition from PLAYING to PLAYING 0:00:00.454143000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264caps' changed state to 4(PLAYING) successfully 0:00:00.454158000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<t>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.454167000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<t>[00m skipping transition from PLAYING to PLAYING 0:00:00.454176000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 't' changed state to 4(PLAYING) successfully 0:00:00.454190000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264converter>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.454199000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<h264converter>[00m skipping transition from PLAYING to PLAYING 0:00:00.454207000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'h264converter' changed state to 4(PLAYING) successfully 0:00:00.454218000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2316:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<filesink>[00m current PLAYING pending VOID_PENDING, desired next PLAYING 0:00:00.454227000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2433:GstStateChangeReturn gst_bin_element_set_state(GstBin *, GstElement *, GstClockTime, GstClockTime, GstState, GstState):<filesink>[00m skipping transition from PLAYING to PLAYING 0:00:00.454235000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'filesink' changed state to 4(PLAYING) successfully 0:00:00.454259000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2372:GstStateChangeReturn gst_element_continue_state(GstElement *, GstStateChangeReturn):<appsrc>[00m completed state change to PLAYING 0:00:00.454269000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstelement.c:2277:void _priv_gst_element_state_changed(GstElement *, GstState, GstState, GstState):<appsrc>[00m notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending) 0:00:00.454277000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, format=(string)RGB, width=(int)1280, height=(int)720 0:00:00.454539000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.454588000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.454768000 [334m25989[00m 0x7fa8b9587f20 [36mINFO [00m [00;01;31m GST_STATES gstbin.c:2764:GstStateChangeReturn gst_bin_change_state_func(GstElement *, GstStateChange):<First pipeline>[00m child 'appsrc' changed state to 4(PLAYING) successfully 0:00:00.454804000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00m videodecoder gstvideodecoder.c:1432:gboolean gst_video_decoder_sink_event_default(GstVideoDecoder *, GstEvent *):<h264>[00m upstream tags: taglist, image-orientation=(string)rotate-90, video-codec=(string)"H.264\ /\ AVC", bitrate=(uint)4481744; 0:00:00.454820000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, width=(int)1280, height=(int)720, format=(string)I420, framerate=(fraction)0/1 0:00:00.454876000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.454906000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00m basetransform gstbasetransform.c:1367:gboolean gst_base_transform_setcaps(GstBaseTransform *, GstPad *, GstCaps *):<h264caps>[00m reuse caps 0:00:00.454920000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, width=(int)1280, height=(int)720, format=(string)I420, framerate=(fraction)0/1 0:00:00.454949000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.455001000 [334m25989[00m 0x7fa8bc0c9140 [36mINFO [00m [00m x264enc gstx264enc.c:561:void gst_x264_enc_add_x264_chroma_format(GstStructure *, int):[00m This x264 build supports 8-bit depth 0:00:00.456121000 [334m25989[00m 0x7fa8bc0c9140 [36mINFO [00m [00m x264enc :0::<x264>[00m using SAR=1/1 0:00:00.459274000 [334m25989[00m 0x7fa8bc0c9140 [36mINFO [00m [00m x264enc :0::<x264>[00m using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 AVX2 LZCNT BMI2 0:00:00.466871000 [334m25989[00m 0x7fa8bb22e8f0 [36mINFO [00m [00m libav :0::[00m Increasing reorder buffer to 1 0:00:00.467028000 [334m25989[00m 0x7fa8bc0c9140 [36mINFO [00m [00m x264enc :0::<x264>[00m profile High, level 3.1 0:00:00.468149000 [334m25989[00m 0x7fa8bc0c9140 [36mINFO [00m [00m x264enc gstx264enc.c:1889:void gst_x264_enc_set_latency(GstX264Enc *):<x264>[00m Updating latency to 0:00:00.040000000 (1 frames) 0:00:00.468849000 [334m25989[00m 0x7fa8bc0c98a0 [33;01mWARN [00m [00m videopool gstvideopool.c:216:video_buffer_pool_set_config:<videobufferpool1>[00m no caps in config 0:00:00.494895000 [334m25989[00m 0x7fa8bc0c9370 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, format=(string)I420, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)30/1 0:00:00.495573000 [334m25989[00m 0x7fa8bc0c9800 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, format=(string)RGB 0:00:00.495638000 [334m25989[00m 0x7fa8bc0c9800 [36mINFO [00m [00m basetransform gstbasetransform.c:1367:gboolean gst_base_transform_setcaps(GstBaseTransform *, GstPad *, GstCaps *):<rgbcaps>[00m reuse caps 0:00:00.495650000 [334m25989[00m 0x7fa8bc0c9800 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, format=(string)RGB 0:00:00.495677000 [334m25989[00m 0x7fa8bc0c9800 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:679:GstEvent *gst_event_new_caps(GstCaps *):[00m creating caps event video/x-raw, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, format=(string)RGB 2016-06-13 19:06:55,178 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:450 - NewPreroll on AppSink: [appSink] 2016-06-13 19:06:55,187 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:457 - On PreRoll Sample caps : [w=1280,h=720, format=RGB] 0:00:00.527330000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00;01;34m GST_EVENT gstevent.c:760:GstEvent *gst_event_new_segment(const GstSegment *):[00m creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999 0:00:00.527393000 [334m25989[00m 0x7fa8bc0c98a0 [36mINFO [00m [00m basesrc gstbasesrc.c:2843:void gst_base_src_loop(GstPad *):<appsrc>[00m marking pending DISCONT 2016-06-13 19:06:55,190 [INFO] [GstBus] com.bumebox.core.red5.transcoder.TestGStreamerTranscoder:468 - Push buffer to appSrc in preroll |
On Mo, 2016-06-13 at 18:31 -0700, Per Thomsen wrote:
> We are building an application that needs to dynamically apply an overlay on > a stream from a Red5 Server. > > We have created a pipeline that includes the appsink/appsrc plugins to > retrieve the individual frames, so we can stamp the overlay image on the > frames. > > We are using the Java wrapper by @neilcsmith. The code is below. > > When we run a sample file into the application, we get the following error: > > basesrc gstbasesrc.c:2843:void gst_base_src_loop(GstPad *):[00m > marking pending DISCONT > (more of the log file below) is not working? Also you say code and more log is below, but nothing is seen there. Can you please attach them to your mail (and also not use Nabble, which often breaks such things in mails)? -- Sebastian Dröge, Centricular Ltd · http://www.centricular.com _______________________________________________ gstreamer-devel mailing list [hidden email] https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel signature.asc (968 bytes) Download Attachment |
The problem is that the pipeline doesn’t work, ie. no data is moved beyond this point in the pipeline.
I have attached a zip file to this email with the Source and log snippet. Thanks, Per > On Jun 13, 2016, at 11:04 PM, Sebastian Dröge <[hidden email]> wrote: > > On Mo, 2016-06-13 at 18:31 -0700, Per Thomsen wrote: >> We are building an application that needs to dynamically apply an overlay on >> a stream from a Red5 Server. >> >> We have created a pipeline that includes the appsink/appsrc plugins to >> retrieve the individual frames, so we can stamp the overlay image on the >> frames. >> >> We are using the Java wrapper by @neilcsmith. The code is below. >> >> When we run a sample file into the application, we get the following error: >> >> basesrc gstbasesrc.c:2843:void gst_base_src_loop(GstPad *):[00m >> marking pending DISCONT >> (more of the log file below) > > This is not an error but normal. What is the actual error you get, what > is not working? > > Also you say code and more log is below, but nothing is seen there. Can > you please attach them to your mail (and also not use Nabble, which > often breaks such things in mails)? > > -- > Sebastian Dröge, Centricular Ltd · http://www.centricular.com > _______________________________________________ > gstreamer-devel mailing list > [hidden email] > https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel _______________________________________________ gstreamer-devel mailing list [hidden email] https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel GST-error.zip (18K) Download Attachment |
On Di, 2016-06-14 at 06:35 +0000, Per Thomsen wrote:
> The problem is that the pipeline doesn’t work, ie. no data is moved > beyond this point in the pipeline. Beyond which point in the pipeline? As most people here are not using the Java bindings, it might also be more efficient to write a small testcase for the problem in C. The code should be translatable more or less 1:1. > I have attached a zip file to this email with the Source and log > snippet. I don't have the time to look into this right now in more detail (but a short look at the log did not show any obvious problem), so it would help if you could analyse yourself a bit more. Up to where does data flow in the pipeline, where does it get stuck. What are all threads doing (get a backtrace of all threads with gdb) once it is stuck? Also follow the data flow inside the logs, at some point it will probably end up at something looking suspicious. I also don't see anything immediately looking wrong in your code, so that would need some further analysis and actually running it (for which see above again, a C testcase would be helpful as it makes it easier for most people here to run it). -- Sebastian Dröge, Centricular Ltd · http://www.centricular.com _______________________________________________ gstreamer-devel mailing list [hidden email] https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel signature.asc (968 bytes) Download Attachment |
Free forum by Nabble | Edit this page |