OpenADR2.0 VTN и VEN конфигурация EPRI
У меня возникли проблемы с подключением к серверу крутящего момента...
Я установил сервер, используя / называемый Torquebox (Ruby/Rails), следуя инструкциям на веб-сайте EPRI для их реализации VTN с открытым исходным кодом. Как видно из файла журнала ниже, при перезапуске службы возникает не так много ошибок. Когда я перебираю пользовательский интерфейс для создания событий или обновления настроек, сервер в конечном итоге падает с кодом http 500 и текстом "Извините, но что-то пошло не так". Я не могу найти много полезной информации отладки.
В конечном итоге я пытаюсь использовать VTN для отправки уведомлений о событиях в VEN, и я не могу правильно получить информацию о событии.
Ссылка для VTN(сервер): https://sourceforge.net/projects/openadr2vtn/ Ссылка для VEN(клиент): https://sourceforge.net/projects/openadr2bven-pull/
ОС: Ubuntu 14.04
Пример файла журнала из Torquebox:
nick@nickuntu:~$ sudo restart torquebox; tail -f /var/log/torquebox/torquebox.log
[sudo] password for nick:
torquebox start/running, process 13122
12:07:19,201 ERROR [stderr] (pool-1-thread-1) file:/opt/torquebox/current/jruby/lib/jruby.jar!/jruby/java/core_ext/object.rb:73 warning: already initialized constant Interval
12:07:19,224 ERROR [stderr] (pool-1-thread-6) file:/opt/torquebox/current/jruby/lib/jruby.jar!/jruby/java/core_ext/object.rb:73 warning: already initialized constant Interval
12:07:29,417 INFO [org.torquebox.core.runtime] (pool-1-thread-1) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: services) in 64.24s
12:07:29,432 INFO [org.torquebox.core.runtime] (pool-1-thread-6) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: web) in 64.24s
12:07:29,460 INFO [org.jboss.web] (ServerService Thread Pool -- 49) JBAS018210: Register web context:
12:07:29,749 INFO [org.jboss.as.server] (ServerService Thread Pool -- 24) JBAS018559: Deployed "epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml" (runtime-name : "epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml")
12:07:29,839 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management
12:07:29,840 INFO [org.jboss.as] (Controller Boot Thread) JBAS015954: Admin console is not enabled
12:07:29,841 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.2.x.slim.incremental.9 "Janus" started in 76607ms - Started 147 of 229 services (81 services are passive or on-demand)
12:22:05,806 INFO [org.apache.coyote.http11] (MSC service thread 1-1) JBWEB003075: Coyote HTTP/1.1 pausing on: http-/0.0.0.0:8080
12:22:05,808 INFO [org.apache.coyote.http11] (MSC service thread 1-1) JBWEB003077: Coyote HTTP/1.1 stopping on : http-/0.0.0.0:8080
12:22:05,835 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) Scheduler jboss.deployment.unit."epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml".job_scheduler_$_NON_CLUSTERED shutting down.
12:22:05,840 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) Scheduler jboss.deployment.unit."epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml".job_scheduler_$_NON_CLUSTERED paused.
12:22:05,842 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-4) JBAS010282: Stopped sessions cache from polyglot container
12:22:05,850 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-1) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA]
12:22:05,849 INFO [org.jboss.web] (ServerService Thread Pool -- 52) JBAS018224: Unregister web context:
12:22:05,887 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 50) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
12:22:05,910 INFO [org.jboss.as.clustering.infinispan] (MSC service thread 1-4) JBAS010282: Stopped __recoveryInfoCacheName__ cache from polyglot container
=========================================================================
JBoss Bootstrap Environment
JBOSS_HOME: /opt/torquebox/current/jboss
JAVA: java
JAVA_OPTS: -server -XX:+UseCompressedOops -Xms64m -Xmx512m -XX:MaxPermSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true
=========================================================================
12:22:05,987 INFO [org.hornetq.ra] (MSC service thread 1-2) HQ151005: HornetQ resource adaptor stopped
12:22:05,995 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (MSC service thread 1-5) Stopped runtime pool messaging
12:22:06,260 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) Scheduler jboss.deployment.unit."epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml".job_scheduler_$_NON_CLUSTERED shutdown complete.
12:22:06,262 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (MSC service thread 1-8) Stopped runtime pool jobs
12:22:06,536 INFO [org.torquebox.core.runtime] (MSC service thread 1-7) Destroyed ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: services)
12:22:06,536 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (MSC service thread 1-7) Stopped runtime pool services
12:22:06,842 INFO [org.jboss.modules] (main) JBoss Modules version 1.2.0.CR1
12:22:07,239 INFO [org.jboss.msc] (main) JBoss MSC version 1.0.4.GA
12:22:07,309 INFO [org.torquebox.core.runtime] (MSC service thread 1-3) Destroyed ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: web)
12:22:07,310 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (MSC service thread 1-3) Stopped runtime pool web
12:22:07,315 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 51) JBAS011605: Unbound messaging object to jndi name java:/ConnectionFactory
12:22:07,335 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015877: Stopped deployment epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml (runtime-name: epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml) in 1559ms
12:22:07,339 WARN [org.hornetq.core.client] (Thread-0 (HornetQ-client-global-threads-2124886728)) HQ212107: Connection failure has been detected: HQ119035: The connection was disconnected because of server shutdown [code=DISCONNECTED]
12:22:07,359 INFO [org.jboss.as] (MSC service thread 1-6) JBAS015899: JBoss AS 7.2.x.slim.incremental.9 "Janus" starting
12:22:07,405 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221004: HornetQ Server version 2.3.0.CR1 (buzzzzz!, 122) [486ae0af-5c37-11e7-aa2b-a9e594e93ebc] stopped
12:22:07,413 INFO [org.jboss.as] (MSC service thread 1-8) JBAS015950: JBoss AS 7.2.x.slim.incremental.9 "Janus" stopped in 1624ms
12:22:08,033 INFO [org.torquebox.bootstrap] (ServerService Thread Pool -- 15) Bootstrapping TorqueBox
12:22:08,035 INFO [org.torquebox.bootstrap] (ServerService Thread Pool -- 15) Bootstrapping TorqueBox with a JRuby home of /opt/torquebox/current/jruby
12:22:08,389 INFO [org.torquebox.services.as] (ServerService Thread Pool -- 18) Initializing TorqueBox Services Subsystem
12:22:08,389 INFO [org.torquebox.jobs.as] (ServerService Thread Pool -- 5) Initializing TorqueBox Jobs Subsystem
12:22:08,393 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) Initializing TorqueBox Core Subsystem
12:22:08,404 INFO [org.projectodd.polyglot.hasingleton.as] (ServerService Thread Pool -- 5) Initializing HA-Singleton Subsystem
12:22:08,396 INFO [org.torquebox.security.as] (ServerService Thread Pool -- 3) Initializing TorqueBox Auth Subsystem
12:22:08,410 INFO [org.projectodd.polyglot.cache.as] (ServerService Thread Pool -- 18) Initializing Polyglot Cache Subsystem
12:22:08,405 INFO [org.torquebox.messaging.as] (ServerService Thread Pool -- 9) Initializing TorqueBox Messaging Subsystem
12:22:08,425 INFO [org.torquebox.stomp.as] (ServerService Thread Pool -- 19) Initializing TorqueBox STOMP Subsystem
12:22:08,425 INFO [org.projectodd.polyglot.stomp.as] (ServerService Thread Pool -- 10) Initializing Polyglot STOMP Subsystem
12:22:08,442 INFO [org.torquebox.web.as] (ServerService Thread Pool -- 16) Initializing TorqueBox Web Subsystem
12:22:08,490 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) Welcome to TorqueBox AS - http://torquebox.org/
12:22:08,491 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) version........... 3.0.0
12:22:08,492 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) build............. 75
12:22:08,493 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) revision.......... 764580a7386acfecbb505600b886891497621bf6
12:22:08,499 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) built with:
12:22:08,504 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) HornetQ......... 2.3.0.CR1 (buzzzzz!, 122)
12:22:08,523 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) JBossAS......... 7.2.x.slim.incremental.9
12:22:08,524 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) Infinispan...... 5.3.0.Final
12:22:08,525 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) Quartz.......... 2.1.5
12:22:08,525 INFO [org.torquebox.core.as] (ServerService Thread Pool -- 1) JRuby........... 1.7.4
12:22:08,766 WARN [org.jboss.as.server] (Controller Boot Thread) JBAS015883: No security realm defined for native management service; all access will be unrestricted.
12:22:08,777 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS015888: Creating http management service using socket-binding (management-http)
12:22:08,778 INFO [org.xnio] (MSC service thread 1-6) XNIO Version 3.0.7.GA
12:22:08,778 WARN [org.jboss.as.server] (Controller Boot Thread) JBAS015884: No security realm defined for http management service; all access will be unrestricted.
12:22:08,786 INFO [org.xnio.nio] (MSC service thread 1-6) XNIO NIO Implementation Version 3.0.7.GA
12:22:08,797 INFO [org.jboss.remoting] (MSC service thread 1-6) JBoss Remoting version 3.2.14.GA
12:22:08,859 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 26) JBAS010280: Activating Infinispan subsystem.
12:22:08,881 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 30) JBAS011800: Activating Naming Subsystem
12:22:08,893 INFO [org.jboss.as.remoting] (MSC service thread 1-6) JBAS017100: Listening on 127.0.0.1:9999
12:22:08,901 INFO [org.jboss.as.security] (ServerService Thread Pool -- 33) JBAS013171: Activating Security Subsystem
12:22:08,912 INFO [org.jboss.as.connector.logging] (MSC service thread 1-5) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.15.Final)
12:22:08,936 INFO [org.jboss.as.security] (MSC service thread 1-6) JBAS013170: Current PicketBox version=4.0.15.Final
12:22:09,002 INFO [org.jboss.as.naming] (MSC service thread 1-7) JBAS011802: Starting Naming Service
12:22:09,283 INFO [org.apache.coyote.http11] (MSC service thread 1-7) JBWEB003001: Coyote HTTP/1.1 initializing on : http-/0.0.0.0:8080
12:22:09,296 WARN [org.jboss.as.messaging] (MSC service thread 1-6) JBAS011600: AIO wasn't located on this platform, it will fall back to using pure Java NIO. If your platform is Linux, install LibAIO to enable the AIO journal
12:22:09,309 INFO [org.torquebox.core.runtime] (pool-1-thread-1) Creating ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, context: global)
12:22:09,318 INFO [org.apache.coyote.http11] (MSC service thread 1-7) JBWEB003000: Coyote HTTP/1.1 starting on: http-/0.0.0.0:8080
12:22:09,410 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221001: live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/opt/torquebox/current/jboss/standalone/data/messagingjournal,bindingsDirectory=/opt/torquebox/current/jboss/standalone/data/messagingbindings,largeMessagesDirectory=/opt/torquebox/current/jboss/standalone/data/messaginglargemessages,pagingDirectory=/opt/torquebox/current/jboss/standalone/data/messagingpaging)
12:22:09,429 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221008: Waiting to obtain live lock
12:22:09,504 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221017: Using NIO Journal
12:22:09,743 INFO [org.jboss.as.remoting] (MSC service thread 1-5) JBAS017100: Listening on 0.0.0.0:4447
12:22:09,750 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221039: Waiting to obtain live lock
12:22:09,751 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221040: Live Server Obtained live lock
12:22:09,764 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-6) JBAS015012: Started FileSystemDeploymentService for directory /opt/torquebox/current/jboss/standalone/deployments
12:22:09,775 INFO [org.infinispan.factories.GlobalComponentRegistry] (ServerService Thread Pool -- 46) ISPN000128: Infinispan version: Infinispan 'Tactical Nuclear Penguin' 5.3.0.Final
12:22:09,775 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml" (runtime-name: "epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml")
12:22:10,122 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 46) ISPN000031: MBeans were successfully registered to the platform MBean server.
12:22:10,136 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 46) JBAS010281: Started __recoveryInfoCacheName__ cache from polyglot container
12:22:10,209 INFO [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 46) ISPN000031: MBeans were successfully registered to the platform MBean server.
12:22:10,210 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 46) JBAS010281: Started sessions cache from polyglot container
12:22:10,404 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221024: Started Netty Acceptor version 3.6.2.Final-c0d783c 0.0.0.0:5455 for CORE protocol
12:22:10,406 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221024: Started Netty Acceptor version 3.6.2.Final-c0d783c 0.0.0.0:5445 for CORE protocol
12:22:10,408 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221009: Server is now live
12:22:10,409 INFO [org.hornetq.core.server] (MSC service thread 1-2) HQ221003: HornetQ Server version 2.3.0.CR1 (buzzzzz!, 122) [486ae0af-5c37-11e7-aa2b-a9e594e93ebc]
12:22:10,416 INFO [org.hornetq.jms.server] (ServerService Thread Pool -- 46) HQ121006: Invalid "host" value "0.0.0.0" detected for "netty" connector. Switching to "nickuntu". If this new address is incorrect please manually configure the connector to use the proper one.
12:22:10,495 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 46) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
12:22:10,499 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 47) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
12:22:10,612 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-4) JBAS010406: Registered connection factory java:/JmsXA
12:22:10,666 INFO [org.hornetq.ra] (MSC service thread 1-4) HornetQ resource adaptor started
12:22:10,667 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-4) IJ020002: Deployed: file://RaActivatorhornetq-ra
12:22:10,673 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-4) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
12:22:17,701 INFO [org.torquebox.core.runtime] (pool-1-thread-1) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, context: global) in 8.38s
12:22:18,308 INFO [org.hornetq.core.server] (pool-5-thread-1) HQ221005: trying to deploy queue jms.queue./queues/torquebox/epri-dev-OpenADR-Virtual-Top-Node-ea436de/tasks/torquebox_backgroundable
12:22:18,345 INFO [org.jboss.as.messaging] (pool-5-thread-1) JBAS011601: Bound messaging object to jndi name java:/queues/torquebox/epri-dev-OpenADR-Virtual-Top-Node-ea436de/tasks/torquebox_backgroundable
12:22:18,423 INFO [org.torquebox.core.runtime] (pool-1-thread-1) Creating ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: services)
12:22:18,440 INFO [org.torquebox.core.runtime] (pool-1-thread-4) Creating ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: web)
12:22:18,440 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (pool-1-thread-6) Deferring start for messaging runtime pool.
12:22:18,454 INFO [org.torquebox.core.runtime.SharedRubyRuntimePool] (pool-1-thread-3) Deferring start for jobs runtime pool.
12:22:18,535 INFO [org.quartz.impl.StdSchedulerFactory] (MSC service thread 1-8) Using default implementation for ThreadExecutor
12:22:18,565 INFO [org.quartz.core.SchedulerSignalerImpl] (MSC service thread 1-8) Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
12:22:18,566 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) Quartz Scheduler v.2.1.5 created.
12:22:18,567 INFO [org.quartz.simpl.RAMJobStore] (MSC service thread 1-8) RAMJobStore initialized.
12:22:18,570 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) Scheduler meta-data: Quartz Scheduler (v2.1.5) 'jboss.deployment.unit."epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml".job_scheduler' 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.
12:22:18,571 INFO [org.quartz.impl.StdSchedulerFactory] (MSC service thread 1-8) Quartz scheduler 'jboss.deployment.unit."epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml".job_scheduler' initialized from an externally provided properties instance.
12:22:18,571 INFO [org.quartz.impl.StdSchedulerFactory] (MSC service thread 1-8) Quartz scheduler version: 2.1.5
12:22:18,572 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) JobFactory set to: org.torquebox.jobs.JobScheduler@1eb59a4a
12:22:18,572 INFO [org.quartz.core.QuartzScheduler] (MSC service thread 1-8) Scheduler jboss.deployment.unit."epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml".job_scheduler_$_NON_CLUSTERED started.
12:22:21,639 INFO [org.torquebox.core.runtime] (pool-1-thread-1) Setting up Bundler
12:22:21,659 INFO [org.torquebox.core.runtime] (pool-1-thread-4) Setting up Bundler
12:23:04,038 ERROR [stderr] (pool-1-thread-4) file:/opt/torquebox/current/jruby/lib/jruby.jar!/jruby/java/core_ext/object.rb:73 warning: already initialized constant Interval
12:23:04,382 ERROR [stderr] (pool-1-thread-1) file:/opt/torquebox/current/jruby/lib/jruby.jar!/jruby/java/core_ext/object.rb:73 warning: already initialized constant Interval
12:23:13,277 INFO [org.torquebox.core.runtime] (pool-1-thread-4) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: web) in 54.83s
12:23:13,292 INFO [org.jboss.web] (ServerService Thread Pool -- 48) JBAS018210: Register web context:
12:23:13,606 INFO [org.torquebox.core.runtime] (pool-1-thread-1) Created ruby runtime (ruby_version: RUBY1_9, compile_mode: JIT, app: epri-dev-OpenADR-Virtual-Top-Node-ea436de, context: services) in 55.18s
12:23:13,719 INFO [org.jboss.as.server] (ServerService Thread Pool -- 24) JBAS018559: Deployed "epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml" (runtime-name : "epri-dev-OpenADR-Virtual-Top-Node-ea436de-knob.yml")
12:23:13,818 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management
12:23:13,819 INFO [org.jboss.as] (Controller Boot Thread) JBAS015954: Admin console is not enabled
12:23:13,819 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.2.x.slim.incremental.9 "Janus" started in 67706ms - Started 147 of 229 services (81 services are passive or on-demand)