Uploaded image for project: 'JBoss Modules'
  1. JBoss Modules
  2. MODULES-263

Blooming addExportedPaths explodes visited set

    XMLWordPrintable

Details

    • Bug
    • Resolution: Unresolved
    • Critical
    • None
    • 1.5.1.Final
    • Filters
    • None
    • Hide

      I have added some logging in Module.addExportedPaths in the "if already visited" test in the Modules 1.5.1. The resulting log is extremely large (30GB) but here are some excerpts:

      These repeat over and over, with different filterStack and different module names. Notice the visted.size(), recursion depth and memory stats.

      2016-08-19 22:48:42,472 TRACE [org.jboss.modules] (MSC service thread 1-2) addExportedPaths exit: module=deployment.org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar, visited.size()=3283729, depth=10, freeMemory=3605623904, t
      otalMemory=8842117120, maxMemory=15271460864, filterStack=[in {org/springframework/orm/jpa/persistenceunit, org/springframework/orm/hibernate5, org/springframework/orm/hibernate4, org/springframework/orm, org/springframework/orm/hibernat
      e3/annotation, org/springframework/orm/hibernate4/support, org/springframework/orm/hibernate5/support, org/springframework/orm/jdo, org/springframework/orm/hibernate3/support, org/springframework/orm/jdo/support, org/springframework/orm/
      hibernate3, org/springframework/orm/jpa/vendor, org/springframework/orm/jpa/support, org/springframework/orm/jpa}, in {org/springframework/cache/concurrent, org/springframework/jmx, org/springframework/context/expression, org/springframe
      work/scripting/bsh, org/springframework/scripting, org/springframework/remoting/rmi, org/springframework/scripting/config, org/springframework/remoting/support, org/springframework/jmx/export/naming, org/springframework/jmx/support, org/
      springframework/cache/interceptor, org/springframework/instrument/classloading, org/springframework/validation, org/springframework/ui/context/support, org/springframework/format/support, org/springframework/scripting/jruby, org/springfr
      amework/format/number/money, org/springframework/remoting, org/springframework/context/i18n, org/springframework/scheduling/annotation, org/springframework/jmx/export, org/springframework/validation/beanvalidation, org/springframework/re
      moting/soap, org/springframework/instrument/classloading/glassfish, org/springframework/jndi/support, org/springframework/ejb/interceptor, org/springframework/format/datetime, org/springframework/scripting/groovy, org/springframework/for
      mat/datetime/standard, org/springframework/context/annotation, org/springframework/instrument/classloading/tomcat, org/springframework/instrument/classloading/weblogic, org/springframework/cache/config, org/springframework/format/annotat
      ion, org/springframework/context, org/springframework/format, org/springframework/jmx/export/assembler, org/springframework/ui/context, org/springframework/jmx/export/notification, org/springframework/cache/annotation, org/springframewor
      k/scheduling, org/springframework/context/support, org/springframework/instrument/classloading/websphere, org/springframework/scheduling/config, org/springframework/instrument/classloading/jboss, org/springframework/context/weaving, org/
      springframework/context/access, org/springframework/jndi, org/springframework/jmx/access, org/springframework/context/config, org/springframework/scheduling/concurrent, org/springframework/format/datetime/joda, org/springframework/jmx/ex
      port/metadata, org/springframework/ejb/access, org/springframework/scheduling/support, org/springframework/ui, org/springframework/cache/support, org/springframework/format/number, org/springframework/scripting/support, org/springframewo
      rk/cache, org/springframework/context/event, org/springframework/validation/support, org/springframework/validation/annotation, org/springframework/stereotype, org/springframework/jmx/export/annotation, org/springframework/ejb/config}, i
      n {org/springframework/orm/jpa/support}, in {org/springframework/validation, org/springframework/ui/context/support, org/springframework/context/annotation, org/springframework/context/access, org/springframework/jndi, org/springframewor
      k/format/support, org/springframework/remoting/rmi, org/springframework/remoting/support, org/springframework/context, org/springframework/remoting, org/springframework/context/i18n, org/springframework/ui/context, org/springframework/re
      moting/soap, org/springframework/ui, org/springframework/context/support, org/springframework/validation/annotation, org/springframework/validation/support, org/springframework/stereotype}, in {org/springframework/web/context/request, or
      g/springframework/http/converter/json, org/springframework/web, org/springframework/web/bind/annotation, org/springframework/web/cors, org/springframework/web/filter, org/springframework/web/client/support, org/springframework/web/bind/a
      nnotation/support, org/springframework/web/client, org/springframework/web/method/annotation, org/springframework/web/multipart/support, org/springframework/http/converter/xml, org/springframework/web/context/request/async, org/springfra
      mework/web/accept, org/springframework/web/method/support, org/springframework/web/method, org/springframework/http/converter/feed, org/springframework/web/multipart/commons, org/springframework/http, org/springframework/web/jsf, org/spr
      ingframework/http/server, org/springframework/web/bind/support, org/springframework/web/context, org/springframework/web/context/support, org/springframework/http/converter, org/springframework/web/jsf/el, org/springframework/remoting/ja
      xws, org/springframework/http/converter/protobuf, org/springframework/web/multipart, org/springframework/web/bind, org/springframework/remoting/caucho, org/springframework/http/client/support, org/springframework/http/converter/support,
      org/springframework/http/client, org/springframework/web/util, org/springframework/remoting/httpinvoker}, in {org/springframework/jdbc/datasource, org/springframework/jdbc/support/lob, org/springframework/jdbc/core/simple, org/springfram
      ework/jdbc/support/incrementer, org/springframework/jdbc/core}, in {org/springframework/context, org/springframework/context/annotation, org/springframework/jndi, org/springframework/scheduling, org/springframework/context/support, org/s
      pringframework/context/event, org/springframework/stereotype}, in {org/springframework/jdbc/datasource, org/springframework/jdbc/support/lob, org/springframework/jdbc, org/springframework/jdbc/datasource/lookup, org/springframework/jdbc/
      support}, in {org/springframework/web/context/request, org/springframework/web/context/request/async, org/springframework/web/filter, org/springframework/web/context, org/springframework/web/context/support}, in {org/springframework/jdbc
      /core/support, org/springframework/jdbc/support/lob, org/springframework/jdbc/core/simple, org/springframework/jdbc/support/incrementer, org/springframework/jdbc/core/namedparam, org/springframework/jdbc/datasource/lookup, org/springfram
      ework/jdbc/support, org/springframework/jdbc/core, org/springframework/jdbc/datasource/embedded, org/springframework/jdbc/datasource, org/springframework/jdbc/support/rowset, org/springframework/jdbc/core/metadata, org/springframework/jd
      bc, org/springframework/jdbc/support/nativejdbc, org/springframework/jdbc/datasource/init, org/springframework/jdbc/support/xml, org/springframework/jdbc/object, org/springframework/jdbc/config}, in {org/springframework/dao, org/springfr
      amework/transaction, org/springframework/dao/support, org/springframework/transaction/jta, org/springframework/transaction/support}, in {org/springframework/dao, org/springframework/transaction, org/springframework/dao/support, org/sprin
      gframework/transaction/support}, in {org/springframework/transaction/interceptor, org/springframework/dao, org/springframework/jca/context, org/springframework/jca/cci, org/springframework/jca/cci/core/support, org/springframework/jca/su
      pport, org/springframework/jca/work, org/springframework/transaction/event, org/springframework/jca/cci/core, org/springframework/dao/annotation, org/springframework/dao/support, org/springframework/transaction/jta, org/springframework/t
      ransaction/config, org/springframework/transaction, org/springframework/jca/endpoint, org/springframework/jca/work/jboss, org/springframework/transaction/annotation, org/springframework/jca/work/glassfish, org/springframework/jca/cci/con
      nection, org/springframework/jca/cci/object, org/springframework/transaction/support}], classFilterStack=[], resourceFilterStack=[], dependencies=[dependency on org.jboss.modules.ClassLoaderLocalLoader@24f8952, optional dependency on jbo
      sgi.framework:main (FrameworkModuleLoader), optional dependency on javax.transaction.api:main (ModuleLoaderIntegration.DelegatingModuleLoader), optional dependency on org.slf4j.jcl-over-slf4j:main (ModuleLoaderIntegration.DelegatingModul
      eLoader), dependency on deployment.org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on deployment.org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_
      1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on deployment.org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on deployment.org.apach
      e.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on HostBundleClassLoader for Module "deployment.org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar:main" from
       Service Module Loader], : java.lang.Exception
          at org.jboss.modules.Module.addExportedPaths(Module.java:1218)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addExportedPaths(Module.java:1292)
          at org.jboss.modules.Module.addPaths(Module.java:1127)
          at org.jboss.modules.Module.link(Module.java:1466)
          at org.jboss.modules.Module.relink(Module.java:1510)
          at org.jboss.modules.ModuleLoader.relink(ModuleLoader.java:566)
          at org.jboss.as.server.moduleservice.ServiceModuleLoader.relinkModule(ServiceModuleLoader.java:193)
          at org.jboss.as.server.moduleservice.ModuleLoadService.start(ModuleLoadService.java:69)
          at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1948)
          at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1881)
          at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
          at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
          at java.lang.Thread.run(Thread.java:745)
      

      Notice the timings:

      2016-08-19 22:44:36,238 INFO  [org.jboss.modules] (main) JBoss Modules version 1.5.1.Final
      2016-08-19 22:44:36,481 INFO  [org.jboss.msc] (main) JBoss MSC version 1.2.6.Final
      2016-08-19 22:44:36,601 INFO  [org.jboss.as] (MSC service thread 1-5) WFLYSRV0049: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) starting
      2016-08-19 22:44:38,518 INFO  [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http)
      2016-08-19 22:44:38,540 INFO  [org.xnio] (MSC service thread 1-8) XNIO version 3.3.4.Final
      2016-08-19 22:44:38,550 INFO  [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.3.4.Final
      2016-08-19 22:44:38,588 INFO  [org.jboss.as.osgi] (ServerService Thread Pool -- 32) JBAS011906: Activating OSGi Subsystem
      2016-08-19 22:44:38,627 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 40) WFLYCLINF0001: Activating Infinispan subsystem.
      2016-08-19 22:44:38,636 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 55) WFLYSEC0002: Activating Security Subsystem
      2016-08-19 22:44:38,647 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 58) WFLYWS0002: Activating WebServices Extension
      2016-08-19 22:44:38,650 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 48) WFLYNAM0001: Activating Naming Subsystem
      2016-08-19 22:44:38,683 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 46) WFLYJSF0007: Activated the following JSF Implementations: [main]
      2016-08-19 22:44:38,690 INFO  [org.jboss.as.security] (MSC service thread 1-7) WFLYSEC0001: Current PicketBox version=4.9.4.Final
      2016-08-19 22:44:38,700 INFO  [org.wildfly.extension.io] (ServerService Thread Pool -- 39) WFLYIO001: Worker 'default' has auto-configured to 16 core threads with 128 task threads based on your 8 available processors
      2016-08-19 22:44:38,760 INFO  [org.jboss.as.connector] (MSC service thread 1-1) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.3.2.Final)
      2016-08-19 22:44:38,763 INFO  [org.jboss.remoting] (MSC service thread 1-8) JBoss Remoting version 4.0.18.Final
      2016-08-19 22:44:38,783 INFO  [org.jboss.as.naming] (MSC service thread 1-1) WFLYNAM0003: Starting Naming Service
      2016-08-19 22:44:38,783 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-1) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default]
      2016-08-19 22:44:38,794 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 36) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
      2016-08-19 22:44:38,797 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-1) WFLYJCA0018: Started Driver service with driver-name = h2
      2016-08-19 22:44:38,835 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0003: Undertow 1.3.15.Final starting
      2016-08-19 22:44:38,839 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 57) WFLYUT0003: Undertow 1.3.15.Final starting
      2016-08-19 22:44:39,119 INFO  [com.arjuna.ats.jbossatx] (MSC service thread 1-5) ARJUNA032010: JBossTS Recovery Service (tag: f6563) - JBoss Inc.
      2016-08-19 22:44:39,132 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 57) WFLYUT0014: Creating file handler for path '/home/arcivanov/Documents/src/jbosgi/JBOSGI-794/wildfly-10.0.0.Final/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]']
      2016-08-19 22:44:39,149 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0012: Started server default-server.
      2016-08-19 22:44:39,150 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0018: Host default-host starting
      2016-08-19 22:44:39,193 INFO  [org.jboss.as.ejb3] (MSC service thread 1-4) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 32 (per class), which is derived from the number of CPUs on this host.
      2016-08-19 22:44:39,193 INFO  [org.jboss.as.ejb3] (MSC service thread 1-3) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 128 (per class), which is derived from thread worker pool sizing.
      2016-08-19 22:44:39,300 INFO  [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011006: OSGi Framework - 5.0.2.Final-SNAPSHOT
      2016-08-19 22:44:39,308 INFO  [com.arjuna.ats.jbossatx] (MSC service thread 1-5) ARJUNA032013: Starting transaction recovery manager
      2016-08-19 22:44:39,315 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0006: Undertow HTTP listener default listening on 127.0.0.1:8080
      2016-08-19 22:44:39,364 INFO  [com.arjuna.ats.jbossatx] (MSC service thread 1-8) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: f6563) - JBoss Inc.
      2016-08-19 22:44:39,552 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS]
      2016-08-19 22:44:39,670 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-7) WFLYSRV0027: Starting deployment of "spring-data-commons-1.11.4.RELEASE.jar" (runtime-name: "spring-data-commons-1.11.4.RELEASE.jar")
      2016-08-19 22:44:39,670 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,670 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,673 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar")
      2016-08-19 22:44:39,674 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar")
      2016-08-19 22:44:39,674 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,675 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "gemini-blueprint-io-2.0.0.RELEASE.jar" (runtime-name: "gemini-blueprint-io-2.0.0.RELEASE.jar")
      2016-08-19 22:44:39,675 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "gemini-blueprint-core-2.0.0.RELEASE.jar" (runtime-name: "gemini-blueprint-core-2.0.0.RELEASE.jar")
      2016-08-19 22:44:39,675 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,675 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,676 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "gemini-blueprint-extender-2.0.0.RELEASE.jar" (runtime-name: "gemini-blueprint-extender-2.0.0.RELEASE.jar")
      2016-08-19 22:44:39,676 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,676 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "com.springsource.org.aopalliance-1.0.0.jar" (runtime-name: "com.springsource.org.aopalliance-1.0.0.jar")
      2016-08-19 22:44:39,677 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar" (runtime-name: "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar")
      2016-08-19 22:44:39,678 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,678 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,678 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar")
      2016-08-19 22:44:39,675 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar")
      2016-08-19 22:44:39,729 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-7) WFLYDS0013: Started FileSystemDeploymentService for directory /home/arcivanov/Documents/src/jbosgi/JBOSGI-794/wildfly-10.0.0.Final/standalone/deployments
      2016-08-19 22:44:39,898 INFO  [org.jboss.ws.common.management] (MSC service thread 1-4) JBWS022052: Starting JBossWS 5.1.3.Final (Apache CXF 3.1.4) 
      2016-08-19 22:44:39,913 INFO  [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-6) ISPN000128: Infinispan version: Infinispan 'Mahou' 8.1.0.Final
      2016-08-19 22:44:40,584 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.log:1.0.1
      2016-08-19 22:44:40,586 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: jboss-osgi-logging:1.0.0
      2016-08-19 22:44:40,587 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.configadmin:1.8.8
      2016-08-19 22:44:40,590 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.metatype:1.1.2
      2016-08-19 22:44:40,592 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.scr:1.8.2
      2016-08-19 22:44:40,595 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.blueprint:1.1.0
      2016-08-19 22:44:40,597 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.jmx:1.1.5
      2016-08-19 22:44:40,599 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.jndi:1.0.0
      2016-08-19 22:44:40,600 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.proxy:1.0.1
      2016-08-19 22:44:40,602 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.util:1.1.1
      2016-08-19 22:44:40,775 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011011: Starting bundles for start level: 1
      2016-08-19 22:44:40,779 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: jbosgi-wildfly-http:2.5.3.Final-SNAPSHOT
      2016-08-19 22:44:40,780 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: jbosgi-wildfly-jpa:2.5.3.Final-SNAPSHOT
      2016-08-19 22:44:40,799 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.log:1.0.1
      2016-08-19 22:44:40,821 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: jboss-osgi-logging:1.0.0
      2016-08-19 22:44:40,847 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.configadmin:1.8.8
      2016-08-19 22:44:40,860 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.metatype:1.1.2
      2016-08-19 22:44:40,911 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.scr:1.8.2
      2016-08-19 22:44:40,979 INFO  [org.apache.aries.blueprint.container.BlueprintExtender] (MSC service thread 1-1) No quiesce support is available, so blueprint components will not participate in quiesce operations
      2016-08-19 22:44:40,980 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.blueprint:1.1.0
      2016-08-19 22:44:40,995 INFO  [org.apache.aries.jmx] (Thread-78) Starting JMX OSGi agent
      2016-08-19 22:44:41,019 INFO  [org.apache.aries.jmx] (Thread-78) Registering MBean with ObjectName [osgi.compendium:service=cm,version=1.3,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0] for service with service.id [25]
      2016-08-19 22:44:41,024 INFO  [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.wiring.BundleWiringStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=wiringState,version=1.1,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0
      2016-08-19 22:44:41,024 INFO  [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.FrameworkMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=framework,version=1.7,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0
      2016-08-19 22:44:41,024 INFO  [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.BundleStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=bundleState,version=1.7,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0
      2016-08-19 22:44:41,026 INFO  [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.ServiceStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=serviceState,version=1.7,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0
      2016-08-19 22:44:41,026 INFO  [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.service.cm.ConfigurationAdminMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.compendium:service=cm,version=1.3,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0
      2016-08-19 22:44:41,027 INFO  [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.PackageStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=packageState,version=1.5,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0
      2016-08-19 22:44:41,027 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.jmx:1.1.5
      2016-08-19 22:44:41,065 INFO  [org.apache.aries.jndi.startup.Activator] (MSC service thread 1-1) It was not possible to register an InitialContextFactoryBuilder with the NamingManager because another builder called org.jboss.as.naming.InitialContextFactoryBuilder was already registered. Support for calling new InitialContext() will not be enabled.
      2016-08-19 22:44:41,065 INFO  [org.apache.aries.jndi.startup.Activator] (MSC service thread 1-1) It was not possible to register an ObjectFactoryBuilder with the NamingManager because another builder called org.jboss.as.naming.InitialContextFactoryBuilder was already registered. Looking up certain objects may not work correctly.
      2016-08-19 22:44:41,093 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.jndi:1.0.0
      2016-08-19 22:44:41,152 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.proxy:1.0.1
      2016-08-19 22:44:41,156 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.util:1.1.1
      2016-08-19 22:44:41,157 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011000: OSGi Framework started
      2016-08-19 22:44:41,173 INFO  [org.jboss.osgi.framework] (MSC service thread 1-2) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-context-support:4.2.5.RELEASE_1
      2016-08-19 22:44:41,197 INFO  [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-aop:4.2.5.RELEASE_1
      2016-08-19 22:44:41,207 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-orm:4.2.5.RELEASE_1
      2016-08-19 22:44:41,209 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-tx:4.2.5.RELEASE_1
      2016-08-19 22:44:41,211 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-expression:4.2.5.RELEASE_1
      2016-08-19 22:44:41,216 INFO  [org.jboss.osgi.framework] (MSC service thread 1-5) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-batch-infrastructure:3.0.7.RELEASE_1
      2016-08-19 22:44:41,219 INFO  [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011001: Bundle installed: org.eclipse.gemini.blueprint.io:2.0.0.RELEASE
      2016-08-19 22:44:41,225 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.eclipse.gemini.blueprint.extender:2.0.0.RELEASE
      2016-08-19 22:44:41,229 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-batch-core:3.0.7.RELEASE_1
      2016-08-19 22:44:41,233 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-ldap:2.0.4.RELEASE_2
      2016-08-19 22:44:41,238 INFO  [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-beans:4.2.5.RELEASE_1
      2016-08-19 22:44:41,243 INFO  [org.jboss.osgi.framework] (MSC service thread 1-2) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-oxm:4.2.5.RELEASE_1
      2016-08-19 22:44:41,260 INFO  [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-jdbc:4.2.5.RELEASE_1
      2016-08-19 22:44:41,279 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-security-config:4.0.4.RELEASE_1
      2016-08-19 22:44:41,289 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-web:4.2.5.RELEASE_1
      2016-08-19 22:44:41,292 INFO  [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011001: Bundle installed: org.eclipse.gemini.blueprint.core:2.0.0.RELEASE
      2016-08-19 22:44:41,294 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-core:4.2.5.RELEASE_1
      2016-08-19 22:44:41,314 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-jms:4.2.5.RELEASE_1
      2016-08-19 22:44:41,320 INFO  [org.jboss.osgi.framework] (MSC service thread 1-5) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-security-web:4.0.4.RELEASE_1
      2016-08-19 22:44:41,407 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0069: Defer FIRST_MODULE_USE for org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar making it NEVER
      2016-08-19 22:44:41,409 INFO  [org.jboss.osgi.framework] (MSC service thread 1-2) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-context:4.2.5.RELEASE_1
      2016-08-19 22:44:41,411 INFO  [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011001: Bundle installed: org.springframework.data.core:1.11.4.RELEASE
      2016-08-19 22:44:41,412 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-websocket:4.2.5.RELEASE_1
      2016-08-19 22:44:41,414 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-security-core:4.0.4.RELEASE_1
      2016-08-19 22:44:41,418 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-webmvc:4.2.5.RELEASE_1
      2016-08-19 22:44:41,418 INFO  [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: com.springsource.org.aopalliance:1.0.0
      2016-08-19 22:45:27,167 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-core:4.2.5.RELEASE_1
      2016-08-19 22:45:27,317 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-oxm:4.2.5.RELEASE_1
      2016-08-19 22:45:27,369 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-aop:4.2.5.RELEASE_1
      2016-08-19 22:45:27,430 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-beans:4.2.5.RELEASE_1
      2016-08-19 22:45:27,472 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-web:4.2.5.RELEASE_1
      2016-08-19 22:45:27,503 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.eclipse.gemini.blueprint.core:2.0.0.RELEASE
      2016-08-19 22:45:27,533 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-context:4.2.5.RELEASE_1
      2016-08-19 22:45:27,569 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-jdbc:4.2.5.RELEASE_1
      2016-08-19 22:45:27,657 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: com.springsource.org.aopalliance:1.0.0
      2016-08-19 22:45:27,689 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-jms:4.2.5.RELEASE_1
      2016-08-19 22:45:27,825 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-security-core:4.0.4.RELEASE_1
      2016-08-19 22:45:27,865 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-orm:4.2.5.RELEASE_1
      2016-08-19 22:45:27,896 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-expression:4.2.5.RELEASE_1
      2016-08-19 22:45:27,928 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.eclipse.gemini.blueprint.io:2.0.0.RELEASE
      2016-08-19 22:45:27,954 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-webmvc:4.2.5.RELEASE_1
      2016-08-19 22:45:27,981 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-tx:4.2.5.RELEASE_1
      2016-08-19 22:45:28,181 INFO  [org.eclipse.gemini.blueprint.extender.internal.boot.ChainActivator] (MSC service thread 1-6) Blueprint API detected; enabling Blueprint Container functionality
      2016-08-19 22:45:28,355 INFO  [org.eclipse.gemini.blueprint.extender.internal.activator.LoggingActivator] (MSC service thread 1-6) Starting [org.eclipse.gemini.blueprint.extender] bundle v.[2.0.0.RELEASE]
      2016-08-19 22:45:34,497 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.springframework.data.core:1.11.4.RELEASE
      2016-08-19 22:45:42,716 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-websocket:4.2.5.RELEASE_1
      2016-08-19 22:45:48,738 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-security-web:4.0.4.RELEASE_1
      2016-08-19 22:47:15,021 INFO  [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-security-config:4.0.4.RELEASE_1
      2016-08-19 22:47:20,197 INFO  [org.eclipse.gemini.blueprint.extender.internal.support.ExtenderConfiguration] (MSC service thread 1-6) No custom extender configuration detected; using defaults...
      2016-08-19 22:47:20,207 INFO  [org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor] (MSC service thread 1-6) Initializing ExecutorService 
      2016-08-19 22:47:20,207 INFO  [org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor] (MSC service thread 1-6) Initializing ExecutorService 
      2016-08-19 22:47:20,512 INFO  [org.eclipse.gemini.blueprint.extender.internal.blueprint.event.EventAdminDispatcher] (MSC service thread 1-6) EventAdmin package not found; no Blueprint lifecycle events will be published
      2016-08-19 22:47:20,715 INFO  [org.eclipse.gemini.blueprint.extender.internal.blueprint.activator.support.BlueprintContainerCreator] (MSC service thread 1-6) Discovered configurations {bundle://org.apache.aries.blueprint-19-13-0/OSGI-INF/blueprint/blueprint-cm.xml, bundle://org.apache.aries.blueprint-19-13-0/OSGI-INF/blueprint/blueprint-ext.xml} in bundle [Apache Aries Blueprint Bundle (org.apache.aries.blueprint;blueprint.graceperiod:=false)]
      2016-08-19 22:47:20,738 INFO  [org.eclipse.gemini.blueprint.extender.internal.activator.LifecycleManager] (MSC service thread 1-6) Bundle Apache Aries Blueprint Bundle is not type compatible with extender gemini-blueprint-extender; ignoring bundle...
      2016-08-19 22:47:20,780 INFO  [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.eclipse.gemini.blueprint.extender:2.0.0.RELEASE
      2016-08-19 22:48:26,767 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-ldap:2.0.4.RELEASE_2
      2016-08-19 22:48:39,669 INFO  [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-batch-infrastructure:3.0.7.RELEASE_1
      2016-08-19 22:48:42,521 INFO  [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-batch-core:3.0.7.RELEASE_1
      2016-08-19 22:48:42,530 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar" (runtime-name : "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "spring-data-commons-1.11.4.RELEASE.jar" (runtime-name : "spring-data-commons-1.11.4.RELEASE.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "gemini-blueprint-io-2.0.0.RELEASE.jar" (runtime-name : "gemini-blueprint-io-2.0.0.RELEASE.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "gemini-blueprint-extender-2.0.0.RELEASE.jar" (runtime-name : "gemini-blueprint-extender-2.0.0.RELEASE.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "gemini-blueprint-core-2.0.0.RELEASE.jar" (runtime-name : "gemini-blueprint-core-2.0.0.RELEASE.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar")
      2016-08-19 22:48:42,531 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "com.springsource.org.aopalliance-1.0.0.jar" (runtime-name : "com.springsource.org.aopalliance-1.0.0.jar")
      2016-08-19 22:48:42,603 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:9990/management
      2016-08-19 22:48:42,604 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:9990
      2016-08-19 22:48:42,604 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) started in 246599ms - Started 1172 of 1615 services (587 services are lazy, passive or on-demand)
      
      Show
      I have added some logging in Module.addExportedPaths in the "if already visited" test in the Modules 1.5.1. The resulting log is extremely large (30GB) but here are some excerpts: These repeat over and over, with different filterStack and different module names. Notice the visted.size(), recursion depth and memory stats. 2016-08-19 22:48:42,472 TRACE [org.jboss.modules] (MSC service thread 1-2) addExportedPaths exit: module=deployment.org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar, visited.size()=3283729, depth=10, freeMemory=3605623904, t otalMemory=8842117120, maxMemory=15271460864, filterStack=[in {org/springframework/orm/jpa/persistenceunit, org/springframework/orm/hibernate5, org/springframework/orm/hibernate4, org/springframework/orm, org/springframework/orm/hibernat e3/annotation, org/springframework/orm/hibernate4/support, org/springframework/orm/hibernate5/support, org/springframework/orm/jdo, org/springframework/orm/hibernate3/support, org/springframework/orm/jdo/support, org/springframework/orm/ hibernate3, org/springframework/orm/jpa/vendor, org/springframework/orm/jpa/support, org/springframework/orm/jpa}, in {org/springframework/cache/concurrent, org/springframework/jmx, org/springframework/context/expression, org/springframe work/scripting/bsh, org/springframework/scripting, org/springframework/remoting/rmi, org/springframework/scripting/config, org/springframework/remoting/support, org/springframework/jmx/export/naming, org/springframework/jmx/support, org/ springframework/cache/interceptor, org/springframework/instrument/classloading, org/springframework/validation, org/springframework/ui/context/support, org/springframework/format/support, org/springframework/scripting/jruby, org/springfr amework/format/number/money, org/springframework/remoting, org/springframework/context/i18n, org/springframework/scheduling/annotation, org/springframework/jmx/export, org/springframework/validation/beanvalidation, org/springframework/re moting/soap, org/springframework/instrument/classloading/glassfish, org/springframework/jndi/support, org/springframework/ejb/interceptor, org/springframework/format/datetime, org/springframework/scripting/groovy, org/springframework/for mat/datetime/standard, org/springframework/context/annotation, org/springframework/instrument/classloading/tomcat, org/springframework/instrument/classloading/weblogic, org/springframework/cache/config, org/springframework/format/annotat ion, org/springframework/context, org/springframework/format, org/springframework/jmx/export/assembler, org/springframework/ui/context, org/springframework/jmx/export/notification, org/springframework/cache/annotation, org/springframewor k/scheduling, org/springframework/context/support, org/springframework/instrument/classloading/websphere, org/springframework/scheduling/config, org/springframework/instrument/classloading/jboss, org/springframework/context/weaving, org/ springframework/context/access, org/springframework/jndi, org/springframework/jmx/access, org/springframework/context/config, org/springframework/scheduling/concurrent, org/springframework/format/datetime/joda, org/springframework/jmx/ex port/metadata, org/springframework/ejb/access, org/springframework/scheduling/support, org/springframework/ui, org/springframework/cache/support, org/springframework/format/number, org/springframework/scripting/support, org/springframewo rk/cache, org/springframework/context/event, org/springframework/validation/support, org/springframework/validation/annotation, org/springframework/stereotype, org/springframework/jmx/export/annotation, org/springframework/ejb/config}, i n {org/springframework/orm/jpa/support}, in {org/springframework/validation, org/springframework/ui/context/support, org/springframework/context/annotation, org/springframework/context/access, org/springframework/jndi, org/springframewor k/format/support, org/springframework/remoting/rmi, org/springframework/remoting/support, org/springframework/context, org/springframework/remoting, org/springframework/context/i18n, org/springframework/ui/context, org/springframework/re moting/soap, org/springframework/ui, org/springframework/context/support, org/springframework/validation/annotation, org/springframework/validation/support, org/springframework/stereotype}, in {org/springframework/web/context/request, or g/springframework/http/converter/json, org/springframework/web, org/springframework/web/bind/annotation, org/springframework/web/cors, org/springframework/web/filter, org/springframework/web/client/support, org/springframework/web/bind/a nnotation/support, org/springframework/web/client, org/springframework/web/method/annotation, org/springframework/web/multipart/support, org/springframework/http/converter/xml, org/springframework/web/context/request/async, org/springfra mework/web/accept, org/springframework/web/method/support, org/springframework/web/method, org/springframework/http/converter/feed, org/springframework/web/multipart/commons, org/springframework/http, org/springframework/web/jsf, org/spr ingframework/http/server, org/springframework/web/bind/support, org/springframework/web/context, org/springframework/web/context/support, org/springframework/http/converter, org/springframework/web/jsf/el, org/springframework/remoting/ja xws, org/springframework/http/converter/protobuf, org/springframework/web/multipart, org/springframework/web/bind, org/springframework/remoting/caucho, org/springframework/http/client/support, org/springframework/http/converter/support, org/springframework/http/client, org/springframework/web/util, org/springframework/remoting/httpinvoker}, in {org/springframework/jdbc/datasource, org/springframework/jdbc/support/lob, org/springframework/jdbc/core/simple, org/springfram ework/jdbc/support/incrementer, org/springframework/jdbc/core}, in {org/springframework/context, org/springframework/context/annotation, org/springframework/jndi, org/springframework/scheduling, org/springframework/context/support, org/s pringframework/context/event, org/springframework/stereotype}, in {org/springframework/jdbc/datasource, org/springframework/jdbc/support/lob, org/springframework/jdbc, org/springframework/jdbc/datasource/lookup, org/springframework/jdbc/ support}, in {org/springframework/web/context/request, org/springframework/web/context/request/async, org/springframework/web/filter, org/springframework/web/context, org/springframework/web/context/support}, in {org/springframework/jdbc /core/support, org/springframework/jdbc/support/lob, org/springframework/jdbc/core/simple, org/springframework/jdbc/support/incrementer, org/springframework/jdbc/core/namedparam, org/springframework/jdbc/datasource/lookup, org/springfram ework/jdbc/support, org/springframework/jdbc/core, org/springframework/jdbc/datasource/embedded, org/springframework/jdbc/datasource, org/springframework/jdbc/support/rowset, org/springframework/jdbc/core/metadata, org/springframework/jd bc, org/springframework/jdbc/support/nativejdbc, org/springframework/jdbc/datasource/init, org/springframework/jdbc/support/xml, org/springframework/jdbc/object, org/springframework/jdbc/config}, in {org/springframework/dao, org/springfr amework/transaction, org/springframework/dao/support, org/springframework/transaction/jta, org/springframework/transaction/support}, in {org/springframework/dao, org/springframework/transaction, org/springframework/dao/support, org/sprin gframework/transaction/support}, in {org/springframework/transaction/interceptor, org/springframework/dao, org/springframework/jca/context, org/springframework/jca/cci, org/springframework/jca/cci/core/support, org/springframework/jca/su pport, org/springframework/jca/work, org/springframework/transaction/event, org/springframework/jca/cci/core, org/springframework/dao/annotation, org/springframework/dao/support, org/springframework/transaction/jta, org/springframework/t ransaction/config, org/springframework/transaction, org/springframework/jca/endpoint, org/springframework/jca/work/jboss, org/springframework/transaction/annotation, org/springframework/jca/work/glassfish, org/springframework/jca/cci/con nection, org/springframework/jca/cci/object, org/springframework/transaction/support}], classFilterStack=[], resourceFilterStack=[], dependencies=[dependency on org.jboss.modules.ClassLoaderLocalLoader@24f8952, optional dependency on jbo sgi.framework:main (FrameworkModuleLoader), optional dependency on javax.transaction.api:main (ModuleLoaderIntegration.DelegatingModuleLoader), optional dependency on org.slf4j.jcl-over-slf4j:main (ModuleLoaderIntegration.DelegatingModul eLoader), dependency on deployment.org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on deployment.org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_ 1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on deployment.org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on deployment.org.apach e.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar:main (ModuleLoaderIntegration.DelegatingModuleLoader), dependency on HostBundleClassLoader for Module "deployment.org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar:main" from Service Module Loader], : java.lang.Exception at org.jboss.modules.Module.addExportedPaths(Module.java:1218) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addExportedPaths(Module.java:1292) at org.jboss.modules.Module.addPaths(Module.java:1127) at org.jboss.modules.Module.link(Module.java:1466) at org.jboss.modules.Module.relink(Module.java:1510) at org.jboss.modules.ModuleLoader.relink(ModuleLoader.java:566) at org.jboss.as.server.moduleservice.ServiceModuleLoader.relinkModule(ServiceModuleLoader.java:193) at org.jboss.as.server.moduleservice.ModuleLoadService.start(ModuleLoadService.java:69) at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1948) at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1881) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Notice the timings: 2016-08-19 22:44:36,238 INFO [org.jboss.modules] (main) JBoss Modules version 1.5.1.Final 2016-08-19 22:44:36,481 INFO [org.jboss.msc] (main) JBoss MSC version 1.2.6.Final 2016-08-19 22:44:36,601 INFO [org.jboss.as] (MSC service thread 1-5) WFLYSRV0049: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) starting 2016-08-19 22:44:38,518 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http) 2016-08-19 22:44:38,540 INFO [org.xnio] (MSC service thread 1-8) XNIO version 3.3.4.Final 2016-08-19 22:44:38,550 INFO [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.3.4.Final 2016-08-19 22:44:38,588 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 32) JBAS011906: Activating OSGi Subsystem 2016-08-19 22:44:38,627 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 40) WFLYCLINF0001: Activating Infinispan subsystem. 2016-08-19 22:44:38,636 INFO [org.jboss.as.security] (ServerService Thread Pool -- 55) WFLYSEC0002: Activating Security Subsystem 2016-08-19 22:44:38,647 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 58) WFLYWS0002: Activating WebServices Extension 2016-08-19 22:44:38,650 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 48) WFLYNAM0001: Activating Naming Subsystem 2016-08-19 22:44:38,683 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 46) WFLYJSF0007: Activated the following JSF Implementations: [main] 2016-08-19 22:44:38,690 INFO [org.jboss.as.security] (MSC service thread 1-7) WFLYSEC0001: Current PicketBox version=4.9.4.Final 2016-08-19 22:44:38,700 INFO [org.wildfly.extension.io] (ServerService Thread Pool -- 39) WFLYIO001: Worker 'default' has auto-configured to 16 core threads with 128 task threads based on your 8 available processors 2016-08-19 22:44:38,760 INFO [org.jboss.as.connector] (MSC service thread 1-1) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.3.2.Final) 2016-08-19 22:44:38,763 INFO [org.jboss.remoting] (MSC service thread 1-8) JBoss Remoting version 4.0.18.Final 2016-08-19 22:44:38,783 INFO [org.jboss.as.naming] (MSC service thread 1-1) WFLYNAM0003: Starting Naming Service 2016-08-19 22:44:38,783 INFO [org.jboss.as.mail.extension] (MSC service thread 1-1) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default] 2016-08-19 22:44:38,794 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 36) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) 2016-08-19 22:44:38,797 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-1) WFLYJCA0018: Started Driver service with driver-name = h2 2016-08-19 22:44:38,835 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0003: Undertow 1.3.15.Final starting 2016-08-19 22:44:38,839 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 57) WFLYUT0003: Undertow 1.3.15.Final starting 2016-08-19 22:44:39,119 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-5) ARJUNA032010: JBossTS Recovery Service (tag: f6563) - JBoss Inc. 2016-08-19 22:44:39,132 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 57) WFLYUT0014: Creating file handler for path '/home/arcivanov/Documents/src/jbosgi/JBOSGI-794/wildfly-10.0.0.Final/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]'] 2016-08-19 22:44:39,149 INFO [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0012: Started server default-server. 2016-08-19 22:44:39,150 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0018: Host default-host starting 2016-08-19 22:44:39,193 INFO [org.jboss.as.ejb3] (MSC service thread 1-4) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 32 (per class), which is derived from the number of CPUs on this host. 2016-08-19 22:44:39,193 INFO [org.jboss.as.ejb3] (MSC service thread 1-3) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 128 (per class), which is derived from thread worker pool sizing. 2016-08-19 22:44:39,300 INFO [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011006: OSGi Framework - 5.0.2.Final-SNAPSHOT 2016-08-19 22:44:39,308 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-5) ARJUNA032013: Starting transaction recovery manager 2016-08-19 22:44:39,315 INFO [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0006: Undertow HTTP listener default listening on 127.0.0.1:8080 2016-08-19 22:44:39,364 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-8) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: f6563) - JBoss Inc. 2016-08-19 22:44:39,552 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS] 2016-08-19 22:44:39,670 INFO [org.jboss.as.server.deployment] (MSC service thread 1-7) WFLYSRV0027: Starting deployment of "spring-data-commons-1.11.4.RELEASE.jar" (runtime-name: "spring-data-commons-1.11.4.RELEASE.jar") 2016-08-19 22:44:39,670 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,670 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,673 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar") 2016-08-19 22:44:39,674 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar") 2016-08-19 22:44:39,674 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,675 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "gemini-blueprint-io-2.0.0.RELEASE.jar" (runtime-name: "gemini-blueprint-io-2.0.0.RELEASE.jar") 2016-08-19 22:44:39,675 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "gemini-blueprint-core-2.0.0.RELEASE.jar" (runtime-name: "gemini-blueprint-core-2.0.0.RELEASE.jar") 2016-08-19 22:44:39,675 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,675 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,676 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "gemini-blueprint-extender-2.0.0.RELEASE.jar" (runtime-name: "gemini-blueprint-extender-2.0.0.RELEASE.jar") 2016-08-19 22:44:39,676 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,676 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "com.springsource.org.aopalliance-1.0.0.jar" (runtime-name: "com.springsource.org.aopalliance-1.0.0.jar") 2016-08-19 22:44:39,677 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar" (runtime-name: "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar") 2016-08-19 22:44:39,678 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,678 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,678 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar") 2016-08-19 22:44:39,675 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar" (runtime-name: "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar") 2016-08-19 22:44:39,729 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-7) WFLYDS0013: Started FileSystemDeploymentService for directory /home/arcivanov/Documents/src/jbosgi/JBOSGI-794/wildfly-10.0.0.Final/standalone/deployments 2016-08-19 22:44:39,898 INFO [org.jboss.ws.common.management] (MSC service thread 1-4) JBWS022052: Starting JBossWS 5.1.3.Final (Apache CXF 3.1.4) 2016-08-19 22:44:39,913 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-6) ISPN000128: Infinispan version: Infinispan 'Mahou' 8.1.0.Final 2016-08-19 22:44:40,584 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.log:1.0.1 2016-08-19 22:44:40,586 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: jboss-osgi-logging:1.0.0 2016-08-19 22:44:40,587 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.configadmin:1.8.8 2016-08-19 22:44:40,590 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.metatype:1.1.2 2016-08-19 22:44:40,592 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.felix.scr:1.8.2 2016-08-19 22:44:40,595 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.blueprint:1.1.0 2016-08-19 22:44:40,597 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.jmx:1.1.5 2016-08-19 22:44:40,599 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.jndi:1.0.0 2016-08-19 22:44:40,600 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.proxy:1.0.1 2016-08-19 22:44:40,602 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.aries.util:1.1.1 2016-08-19 22:44:40,775 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011011: Starting bundles for start level: 1 2016-08-19 22:44:40,779 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: jbosgi-wildfly-http:2.5.3.Final-SNAPSHOT 2016-08-19 22:44:40,780 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: jbosgi-wildfly-jpa:2.5.3.Final-SNAPSHOT 2016-08-19 22:44:40,799 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.log:1.0.1 2016-08-19 22:44:40,821 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: jboss-osgi-logging:1.0.0 2016-08-19 22:44:40,847 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.configadmin:1.8.8 2016-08-19 22:44:40,860 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.metatype:1.1.2 2016-08-19 22:44:40,911 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.felix.scr:1.8.2 2016-08-19 22:44:40,979 INFO [org.apache.aries.blueprint.container.BlueprintExtender] (MSC service thread 1-1) No quiesce support is available, so blueprint components will not participate in quiesce operations 2016-08-19 22:44:40,980 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.blueprint:1.1.0 2016-08-19 22:44:40,995 INFO [org.apache.aries.jmx] (Thread-78) Starting JMX OSGi agent 2016-08-19 22:44:41,019 INFO [org.apache.aries.jmx] (Thread-78) Registering MBean with ObjectName [osgi.compendium:service=cm,version=1.3,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0] for service with service.id [25] 2016-08-19 22:44:41,024 INFO [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.wiring.BundleWiringStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=wiringState,version=1.1,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0 2016-08-19 22:44:41,024 INFO [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.FrameworkMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=framework,version=1.7,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0 2016-08-19 22:44:41,024 INFO [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.BundleStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=bundleState,version=1.7,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0 2016-08-19 22:44:41,026 INFO [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.ServiceStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=serviceState,version=1.7,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0 2016-08-19 22:44:41,026 INFO [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.service.cm.ConfigurationAdminMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.compendium:service=cm,version=1.3,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0 2016-08-19 22:44:41,027 INFO [org.apache.aries.jmx] (Thread-78) Registering org.osgi.jmx.framework.PackageStateMBean to MBeanServer org.jboss.as.jmx.PluggableMBeanServerImpl@45e5dfe8 with name osgi.core:type=packageState,version=1.5,framework=org.jboss.osgi.framework,uuid=33f586e3-fdfb-4f25-bde0-d45eb0e393b0 2016-08-19 22:44:41,027 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.jmx:1.1.5 2016-08-19 22:44:41,065 INFO [org.apache.aries.jndi.startup.Activator] (MSC service thread 1-1) It was not possible to register an InitialContextFactoryBuilder with the NamingManager because another builder called org.jboss.as.naming.InitialContextFactoryBuilder was already registered. Support for calling new InitialContext() will not be enabled. 2016-08-19 22:44:41,065 INFO [org.apache.aries.jndi.startup.Activator] (MSC service thread 1-1) It was not possible to register an ObjectFactoryBuilder with the NamingManager because another builder called org.jboss.as.naming.InitialContextFactoryBuilder was already registered. Looking up certain objects may not work correctly. 2016-08-19 22:44:41,093 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.jndi:1.0.0 2016-08-19 22:44:41,152 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.proxy:1.0.1 2016-08-19 22:44:41,156 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.aries.util:1.1.1 2016-08-19 22:44:41,157 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011000: OSGi Framework started 2016-08-19 22:44:41,173 INFO [org.jboss.osgi.framework] (MSC service thread 1-2) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-context-support:4.2.5.RELEASE_1 2016-08-19 22:44:41,197 INFO [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-aop:4.2.5.RELEASE_1 2016-08-19 22:44:41,207 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-orm:4.2.5.RELEASE_1 2016-08-19 22:44:41,209 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-tx:4.2.5.RELEASE_1 2016-08-19 22:44:41,211 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-expression:4.2.5.RELEASE_1 2016-08-19 22:44:41,216 INFO [org.jboss.osgi.framework] (MSC service thread 1-5) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-batch-infrastructure:3.0.7.RELEASE_1 2016-08-19 22:44:41,219 INFO [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011001: Bundle installed: org.eclipse.gemini.blueprint.io:2.0.0.RELEASE 2016-08-19 22:44:41,225 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.eclipse.gemini.blueprint.extender:2.0.0.RELEASE 2016-08-19 22:44:41,229 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-batch-core:3.0.7.RELEASE_1 2016-08-19 22:44:41,233 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-ldap:2.0.4.RELEASE_2 2016-08-19 22:44:41,238 INFO [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-beans:4.2.5.RELEASE_1 2016-08-19 22:44:41,243 INFO [org.jboss.osgi.framework] (MSC service thread 1-2) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-oxm:4.2.5.RELEASE_1 2016-08-19 22:44:41,260 INFO [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-jdbc:4.2.5.RELEASE_1 2016-08-19 22:44:41,279 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-security-config:4.0.4.RELEASE_1 2016-08-19 22:44:41,289 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-web:4.2.5.RELEASE_1 2016-08-19 22:44:41,292 INFO [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011001: Bundle installed: org.eclipse.gemini.blueprint.core:2.0.0.RELEASE 2016-08-19 22:44:41,294 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-core:4.2.5.RELEASE_1 2016-08-19 22:44:41,314 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-jms:4.2.5.RELEASE_1 2016-08-19 22:44:41,320 INFO [org.jboss.osgi.framework] (MSC service thread 1-5) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-security-web:4.0.4.RELEASE_1 2016-08-19 22:44:41,407 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0069: Defer FIRST_MODULE_USE for org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar making it NEVER 2016-08-19 22:44:41,409 INFO [org.jboss.osgi.framework] (MSC service thread 1-2) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-context:4.2.5.RELEASE_1 2016-08-19 22:44:41,411 INFO [org.jboss.osgi.framework] (MSC service thread 1-3) JBOSGI011001: Bundle installed: org.springframework.data.core:1.11.4.RELEASE 2016-08-19 22:44:41,412 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-websocket:4.2.5.RELEASE_1 2016-08-19 22:44:41,414 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-security-core:4.0.4.RELEASE_1 2016-08-19 22:44:41,418 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011001: Bundle installed: org.apache.servicemix.bundles.spring-webmvc:4.2.5.RELEASE_1 2016-08-19 22:44:41,418 INFO [org.jboss.osgi.framework] (MSC service thread 1-7) JBOSGI011001: Bundle installed: com.springsource.org.aopalliance:1.0.0 2016-08-19 22:45:27,167 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-core:4.2.5.RELEASE_1 2016-08-19 22:45:27,317 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-oxm:4.2.5.RELEASE_1 2016-08-19 22:45:27,369 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-aop:4.2.5.RELEASE_1 2016-08-19 22:45:27,430 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-beans:4.2.5.RELEASE_1 2016-08-19 22:45:27,472 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-web:4.2.5.RELEASE_1 2016-08-19 22:45:27,503 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.eclipse.gemini.blueprint.core:2.0.0.RELEASE 2016-08-19 22:45:27,533 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-context:4.2.5.RELEASE_1 2016-08-19 22:45:27,569 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-jdbc:4.2.5.RELEASE_1 2016-08-19 22:45:27,657 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: com.springsource.org.aopalliance:1.0.0 2016-08-19 22:45:27,689 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-jms:4.2.5.RELEASE_1 2016-08-19 22:45:27,825 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-security-core:4.0.4.RELEASE_1 2016-08-19 22:45:27,865 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-orm:4.2.5.RELEASE_1 2016-08-19 22:45:27,896 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-expression:4.2.5.RELEASE_1 2016-08-19 22:45:27,928 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.eclipse.gemini.blueprint.io:2.0.0.RELEASE 2016-08-19 22:45:27,954 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-webmvc:4.2.5.RELEASE_1 2016-08-19 22:45:27,981 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-tx:4.2.5.RELEASE_1 2016-08-19 22:45:28,181 INFO [org.eclipse.gemini.blueprint.extender.internal.boot.ChainActivator] (MSC service thread 1-6) Blueprint API detected; enabling Blueprint Container functionality 2016-08-19 22:45:28,355 INFO [org.eclipse.gemini.blueprint.extender.internal.activator.LoggingActivator] (MSC service thread 1-6) Starting [org.eclipse.gemini.blueprint.extender] bundle v.[2.0.0.RELEASE] 2016-08-19 22:45:34,497 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.springframework.data.core:1.11.4.RELEASE 2016-08-19 22:45:42,716 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-websocket:4.2.5.RELEASE_1 2016-08-19 22:45:48,738 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-security-web:4.0.4.RELEASE_1 2016-08-19 22:47:15,021 INFO [org.jboss.osgi.framework] (MSC service thread 1-8) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-security-config:4.0.4.RELEASE_1 2016-08-19 22:47:20,197 INFO [org.eclipse.gemini.blueprint.extender.internal.support.ExtenderConfiguration] (MSC service thread 1-6) No custom extender configuration detected; using defaults... 2016-08-19 22:47:20,207 INFO [org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor] (MSC service thread 1-6) Initializing ExecutorService 2016-08-19 22:47:20,207 INFO [org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor] (MSC service thread 1-6) Initializing ExecutorService 2016-08-19 22:47:20,512 INFO [org.eclipse.gemini.blueprint.extender.internal.blueprint.event.EventAdminDispatcher] (MSC service thread 1-6) EventAdmin package not found; no Blueprint lifecycle events will be published 2016-08-19 22:47:20,715 INFO [org.eclipse.gemini.blueprint.extender.internal.blueprint.activator.support.BlueprintContainerCreator] (MSC service thread 1-6) Discovered configurations {bundle://org.apache.aries.blueprint-19-13-0/OSGI-INF/blueprint/blueprint-cm.xml, bundle://org.apache.aries.blueprint-19-13-0/OSGI-INF/blueprint/blueprint-ext.xml} in bundle [Apache Aries Blueprint Bundle (org.apache.aries.blueprint;blueprint.graceperiod:=false)] 2016-08-19 22:47:20,738 INFO [org.eclipse.gemini.blueprint.extender.internal.activator.LifecycleManager] (MSC service thread 1-6) Bundle Apache Aries Blueprint Bundle is not type compatible with extender gemini-blueprint-extender; ignoring bundle... 2016-08-19 22:47:20,780 INFO [org.jboss.osgi.framework] (MSC service thread 1-6) JBOSGI011002: Bundle started: org.eclipse.gemini.blueprint.extender:2.0.0.RELEASE 2016-08-19 22:48:26,767 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-ldap:2.0.4.RELEASE_2 2016-08-19 22:48:39,669 INFO [org.jboss.osgi.framework] (MSC service thread 1-4) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-batch-infrastructure:3.0.7.RELEASE_1 2016-08-19 22:48:42,521 INFO [org.jboss.osgi.framework] (MSC service thread 1-1) JBOSGI011002: Bundle started: org.apache.servicemix.bundles.spring-batch-core:3.0.7.RELEASE_1 2016-08-19 22:48:42,530 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-websocket-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-webmvc-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-web-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-tx-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-security-web-4.0.4.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-security-core-4.0.4.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-security-config-4.0.4.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-oxm-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-orm-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar" (runtime-name : "org.apache.servicemix.bundles.spring-ldap-2.0.4.RELEASE_2.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-jms-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-jdbc-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-expression-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "spring-data-commons-1.11.4.RELEASE.jar" (runtime-name : "spring-data-commons-1.11.4.RELEASE.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-core-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-context-support-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-context-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-beans-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-aop-4.2.5.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "gemini-blueprint-io-2.0.0.RELEASE.jar" (runtime-name : "gemini-blueprint-io-2.0.0.RELEASE.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "gemini-blueprint-extender-2.0.0.RELEASE.jar" (runtime-name : "gemini-blueprint-extender-2.0.0.RELEASE.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "gemini-blueprint-core-2.0.0.RELEASE.jar" (runtime-name : "gemini-blueprint-core-2.0.0.RELEASE.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-batch-infrastructure-3.0.7.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar" (runtime-name : "org.apache.servicemix.bundles.spring-batch-core-3.0.7.RELEASE_1.jar") 2016-08-19 22:48:42,531 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) WFLYSRV0010: Deployed "com.springsource.org.aopalliance-1.0.0.jar" (runtime-name : "com.springsource.org.aopalliance-1.0.0.jar") 2016-08-19 22:48:42,603 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:9990/management 2016-08-19 22:48:42,604 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:9990 2016-08-19 22:48:42,604 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) started in 246599ms - Started 1172 of 1615 services (587 services are lazy, passive or on-demand)

    Description

      The short description is this:

      Spring + Servicemix deployed as drop-in deployments alongside JBOSGI patch in WildFly 10. After all of the JBOSGI wiring has been fully and successfully resolved, ModuleLoaderService.start -> ServiceModuleLoader.relinkModule unconditionally relinks the starting modules. The resulting Module.addPaths -> Module.addExportedPaths produces enormous visited set sizes due to uniqueness and mutation of filterStack.

      I am unable to determine the cause of this. JBOSGI resolution mechanism finds no infinite loops and actually resolves the wiring nearly instantaneously. The fact that the resolution actually does start modules without failures after about 1 minutes leads me to believe that there are no unresolvable loop dependencies.

      The dimensions of a problem are as follows:

      • the visited set explodes to over 3M (three million) entries during traversal
      • resulting in addExportedPaths depth of up to 16 or so
      • requires 12GB of heap to complete linkage of all deployed Spring modules.
      • CPU is pegged on all cores for up to 1 minute.

      Any theories as to what might be causing such behavior and what peculiar dependency wiring and setup I should look for? I have a sneaking suspicion a cartesian product of dependencies and all export filters is somehow produced due to the code below but I'm unable to find the dependency configuration issue that may cause it:

                          if (filterStack.contains(importFilter) && filterStack.contains(exportFilter)) {
                              nestedFilters = filterStack;
                          } else {
                              nestedFilters = filterStack.clone();
                              nestedFilters.add(importFilter);
                              nestedFilters.add(exportFilter);
                          }
      

      Your help is greatly appreciated.

      Attachments

        Activity

          People

            Unassigned Unassigned
            arcivanov_jira Arcadiy Ivanov (Inactive)
            Votes:
            1 Vote for this issue
            Watchers:
            3 Start watching this issue

            Dates

              Created:
              Updated: