Skip to content

Instantly share code, notes, and snippets.

@freerobby

freerobby/torquebox.log

Created July 3, 2012 16:57
Show Gist options
  • Save freerobby/3041018 to your computer and use it in GitHub Desktop.
Save freerobby/3041018 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
torquebox.log
=========================================================================
JBoss Bootstrap Environment
JBOSS_HOME: /opt/torquebox/current/jboss
JAVA: java
JAVA_OPTS: -server -XX:+UseCompressedOops -XX:+TieredCompilation -Xms2048m -Xmx2048m -XX:MaxPermSize=256m -Djava.net.preferIPv4Stack=true -Dorg.jboss.resolver.warning=true -Dsun.rmi.dgc.client.gcInterval=3600000 -Dsun.rmi.dgc.server.gcInterval=3600000 -XX:ReservedCodeCacheSize=128m -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true -Djboss.server.default.config=standalone.xml -Xss2048k
=========================================================================
15:17:00,927 INFO [org.jboss.modules] JBoss Modules version 1.1.1.GA
15:17:01,237 INFO [org.jboss.msc] JBoss MSC version 1.0.2.GA
15:17:01,296 INFO [org.jboss.as] JBAS015899: JBoss AS 7.1.1.Final "Brontes" starting
15:17:02,327 INFO [org.torquebox.bootstrap] Bootstrapping TorqueBox
15:17:02,329 INFO [org.torquebox.bootstrap] ===> /opt/torquebox/current/jruby
15:17:02,330 INFO [org.torquebox.bootstrap] Adding: /opt/torquebox/current/jruby/lib/jruby.jar
15:17:02,382 INFO [org.torquebox.bootstrap] Swizzle: [org.jboss.modules.ResourceLoaderSpec@261a6518, org.jboss.modules.ResourceLoaderSpec@ee31e33, org.jboss.modules.ResourceLoaderSpec@60d3ade7]
15:17:02,962 INFO [org.torquebox.jobs.as] readElement
15:17:02,964 INFO [org.torquebox.jobs.as] done readElement
15:17:03,071 INFO [org.torquebox.bootstrap] Bootstrapping TorqueBox
15:17:03,151 INFO [org.torquebox.cache.as] Initializing TorqueBox Cache Subsystem
15:17:03,153 INFO [org.torquebox.core.as] Boostrapping TorqueBox Core Subsystem
15:17:03,155 INFO [org.torquebox.core.as] Initializing TorqueBox Core Subsystem
15:17:03,157 INFO [org.torquebox.jobs.as] Initializing TorqueBox jobs Subsystem
15:17:03,167 INFO [org.torquebox.cdi.as] Initializing TorqueBox CDI Subsystem
15:17:03,171 INFO [org.torquebox.services.as] Initializing TorqueBox Services Subsystem
15:17:03,190 INFO [org.torquebox.core.as] Welcome to TorqueBox AS - http://torquebox.org/
15:17:03,176 INFO [org.torquebox.messaging.as] Initializing TorqueBox Messaging Subsystem
15:17:03,220 INFO [org.torquebox.web.as] Initializing TorqueBox Web Subsystem
15:17:03,227 INFO [org.torquebox.stomp.as] Initializing TorqueBox STOMP Subsystem
15:17:03,239 INFO [org.torquebox.core.as] version........... 2.0.3
15:17:03,239 INFO [org.torquebox.core.as] build............. 58
15:17:03,249 INFO [org.torquebox.core.as] revision.......... 54d311853ad74c8292d43f36c7ec97aee5312d50
15:17:03,250 INFO [org.torquebox.core.as] built with:
15:17:03,250 INFO [org.torquebox.core.as] HornetQ......... 2.2.13.Final (HQ_2_2_13_FINAL_AS7, 122)
15:17:03,251 INFO [org.torquebox.core.as] JBossAS......... 7.1.1.Final
15:17:03,251 INFO [org.torquebox.core.as] Infinispan...... 5.1.2.FINAL
15:17:03,252 INFO [org.torquebox.core.as] Quartz.......... 1.8.5
15:17:03,252 INFO [org.torquebox.core.as] JRuby........... 1.6.7.2
15:17:03,259 INFO [org.projectodd.polyglot.hasingleton.as] Initializing HA-Singleton Subsystem
15:17:03,236 INFO [org.torquebox.security.as] Initializing TorqueBox Auth Subsystem
15:17:03,842 WARN [org.jboss.as.server] JBAS015883: No security realm defined for native management service; all access will be unrestricted.
15:17:03,854 INFO [org.jboss.as.server] JBAS015888: Creating http management service using socket-binding (management-http)
15:17:03,855 WARN [org.jboss.as.server] JBAS015884: No security realm defined for http management service; all access will be unrestricted.
15:17:03,900 INFO [org.xnio] XNIO Version 3.0.3.GA
15:17:03,926 INFO [org.xnio.nio] XNIO NIO Implementation Version 3.0.3.GA
15:17:03,932 INFO [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers
15:17:03,940 INFO [org.jboss.remoting] (MSC service thread 1-4) JBoss Remoting version 3.2.3.GA
15:17:04,227 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 42) JBAS016200: Activating ConfigAdmin Subsystem
15:17:04,228 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 47) JBAS010280: Activating Infinispan subsystem.
15:17:04,337 INFO [org.jboss.as.security] (ServerService Thread Pool -- 64) JBAS013101: Activating Security Subsystem
15:17:04,374 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 48) JBAS016300: Activating JacORB Subsystem
15:17:04,468 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 59) JBAS011940: Activating OSGi Subsystem
15:17:04,471 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 68) JBAS015537: Activating WebServices Extension
15:17:04,547 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 58) JBAS011800: Activating Naming Subsystem
15:17:04,621 INFO [org.jboss.as.connector] (MSC service thread 1-1) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.9.Final)
15:17:04,683 INFO [org.jboss.as.security] (MSC service thread 1-1) JBAS013100: Current PicketBox version=4.0.7.Final
15:17:04,684 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 43) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
15:17:04,997 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-3) JBoss Web Services - Stack CXF Server 4.0.2.GA
15:17:05,059 INFO [org.jboss.as.naming] (MSC service thread 1-2) JBAS011802: Starting Naming Service
15:17:05,098 INFO [org.jboss.as.remoting] (MSC service thread 1-1) JBAS017100: Listening on /127.0.0.1:9999
15:17:05,298 INFO [org.torquebox.core.runtime] (MSC service thread 1-4) Creating ruby runtime (ruby_version: RUBY1_8, compile_mode: JIT, context: global)
15:17:05,403 INFO [org.jboss.jaxr] (MSC service thread 1-1) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory
15:17:05,431 INFO [org.jboss.as.mail.extension] (MSC service thread 1-1) JBAS015400: Bound mail session [java:jboss/mail/Default]
15:17:05,616 WARN [org.jboss.as.messaging] (MSC service thread 1-1) JBAS011600: AIO wasn't located on this platform, it will fall back to using pure Java NIO. If your platform is Linux, install LibAIO to enable the AIO journal
15:17:05,690 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-2) Starting Coyote HTTP/1.1 on http-ip-10-124-166-233.ec2.internal-10.124.166.233-8080
15:17:05,855 INFO [org.hornetq.core.server.impl.HornetQServerImpl] (MSC service thread 1-1) live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/opt/torquebox/current/jboss/standalone/data/messagingjournal,bindingsDirectory=/opt/torquebox/current/jboss/standalone/data/messagingbindings,largeMessagesDirectory=/opt/torquebox/current/jboss/standalone/data/messaginglargemessages,pagingDirectory=/opt/torquebox/current/jboss/standalone/data/messagingpaging)
15:17:05,869 INFO [org.hornetq.core.server.impl.HornetQServerImpl] (MSC service thread 1-1) Waiting to obtain live lock
15:17:05,975 INFO [org.hornetq.core.persistence.impl.journal.JournalStorageManager] (MSC service thread 1-1) Using NIO Journal
15:17:06,567 INFO [org.hornetq.core.server.impl.FileLockNodeManager] (MSC service thread 1-1) Waiting to obtain live lock
15:17:06,568 INFO [org.hornetq.core.server.impl.FileLockNodeManager] (MSC service thread 1-1) Live Server Obtained live lock
15:17:06,600 INFO [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016330: CORBA ORB Service started
15:17:07,419 INFO [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016328: CORBA Naming Service started
15:17:07,478 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
15:17:08,736 INFO [org.hornetq.core.remoting.impl.netty.NettyAcceptor] (MSC service thread 1-1) Started Netty Acceptor version 3.2.5.Final-a96d88c ip-10-124-166-233.ec2.internal:5455 for CORE protocol
15:17:08,744 INFO [org.hornetq.core.remoting.impl.netty.NettyAcceptor] (MSC service thread 1-1) Started Netty Acceptor version 3.2.5.Final-a96d88c ip-10-124-166-233.ec2.internal:5445 for CORE protocol
15:17:08,748 INFO [org.hornetq.core.server.impl.HornetQServerImpl] (MSC service thread 1-1) Server is now live
15:17:08,749 INFO [org.hornetq.core.server.impl.HornetQServerImpl] (MSC service thread 1-1) HornetQ Server version 2.2.13.Final (HQ_2_2_13_FINAL_AS7, 122) [3a0f9569-c21f-11e1-a258-1231380dc51b]) started
15:17:08,916 INFO [org.jboss.as.messaging] (MSC service thread 1-2) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
15:17:08,920 INFO [org.jboss.as.messaging] (MSC service thread 1-2) JBAS011601: Bound messaging object to jndi name java:/RemoteConnectionFactory
15:17:08,947 INFO [org.jboss.as.messaging] (MSC service thread 1-3) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
15:17:09,017 INFO [org.jboss.as.deployment.connector] (MSC service thread 1-1) JBAS010406: Registered connection factory java:/JmsXA
15:17:09,048 INFO [org.hornetq.ra.HornetQResourceAdapter] (MSC service thread 1-1) HornetQ resource adaptor started
15:17:09,049 INFO [org.jboss.as.connector.services.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-1) IJ020002: Deployed: file://RaActivatorhornetq-ra
15:17:09,127 INFO [org.jboss.as.deployment.connector] (MSC service thread 1-3) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
15:17:11,026 INFO [org.jboss.as.remoting] (MSC service thread 1-3) JBAS017100: Listening on ip-10-124-166-233.ec2.internal/10.124.166.233:4447
15:17:11,071 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-1) JBAS015012: Started FileSystemDeploymentService for directory /opt/torquebox/current/jboss/standalone/deployments
15:17:13,654 INFO [org.torquebox.core.runtime] (MSC service thread 1-4) Initialize? true
15:17:13,655 INFO [org.torquebox.core.runtime] (MSC service thread 1-4) Initializer=null
15:17:13,700 INFO [org.torquebox.core.runtime] (MSC service thread 1-4) Created ruby runtime (ruby_version: RUBY1_8, compile_mode: JIT, context: global) in 8.4s
15:17:13,725 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990
15:17:13,729 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.1.1.Final "Brontes" started in 13233ms - Started 171 of 267 services (94 services are passive or on-demand)
15:17:13,816 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "current-knob.yml"
15:17:15,398 INFO [org.hornetq.core.server.impl.HornetQServerImpl] (MSC service thread 1-2) trying to deploy queue jms.queue./queues/torquebox/current/tasks/torquebox_backgroundable
15:17:15,440 INFO [org.torquebox.core.runtime] (MSC service thread 1-1) Creating ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: current, context: JDBC Driver Loader)
15:17:15,486 INFO [org.jboss.as.messaging] (MSC service thread 1-2) JBAS011601: Bound messaging object to jndi name java:/queues/torquebox/current/tasks/torquebox_backgroundable
15:17:18,293 INFO [org.torquebox.core.runtime] (MSC service thread 1-1) Initialize? false
15:17:18,359 INFO [org.torquebox.core.runtime] (MSC service thread 1-1) Initializer=org.torquebox.web.rails.RailsRuntimeInitializer@755ee1ed
15:17:18,366 INFO [org.torquebox.core.runtime] (MSC service thread 1-1) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: current, context: JDBC Driver Loader) in 2.92s
15:17:25,027 INFO [org.torquebox.db] (MSC service thread 1-3) Bound data source [java:/torquebox/datasources/current-knob.yml/production]
15:17:25,824 INFO [org.torquebox.core.datasource] (MSC service thread 1-4) Verifier completed
15:17:25,826 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (MSC service thread 1-4) Deferring start for messaging runtime pool.
15:17:25,827 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (MSC service thread 1-1) Starting web runtime pool asynchronously
15:17:25,848 INFO [org.torquebox.core.runtime] (Thread-104) Creating ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: current, context: web)
15:17:26,319 INFO [org.jboss.web] (MSC service thread 1-2) JBAS018210: Registering web context:
15:17:26,516 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS018559: Deployed "current-knob.yml"
15:17:27,810 INFO [org.torquebox.core.runtime] (Thread-104) Initialize? true
15:17:27,811 INFO [org.torquebox.core.runtime] (Thread-104) Initializer=org.torquebox.web.rails.RailsRuntimeInitializer@755ee1ed
15:17:28,249 INFO [org.torquebox.core.runtime] (Thread-104) Setting up Bundler
15:17:49,862 INFO [stdout] (Thread-104) INFO: Configuring Infinispan local cache current
15:17:49,887 INFO [stdout] (Thread-104) INFO: Configuring current local cache with no indexing
15:17:49,888 INFO [stdout] (Thread-104) INFO: No local CacheManager exists for current. Creating one.
15:17:50,135 INFO [org.infinispan.factories.GlobalComponentRegistry] (Thread-104) ISPN000128: Infinispan version: Infinispan 'Brahma' 5.1.2.FINAL
15:17:54,846 INFO [org.torquebox.core.runtime] (Thread-104) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: current, context: web) in 28.99s
15:18:08,691 INFO [org.torquebox.core.runtime] (Thread-1 (HornetQ-client-global-threads-1870285440)) Creating ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: current, context: messaging)
15:18:09,555 INFO [org.torquebox.core.runtime] (Thread-1 (HornetQ-client-global-threads-1870285440)) Initialize? true
15:18:09,560 INFO [org.torquebox.core.runtime] (Thread-1 (HornetQ-client-global-threads-1870285440)) Initializer=org.torquebox.web.rails.RailsRuntimeInitializer@755ee1ed
15:18:09,928 INFO [org.torquebox.core.runtime] (Thread-1 (HornetQ-client-global-threads-1870285440)) Setting up Bundler
15:18:33,572 INFO [stdout] (Thread-1 (HornetQ-client-global-threads-1870285440)) INFO: Configuring Infinispan local cache current
15:18:33,580 INFO [stdout] (Thread-1 (HornetQ-client-global-threads-1870285440)) INFO: Configuring current local cache with no indexing
15:18:33,581 INFO [stdout] (Thread-1 (HornetQ-client-global-threads-1870285440)) INFO: No local CacheManager exists for current. Creating one.
15:18:38,202 INFO [org.torquebox.core.runtime] (Thread-1 (HornetQ-client-global-threads-1870285440)) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: current, context: messaging) in 29.51s
16:34:37,731 WARN [com.arjuna.ats.arjuna] (Transaction Reaper) ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:c16 in state RUN
16:34:37,734 WARN [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012095: Abort of action id 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:c16 invoked while multiple threads active within it.
16:34:37,735 WARN [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012108: CheckedAction::check - atomic action 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:c16 aborting with 1 threads active!
16:34:37,741 WARN [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012121: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 0,5,main] successfully canceled TX 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:c16
16:34:44,765 WARN [com.arjuna.ats.arjuna] (Thread-4 (HornetQ-client-global-threads-1870285440)) ARJUNA012077: Abort called on already aborted atomic action 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:c16
16:34:44,787 INFO [stdout] (Thread-4 (HornetQ-client-global-threads-1870285440)) Transaction rollback: javax.transaction.RollbackException: ARJUNA016063: The transaction is not active!
16:34:44,911 ERROR [org.torquebox.messaging] (Thread-4 (HornetQ-client-global-threads-1870285440)) Unable to process inbound message: org.jruby.exceptions.RaiseException: Native Exception: 'class java.lang.IllegalStateException'; Message: BaseTransaction.rollback - ARJUNA016074: no transaction!; StackTrace: java.lang.IllegalStateException: BaseTransaction.rollback - ARJUNA016074: no transaction!
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.rollback(BaseTransaction.java:130)
at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.rollback(BaseTransactionManagerDelegate.java:114)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.rollback(BaseTransaction.java:130)
at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.rollback(BaseTransactionManagerDelegate.java:114)
Caused by: java.lang.IllegalStateException: BaseTransaction.rollback - ARJUNA016074: no transaction!
... 2 more
16:44:16,154 WARN [com.arjuna.ats.arjuna] (Transaction Reaper) ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:ccf in state RUN
16:44:16,157 WARN [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012095: Abort of action id 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:ccf invoked while multiple threads active within it.
16:44:16,158 WARN [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012108: CheckedAction::check - atomic action 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:ccf aborting with 1 threads active!
16:44:16,163 WARN [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012121: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 0,5,main] successfully canceled TX 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:ccf
16:45:07,011 WARN [com.arjuna.ats.arjuna] (Thread-4 (HornetQ-client-global-threads-1870285440)) ARJUNA012077: Abort called on already aborted atomic action 0:ffff0a7ca6e9:77e42ab9:4ff30cf2:ccf
16:45:07,014 INFO [stdout] (Thread-4 (HornetQ-client-global-threads-1870285440)) Transaction rollback: javax.transaction.RollbackException: ARJUNA016063: The transaction is not active!
16:45:07,025 ERROR [org.torquebox.messaging] (Thread-4 (HornetQ-client-global-threads-1870285440)) Unable to process inbound message: org.jruby.exceptions.RaiseException: Native Exception: 'class java.lang.IllegalStateException'; Message: BaseTransaction.rollback - ARJUNA016074: no transaction!; StackTrace: java.lang.IllegalStateException: BaseTransaction.rollback - ARJUNA016074: no transaction!
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.rollback(BaseTransaction.java:130)
at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.rollback(BaseTransactionManagerDelegate.java:114)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.rollback(BaseTransaction.java:130)
at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.rollback(BaseTransactionManagerDelegate.java:114)
Caused by: java.lang.IllegalStateException: BaseTransaction.rollback - ARJUNA016074: no transaction!
... 2 more
ubuntu@ip-10-124-166-233:/var/log/torquebox$
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment