11 Replies Latest reply: May 24, 2012 10:51 AM by john_k Branched from an earlier discussion. RSS

Custom loginmodule for EJB - Null subject

john_k Newbie

good to see you solved a part of your problem francois

 

iam still stucking with another problem...

in this callbackhandler jaikiran pai mentioned i get a problem that the subject from the subjectcallback is null...

javax.security.auth.login.LoginException: LoginModule konnte nicht instanziiert werden: null

anyone knows whats the problem?

  • 1. Re: Custom Login Module with remote EJB
    Francois Swiegers Newbie

    Is your remote EJB tagged with

     

    @SecurityDomain("yoursecuritydomainname")

  • 2. Re: Custom Login Module with remote EJB
    john_k Newbie

    iam not sure thats the problem because he is not even ready with initializing the InitialContext...

  • 3. Re: Custom Login Module with remote EJB
    Francois Swiegers Newbie

    If you are using Maven, I am attaching my entire project, hopefully that will help you figure out what's wrong. I'm using Jboss 7.1.1-Final.

     

    In order to get this to work, you have to add zboss-login-module.jar to your jboss's org.jboss.as.remote module as described earlier in this thread.

    You also have to update your standalone.xml as follows:

     

    <security-realm name="ApplicationRealm">

                    <authentication>

                        <jaas name="alchemy"/>

                    </authentication>

                </security-realm>

     

    ...

     

    <security-domain name="alchemy" cache-type="default">

                        <authentication>

                            <login-module code="org.zboss.login.module.AlchemyLoginModule" flag="required"/>

                        </authentication>

                    </security-domain>

     

     

     

    The file to run is ClientCalculator.java.

     

    Let me know if this helps or not.

  • 4. Re: Custom Login Module with remote EJB
    john_k Newbie

    hi francois,

    thanks for your projects, but the problem remains the same the subject is still null for a reason i cannot figure out yet :/...

    but thanks for your help and time

  • 5. Re: Custom Login Module with remote EJB
    john_k Newbie

    i even tried to start from a brand new standalone.xml just added some minimal configuration:

    <security-realm name="MyAppRealm">
                    <authentication>
                        <jaas name="myAppJaas"/>
                    </authentication>
     </security-realm>
    
    <subsystem xmlns="urn:jboss:domain:remoting:1.1">
                <connector name="remoting-connector" socket-binding="remoting" security-realm="MyAppRealm"/>
    </subsystem>
    
    
    <security-domain name="myAppJaas" cache-type="default">
                        <authentication>
                            <login-module code="sampleApp.jboss.login.AuthLoginModule" flag="required"/>
                        </authentication>
    </security-domain>
    

    SubjectCallback has always a null value!

     

    fyi my loginmodule looks like this:

    so it has no real functionality

    public abstract class AuthLoginModule extends AbstractServerLoginModule {
    
        @Override
        public boolean login() throws LoginException {
            loginOk = true;
            return super.login();// super.login();
        }
    
        @Override
        public void initialize(Subject subject, CallbackHandler callbackHandler,
                Map<String, ?> sharedState, Map<String, ?> options) {
            super.initialize(subject, callbackHandler, sharedState, options);
        }
    
    }
    

    FullConfig:

     

    <?xml version='1.0' encoding='UTF-8'?>
    
    <server xmlns="urn:jboss:domain:1.2">
    
        <extensions>
            <extension module="org.jboss.as.clustering.infinispan"/>
            <extension module="org.jboss.as.configadmin"/>
            <extension module="org.jboss.as.connector"/>
            <extension module="org.jboss.as.deployment-scanner"/>
            <extension module="org.jboss.as.ee"/>
            <extension module="org.jboss.as.ejb3"/>
            <extension module="org.jboss.as.jaxrs"/>
            <extension module="org.jboss.as.jdr"/>
            <extension module="org.jboss.as.jmx"/>
            <extension module="org.jboss.as.jpa"/>
            <extension module="org.jboss.as.logging"/>
            <extension module="org.jboss.as.mail"/>
            <extension module="org.jboss.as.naming"/>
            <extension module="org.jboss.as.osgi"/>
            <extension module="org.jboss.as.pojo"/>
            <extension module="org.jboss.as.remoting"/>
            <extension module="org.jboss.as.sar"/>
            <extension module="org.jboss.as.security"/>
            <extension module="org.jboss.as.threads"/>
            <extension module="org.jboss.as.transactions"/>
            <extension module="org.jboss.as.web"/>
            <extension module="org.jboss.as.webservices"/>
            <extension module="org.jboss.as.weld"/>
        </extensions>
    
        <management>
            <security-realms>
                <security-realm name="ManagementRealm">
                    <authentication>
                        <properties path="mgmt-users.properties" relative-to="jboss.server.config.dir"/>
                    </authentication>
                </security-realm>
                <security-realm name="ApplicationRealm">
                    <authentication>
                        <properties path="application-users.properties" relative-to="jboss.server.config.dir"/>
                    </authentication>
                </security-realm>
                <security-realm name="MyAppRealm">
                    <authentication>
                        <jaas name="myAppJaas"/>
                    </authentication>
                </security-realm>
            </security-realms>
            <management-interfaces>
                <native-interface security-realm="ManagementRealm">
                    <socket-binding native="management-native"/>
                </native-interface>
                <http-interface security-realm="ManagementRealm">
                    <socket-binding http="management-http"/>
                </http-interface>
            </management-interfaces>
        </management>
    
        <profile>
            <subsystem xmlns="urn:jboss:domain:logging:1.1">
                <console-handler name="CONSOLE">
                    <level name="INFO"/>
                    <formatter>
                        <pattern-formatter pattern="%d{HH:mm:ss,SSS} %-5p [%c] (%t) %s%E%n"/>
                    </formatter>
                </console-handler>
                <periodic-rotating-file-handler name="FILE">
                    <formatter>
                        <pattern-formatter pattern="%d{HH:mm:ss,SSS} %-5p [%c] (%t) %s%E%n"/>
                    </formatter>
                    <file relative-to="jboss.server.log.dir" path="server.log"/>
                    <suffix value=".yyyy-MM-dd"/>
                    <append value="true"/>
                </periodic-rotating-file-handler>
                <logger category="com.arjuna">
                    <level name="WARN"/>
                </logger>
                <logger category="org.apache.tomcat.util.modeler">
                    <level name="WARN"/>
                </logger>
                <logger category="sun.rmi">
                    <level name="WARN"/>
                </logger>
                <logger category="org.jboss.security">
                    <level name="TRACE"/>
                </logger>
                <logger category="org.jboss.remoting">
                    <level name="TRACE"/>
                </logger>
                <logger category="jacorb">
                    <level name="WARN"/>
                </logger>
                <logger category="jacorb.config">
                    <level name="ERROR"/>
                </logger>
                <root-logger>
                    <level name="INFO"/>
                    <handlers>
                        <handler name="CONSOLE"/>
                        <handler name="FILE"/>
                    </handlers>
                </root-logger>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:configadmin:1.0"/>
            <subsystem xmlns="urn:jboss:domain:datasources:1.0">
                <datasources>
                    <datasource jndi-name="java:jboss/datasources/ExampleDS" pool-name="ExampleDS" enabled="true" use-java-context="true">
                        <connection-url>jdbc:h2:mem:test;DB_CLOSE_DELAY=-1</connection-url>
                        <driver>h2</driver>
                        <security>
                            <user-name>sa</user-name>
                            <password>sa</password>
                        </security>
                    </datasource>
                    <drivers>
                        <driver name="h2" module="com.h2database.h2">
                            <xa-datasource-class>org.h2.jdbcx.JdbcDataSource</xa-datasource-class>
                        </driver>
                    </drivers>
                </datasources>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:deployment-scanner:1.1">
                <deployment-scanner path="deployments" relative-to="jboss.server.base.dir" scan-interval="5000"/>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:ee:1.0"/>
            <subsystem xmlns="urn:jboss:domain:ejb3:1.2">
                <session-bean>
                    <stateless>
                        <bean-instance-pool-ref pool-name="slsb-strict-max-pool"/>
                    </stateless>
                    <stateful default-access-timeout="5000" cache-ref="simple"/>
                    <singleton default-access-timeout="5000"/>
                </session-bean>
                <pools>
                    <bean-instance-pools>
                        <strict-max-pool name="slsb-strict-max-pool" max-pool-size="20" instance-acquisition-timeout="5" instance-acquisition-timeout-unit="MINUTES"/>
                        <strict-max-pool name="mdb-strict-max-pool" max-pool-size="20" instance-acquisition-timeout="5" instance-acquisition-timeout-unit="MINUTES"/>
                    </bean-instance-pools>
                </pools>
                <caches>
                    <cache name="simple" aliases="NoPassivationCache"/>
                    <cache name="passivating" passivation-store-ref="file" aliases="SimpleStatefulCache"/>
                </caches>
                <passivation-stores>
                    <file-passivation-store name="file"/>
                </passivation-stores>
                <async thread-pool-name="default"/>
                <timer-service thread-pool-name="default">
                    <data-store path="timer-service-data" relative-to="jboss.server.data.dir"/>
                </timer-service>
                <remote connector-ref="remoting-connector" thread-pool-name="default"/>
                <thread-pools>
                    <thread-pool name="default">
                        <max-threads count="10"/>
                        <keepalive-time time="100" unit="milliseconds"/>
                    </thread-pool>
                </thread-pools>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:infinispan:1.2" default-cache-container="hibernate">
                <cache-container name="hibernate" default-cache="local-query">
                    <local-cache name="entity">
                        <transaction mode="NON_XA"/>
                        <eviction strategy="LRU" max-entries="10000"/>
                        <expiration max-idle="100000"/>
                    </local-cache>
                    <local-cache name="local-query">
                        <transaction mode="NONE"/>
                        <eviction strategy="LRU" max-entries="10000"/>
                        <expiration max-idle="100000"/>
                    </local-cache>
                    <local-cache name="timestamps">
                        <transaction mode="NONE"/>
                        <eviction strategy="NONE"/>
                    </local-cache>
                </cache-container>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:jaxrs:1.0"/>
            <subsystem xmlns="urn:jboss:domain:jca:1.1">
                <archive-validation enabled="true" fail-on-error="true" fail-on-warn="false"/>
                <bean-validation enabled="true"/>
                <default-workmanager>
                    <short-running-threads>
                        <core-threads count="50"/>
                        <queue-length count="50"/>
                        <max-threads count="50"/>
                        <keepalive-time time="10" unit="seconds"/>
                    </short-running-threads>
                    <long-running-threads>
                        <core-threads count="50"/>
                        <queue-length count="50"/>
                        <max-threads count="50"/>
                        <keepalive-time time="10" unit="seconds"/>
                    </long-running-threads>
                </default-workmanager>
                <cached-connection-manager/>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:jdr:1.0"/>
            <subsystem xmlns="urn:jboss:domain:jmx:1.1">
                <show-model value="true"/>
                <remoting-connector/>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:jpa:1.0">
                <jpa default-datasource=""/>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:mail:1.0">
                <mail-session jndi-name="java:jboss/mail/Default">
                    <smtp-server outbound-socket-binding-ref="mail-smtp"/>
                </mail-session>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:naming:1.1"/>
            <subsystem xmlns="urn:jboss:domain:osgi:1.2" activation="lazy">
                <properties>
                    <property name="org.osgi.framework.startlevel.beginning">
                        1
                    </property>
                </properties>
                <capabilities>
                    <capability name="javax.servlet.api:v25"/>
                    <capability name="javax.transaction.api"/>
                    <capability name="org.apache.felix.log" startlevel="1"/>
                    <capability name="org.jboss.osgi.logging" startlevel="1"/>
                    <capability name="org.apache.felix.configadmin" startlevel="1"/>
                    <capability name="org.jboss.as.osgi.configadmin" startlevel="1"/>
                </capabilities>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:pojo:1.0"/>
            <subsystem xmlns="urn:jboss:domain:remoting:1.1">
                <connector name="remoting-connector" socket-binding="remoting" security-realm="MyAppRealm"/>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:resource-adapters:1.0"/>
            <subsystem xmlns="urn:jboss:domain:sar:1.0"/>
            <subsystem xmlns="urn:jboss:domain:security:1.1">
                <security-domains>
                    <security-domain name="other" cache-type="default">
                        <authentication>
                            <login-module code="Remoting" flag="optional">
                                <module-option name="password-stacking" value="useFirstPass"/>
                            </login-module>
                            <login-module code="RealmUsersRoles" flag="required">
                                <module-option name="usersProperties" value="${jboss.server.config.dir}/application-users.properties"/>
                                <module-option name="rolesProperties" value="${jboss.server.config.dir}/application-roles.properties"/>
                                <module-option name="realm" value="ApplicationRealm"/>
                                <module-option name="password-stacking" value="useFirstPass"/>
                            </login-module>
                        </authentication>
                    </security-domain>
                    <security-domain name="myAppJaas" cache-type="default">
                        <authentication>
                            <login-module code="sampleApp.jboss.login.AuthLoginModule" flag="required"/>
                        </authentication>
                    </security-domain>
                    <security-domain name="jboss-web-policy" cache-type="default">
                        <authorization>
                            <policy-module code="Delegating" flag="required"/>
                        </authorization>
                    </security-domain>
                    <security-domain name="jboss-ejb-policy" cache-type="default">
                        <authorization>
                            <policy-module code="Delegating" flag="required"/>
                        </authorization>
                    </security-domain>
                </security-domains>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:threads:1.1"/>
            <subsystem xmlns="urn:jboss:domain:transactions:1.1">
                <core-environment>
                    <process-id>
                        <uuid/>
                    </process-id>
                </core-environment>
                <recovery-environment socket-binding="txn-recovery-environment" status-socket-binding="txn-status-manager"/>
                <coordinator-environment default-timeout="300"/>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:web:1.1" default-virtual-server="default-host" native="false">
                <connector name="http" protocol="HTTP/1.1" scheme="http" socket-binding="http"/>
                <virtual-server name="default-host" enable-welcome-root="true">
                    <alias name="localhost"/>
                    <alias name="example.com"/>
                </virtual-server>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:webservices:1.1">
                <modify-wsdl-address>true</modify-wsdl-address>
                <wsdl-host>${jboss.bind.address:127.0.0.1}</wsdl-host>
                <endpoint-config name="Standard-Endpoint-Config"/>
                <endpoint-config name="Recording-Endpoint-Config">
                    <pre-handler-chain name="recording-handlers" protocol-bindings="##SOAP11_HTTP ##SOAP11_HTTP_MTOM ##SOAP12_HTTP ##SOAP12_HTTP_MTOM">
                        <handler name="RecordingHandler" class="org.jboss.ws.common.invocation.RecordingServerHandler"/>
                    </pre-handler-chain>
                </endpoint-config>
            </subsystem>
            <subsystem xmlns="urn:jboss:domain:weld:1.0"/>
        </profile>
    
        <interfaces>
            <interface name="management">
                <inet-address value="${jboss.bind.address.management:127.0.0.1}"/>
            </interface>
            <interface name="public">
                <inet-address value="${jboss.bind.address:127.0.0.1}"/>
            </interface>
            <interface name="unsecure">
                <inet-address value="${jboss.bind.address.unsecure:127.0.0.1}"/>
            </interface>
        </interfaces>
    
        <socket-binding-group name="standard-sockets" default-interface="public" port-offset="${jboss.socket.binding.port-offset:0}">
            <socket-binding name="management-native" interface="management" port="${jboss.management.native.port:9999}"/>
            <socket-binding name="management-http" interface="management" port="${jboss.management.http.port:9990}"/>
            <socket-binding name="management-https" interface="management" port="${jboss.management.https.port:9443}"/>
            <socket-binding name="ajp" port="8009"/>
            <socket-binding name="http" port="8080"/>
            <socket-binding name="https" port="8443"/>
            <socket-binding name="osgi-http" interface="management" port="8090"/>
            <socket-binding name="remoting" port="4447"/>
            <socket-binding name="txn-recovery-environment" port="4712"/>
            <socket-binding name="txn-status-manager" port="4713"/>
            <outbound-socket-binding name="mail-smtp">
                <remote-destination host="localhost" port="25"/>
            </outbound-socket-binding>
        </socket-binding-group>
    
    </server>
    
  • 6. Re: Custom loginmodule for EJB - Null subject
    jaikiran pai Master

    I've branched this discussion to a thread of its own. The original thread was meant for a different issue and continuing this discussion there was just adding to confusion. Please continue the rest of this discussion in this thread.

  • 7. Re: Custom loginmodule for EJB - Null subject
    john_k Newbie

    thank you...

    meanwhile i tried with jboss as 7.1.0; 7.1.1 and 7.2.0.Alpha1

    with my configuration login, module, etc... it seems to be always the same problem!

    that the Subject inside the SubjectCallback is null...

     

    edit:

    seems that the PlainSaslServer#tryHandleCallbacks(Callback... callbacks)

    calls  callbackHandler.handle(callbacks); what is in this case org.jboss.as.remoting.RealmSecurityProvider

    and this provider adds a new SubjectCallback line 416

    if (subjectCallbackSupported && callbacks.length != 1 && callbacks[0] instanceof AuthorizeCallback == false) {

                    Callback[] newCallbacks = new Callback[callbacks.length + 1];

                    System.arraycopy(callbacks, 0, newCallbacks, 0, callbacks.length);

                    SubjectCallback subjectCallBack = new SubjectCallback(); //line 416 this is empty (null Subject!)

                    newCallbacks[newCallbacks.length - 1] = subjectCallBack;

                    callbackHandler.handle(newCallbacks);

                    subject = subjectCallBack.getSubject();

     

    } else {

                    callbackHandler.handle(callbacks);

             }

     

    the JaasCallbackHandler expects a Subject instance instead of a null

  • 8. Re: Custom Login Module with remote EJB
    john_k Newbie

    omg... totally dumb coding error i feel so bad :/ now my loginmodule works aswell ! what i didnt recognized it was declared abstract... !

  • 9. Re: Custom Login Module with remote EJB
    jaikiran pai Master

    john_k wrote:

     

    omg... totally dumb coding error i feel so bad :/ now my loginmodule works aswell ! what i didnt recognized it was declared abstract... !

    And there was no error logged for that?

  • 10. Re: Custom Login Module with remote EJB
    john_k Newbie

    nope nothing i saw on server side ... just try yourself create a custom login module and declare it abstract...

    btw francois thank you so much for your maven projects ...

  • 11. Re: Custom Login Module with remote EJB
    john_k Newbie

    log with abstract class and before starting client

     

    WARNING: -logmodule is deprecated. Please use the system property 'java.util.logging.manager' or the 'java.util.logging.LogManager' service loader.

    16:46:36,825 INFO  [org.jboss.modules] JBoss Modules version 1.1.1.GA

    16:46:37,023 INFO  [org.jboss.msc] JBoss MSC version 1.0.2.GA

    16:46:37,070 INFO  [org.jboss.as] JBAS015899: JBoss AS 7.1.1.Final "Brontes" starting

    16:46:37,912 INFO  [org.xnio] XNIO Version 3.0.3.GA

    16:46:37,912 INFO  [org.jboss.as.server] JBAS015888: Creating http management service using socket-binding (management-http)

    16:46:37,920 INFO  [org.xnio.nio] XNIO NIO Implementation Version 3.0.3.GA

    16:46:37,928 INFO  [org.jboss.remoting] JBoss Remoting version 3.2.3.GA

    16:46:37,948 INFO  [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers

    16:46:37,951 INFO  [org.jboss.as.configadmin] (ServerService Thread Pool -- 26) JBAS016200: Activating ConfigAdmin Subsystem

    16:46:37,971 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 38) JBAS011800: Activating Naming Subsystem

    16:46:37,991 INFO  [org.jboss.as.naming] (MSC service thread 1-4) JBAS011802: Starting Naming Service

    16:46:37,993 INFO  [org.jboss.as.connector] (MSC service thread 1-4) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.9.Final)

    16:46:38,010 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 31) JBAS010280: Activating Infinispan subsystem.

    16:46:38,016 INFO  [org.jboss.as.osgi] (ServerService Thread Pool -- 39) JBAS011940: Activating OSGi Subsystem

    16:46:38,027 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 44) JBAS013101: Activating Security Subsystem

    16:46:38,031 INFO  [org.jboss.as.security] (MSC service thread 1-5) JBAS013100: Current PicketBox version=4.0.7.Final

    16:46:38,034 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-4) JBAS015400: Bound mail session [java:jboss/mail/Default]

    16:46:38,040 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 48) JBAS015537: Activating WebServices Extension

    16:46:38,083 TRACE [org.jboss.security.jacc.DelegatingPolicy] (MSC service thread 1-5) Loaded JACC permissions: true

    16:46:38,084 TRACE [org.jboss.security.jacc.DelegatingPolicy] (MSC service thread 1-5) loaded policy context classclass javax.security.jacc.PolicyContext

    16:46:38,107 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (MSC service thread 1-3) CallbackHandler: org.jboss.security.auth.callback.JBossCallbackHandler@f2bc74

    16:46:38,111 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (MSC service thread 1-7) CallbackHandler: org.jboss.security.auth.callback.JBossCallbackHandler@1af16c8

    16:46:38,111 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (MSC service thread 1-5) CallbackHandler: org.jboss.security.auth.callback.JBossCallbackHandler@b6ccbc

    16:46:38,113 TRACE [org.jboss.security.authentication.JBossCachedAuthenticationManager] (MSC service thread 1-4) CallbackHandler: org.jboss.security.auth.callback.JBossCallbackHandler@1ba12cb

    16:46:38,170 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 27) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)

    16:46:38,214 TRACE [org.jboss.remoting.endpoint] (MSC service thread 1-2) Completed open of endpoint "client-xxx" <2a42db>

    16:46:38,216 TRACE [org.jboss.remoting.endpoint] (MSC service thread 1-2) Allocated tick to 1 of endpoint "client-xxx" <2a42db> (opened Connection provider for remote)

    16:46:38,224 TRACE [org.jboss.remoting.endpoint] (MSC service thread 1-2) Adding connection provider registration named 'remote': Remoting remote connection provider 48f52 for endpoint "client-xxx" <2a42db>

    16:46:38,232 TRACE [org.jboss.remoting.endpoint] (MSC service thread 1-1) Completed open of endpoint "client-xxx:MANAGEMENT" <16ef287>

    16:46:38,236 TRACE [org.jboss.remoting.endpoint] (MSC service thread 1-1) Allocated tick to 1 of endpoint "client-xxx:MANAGEMENT" <16ef287> (opened Connection provider for remote)

    16:46:38,238 TRACE [org.jboss.remoting.endpoint] (MSC service thread 1-1) Adding connection provider registration named 'remote': Remoting remote connection provider 7e78d2 for endpoint "client-xxx:MANAGEMENT" <16ef287>

    16:46:38,567 INFO  [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-1) Starting Coyote HTTP/1.1 on http--127.0.0.1-8080

    16:46:38,616 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-5) JBoss Web Services - Stack CXF Server 4.0.2.GA

    16:46:38,634 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-5) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]

    16:46:38,691 INFO  [org.jboss.as.remoting] (MSC service thread 1-8) JBAS017100: Listening on /127.0.0.1:9999

    16:46:38,697 INFO  [org.jboss.as.remoting] (MSC service thread 1-4) JBAS017100: Listening on /127.0.0.1:4447

    16:46:38,698 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-3) JBAS015012: Started FileSystemDeploymentService for directory C:\develop\jboss\jboss-as-7.1.1.Final\standalone\deployments

    16:46:38,707 INFO [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) JBAS015003: Found zboss-ejb.jar in deployment directory. To trigger deployment create a file called zboss-ejb.jar.dodeploy

    16:46:38,789 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "zboss-ejb.jar"

    16:46:38,853 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Accepted connection from /127.0.0.1:62880 to 127.0.0.1/127.0.0.1:9999

    16:46:38,857 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Setting read listener to org.jboss.remoting3.remote.ServerConnectionOpenListener$Initial@151ca5b

    16:46:38,859 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Sent message java.nio.HeapByteBuffer[pos=12 lim=12 cap=8192] (direct)

    16:46:38,861 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Flushed channel (direct)

    16:46:38,864 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Server received capabilities request

    16:46:38,865 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Server received capability: version 1

    16:46:38,867 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Server received capability: remote endpoint name "management-client"

    16:46:38,910 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) No EXTERNAL mechanism due to explicit exclusion

    16:46:38,911 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory org.jboss.sasl.localuser.LocalUserServerFactory@149ba1d

    16:46:38,913 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Added mechanism JBOSS-LOCAL-USER

    16:46:38,914 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory org.jboss.sasl.digest.DigestMD5ServerFactory@15c3bdb

    16:46:38,917 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Added mechanism DIGEST-MD5

    16:46:38,919 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory org.jboss.sasl.plain.PlainServerFactory@52a25f

    16:46:38,920 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Excluding mechanism PLAIN because it is not in the allowed list

    16:46:38,924 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory org.jboss.sasl.anonymous.AnonymousServerFactory@1ae93f4

    16:46:38,927 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory com.sun.security.sasl.digest.FactoryImpl@26ead3

    16:46:38,929 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Excluding repeated occurrence of mechanism DIGEST-MD5

    16:46:38,931 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory com.sun.security.sasl.ServerFactoryImpl@1ecda04

    16:46:38,933 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Excluding mechanism CRAM-MD5 because it is not in the allowed list

    16:46:38,936 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Trying SASL server factory com.sun.security.sasl.gsskerb.FactoryImpl@17cdeba

    16:46:38,938 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Excluding mechanism GSSAPI because it is not in the allowed list

    16:46:38,940 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Sent message java.nio.HeapByteBuffer[pos=62 lim=62 cap=8192] (direct)

    16:46:38,942 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Flushed channel (direct)

    16:46:38,945 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Server received authentication request

    16:46:38,957 TRACE [org.jboss.security.util.state.StateMachine] (MSC service thread 1-5) nextState(getPolicyConfiguration) = State(name=open

            on: addToExcludedPolicy go to: open

            on: inService go to: open

            on: getContextID go to: open

            on: removeExcludedPolicy go to: open

            on: commit go to: inService

            on: removeRole go to: open

            on: addToUncheckedPolicy go to: open

            on: delete go to: deleted

            on: getPolicyConfiguration go to: open

            on: addToRole go to: open

            on: linkConfiguration go to: open

            on: removeUncheckedPolicy go to: open)

    16:46:38,960 TRACE [org.jboss.security.jacc.JBossPolicyConfiguration] (MSC service thread 1-5) ctor, contextID=zboss-ejb.jar

    16:46:38,961 TRACE [org.jboss.security.util.state.StateMachine] (MSC service thread 1-5) nextState(getPolicyConfiguration) = State(name=open

            on: addToExcludedPolicy go to: open

            on: inService go to: open

            on: getContextID go to: open

            on: removeExcludedPolicy go to: open

            on: commit go to: inService

            on: removeRole go to: open

            on: addToUncheckedPolicy go to: open

            on: delete go to: deleted

            on: getPolicyConfiguration go to: open

            on: addToRole go to: open

            on: linkConfiguration go to: open

            on: removeUncheckedPolicy go to: open)

    16:46:38,966 TRACE [org.jboss.security.jacc.JBossPolicyConfiguration] (MSC service thread 1-5) commit:zboss-ejb.jar

    16:46:38,967 TRACE [org.jboss.security.util.state.StateMachine] (MSC service thread 1-5) nextState(commit) = State(name=inService

            on: getContextID go to: inService

            on: inService go to: inService

            on: delete go to: deleted

            on: getPolicyConfiguration go to: open)

    16:46:39,104 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990

    16:46:39,108 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.1.1.Final "Brontes" started in 2517ms - Started 156 of 235 services (78 services are passive or on-demand)

    16:46:39,115 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" task-1) Server sending authentication challenge

    16:46:39,121 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" task-1) Setting read listener to org.jboss.remoting3.remote.ServerConnectionOpenListener$Authentication@41db2b

    16:46:39,128 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" task-1) Sent message java.nio.HeapByteBuffer[pos=76 lim=76 cap=8192] (direct)

    16:46:39,132 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" task-1) Flushed channel (direct)

    16:46:39,134 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Received java.nio.HeapByteBuffer[pos=10 lim=8192 cap=8192]

    16:46:39,136 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" read-1) Server received authentication response

    16:46:39,138 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx:MANAGEMENT" task-2) Server sending authentication complete

    16:46:39,142 TRACE [org.jboss.remoting.endpoint] (Remoting "client-xxx:MANAGEMENT" task-2) Allocated tick to 2 of endpoint "client-xxx:MANAGEMENT" <16ef287> (opened an inbound connection)

    16:46:39,151 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" task-2) Setting read listener to org.jboss.remoting3.remote.RemoteReadListener@13f489d

    16:46:39,153 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" task-2) Sent message java.nio.HeapByteBuffer[pos=1 lim=1 cap=8192] (direct)

    16:46:39,155 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" task-2) Flushed channel (direct)

    16:46:39,156 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received channel open request

    16:46:39,158 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Connection handler for Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IC=0 OC=0

           new: RS=false WS=false IC=1 OC=0

    16:46:39,160 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Opened inbound channel on Connection handler for Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,163 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Sent message java.nio.HeapByteBuffer[pos=16 lim=16 cap=8192] (direct)

    16:46:39,165 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Flushed channel (direct)

    16:46:39,167 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received message data

    16:46:39,169 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=0 OM=0

           new: RS=false WS=false IM=1 OM=0

    16:46:39,172 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Opened inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,176 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received message data

    16:46:39,177 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=1 OM=0

           new: RS=false WS=false IM=0 OM=0

    16:46:39,180 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Closed inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,182 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) No message ready; returning

    16:46:39,189 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=0 OM=0

           new: RS=false WS=false IM=0 OM=1

    16:46:39,191 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Opened outbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,194 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Message window is open, proceeding with send

    16:46:39,195 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Flushing message channel

    16:46:39,196 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received message window open

    16:46:39,196 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Sending message (with EOF) (java.nio.HeapByteBuffer[pos=0 lim=8 cap=8192]) to org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <106728d>

    16:46:39,197 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Acknowledged 70 bytes on Outbound message ID 6f94 on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,201 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Message window is open, proceeding with send

    16:46:39,201 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received message data

    16:46:39,202 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Removed Outbound message ID 6f94 on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,203 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=0 OM=1

           new: RS=false WS=false IM=1 OM=1

    16:46:39,204 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=1 OM=1

           new: RS=false WS=false IM=1 OM=0

    16:46:39,206 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Opened inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,208 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Closed outbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,210 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received message data

    16:46:39,211 TRACE [org.jboss.remoting.remote] (management-handler-thread - 1) Flushing message channel

    16:46:39,212 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=1 OM=0

           new: RS=false WS=false IM=2 OM=0

    16:46:39,216 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Opened inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,218 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Sent message java.nio.HeapByteBuffer[pos=7 lim=7 cap=8192] (direct)

    16:46:39,220 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Flushed channel (direct)

    16:46:39,221 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=2 OM=0

           new: RS=false WS=false IM=1 OM=0

    16:46:39,224 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Closed inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,226 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received message data

    16:46:39,227 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=1 OM=0

           new: RS=false WS=false IM=2 OM=0

    16:46:39,229 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Opened inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,231 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Sent message java.nio.HeapByteBuffer[pos=7 lim=7 cap=8192] (direct)

    16:46:39,232 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Flushed channel (direct)

    16:46:39,234 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=2 OM=0

           new: RS=false WS=false IM=1 OM=0

    16:46:39,237 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Closed inbound message on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,239 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Received connection end-of-stream

    16:46:39,242 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" read-1) Closing Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880 asynchronously

    16:46:39,245 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

           old: RS=false WS=false IM=1 OM=0

           new: RS=true WS=true IM=1 OM=0

    16:46:39,247 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Sent message java.nio.HeapByteBuffer[pos=5 lim=5 cap=8192] (direct)

    16:46:39,248 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Flushed channel (direct)

    16:46:39,249 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Closed channel reads on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,251 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Closed channel reads and writes on Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,253 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-2) Closing Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880 synchronously

    16:46:39,253 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" read-1) Completed close of Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to /127.0.0.1:62880

    16:46:39,262 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx:MANAGEMENT" read-1) Shut down writes on channel

    16:46:39,263 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) CAS Connection handler for Remoting connection 00be9e60 to null

           old: RS=false WS=false IC=1 OC=0

           new: RS=false WS=true IC=1 OC=0

    16:46:39,263 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-3) Closing Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to null asynchronously

    16:46:39,265 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Sending close request on Connection handler for Remoting connection 00be9e60 to null

    16:46:39,266 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-3) Closing Connection handler for Remoting connection 00be9e60 to null asynchronously

    16:46:39,268 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx:MANAGEMENT" read-1) Sent close request on Connection handler for Remoting connection 00be9e60 to null

    16:46:39,269 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-3) Completed close of Connection handler for Remoting connection 00be9e60 to null

    16:46:39,271 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" read-1) Closing Channel ID 0ac40150 (inbound) of Remoting connection 00be9e60 to null asynchronously

    16:46:39,272 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-3) Closing Remoting connection <1b86517> asynchronously

    16:46:39,275 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-3) Closing Connection handler for Remoting connection 00be9e60 to null asynchronously

    16:46:39,277 TRACE [org.jboss.remoting.resource] (Remoting "client-xxx:MANAGEMENT" task-3) Completed close of Remoting connection <1b86517>

    16:46:39,278 TRACE [org.jboss.remoting.endpoint] (Remoting "client-xxx:MANAGEMENT" task-3) Resource closed count 00000001 of endpoint "client-xxx:MANAGEMENT" <16ef287> (closed Remoting connection <1b86517>)

    16:46:39,327 INFO  [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS018559: Deployed "zboss-ejb.jar"

     

     

    log after starting client

     

    16:50:19,587 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx" read-1) Accepted connection from /127.0.0.1:63009 to 127.0.0.1/127.0.0.1:4447

    16:50:19,589 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx" read-1) Setting read listener to org.jboss.remoting3.remote.ServerConnectionOpenListener$Initial@3f6e22

    16:50:19,591 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" read-1) Sent message java.nio.HeapByteBuffer[pos=12 lim=12 cap=8192] (direct)

    16:50:19,593 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" read-1) Flushed channel (direct)

    16:50:19,602 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received capabilities request

    16:50:19,603 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received capability: version 1

    16:50:19,604 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received capability: remote endpoint name "client-endpoint"

    16:50:19,607 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) No EXTERNAL mechanism due to explicit exclusion

    16:50:19,608 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory org.jboss.sasl.localuser.LocalUserServerFactory@57b4c7

    16:50:19,609 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Added mechanism JBOSS-LOCAL-USER

    16:50:19,610 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory org.jboss.sasl.digest.DigestMD5ServerFactory@1d866e

    16:50:19,612 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Excluding mechanism DIGEST-MD5 because it is not in the allowed list

    16:50:19,613 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory org.jboss.sasl.plain.PlainServerFactory@64b662

    16:50:19,614 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Added mechanism PLAIN

    16:50:19,615 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory org.jboss.sasl.anonymous.AnonymousServerFactory@20008

    16:50:19,616 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory com.sun.security.sasl.digest.FactoryImpl@1504dd9

    16:50:19,617 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Excluding mechanism DIGEST-MD5 because it is not in the allowed list

    16:50:19,619 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory com.sun.security.sasl.ServerFactoryImpl@126fce7

    16:50:19,620 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Excluding mechanism CRAM-MD5 because it is not in the allowed list

    16:50:19,621 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Trying SASL server factory com.sun.security.sasl.gsskerb.FactoryImpl@a5b7d3

    16:50:19,623 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Excluding mechanism GSSAPI because it is not in the allowed list

    16:50:19,625 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" read-1) Sent message java.nio.HeapByteBuffer[pos=46 lim=46 cap=8192] (direct)

    16:50:19,627 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" read-1) Flushed channel (direct)

    16:50:19,675 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received authentication request

    16:50:19,678 TRACE [org.jboss.security.auth.login.XMLLoginConfigImpl] (Remoting "client-xxx" task-1) Begin getAppConfigurationEntry(myAppJaas), size=4

    16:50:19,682 TRACE [org.jboss.security.auth.login.XMLLoginConfigImpl] (Remoting "client-xxx" task-1) End getAppConfigurationEntry(myAppJaas), authInfo=AppConfigurationEntry[]:

    [0]

    LoginModule Class: login.CustomLoginModule

    ControlFlag: Anmeldemodul-Steuerflag: required

    Options:

     

    16:50:19,686 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" task-1) Server sending authentication rejected (javax.security.sasl.SaslException: PLAIN password not verified by CallbackHandler)

    16:50:19,688 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" task-1) Sent message java.nio.HeapByteBuffer[pos=1 lim=1 cap=8192] (direct)

    16:50:19,690 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" task-1) Flushed channel (direct)

    16:50:19,691 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received capabilities request

    16:50:19,692 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received capability: version 1

    16:50:19,694 TRACE [org.jboss.remoting.remote.server] (Remoting "client-xxx" read-1) Server received capability: remote endpoint name "client-endpoint"

    16:50:19,696 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" read-1) Sent message java.nio.HeapByteBuffer[pos=46 lim=46 cap=8192] (direct)

    16:50:19,697 TRACE [org.jboss.remoting.remote.connection] (Remoting "client-xxx" read-1) Flushed channel (direct)

    16:50:19,698 TRACE [org.jboss.remoting.remote] (Remoting "client-xxx" read-1) Received connection end-of-stream

     

    client exception

    24.05.2012 16:50:19 org.xnio.Xnio <clinit>

    INFO: XNIO Version 3.0.3.GA

    24.05.2012 16:50:19 org.xnio.nio.NioXnio <clinit>

    INFO: XNIO NIO Implementation Version 3.0.3.GA

    24.05.2012 16:50:19 org.jboss.remoting3.EndpointImpl <clinit>

    INFO: JBoss Remoting version 3.2.3.GA

    24.05.2012 16:50:19 org.jboss.remoting3.remote.RemoteConnection handleException

    ERROR: JBREM000200: Remote connection failed: javax.security.sasl.SaslException: Authentication failed: all available authentication mechanisms failed

    javax.naming.NamingException: Failed to create remoting connection [Root exception is java.lang.RuntimeException: javax.security.sasl.SaslException: Authentication failed: all available authentication mechanisms failed]

        at org.jboss.naming.remote.client.ClientUtil.namingException(ClientUtil.java:36)

        at org.jboss.naming.remote.client.InitialContextFactory.getInitialContext(InitialContextFactory.java:121)

        at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:667)

        at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:288)

        at javax.naming.InitialContext.init(InitialContext.java:223)

        at javax.naming.InitialContext.<init>(InitialContext.java:197)

        at a.Main$1.run(Main.java:65)

        at a.Main$1.run(Main.java:1)

        at java.security.AccessController.doPrivileged(Native Method)

        at javax.security.auth.Subject.doAs(Subject.java:337)

        at a.Main.main(Main.java:60)

    Caused by: java.lang.RuntimeException: javax.security.sasl.SaslException: Authentication failed: all available authentication mechanisms failed

        at org.jboss.naming.remote.protocol.IoFutureHelper.get(IoFutureHelper.java:87)

        at org.jboss.naming.remote.client.NamingStoreCache.getRemoteNamingStore(NamingStoreCache.java:56)

        at org.jboss.naming.remote.client.InitialContextFactory.getOrCreateCachedNamingStore(InitialContextFactory.java:166)

        at org.jboss.naming.remote.client.InitialContextFactory.getOrCreateNamingStore(InitialContextFactory.java:139)

        at org.jboss.naming.remote.client.InitialContextFactory.getInitialContext(InitialContextFactory.java:104)

        ... 9 more

    Caused by: javax.security.sasl.SaslException: Authentication failed: all available authentication mechanisms failed

        at org.jboss.remoting3.remote.ClientConnectionOpenListener$Capabilities.handleEvent(ClientConnectionOpenListener.java:315)

        at org.jboss.remoting3.remote.ClientConnectionOpenListener$Capabilities.handleEvent(ClientConnectionOpenListener.java:214)

        at org.xnio.ChannelListeners.invokeChannelListener(ChannelListeners.java:72)

        at org.xnio.channels.TranslatingSuspendableChannel.handleReadable(TranslatingSuspendableChannel.java:189)

        at org.xnio.channels.TranslatingSuspendableChannel$1.handleEvent(TranslatingSuspendableChannel.java:103)

        at org.xnio.ChannelListeners.invokeChannelListener(ChannelListeners.java:72)

        at org.xnio.nio.NioHandle.run(NioHandle.java:90)

        at org.xnio.nio.WorkerThread.run(WorkerThread.java:184)

        at ...asynchronous invocation...(Unknown Source)

        at org.jboss.remoting3.EndpointImpl.doConnect(EndpointImpl.java:270)

        at org.jboss.remoting3.EndpointImpl.doConnect(EndpointImpl.java:251)

        at org.jboss.remoting3.EndpointImpl.connect(EndpointImpl.java:349)

        at org.jboss.remoting3.EndpointImpl.connect(EndpointImpl.java:333)

        at org.jboss.naming.remote.client.EndpointCache$EndpointWrapper.connect(EndpointCache.java:105)

        at org.jboss.naming.remote.client.NamingStoreCache.getRemoteNamingStore(NamingStoreCache.java:55)

        ... 12 more

    Exception in thread "main" java.lang.NullPointerException

        at a.Main.main(Main.java:75)