Interceptor Lifecycle callback issue.

[Roy Pozarelli]

I'm confused by the output that I have (see the bottom) for the following code:

public class ReportInterceptor1 {
    
    @PostConstruct
    public void myInit(InvocationContext ic) {
        try {
            System.out.println("ReportInterceptor1.myInit() was called before.");
            ic.proceed();
            System.out.println("ReportInterceptor1.myInit() was called after.");
        } catch (Exception e) {
            e.printStackTrace();
        }
    }
    
    @PreDestroy
    public void myCleanup(InvocationContext ic) {
         try {
            System.out.println("ReportInterceptor1.myCleanup() was called before.");
            ic.proceed();
            System.out.println("ReportInterceptor1.myCleanup() was called after.");
        } catch (Exception e) {
            e.printStackTrace();
        }
    }
    
    @AroundInvoke
    public Object reportIt(InvocationContext ic) throws Exception {
        
        String className = ic.getMethod().getDeclaringClass().getName();
        String methodName = ic.getMethod().getName();
        
        try {
            System.out.println("ReportInterceptor 1 : " + className + "." + methodName + "() was called before.");
            Object result = ic.proceed();
            System.out.println("ReportInterceptor 1 : " + className + "." + methodName + "() was called after.");
            return result;
        } catch (Exception e) {
            throw e;
        }
    }
}

I have 3 other classes with the only difference in the numbers.

@Stateless
@Interceptors({ReportInterceptor1.class, ReportInterceptor2.class})
public class Advice implements AdviceLocal, AdviceRemote {

    private String[] adviceStrings = {
        "One word: inappropriate.",
        "You might want to rethink that haircut.",
        "Your boss will respect you if you tell him what you REALLY think of him.",
        "Visualize yourself with better clothes.",
        "Of course you don't have to go to work today.",
        "Do you really think you should be leaving the house like that?",
        "Read a book, once a year whether you need to or not."};  

    @Interceptors({ReportInterceptor3.class}_
    public String getLocalAdvice() {
        return getAdvice();
    }
    
    @Interceptors({ReportInterceptor4.class})    
    public String getRemoteAdvice() {
        return getAdvice();
    }

    private String getAdvice() {
        int random = (int) (Math.random() * adviceStrings.length);
        return adviceStrings[random];
    }

    @PostConstruct
    public void myInit() {
        try {
            System.out.println("Advice.myInit() called before.");
            System.out.println("Advice.myInit() called after.");
        } catch (Exception e) {
            e.printStackTrace();
        }
    }
    
    @PreDestroy
    public void myCleanup() {
        try {
            System.out.println("Advice.myCleanup() called before.");
            System.out.println("Advice.myCleanup() called after.");
        } catch (Exception e) {
            e.printStackTrace();
        }
    }
}

I have no other information in the DD for the interceptors.

The following is the output that I get in Glassfish Server 3.1.1 window.

INFO: Test1 was successfully deployed in 510 milliseconds.
INFO: ReportInterceptor1.myInit() was called before.
INFO: ReportInterceptor2.myInit() was called before.
INFO: Advice.myInit() called before.
INFO: Advice.myInit() called after.
INFO: ReportInterceptor2.myInit() was called after.
INFO: ReportInterceptor1.myInit() was called after.
INFO: ReportInterceptor 1 : MyBeans.Advice.getLocalAdvice() was called before.
INFO: ReportInterceptor 2 : MyBeans.Advice.getLocalAdvice() was called before.
INFO: ReportInterceptor 3 : MyBeans.Advice.getLocalAdvice() was called before.
INFO: ReportInterceptor 3 : MyBeans.Advice.getLocalAdvice() was called after.
INFO: ReportInterceptor 2 : MyBeans.Advice.getLocalAdvice() was called after.
INFO: ReportInterceptor 1 : MyBeans.Advice.getLocalAdvice() was called after.
INFO: ReportInterceptor 1 : MyBeans.Advice.getLocalAdvice() was called before.
INFO: ReportInterceptor 2 : MyBeans.Advice.getLocalAdvice() was called before.
INFO: ReportInterceptor 3 : MyBeans.Advice.getLocalAdvice() was called before.
INFO: ReportInterceptor 3 : MyBeans.Advice.getLocalAdvice() was called after.
INFO: ReportInterceptor 2 : MyBeans.Advice.getLocalAdvice() was called after.
INFO: ReportInterceptor 1 : MyBeans.Advice.getLocalAdvice() was called after.
INFO: ReportInterceptor1.myCleanup() was called before.
INFO: ReportInterceptor2.myCleanup() was called before.
INFO: Advice.myCleanup() called before.
INFO: Advice.myCleanup() called after.
INFO: ReportInterceptor2.myCleanup() was called after.
INFO: ReportInterceptor1.myCleanup() was called after.


I get the myCleanup() messages since I "undeploy" the application. What confuses me is that I do NOT get a
INFO: ReportInterceptor3.myInit() was called before. (or "after")
or
INFO: ReportInterceptor3.myCleanupt() was called before. (or "after")
message.

The only difference that is evident is that the 1 & 2 are class level interceptors and 3 & 4 are method level interceptors. I used a *.jsp file to invoke the getLocalAdvice(). So why am I seeing this output?