Errors when using appsink/appsrc

classic Classic list List threaded Threaded
4 messages Options
Reply | Threaded
Open this post in threaded view
|

Errors when using appsink/appsrc

Per Thomsen
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> 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

CODE

package 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();
		}
	}
}


LOG


0: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
Reply | Threaded
Open this post in threaded view
|

Re: Errors when using appsink/appsrc

Sebastian Dröge-3
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 *):
> 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

signature.asc (968 bytes) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: Errors when using appsink/appsrc

Per Thomsen
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 *):
>> 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
Reply | Threaded
Open this post in threaded view
|

Re: Errors when using appsink/appsrc

Sebastian Dröge-3
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