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

    Custom loginmodule for EJB - Null subject

    john_k

      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

          Is your remote EJB tagged with

           

          @SecurityDomain("yoursecuritydomainname")

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

            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

              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

                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

                  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

                    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

                      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

                        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

                          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

                            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

                              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)