Skip to content

Instantly share code, notes, and snippets.

@tobias
Created March 3, 2011 15:38
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save tobias/852949 to your computer and use it in GitHub Desktop.
Save tobias/852949 to your computer and use it in GitHub Desktop.
TORQUE-286
10:28:51,173 INFO [org.torquebox.mc.vdf.PojoDeployment] Deploying: kitchen-sink -- "/Users/tobias/work/test-apps/kitchen-sink"
10:28:51,180 INFO [org.torquebox.rails.deployers.RailsApplicationRecognizer] Recognized rails application: AbstractVFSDeploymentContext@298868057{kitchen-sink}
10:28:51,180 INFO [org.torquebox.rails.deployers.RailsApplicationRecognizer] Initializing rails application: AbstractVFSDeploymentContext@298868057{kitchen-sink}
10:28:51,186 INFO [org.torquebox.rack.deployers.DefaultRackApplicationRecognizer] Recognized rack application: AbstractVFSDeploymentContext@298868057{kitchen-sink}
10:28:51,186 INFO [org.torquebox.rack.deployers.DefaultRackApplicationRecognizer] Initializing rack application: AbstractVFSDeploymentContext@298868057{kitchen-sink}
10:28:51,186 INFO [org.torquebox.base.deployers.TorqueBoxYamlParsingDeployer] Parsing: AbstractVFSDeploymentContext@298868057{kitchen-sink}
10:28:51,189 INFO [org.torquebox.base.deployers.TorqueBoxYamlParsingDeployer] Parsed: [TorqueBoxMetaData: data={web={context=/kitchen-sink}, queues={/queues/a-kitchen-sink-queue=null}, topics={/topics/a-kitchen-sink-topic=null}, messaging={/queues/a-kitchen-sink-queue=QueueProcessor, /topics/a-kitchen-sink-topic=[OneTopicProcessor, AnotherTopicProcessor]}, services={AService=null}, jobs={message.producer={job=MessageProducer, cron=*/15 * * * * ?}}}]
10:28:51,200 ERROR [STDERR] parsing: {/queues/a-kitchen-sink-queue=QueueProcessor, /topics/a-kitchen-sink-topic=[OneTopicProcessor, AnotherTopicProcessor]} // class java.util.LinkedHashMap
10:28:51,201 INFO [org.torquebox.messaging.deployers.TasksScanningDeployer] deploying message_producer_task.rb
10:28:51,201 ERROR [STDERR] deploy with: {/topics/a-kitchen-sink-topic=null}
10:28:51,201 INFO [org.torquebox.messaging.deployers.TopicsYamlParsingDeployer] Read configuration for topic [/topics/a-kitchen-sink-topic]
10:28:51,202 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] Rails Root = "/Users/tobias/work/test-apps/kitchen-sink"
10:28:51,202 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] config/environment.rb = "/Users/tobias/work/test-apps/kitchen-sink/config/environment.rb"
10:28:51,202 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] Gemfile = "/Users/tobias/work/test-apps/kitchen-sink/Gemfile"
10:28:51,217 INFO [org.torquebox.rails.deployers.RailsRackDeployer] [RackApplicationMetaData:868419006
rackupScriptLocation=config.ru
rackUpScript=null
host=[]
context=/kitchen-sink
static=null]
10:28:51,217 INFO [org.torquebox.rails.deployers.RailsRackDeployer] [RailsApplicationMetaData:
version=3.0.5]
10:28:51,218 INFO [org.torquebox.rack.deployers.RackApplicationFactoryDeployer] factory rackup: # This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
10:28:51,218 WARN [org.torquebox.rack.deployers.RackRuntimeDeployer] Ruby runtime already initialized
10:28:51,227 INFO [org.torquebox.jobs.deployers.JobsRuntimePoolDeployer] Configured Ruby runtime pool for jobs: [PoolMetaData: name=jobs min=1 max=2]
10:28:51,227 INFO [org.torquebox.messaging.deployers.TasksDeployer] Setting up queue and message processor for /queues/torquebox/kitchen-sink.trq/tasks/message_producer with a concurrency of 1
10:28:51,227 INFO [org.torquebox.messaging.deployers.TasksDeployer] Setting up queue and message processor for /queues/torquebox/kitchen-sink.trq/backgroundable with a concurrency of 1
10:28:51,227 INFO [org.torquebox.messaging.deployers.MessagingRuntimePoolDeployer] Configured Ruby runtime pool for messaging: [PoolMetaData: name=messaging min=1 max=2]
10:28:51,231 INFO [org.torquebox.interp.deployers.RubyRuntimeFactoryDeployer] Using initializer: org.torquebox.rails.core.RailsRuntimeInitializer@7217a3db
10:28:51,235 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] MessageProducerTask alwaysReload=true
10:28:51,235 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] QueueProcessor alwaysReload=true
10:28:51,236 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] TorqueBox::Messaging::BackgroundableProcessor alwaysReload=true
10:28:51,236 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] AnotherTopicProcessor alwaysReload=true
10:28:51,236 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] OneTopicProcessor alwaysReload=true
10:28:51,251 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=services type=SHARED]
10:28:51,251 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=jobs min=1 max=2]
10:28:51,252 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=messaging min=1 max=2]
10:28:51,252 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=web type=SHARED]
10:28:51,319 INFO [STDOUT] CONTEXT: /kitchen-sink
10:28:51,319 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:28:51,319 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:868419006
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:28:52,036 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:28:52,036 INFO [STDOUT] RAILS_ENV = development
10:28:55,148 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:28:55,148 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:28:58,788 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:28:58,788 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:28:58,789 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:28:58,789 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:28:58,790 INFO [STDOUT] Loaded VFS
10:28:58,890 INFO [STDOUT] CONTEXT: /kitchen-sink
10:28:58,890 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:28:58,890 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:868419006
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:28:59,643 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:28:59,643 INFO [STDOUT] RAILS_ENV = development
10:29:08,831 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:29:08,832 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:29:11,809 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:29:11,809 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:29:11,809 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:29:11,810 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:29:11,811 INFO [STDOUT] Loaded VFS
10:29:11,812 INFO [org.torquebox.rack.core.WebHost] Host already registered: localhost
10:29:11,813 INFO [org.torquebox.messaging.core.ManagedTopic] Starting topic /topics/a-kitchen-sink-topic
10:29:11,826 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/a-kitchen-sink-queue
10:29:11,871 INFO [STDOUT] CONTEXT: /kitchen-sink
10:29:11,871 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:29:11,871 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:868419006
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:29:12,464 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:29:12,464 INFO [STDOUT] RAILS_ENV = development
10:29:15,632 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:29:15,633 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:29:19,236 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:29:19,236 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:29:19,236 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:29:19,237 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:29:19,238 INFO [STDOUT] Loaded VFS
10:29:19,284 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:19,290 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:19,292 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:19,294 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:19,319 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:19,320 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:19,323 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/torquebox/kitchen-sink.trq/backgroundable
10:29:19,333 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:19,335 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:19,337 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:19,339 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/torquebox/kitchen-sink.trq/tasks/message_producer
10:29:19,348 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:19,351 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:19,352 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:19,360 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:19,362 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:19,370 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:19,456 INFO [STDOUT] CONTEXT: /kitchen-sink
10:29:19,457 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:29:19,457 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:868419006
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:29:20,225 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:29:20,227 INFO [STDOUT] RAILS_ENV = development
10:29:29,893 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:29:29,893 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:29:34,737 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:29:34,738 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:29:34,739 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:29:34,739 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:29:34,741 INFO [STDOUT] Loaded VFS
10:29:34,793 INFO [STDOUT] AService STARTING
10:29:34,794 INFO [STDOUT] AService: calling AModel#create_message
10:29:34,899 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] deploy, ctxPath=/kitchen-sink
10:29:34,934 INFO [STDOUT] AModel#create_message - SLEEPING
10:29:34,955 WARN [org.jboss.web.tomcat.service.deployers.JBossContextConfig] Failed to setup clustering, clustering disabled. ClusteringNotSupportedException: No DistributedCacheManagerFactory service provider found.
10:29:34,968 INFO [org.torquebox.jobs.core.RubyScheduler] Starting Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:29:34,996 INFO [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
10:29:34,997 INFO [org.quartz.core.QuartzScheduler] Quartz Scheduler v.1.8.3 created.
10:29:34,997 INFO [org.quartz.simpl.RAMJobStore] RAMJobStore initialized.
10:29:34,997 INFO [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v1.8.3) 'RubyScheduler$kitchen-sink.trq' with instanceId 'NON_CLUSTERED'
Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
NOT STARTED.
Currently in standby mode.
Number of jobs executed: 0
Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads.
Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.
10:29:34,997 INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'RubyScheduler$kitchen-sink.trq' initialized from an externally provided properties instance.
10:29:34,997 INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 1.8.3
10:29:34,997 INFO [org.quartz.core.QuartzScheduler] JobFactory set to: org.torquebox.jobs.core.RubyJobFactory@732d1e28
10:29:34,997 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED started.
10:29:34,999 INFO [org.torquebox.jobs.core.ScheduledJob] Starting Ruby job: kitchen-sink.message.producer
10:29:35,001 ERROR [STDERR] DEPLOY RUBY APP: AbstractVFSDeploymentContext@298868057{kitchen-sink}
10:29:35,001 INFO [org.torquebox.base.deployers.DeploymentNotifier] Fully deployed: kitchen-sink
10:29:35,001 INFO [org.torquebox.mc.vdf.PojoDeployment] Fully deployed: "/Users/tobias/work/test-apps/kitchen-sink"
10:29:39,955 INFO [STDOUT] AModel: publishing from AModel#create_message(Thu Mar 03 10:29:39 -0500 2011)
10:29:39,989 INFO [STDOUT] QueueProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:29:39 -0500 2011)
10:29:40,064 INFO [STDOUT] OneTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:29:39 -0500 2011):QueueProcessor(Thu Mar 03 10:29:39 -0500 2011)
10:29:40,067 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:29:39 -0500 2011):QueueProcessor(Thu Mar 03 10:29:39 -0500 2011)
10:29:40,093 INFO [STDOUT] CONTEXT: /kitchen-sink
10:29:40,093 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:29:40,093 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:868419006
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:29:40,951 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:29:40,952 INFO [STDOUT] RAILS_ENV = development
10:29:43,273 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:29:43,273 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:29:45,069 INFO [STDOUT] MessageProducer: publishing from MessageProducer(Thu Mar 03 10:29:45 -0500 2011)
10:29:45,080 INFO [org.torquebox.jobs.core.ScheduledJob] Stopping Ruby job: kitchen-sink.message.producer
10:29:45,082 INFO [org.torquebox.jobs.core.RubyScheduler] Stopping Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:29:45,082 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED shutting down.
10:29:45,082 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED paused.
10:29:45,137 INFO [STDOUT] QueueProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:29:45 -0500 2011)
10:29:45,139 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED shutdown complete.
10:29:45,140 INFO [org.torquebox.jobs.core.RubyScheduler] Stopped Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:29:45,143 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] undeploy, ctxPath=/kitchen-sink
10:29:45,156 INFO [STDOUT] AService STOPPING
10:29:45,168 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:29:45 -0500 2011):QueueProcessor(Thu Mar 03 10:29:45 -0500 2011)
10:29:45,172 INFO [STDOUT] OneTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:29:45 -0500 2011):QueueProcessor(Thu Mar 03 10:29:45 -0500 2011)
10:29:46,196 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:29:46,196 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:29:46,196 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:29:46,196 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:29:46,197 INFO [STDOUT] Loaded VFS
10:29:46,200 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,200 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,201 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,201 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,215 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:46,215 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:46,217 INFO [org.torquebox.messaging.core.ManagedQueue] Destroying queue: /queues/torquebox/kitchen-sink.trq/tasks/message_producer
10:29:46,217 WARN [org.jboss.kernel.plugins.dependency.CreateDestroyLifecycleAction] Error during destroy for torquebox.kitchen-sink.trq.ManagedQueue./queues/torquebox/kitchen-sink.trq/tasks/message_producer: HornetQException[errorCode=104 message=Cannot delete queue jms.queue./queues/torquebox/kitchen-sink.trq/tasks/message_producer on binding jms.queue./queues/torquebox/kitchen-sink.trq/tasks/message_producer - it has consumers = org.hornetq.core.postoffice.impl.LocalQueueBinding]
at org.hornetq.core.server.impl.HornetQServerImpl.destroyQueue(HornetQServerImpl.java:713) [:6.0.0.Final]
at org.hornetq.jms.server.impl.JMSServerManagerImpl.destroyQueue(JMSServerManagerImpl.java:633) [:6.0.0.Final]
at org.torquebox.messaging.core.ManagedQueue.destroy(ManagedQueue.java:35) [:1.0.0.CR1-SNAPSHOT]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]
at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]
at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:60) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:168) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:257) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:125) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.LifecycleAction.uninstallActionInternal(LifecycleAction.java:286) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:175) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:42) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleUninstallAction(SimpleControllerContextAction.java:79) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.AccessControllerContextAction.uninstall(AccessControllerContextAction.java:131) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContextActions.uninstall(AbstractControllerContextActions.java:58) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1472) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:756) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:669) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:230) [:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:58) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalUndeploy(AbstractSimpleRealDeployer.java:69) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.undeploy(AbstractRealDeployer.java:117) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployerWrapper.undeploy(DeployerWrapper.java:204) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUndeploy(DeployersImpl.java:1862) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1769) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1762) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.uninstall(DeployersImpl.java:1724) [:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:948) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:654) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1983) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1968) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:1001) [:2.2.0.GA]
at org.jboss.deployers.plugins.main.MainDeployerImpl.undeploy(MainDeployerImpl.java:578) [:2.2.0.GA]
at org.torquebox.mc.vdf.PojoDeployment.stop(PojoDeployment.java:71) [:1.0.0.CR1-SNAPSHOT]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]
at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]
at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:60) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:168) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:257) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:125) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.LifecycleAction.uninstallActionInternal(LifecycleAction.java:286) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:175) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:42) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleUninstallAction(SimpleControllerContextAction.java:79) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.AccessControllerContextAction.uninstall(AccessControllerContextAction.java:131) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContextActions.uninstall(AbstractControllerContextActions.java:58) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1472) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:756) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:669) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:230) [:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:58) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalUndeploy(AbstractSimpleRealDeployer.java:69) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.undeploy(AbstractRealDeployer.java:117) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployerWrapper.undeploy(DeployerWrapper.java:204) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUndeploy(DeployersImpl.java:1862) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1769) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1762) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.uninstall(DeployersImpl.java:1724) [:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:948) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:654) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1983) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1968) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:1001) [:2.2.0.GA]
at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:656) [:2.2.0.GA]
at org.jboss.system.server.profileservice.deployers.MainDeployerPlugin.process(MainDeployerPlugin.java:106) [:6.0.0.Final]
at org.jboss.profileservice.dependency.ProfileControllerContext$DelegateDeployer.process(ProfileControllerContext.java:143) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner$HDScanAction.deploy(HDScanner.java:240) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner$HDScanAction.complete(HDScanner.java:192) [:0.2.2]
at org.jboss.profileservice.management.TwoPCActionWrapper.doComplete(TwoPCActionWrapper.java:57) [:0.2.2]
at org.jboss.profileservice.management.actions.AbstractTwoPhaseModificationAction.complete(AbstractTwoPhaseModificationAction.java:74) [:0.2.2]
at org.jboss.profileservice.management.actions.AbstractTwoPhaseModificationAction.prepare(AbstractTwoPhaseModificationAction.java:95) [:0.2.2]
at org.jboss.profileservice.management.ModificationSession.prepare(ModificationSession.java:87) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.internalPerfom(AbstractActionController.java:234) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.performWrite(AbstractActionController.java:213) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.perform(AbstractActionController.java:150) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.perform(AbstractActionController.java:135) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner.scan(HDScanner.java:146) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner.run(HDScanner.java:90) [:0.2.2]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [:1.6.0_22]
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317) [:1.6.0_22]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]
10:29:46,230 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:46,230 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:46,257 INFO [org.torquebox.messaging.core.ManagedQueue] Destroying queue: /queues/torquebox/kitchen-sink.trq/backgroundable
10:29:46,257 WARN [org.jboss.kernel.plugins.dependency.CreateDestroyLifecycleAction] Error during destroy for torquebox.kitchen-sink.trq.ManagedQueue./queues/torquebox/kitchen-sink.trq/backgroundable: HornetQException[errorCode=104 message=Cannot delete queue jms.queue./queues/torquebox/kitchen-sink.trq/backgroundable on binding jms.queue./queues/torquebox/kitchen-sink.trq/backgroundable - it has consumers = org.hornetq.core.postoffice.impl.LocalQueueBinding]
at org.hornetq.core.server.impl.HornetQServerImpl.destroyQueue(HornetQServerImpl.java:713) [:6.0.0.Final]
at org.hornetq.jms.server.impl.JMSServerManagerImpl.destroyQueue(JMSServerManagerImpl.java:633) [:6.0.0.Final]
at org.torquebox.messaging.core.ManagedQueue.destroy(ManagedQueue.java:35) [:1.0.0.CR1-SNAPSHOT]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]
at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]
at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:60) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:168) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:257) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:125) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.LifecycleAction.uninstallActionInternal(LifecycleAction.java:286) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:175) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:42) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleUninstallAction(SimpleControllerContextAction.java:79) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.AccessControllerContextAction.uninstall(AccessControllerContextAction.java:131) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContextActions.uninstall(AbstractControllerContextActions.java:58) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1472) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:756) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:669) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:230) [:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:58) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalUndeploy(AbstractSimpleRealDeployer.java:69) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.undeploy(AbstractRealDeployer.java:117) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployerWrapper.undeploy(DeployerWrapper.java:204) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUndeploy(DeployersImpl.java:1862) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1769) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1762) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.uninstall(DeployersImpl.java:1724) [:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:948) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:654) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1983) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1968) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:1001) [:2.2.0.GA]
at org.jboss.deployers.plugins.main.MainDeployerImpl.undeploy(MainDeployerImpl.java:578) [:2.2.0.GA]
at org.torquebox.mc.vdf.PojoDeployment.stop(PojoDeployment.java:71) [:1.0.0.CR1-SNAPSHOT]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]
at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]
at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:60) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:168) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:257) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:125) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.LifecycleAction.uninstallActionInternal(LifecycleAction.java:286) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:175) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:42) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleUninstallAction(SimpleControllerContextAction.java:79) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.AccessControllerContextAction.uninstall(AccessControllerContextAction.java:131) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContextActions.uninstall(AbstractControllerContextActions.java:58) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1472) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:756) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:669) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:230) [:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:58) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalUndeploy(AbstractSimpleRealDeployer.java:69) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.undeploy(AbstractRealDeployer.java:117) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployerWrapper.undeploy(DeployerWrapper.java:204) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUndeploy(DeployersImpl.java:1862) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1769) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1762) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.uninstall(DeployersImpl.java:1724) [:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:948) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:654) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1983) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1968) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:1001) [:2.2.0.GA]
at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:656) [:2.2.0.GA]
at org.jboss.system.server.profileservice.deployers.MainDeployerPlugin.process(MainDeployerPlugin.java:106) [:6.0.0.Final]
at org.jboss.profileservice.dependency.ProfileControllerContext$DelegateDeployer.process(ProfileControllerContext.java:143) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner$HDScanAction.deploy(HDScanner.java:240) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner$HDScanAction.complete(HDScanner.java:192) [:0.2.2]
at org.jboss.profileservice.management.TwoPCActionWrapper.doComplete(TwoPCActionWrapper.java:57) [:0.2.2]
at org.jboss.profileservice.management.actions.AbstractTwoPhaseModificationAction.complete(AbstractTwoPhaseModificationAction.java:74) [:0.2.2]
at org.jboss.profileservice.management.actions.AbstractTwoPhaseModificationAction.prepare(AbstractTwoPhaseModificationAction.java:95) [:0.2.2]
at org.jboss.profileservice.management.ModificationSession.prepare(ModificationSession.java:87) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.internalPerfom(AbstractActionController.java:234) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.performWrite(AbstractActionController.java:213) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.perform(AbstractActionController.java:150) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.perform(AbstractActionController.java:135) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner.scan(HDScanner.java:146) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner.run(HDScanner.java:90) [:0.2.2]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [:1.6.0_22]
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317) [:1.6.0_22]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]
10:29:46,267 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,267 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,268 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,268 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:29:46,273 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:46,273 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:29:46,279 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:46,280 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:46,281 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:46,282 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQQueue[/queues/a-kitchen-sink-queue]
10:29:46,317 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:46,317 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:29:46,322 INFO [org.torquebox.messaging.core.ManagedQueue] Destroying queue: /queues/a-kitchen-sink-queue
10:29:46,330 INFO [org.torquebox.messaging.core.ManagedTopic] Destroying topic /topics/a-kitchen-sink-topic
10:30:21,413 INFO [org.torquebox.mc.vdf.PojoDeployment] Deploying: kitchen-sink -- "/Users/tobias/work/test-apps/kitchen-sink"
10:30:21,413 INFO [org.torquebox.rails.deployers.RailsApplicationRecognizer] Recognized rails application: AbstractVFSDeploymentContext@310631310{kitchen-sink}
10:30:21,414 INFO [org.torquebox.rails.deployers.RailsApplicationRecognizer] Initializing rails application: AbstractVFSDeploymentContext@310631310{kitchen-sink}
10:30:21,414 INFO [org.torquebox.rack.deployers.DefaultRackApplicationRecognizer] Recognized rack application: AbstractVFSDeploymentContext@310631310{kitchen-sink}
10:30:21,414 INFO [org.torquebox.rack.deployers.DefaultRackApplicationRecognizer] Initializing rack application: AbstractVFSDeploymentContext@310631310{kitchen-sink}
10:30:21,414 INFO [org.torquebox.base.deployers.TorqueBoxYamlParsingDeployer] Parsing: AbstractVFSDeploymentContext@310631310{kitchen-sink}
10:30:21,416 INFO [org.torquebox.base.deployers.TorqueBoxYamlParsingDeployer] Parsed: [TorqueBoxMetaData: data={web={context=/kitchen-sink}, queues={/queues/a-kitchen-sink-queue=null}, topics={/topics/a-kitchen-sink-topic=null}, messaging={/queues/a-kitchen-sink-queue=QueueProcessor, /topics/a-kitchen-sink-topic=[OneTopicProcessor, AnotherTopicProcessor]}, services={AService=null}, jobs={message.producer={job=MessageProducer, cron=0/15 * * * * ?}}}]
10:30:21,441 ERROR [STDERR] parsing: {/queues/a-kitchen-sink-queue=QueueProcessor, /topics/a-kitchen-sink-topic=[OneTopicProcessor, AnotherTopicProcessor]} // class java.util.LinkedHashMap
10:30:21,442 INFO [org.torquebox.messaging.deployers.TasksScanningDeployer] deploying message_producer_task.rb
10:30:21,442 ERROR [STDERR] deploy with: {/topics/a-kitchen-sink-topic=null}
10:30:21,442 INFO [org.torquebox.messaging.deployers.TopicsYamlParsingDeployer] Read configuration for topic [/topics/a-kitchen-sink-topic]
10:30:21,442 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] Rails Root = "/Users/tobias/work/test-apps/kitchen-sink"
10:30:21,442 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] config/environment.rb = "/Users/tobias/work/test-apps/kitchen-sink/config/environment.rb"
10:30:21,443 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] Gemfile = "/Users/tobias/work/test-apps/kitchen-sink/Gemfile"
10:30:21,447 INFO [org.torquebox.rails.deployers.RailsRackDeployer] [RackApplicationMetaData:1987040887
rackupScriptLocation=config.ru
rackUpScript=null
host=[]
context=/kitchen-sink
static=null]
10:30:21,447 INFO [org.torquebox.rails.deployers.RailsRackDeployer] [RailsApplicationMetaData:
version=3.0.5]
10:30:21,447 INFO [org.torquebox.rack.deployers.RackApplicationFactoryDeployer] factory rackup: # This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
10:30:21,447 WARN [org.torquebox.rack.deployers.RackRuntimeDeployer] Ruby runtime already initialized
10:30:21,449 INFO [org.torquebox.jobs.deployers.JobsRuntimePoolDeployer] Configured Ruby runtime pool for jobs: [PoolMetaData: name=jobs min=1 max=2]
10:30:21,449 INFO [org.torquebox.messaging.deployers.TasksDeployer] Setting up queue and message processor for /queues/torquebox/kitchen-sink.trq/backgroundable with a concurrency of 1
10:30:21,450 INFO [org.torquebox.messaging.deployers.TasksDeployer] Setting up queue and message processor for /queues/torquebox/kitchen-sink.trq/tasks/message_producer with a concurrency of 1
10:30:21,450 INFO [org.torquebox.messaging.deployers.MessagingRuntimePoolDeployer] Configured Ruby runtime pool for messaging: [PoolMetaData: name=messaging min=1 max=2]
10:30:21,453 INFO [org.torquebox.interp.deployers.RubyRuntimeFactoryDeployer] Using initializer: org.torquebox.rails.core.RailsRuntimeInitializer@33e3631a
10:30:21,457 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] OneTopicProcessor alwaysReload=true
10:30:21,458 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] AnotherTopicProcessor alwaysReload=true
10:30:21,458 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] QueueProcessor alwaysReload=true
10:30:21,458 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] TorqueBox::Messaging::BackgroundableProcessor alwaysReload=true
10:30:21,459 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] MessageProducerTask alwaysReload=true
10:30:21,474 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=web type=SHARED]
10:30:21,475 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=messaging min=1 max=2]
10:30:21,475 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=services type=SHARED]
10:30:21,475 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=jobs min=1 max=2]
10:30:21,536 INFO [STDOUT] CONTEXT: /kitchen-sink
10:30:21,537 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:30:21,537 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:1987040887
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:30:22,277 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:30:22,277 INFO [STDOUT] RAILS_ENV = development
10:30:25,511 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:30:25,512 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:30:29,505 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:30:29,505 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:30:29,505 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:30:29,506 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:30:29,507 INFO [STDOUT] Loaded VFS
10:30:29,580 INFO [org.torquebox.rack.core.WebHost] Host already registered: localhost
10:30:29,581 INFO [org.torquebox.messaging.core.ManagedTopic] Starting topic /topics/a-kitchen-sink-topic
10:30:29,673 INFO [STDOUT] CONTEXT: /kitchen-sink
10:30:29,674 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:30:29,674 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:1987040887
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:30:30,475 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:30:30,475 INFO [STDOUT] RAILS_ENV = development
10:30:39,972 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:30:39,972 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:30:42,948 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:30:42,948 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:30:42,949 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:30:42,949 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:30:42,950 INFO [STDOUT] Loaded VFS
10:30:42,961 INFO [STDOUT] AService STARTING
10:30:42,962 INFO [STDOUT] AService: calling AModel#create_message
10:30:42,969 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/torquebox/kitchen-sink.trq/tasks/message_producer
10:30:42,988 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQTopic[/topics/a-kitchen-sink-topic]
10:30:42,993 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQTopic[/topics/a-kitchen-sink-topic]
10:30:43,018 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQTopic[/topics/a-kitchen-sink-topic]
10:30:43,021 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQTopic[/topics/a-kitchen-sink-topic]
10:30:43,024 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQTopic[/topics/a-kitchen-sink-topic]
10:30:43,032 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQTopic[/topics/a-kitchen-sink-topic]
10:30:43,035 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:30:43,038 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:30:43,040 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:30:43,041 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/torquebox/kitchen-sink.trq/backgroundable
10:30:43,092 INFO [STDOUT] CONTEXT: /kitchen-sink
10:30:43,093 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:30:43,093 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:1987040887
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:30:43,715 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:30:43,715 INFO [STDOUT] RAILS_ENV = development
10:30:46,278 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:30:46,278 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:30:49,260 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:30:49,260 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:30:49,260 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:30:49,260 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:30:49,262 INFO [STDOUT] Loaded VFS
10:30:49,265 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:30:49,267 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:30:49,268 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:30:49,314 INFO [STDOUT] AModel#create_message - SLEEPING
10:30:49,321 INFO [STDOUT] CONTEXT: /kitchen-sink
10:30:49,322 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:30:49,322 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:1987040887
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:30:49,892 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:30:49,892 INFO [STDOUT] RAILS_ENV = development
10:30:52,137 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:30:52,137 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:30:54,314 INFO [STDOUT] AModel: publishing from AModel#create_message(Thu Mar 03 10:30:54 -0500 2011)
10:30:54,985 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:30:54,985 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:30:54,985 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:30:54,985 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:30:54,986 INFO [STDOUT] Loaded VFS
10:30:55,013 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/a-kitchen-sink-queue
10:30:55,022 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/a-kitchen-sink-queue]
10:30:55,025 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/a-kitchen-sink-queue]
10:30:55,030 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/a-kitchen-sink-queue]
10:30:55,033 INFO [org.torquebox.jobs.core.RubyScheduler] Starting Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:30:55,038 INFO [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
10:30:55,043 INFO [org.quartz.core.QuartzScheduler] Quartz Scheduler v.1.8.3 created.
10:30:55,044 INFO [org.quartz.simpl.RAMJobStore] RAMJobStore initialized.
10:30:55,044 INFO [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v1.8.3) 'RubyScheduler$kitchen-sink.trq' with instanceId 'NON_CLUSTERED'
Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
NOT STARTED.
Currently in standby mode.
Number of jobs executed: 0
Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads.
Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.
10:30:55,044 INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'RubyScheduler$kitchen-sink.trq' initialized from an externally provided properties instance.
10:30:55,044 INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 1.8.3
10:30:55,045 INFO [org.quartz.core.QuartzScheduler] JobFactory set to: org.torquebox.jobs.core.RubyJobFactory@cbae329
10:30:55,045 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED started.
10:30:55,046 INFO [org.torquebox.jobs.core.ScheduledJob] Starting Ruby job: kitchen-sink.message.producer
10:30:55,051 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] deploy, ctxPath=/kitchen-sink
10:30:55,062 WARN [org.jboss.web.tomcat.service.deployers.JBossContextConfig] Failed to setup clustering, clustering disabled. ClusteringNotSupportedException: No DistributedCacheManagerFactory service provider found.
10:30:55,073 ERROR [STDERR] DEPLOY RUBY APP: AbstractVFSDeploymentContext@310631310{kitchen-sink}
10:30:55,074 INFO [org.torquebox.base.deployers.DeploymentNotifier] Fully deployed: kitchen-sink
10:30:55,074 INFO [org.torquebox.mc.vdf.PojoDeployment] Fully deployed: "/Users/tobias/work/test-apps/kitchen-sink"
10:30:55,128 INFO [STDOUT] QueueProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:30:54 -0500 2011)
10:30:55,143 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:30:54 -0500 2011):QueueProcessor(Thu Mar 03 10:30:55 -0500 2011)
10:30:55,147 INFO [STDOUT] OneTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:30:54 -0500 2011):QueueProcessor(Thu Mar 03 10:30:55 -0500 2011)
10:30:55,168 INFO [STDOUT] CONTEXT: /kitchen-sink
10:30:55,168 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:30:55,169 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:1987040887
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:30:55,754 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:30:55,754 INFO [STDOUT] RAILS_ENV = development
10:30:58,053 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:30:58,054 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:30:58,148 INFO [STDOUT] AService: calling AModel#create_message
10:30:58,172 INFO [STDOUT] AModel#create_message - SLEEPING
10:31:00,039 INFO [STDOUT] MessageProducer: publishing from MessageProducer(Thu Mar 03 10:31:00 -0500 2011)
10:31:00,088 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] undeploy, ctxPath=/kitchen-sink
10:31:00,092 INFO [org.torquebox.jobs.core.ScheduledJob] Stopping Ruby job: kitchen-sink.message.producer
10:31:00,093 INFO [org.torquebox.jobs.core.RubyScheduler] Stopping Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:31:00,094 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED shutting down.
10:31:00,094 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED paused.
10:31:00,095 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED shutdown complete.
10:31:00,095 INFO [org.torquebox.jobs.core.RubyScheduler] Stopped Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:31:00,099 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:00,099 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:01,074 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:31:01,075 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:31:01,075 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:31:01,076 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:31:01,077 INFO [STDOUT] Loaded VFS
10:31:01,088 INFO [STDOUT] QueueProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:31:00 -0500 2011)
10:31:01,107 INFO [org.torquebox.messaging.core.ManagedQueue] Destroying queue: /queues/a-kitchen-sink-queue
10:31:01,107 WARN [org.jboss.kernel.plugins.dependency.CreateDestroyLifecycleAction] Error during destroy for torquebox.kitchen-sink.trq.ManagedQueue./queues/a-kitchen-sink-queue: HornetQException[errorCode=104 message=Cannot delete queue jms.queue./queues/a-kitchen-sink-queue on binding jms.queue./queues/a-kitchen-sink-queue - it has consumers = org.hornetq.core.postoffice.impl.LocalQueueBinding]
at org.hornetq.core.server.impl.HornetQServerImpl.destroyQueue(HornetQServerImpl.java:713) [:6.0.0.Final]
at org.hornetq.jms.server.impl.JMSServerManagerImpl.destroyQueue(JMSServerManagerImpl.java:633) [:6.0.0.Final]
at org.torquebox.messaging.core.ManagedQueue.destroy(ManagedQueue.java:35) [:1.0.0.CR1-SNAPSHOT]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]
at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]
at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:60) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:168) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:257) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:125) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.LifecycleAction.uninstallActionInternal(LifecycleAction.java:286) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:175) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:42) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleUninstallAction(SimpleControllerContextAction.java:79) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.AccessControllerContextAction.uninstall(AccessControllerContextAction.java:131) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContextActions.uninstall(AbstractControllerContextActions.java:58) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1472) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:756) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:669) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:230) [:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:58) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalUndeploy(AbstractSimpleRealDeployer.java:69) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.undeploy(AbstractRealDeployer.java:117) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployerWrapper.undeploy(DeployerWrapper.java:204) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUndeploy(DeployersImpl.java:1862) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1769) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1762) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.uninstall(DeployersImpl.java:1724) [:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:948) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:654) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1983) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1968) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:1001) [:2.2.0.GA]
at org.jboss.deployers.plugins.main.MainDeployerImpl.undeploy(MainDeployerImpl.java:578) [:2.2.0.GA]
at org.torquebox.mc.vdf.PojoDeployment.stop(PojoDeployment.java:71) [:1.0.0.CR1-SNAPSHOT]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [:1.6.0_22]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [:1.6.0_22]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [:1.6.0_22]
at java.lang.reflect.Method.invoke(Method.java:597) [:1.6.0_22]
at org.jboss.reflect.plugins.introspection.ReflectionUtils.invoke(ReflectionUtils.java:60) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.reflect.plugins.introspection.ReflectMethodInfoImpl.invoke(ReflectMethodInfoImpl.java:168) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.joinpoint.plugins.BasicMethodJoinPoint.dispatch(BasicMethodJoinPoint.java:66) [jboss-reflect.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction$JoinpointDispatchWrapper.execute(KernelControllerContextAction.java:257) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.ExecutionWrapper.execute(ExecutionWrapper.java:47) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchExecutionWrapper(KernelControllerContextAction.java:125) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.KernelControllerContextAction.dispatchJoinPoint(KernelControllerContextAction.java:72) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.LifecycleAction.uninstallActionInternal(LifecycleAction.java:286) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:175) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.kernel.plugins.dependency.InstallsAwareAction.uninstallAction(InstallsAwareAction.java:42) [jboss-kernel.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleUninstallAction(SimpleControllerContextAction.java:79) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.action.AccessControllerContextAction.uninstall(AccessControllerContextAction.java:131) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContextActions.uninstall(AbstractControllerContextActions.java:58) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1472) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:756) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:669) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:230) [:2.2.0.GA]
at org.jboss.deployers.vfs.deployer.kernel.BeanMetaDataDeployer.undeploy(BeanMetaDataDeployer.java:58) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalUndeploy(AbstractSimpleRealDeployer.java:69) [:2.2.0.GA]
at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.undeploy(AbstractRealDeployer.java:117) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployerWrapper.undeploy(DeployerWrapper.java:204) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUndeploy(DeployersImpl.java:1862) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1769) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.doUninstallParentLast(DeployersImpl.java:1762) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.uninstall(DeployersImpl.java:1724) [:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractControllerContext.uninstall(AbstractControllerContext.java:385) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstall(AbstractController.java:2078) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.uninstallContext(AbstractController.java:1624) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:948) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:654) [jboss-dependency.jar:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1983) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.change(DeployersImpl.java:1968) [:2.2.0.GA]
at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:1001) [:2.2.0.GA]
at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:656) [:2.2.0.GA]
at org.jboss.system.server.profileservice.deployers.MainDeployerPlugin.process(MainDeployerPlugin.java:106) [:6.0.0.Final]
at org.jboss.profileservice.dependency.ProfileControllerContext$DelegateDeployer.process(ProfileControllerContext.java:143) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner$HDScanAction.deploy(HDScanner.java:240) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner$HDScanAction.complete(HDScanner.java:192) [:0.2.2]
at org.jboss.profileservice.management.TwoPCActionWrapper.doComplete(TwoPCActionWrapper.java:57) [:0.2.2]
at org.jboss.profileservice.management.actions.AbstractTwoPhaseModificationAction.complete(AbstractTwoPhaseModificationAction.java:74) [:0.2.2]
at org.jboss.profileservice.management.actions.AbstractTwoPhaseModificationAction.prepare(AbstractTwoPhaseModificationAction.java:95) [:0.2.2]
at org.jboss.profileservice.management.ModificationSession.prepare(ModificationSession.java:87) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.internalPerfom(AbstractActionController.java:234) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.performWrite(AbstractActionController.java:213) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.perform(AbstractActionController.java:150) [:0.2.2]
at org.jboss.profileservice.management.AbstractActionController.perform(AbstractActionController.java:135) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner.scan(HDScanner.java:146) [:0.2.2]
at org.jboss.profileservice.deployment.hotdeploy.HDScanner.run(HDScanner.java:90) [:0.2.2]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [:1.6.0_22]
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317) [:1.6.0_22]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180) [:1.6.0_22]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]
10:31:01,126 INFO [STDOUT] OneTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:31:00 -0500 2011):QueueProcessor(Thu Mar 03 10:31:01 -0500 2011)
10:31:01,131 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:01,131 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:01,132 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:31:00 -0500 2011):QueueProcessor(Thu Mar 03 10:31:01 -0500 2011)
10:31:03,172 INFO [STDOUT] AModel: publishing from AModel#create_message(Thu Mar 03 10:31:03 -0500 2011)
10:31:11,132 WARN [org.hornetq.core.client.impl.ClientConsumerImpl] Timed out waiting for handler to complete processing
10:31:11,134 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:11,134 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:13,171 INFO [STDOUT] AService: calling AModel#create_message
10:31:21,134 WARN [org.hornetq.core.client.impl.ClientConsumerImpl] Timed out waiting for handler to complete processing
10:31:21,146 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:21,147 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:21,157 INFO [org.torquebox.messaging.core.ManagedQueue] Destroying queue: /queues/torquebox/kitchen-sink.trq/backgroundable
10:31:21,160 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:21,161 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:21,162 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:21,162 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:21,174 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,174 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,176 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,176 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,193 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,193 INFO [org.torquebox.messaging.core.RubyMessageProcessor] stopping connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,194 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,194 INFO [org.torquebox.messaging.core.RubyMessageProcessor] destroying connection for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:21,208 INFO [org.torquebox.messaging.core.ManagedQueue] Destroying queue: /queues/torquebox/kitchen-sink.trq/tasks/message_producer
10:31:21,211 INFO [STDOUT] AService STOPPING
10:31:21,212 INFO [org.torquebox.messaging.core.ManagedTopic] Destroying topic /topics/a-kitchen-sink-topic
10:31:21,250 INFO [org.torquebox.mc.vdf.PojoDeployment] Deploying: kitchen-sink -- "/Users/tobias/work/test-apps/kitchen-sink"
10:31:21,251 INFO [org.torquebox.rails.deployers.RailsApplicationRecognizer] Recognized rails application: AbstractVFSDeploymentContext@1739528062{kitchen-sink}
10:31:21,251 INFO [org.torquebox.rails.deployers.RailsApplicationRecognizer] Initializing rails application: AbstractVFSDeploymentContext@1739528062{kitchen-sink}
10:31:21,252 INFO [org.torquebox.rack.deployers.DefaultRackApplicationRecognizer] Recognized rack application: AbstractVFSDeploymentContext@1739528062{kitchen-sink}
10:31:21,252 INFO [org.torquebox.rack.deployers.DefaultRackApplicationRecognizer] Initializing rack application: AbstractVFSDeploymentContext@1739528062{kitchen-sink}
10:31:21,252 INFO [org.torquebox.base.deployers.TorqueBoxYamlParsingDeployer] Parsing: AbstractVFSDeploymentContext@1739528062{kitchen-sink}
10:31:21,254 INFO [org.torquebox.base.deployers.TorqueBoxYamlParsingDeployer] Parsed: [TorqueBoxMetaData: data={web={context=/kitchen-sink}, queues={/queues/a-kitchen-sink-queue=null}, topics={/topics/a-kitchen-sink-topic=null}, messaging={/queues/a-kitchen-sink-queue=QueueProcessor, /topics/a-kitchen-sink-topic=[OneTopicProcessor, AnotherTopicProcessor]}, services={AService=null}, jobs={message.producer={job=MessageProducer, cron=0/15 * * * * ?}}}]
10:31:21,264 ERROR [STDERR] parsing: {/queues/a-kitchen-sink-queue=QueueProcessor, /topics/a-kitchen-sink-topic=[OneTopicProcessor, AnotherTopicProcessor]} // class java.util.LinkedHashMap
10:31:21,265 INFO [org.torquebox.messaging.deployers.TasksScanningDeployer] deploying message_producer_task.rb
10:31:21,266 ERROR [STDERR] deploy with: {/topics/a-kitchen-sink-topic=null}
10:31:21,266 INFO [org.torquebox.messaging.deployers.TopicsYamlParsingDeployer] Read configuration for topic [/topics/a-kitchen-sink-topic]
10:31:21,266 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] Rails Root = "/Users/tobias/work/test-apps/kitchen-sink"
10:31:21,266 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] config/environment.rb = "/Users/tobias/work/test-apps/kitchen-sink/config/environment.rb"
10:31:21,266 INFO [org.torquebox.rails.deployers.RailsGemVersionDeployer] Gemfile = "/Users/tobias/work/test-apps/kitchen-sink/Gemfile"
10:31:21,271 INFO [org.torquebox.rails.deployers.RailsRackDeployer] [RackApplicationMetaData:562833637
rackupScriptLocation=config.ru
rackUpScript=null
host=[]
context=/kitchen-sink
static=null]
10:31:21,271 INFO [org.torquebox.rails.deployers.RailsRackDeployer] [RailsApplicationMetaData:
version=3.0.5]
10:31:21,272 INFO [org.torquebox.rack.deployers.RackApplicationFactoryDeployer] factory rackup: # This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
10:31:21,273 WARN [org.torquebox.rack.deployers.RackRuntimeDeployer] Ruby runtime already initialized
10:31:21,274 INFO [org.torquebox.jobs.deployers.JobsRuntimePoolDeployer] Configured Ruby runtime pool for jobs: [PoolMetaData: name=jobs min=1 max=2]
10:31:21,274 INFO [org.torquebox.messaging.deployers.TasksDeployer] Setting up queue and message processor for /queues/torquebox/kitchen-sink.trq/backgroundable with a concurrency of 1
10:31:21,274 INFO [org.torquebox.messaging.deployers.TasksDeployer] Setting up queue and message processor for /queues/torquebox/kitchen-sink.trq/tasks/message_producer with a concurrency of 1
10:31:21,275 INFO [org.torquebox.messaging.deployers.MessagingRuntimePoolDeployer] Configured Ruby runtime pool for messaging: [PoolMetaData: name=messaging min=1 max=2]
10:31:21,277 INFO [org.torquebox.interp.deployers.RubyRuntimeFactoryDeployer] Using initializer: org.torquebox.rails.core.RailsRuntimeInitializer@3ebf4b54
10:31:21,280 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] OneTopicProcessor alwaysReload=true
10:31:21,281 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] TorqueBox::Messaging::BackgroundableProcessor alwaysReload=true
10:31:21,281 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] MessageProducerTask alwaysReload=true
10:31:21,281 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] QueueProcessor alwaysReload=true
10:31:21,281 INFO [org.torquebox.messaging.deployers.MessageProcessorDeployer] AnotherTopicProcessor alwaysReload=true
10:31:21,298 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=jobs min=1 max=2]
10:31:21,298 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=services type=SHARED]
10:31:21,298 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=messaging min=1 max=2]
10:31:21,298 INFO [org.torquebox.interp.deployers.RuntimePoolDeployer] Deploying runtime pool: [PoolMetaData: name=web type=SHARED]
10:31:21,304 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/torquebox/kitchen-sink.trq/backgroundable
10:31:21,384 INFO [STDOUT] CONTEXT: /kitchen-sink
10:31:21,384 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:31:21,384 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:562833637
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:31:22,109 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:31:22,109 INFO [STDOUT] RAILS_ENV = development
10:31:25,383 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:31:25,383 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:31:29,319 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:31:29,320 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:31:29,321 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:31:29,321 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:31:29,323 INFO [STDOUT] Loaded VFS
10:31:29,327 INFO [org.torquebox.rack.core.WebHost] Host already registered: localhost
10:31:29,330 INFO [org.torquebox.messaging.core.ManagedTopic] Starting topic /topics/a-kitchen-sink-topic
10:31:29,463 INFO [STDOUT] CONTEXT: /kitchen-sink
10:31:29,463 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:31:29,463 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:562833637
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:31:30,298 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:31:30,298 INFO [STDOUT] RAILS_ENV = development
10:31:33,281 INFO [STDOUT] javax.naming.NameNotFoundException: a-kitchen-sink-queue not bound
10:31:33,281 INFO [STDOUT] org/jnp/server/NamingServer.java:771:in `getBinding'
10:31:33,281 INFO [STDOUT] org/jnp/server/NamingServer.java:779:in `getBinding'
10:31:33,282 INFO [STDOUT] org/jnp/server/NamingServer.java:785:in `getObject'
10:31:33,282 INFO [STDOUT] org/jnp/server/NamingServer.java:443:in `lookup'
10:31:33,282 INFO [STDOUT] org/jnp/server/NamingServer.java:399:in `lookup'
10:31:33,282 INFO [STDOUT] org/jnp/interfaces/NamingContext.java:728:in `lookup'
10:31:33,282 INFO [STDOUT] org/jnp/interfaces/NamingContext.java:688:in `lookup'
10:31:33,282 INFO [STDOUT] javax/naming/InitialContext.java:392:in `lookup'
10:31:33,282 INFO [STDOUT] :1:in `[]'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/ext/javax_jms_session.rb:111:in `lookup_destination'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/ext/javax_jms_session.rb:26:in `publish'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/destination.rb:46:in `publish'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/client.rb:65:in `call'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/client.rb:65:in `connect'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-naming-1.0.0.CR1-java/lib/torquebox/naming.rb:83:in `call'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-naming-1.0.0.CR1-java/lib/torquebox/naming.rb:83:in `connect'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-naming-1.0.0.CR1-java/lib/torquebox/naming.rb:94:in `reconfigure_on_error'
10:31:33,283 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-naming-1.0.0.CR1-java/lib/torquebox/naming.rb:80:in `connect'
10:31:33,284 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/client.rb:56:in `connect'
10:31:33,284 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/destination.rb:45:in `publish'
10:31:33,284 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/destination.rb:123:in `call'
10:31:33,284 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/destination.rb:123:in `wait_for_destination'
10:31:33,284 INFO [STDOUT] /Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8/gems/torquebox-messaging-1.0.0.CR1-java/lib/torquebox/messaging/destination.rb:44:in `publish'
10:31:33,284 INFO [STDOUT] vfs:/Users/tobias/work/test-apps/kitchen-sink/app/models/a_model.rb:9:in `create_message'
10:31:33,284 INFO [STDOUT] classpath:/torquebox/messaging/backgroundable_processor.rb:8:in `on_message'
10:31:33,284 INFO [STDOUT] classpath:/torquebox/messaging/message_processor.rb:25:in `process!'
10:31:33,285 INFO [STDOUT] <script>:1
10:31:33,285 ERROR [org.torquebox.messaging.core.RubyMessageProcessor] unable to dispatch: javax.jms.IllegalStateException: Session is closed
at org.hornetq.jms.client.HornetQSession.checkClosed(HornetQSession.java:1002) [:6.0.0.Final]
at org.hornetq.jms.client.HornetQSession.getTransacted(HornetQSession.java:205) [:6.0.0.Final]
at org.torquebox.messaging.core.RubyMessageProcessor$Handler.onMessage(RubyMessageProcessor.java:195) [:1.0.0.CR1-SNAPSHOT]
at org.hornetq.jms.client.JMSMessageListenerWrapper.onMessage(JMSMessageListenerWrapper.java:91) [:6.0.0.Final]
at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:822) [:6.0.0.Final]
at org.hornetq.core.client.impl.ClientConsumerImpl.access$100(ClientConsumerImpl.java:46) [:6.0.0.Final]
at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:940) [:6.0.0.Final]
at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:100) [:6.0.0.Final]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]
10:31:33,286 WARN [org.hornetq.jms.client.JMSMessageListenerWrapper] Unhandled exception thrown from onMessage: java.lang.NullPointerException
at org.torquebox.messaging.core.RubyMessageProcessor$Handler.onMessage(RubyMessageProcessor.java:206) [:1.0.0.CR1-SNAPSHOT]
at org.hornetq.jms.client.JMSMessageListenerWrapper.onMessage(JMSMessageListenerWrapper.java:91) [:6.0.0.Final]
at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:822) [:6.0.0.Final]
at org.hornetq.core.client.impl.ClientConsumerImpl.access$100(ClientConsumerImpl.java:46) [:6.0.0.Final]
at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:940) [:6.0.0.Final]
at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:100) [:6.0.0.Final]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_22]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_22]
at java.lang.Thread.run(Thread.java:680) [:1.6.0_22]
10:31:39,934 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:31:39,935 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:31:43,446 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:31:43,446 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:31:43,446 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:31:43,447 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:31:43,448 INFO [STDOUT] Loaded VFS
10:31:43,457 INFO [STDOUT] AService STARTING
10:31:43,458 INFO [STDOUT] AService: calling AModel#create_message
10:31:43,465 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/torquebox/kitchen-sink.trq/tasks/message_producer
10:31:43,493 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:43,498 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:43,504 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/torquebox/kitchen-sink.trq/tasks/message_producer]
10:31:43,509 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:43,519 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:43,540 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:43,544 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:43,547 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:43,548 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/torquebox/kitchen-sink.trq/backgroundable]
10:31:43,598 INFO [STDOUT] AModel#create_message - SLEEPING
10:31:43,607 INFO [STDOUT] CONTEXT: /kitchen-sink
10:31:43,607 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:31:43,607 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:562833637
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:31:44,314 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:31:44,314 INFO [STDOUT] RAILS_ENV = development
10:31:48,598 INFO [STDOUT] AModel: publishing from AModel#create_message(Thu Mar 03 10:31:48 -0500 2011)
10:31:49,039 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:31:49,039 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:31:51,952 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:31:51,952 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:31:51,952 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:31:51,953 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:31:51,954 INFO [STDOUT] Loaded VFS
10:31:52,000 INFO [org.torquebox.jobs.core.RubyScheduler] Starting Ruby job scheduler: RubyScheduler$kitchen-sink.trq
10:31:52,005 INFO [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
10:31:52,006 INFO [org.quartz.core.QuartzScheduler] Quartz Scheduler v.1.8.3 created.
10:31:52,006 INFO [org.quartz.simpl.RAMJobStore] RAMJobStore initialized.
10:31:52,006 INFO [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v1.8.3) 'RubyScheduler$kitchen-sink.trq' with instanceId 'NON_CLUSTERED'
Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
NOT STARTED.
Currently in standby mode.
Number of jobs executed: 0
Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads.
Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.
10:31:52,010 INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'RubyScheduler$kitchen-sink.trq' initialized from an externally provided properties instance.
10:31:52,010 INFO [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 1.8.3
10:31:52,011 INFO [org.quartz.core.QuartzScheduler] JobFactory set to: org.torquebox.jobs.core.RubyJobFactory@56825135
10:31:52,011 INFO [org.quartz.core.QuartzScheduler] Scheduler RubyScheduler$kitchen-sink.trq_$_NON_CLUSTERED started.
10:31:52,013 INFO [org.torquebox.jobs.core.ScheduledJob] Starting Ruby job: kitchen-sink.message.producer
10:31:52,014 INFO [org.torquebox.messaging.core.ManagedQueue] Starting queue: /queues/a-kitchen-sink-queue
10:31:52,067 INFO [STDOUT] CONTEXT: /kitchen-sink
10:31:52,067 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:31:52,067 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:562833637
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:31:52,672 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:31:52,672 INFO [STDOUT] RAILS_ENV = development
10:31:55,480 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:31:55,481 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:31:58,563 INFO [STDOUT] AService: calling AModel#create_message
10:31:58,605 INFO [STDOUT] AModel#create_message - SLEEPING
10:31:59,083 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:31:59,084 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:31:59,084 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:31:59,085 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:31:59,086 INFO [STDOUT] Loaded VFS
10:31:59,089 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:59,092 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:59,094 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQQueue[/queues/a-kitchen-sink-queue]
10:31:59,129 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:59,131 INFO [org.torquebox.messaging.core.RubyMessageProcessor] creating session handler for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:59,220 INFO [STDOUT] CONTEXT: /kitchen-sink
10:31:59,220 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Current directory: /Users/tobias/work/test-apps/kitchen-sink
10:31:59,221 INFO [org.torquebox.rack.core.RackRuntimeInitializer] Initialize with:
[RubyApplicationMetaData:
root="/Users/tobias/work/test-apps/kitchen-sink"
environmentName=development
archive=false
environment=null]
[RackApplicationMetaData:562833637
rackupScriptLocation=config.ru
rackUpScript=# This file is used by Rack-based servers to start the application.
require ::File.expand_path('../config/environment', __FILE__)
run KitchenSink::Application
host=[localhost]
context=/kitchen-sink
static=public]
10:31:59,249 INFO [org.torquebox.messaging.core.RubyMessageProcessor] starting for HornetQTopic[/topics/a-kitchen-sink-topic]
10:31:59,253 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] deploy, ctxPath=/kitchen-sink
10:31:59,365 WARN [org.jboss.web.tomcat.service.deployers.JBossContextConfig] Failed to setup clustering, clustering disabled. ClusteringNotSupportedException: No DistributedCacheManagerFactory service provider found.
10:31:59,377 ERROR [STDERR] DEPLOY RUBY APP: AbstractVFSDeploymentContext@1739528062{kitchen-sink}
10:31:59,390 INFO [org.torquebox.base.deployers.DeploymentNotifier] Fully deployed: kitchen-sink
10:31:59,391 INFO [org.torquebox.mc.vdf.PojoDeployment] Fully deployed: "/Users/tobias/work/test-apps/kitchen-sink"
10:31:59,977 INFO [STDOUT] RAILS_ROOT = vfs:/Users/tobias/work/test-apps/kitchen-sink
10:31:59,978 INFO [STDOUT] RAILS_ENV = development
10:32:00,050 INFO [STDOUT] MessageProducer: publishing from MessageProducer(Thu Mar 03 10:32:00 -0500 2011)
10:32:04,188 INFO [STDOUT] AModel: publishing from AModel#create_message(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,222 INFO [STDOUT] QueueProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:31:48 -0500 2011)
10:32:04,290 INFO [STDOUT] QueueProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:32:00 -0500 2011)
10:32:04,320 INFO [STDOUT] QueueProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,354 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:31:48 -0500 2011):QueueProcessor(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,357 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:32:00 -0500 2011):QueueProcessor(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,359 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:32:04 -0500 2011):QueueProcessor(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,362 INFO [STDOUT] OneTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:31:48 -0500 2011):QueueProcessor(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,364 INFO [STDOUT] OneTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:32:00 -0500 2011):QueueProcessor(Thu Mar 03 10:32:04 -0500 2011)
10:32:04,366 INFO [STDOUT] OneTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:32:04 -0500 2011):QueueProcessor(Thu Mar 03 10:32:04 -0500 2011)
10:32:06,290 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ROOT
10:32:06,290 ERROR [STDERR] (eval):1 warning: already initialized constant RAILS_ENV
10:32:09,011 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] TorqueBox...1.0.0.CR1-SNAPSHOT
10:32:09,012 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JBossAS.....6.0.0.Final
10:32:09,012 INFO [org.torquebox.interp.core.RubyRuntimeFactoryImpl] JRuby.......1.5.6
10:32:09,012 INFO [STDOUT] ["/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8", "/Users/tobias/.gem/jruby/1.8", "vfs:/Users/tobias/work/torquebox-current/jruby/lib/ruby/gems/1.8"]
10:32:09,013 INFO [STDOUT] Loaded VFS
10:32:13,624 INFO [STDOUT] AService: calling AModel#create_message
10:32:13,660 INFO [STDOUT] AModel#create_message - SLEEPING
10:32:15,000 INFO [STDOUT] MessageProducer: publishing from MessageProducer(Thu Mar 03 10:32:15 -0500 2011)
10:32:15,020 INFO [STDOUT] QueueProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:32:15 -0500 2011)
10:32:15,061 INFO [STDOUT] OneTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:32:15 -0500 2011):QueueProcessor(Thu Mar 03 10:32:15 -0500 2011)
10:32:15,063 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from MessageProducer(Thu Mar 03 10:32:15 -0500 2011):QueueProcessor(Thu Mar 03 10:32:15 -0500 2011)
10:32:18,661 INFO [STDOUT] AModel: publishing from AModel#create_message(Thu Mar 03 10:32:18 -0500 2011)
10:32:18,671 INFO [STDOUT] QueueProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:32:18 -0500 2011)
10:32:18,684 INFO [STDOUT] OneTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:32:18 -0500 2011):QueueProcessor(Thu Mar 03 10:32:18 -0500 2011)
10:32:18,692 INFO [STDOUT] AnotherTopicProcessor: PROCESSING from AModel#create_message(Thu Mar 03 10:32:18 -0500 2011):QueueProcessor(Thu Mar 03 10:32:18 -0500 2011)
10:32:28,634 INFO [STDOUT] AService: calling AModel#create_message
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment