使用拦截器计算 Mule 流的处理时间

Question

我想计算我的 mule 流执行所需的执行时间，所以我使用了拦截器， 这是我的拦截器代码

    class CustomLoggerInterceptor extends AbstractEnvelopeInterceptor {
       @Override
      public MuleEvent last(MuleEvent event, ProcessingTime time, long startTime,
        boolean exceptionWasThrown) throws MuleException {
             long totalTime=time.getStatistics().getTotalProcessingTime();
             LOG.info("Start time for flow: "+event.getFlowConstruct().getName()+" is: "+startTime+" total execution time is: "+totalTime);
             return event;
       }
       //other inherited methods

    }

现在的问题是，每当我执行我的 mule 流程时，我从 time.getStatistics().getTotalProcessingTime() 获得的所有值始终是“0”。

是我使用了正确的方法还是我犯了一些错误？

我基本上需要一种从ProcessingTime 对象中查找执行时间的方法。

欢迎指点

谢谢！

Answer 1

我不建议将 AbstractEnvelopeInterceptor 用于两个原因，它不是公共 API 的一部分，而且它们不会完全按照您的要求做：

EnvelopeInterceptor is an intercepter that will fire before and after an event is received.

您是否考虑过使用server notifications？

Answer 2

您可以通过以下两种方式来做到这一点：-

1) 通过使用定时器拦截器：-

<timer-interceptor />

将其放在流程的末尾

2) 使用自定义拦截器创建自己的定时器拦截器：-

在流程结束时使用它：-

<custom-interceptor class="com.customInterceptor.TimerInterceptor" />

和 com.customInterceptor.TimerInterceptor 类：-

import org.mule.api.MuleEvent;
import org.mule.api.MuleException;
import org.mule.api.interceptor.Interceptor;
import org.mule.processor.AbstractInterceptingMessageProcessor;

import org.apache.commons.logging.Log;
import org.apache.commons.logging.LogFactory;

/**
 * <code>TimerInterceptor</code> simply times and displays the time taken to
 * process an event.
 */
public class TimerInterceptor extends AbstractInterceptingMessageProcessor
        implements Interceptor {
    /**
     * logger used by this class
     */
    private static Log logger = LogFactory.getLog(TimerInterceptor.class);

    public MuleEvent process(MuleEvent event) throws MuleException {
        long startTime = System.currentTimeMillis();

        MuleEvent resultEvent = processNext(event);

        if (logger.isInfoEnabled()) {
            long executionTime = System.currentTimeMillis() - startTime;
            logger.info("Custom Timer : "+resultEvent.getFlowConstruct().getName() + " took "
                    + executionTime + "ms to process event ["
                    + resultEvent.getId() + "]");
        }

        return resultEvent;
    }
}

Answer 3

我做了同样的测试，因为我想计算一个流的处理时间。

这是一个例子：

package com.testing.interceptor;

import org.apache.commons.logging.Log;
import org.apache.commons.logging.LogFactory;
import org.mule.api.MuleEvent;
import org.mule.api.MuleException;
import org.mule.interceptor.AbstractEnvelopeInterceptor;
import org.mule.management.stats.ProcessingTime;

public class CustomInterceptor extends AbstractEnvelopeInterceptor{

    private static Log logger = LogFactory.getLog(CustomInterceptor.class);


    @Override
    public MuleEvent last(MuleEvent event, ProcessingTime time, long startTime,
      boolean exceptionWasThrown) throws MuleException {
        long endTime = System.currentTimeMillis();

        logger.info("Flow:"+event.getFlowConstruct().getName()+"-Procesing Time:"+(endTime-startTime));
        return event;
     }

    @Override
    public MuleEvent before(MuleEvent event) throws MuleException {

        return event;
    }

    @Override
    public MuleEvent after(MuleEvent event) throws MuleException {


        return event;
    }
}

这就是流程：

<flow name="testingFlowClient">
        <http:listener config-ref="HTTP_Listener_Configuration" path="/client/*" doc:name="HTTP"/>
        <custom-interceptor class="com.testing.interceptor.CustomInterceptor" />
        <logger message="Before Transformation" level="INFO" />
        <json:json-to-object-transformer returnClass="com.testing.domain.GeneralRequest" />
        <set-variable variableName="processTypeJob" value="#[payload.processTypeJob]"/>
        <set-variable variableName="waitTime" value="#[payload.waitTime]"/>
        <logger message="After Transformation" level="INFO" />
        <json:object-to-json-transformer/>
        <http:request config-ref="HTTP_Request_Configuration" path="/service/{waitTime}" method="POST" 
                        responseTimeout="50000000">
            <http:request-builder>
                <http:query-param paramName="api_key"
                    value="abcde" />
                <http:query-param paramName="processTypeJob" value="#[processTypeJob]" />
                <http:query-param paramName="fields" value="averages" />
                <http:uri-param paramName="waitTime" value="#[waitTime]" />
            </http:request-builder>
        </http:request>
    </flow>

请注意，我使用“last”方法的“startTime”参数计算处理时间，并将“custom-interceptor”元素放在流程的开头。

这是打印出来的日志：

2015-12-10 18:21:27,493 INFO [com.testing.interceptor.TimerInterceptor] - Flow:testingFlowClient-Procesing Time:341