tarun
2012-10-30 01:33:28 UTC
tarun [http://community.zenoss.org/people/tarun] created the discussion
"ZenJMX - Not generating graphs -[zenjmxjavaport vs jmremoteport]]"
To view the discussion, visit: http://community.zenoss.org/message/69607#69607
--------------------------------------------------------------
Dear All
I'm using Zenoss JMX zenpack : http://community.zenoss.org/servlet/JiveServlet/download/3396-10-3491/ZenPacks.zenoss.ZenJMX-3.5.3-py2.6.egg.zip ZenPacks.zenoss.ZenJMX-3.5.3-py2.6.egg.zip to monitor JVM heap memory size on a Windows Machine , Non-Heap , my customized Mbean attribute called CacheSize.
I attached the ZenJMX template and my locally created template to the device i wish to monitor. In the zconfiguration i changed the default port from 1234 to 1099 . Using Jconsole I'm able to monitor this Mbean and JVM's heap size etc.. But I get no graphs(ZenJMZ Non-Heap Memory etc) on Zenoss for this device.
Please help me understand I have what the difference between zJmxManagementPort and zenjmxjavaPort which is set to 9988 in the Zenjmx Configuration tab in Zenoss Version 4
I ran the following command as Zenoss user : zenjmx run -v10 but Im gettings these error , ConfigID error...:
I cannot figure out where I'm going wrong, I'm not able to get any ZenJMX graph. :( Â ... Your help is much appreciated.
Also my VM arguments are :
       -Dcom.sun.management.jmxremote
       -Dcom.sun.management.jmxremote.authentication=false
      -Dcom.sun.management.jmxremote.ssl=false
       -Dcom.sun.management.jmxremote.port=1099
       ------ Result when I ran zenjmx run -v10 --------
2012-10-30 10:30:15,680 DEBUG zen.zenjmx: Starting PBDaemon initialization
2012-10-30 10:30:15,681 INFO zen.zenjmx: Connecting to localhost:8789
2012-10-30 10:30:15,683 DEBUG zen.pbclientfactory: Starting connection...
2012-10-30 10:30:15,684 DEBUG zen.zenjmx: Logging in as admin
2012-10-30 10:30:15,686 DEBUG zen.pbclientfactory: Connected
2012-10-30 10:30:15,686 DEBUG zen.pbclientfactory: Cancelling connect timeout
2012-10-30 10:30:15,686 DEBUG zen.pbclientfactory: Sending credentials
2012-10-30 10:30:15,690 DEBUG zen.pbclientfactory: Cancelling connect timeout
2012-10-30 10:30:15,690 INFO zen.zenjmx: Connected to ZenHub
2012-10-30 10:30:15,690 DEBUG zen.zenjmx: Setting up initial services: EventService, ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService
2012-10-30 10:30:15,691 DEBUG zen.zenjmx: Chaining getInitialServices with d2
2012-10-30 10:30:15,691 DEBUG zen.pbclientfactory: pinging perspective
2012-10-30 10:30:15,693 DEBUG zen.zenjmx: Loaded service EventService from zenhub
2012-10-30 10:30:15,693 DEBUG zen.zenjmx: Loaded service ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService from zenhub
2012-10-30 10:30:15,693 DEBUG zen.zenjmx: Queued event (total of 1) {'device': 'localhost', 'eventClass': '/App/Start', 'component': 'zenjmx', 'severity': 0, 'summary': 'started'}
2012-10-30 10:30:15,694 DEBUG zen.zenjmx: Sending 1 events, 0 perfevents, 0 heartbeats.
2012-10-30 10:30:15,695 DEBUG zen.zenjmx: Calling connected.
2012-10-30 10:30:15,695 DEBUG zen.zenjmx: Retrieving java client startup args
2012-10-30 10:30:15,695 DEBUG zen.zenjmx: Attempting java client startup on port 9988
2012-10-30 10:30:15,698 INFO zen.ZenJMXJavaClient: run():starting zenjmxjava
2012-10-30 10:30:15,699 DEBUG zen.ZenJMXJavaClient: run():spawn process ('/opt/zenoss/ZenPacks/ZenPacks.zenoss.ZenJMX-3.5.3.egg/ZenPacks/zenoss/ZenJMX/bin/zenjmxjava', 'run', '-zenjmxjavaport', '9988', '--configfile', '/opt/zenoss/etc/zenjmx.conf', '-v', '10')
2012-10-30 10:30:15,742 DEBUG zen.ZenJMXJavaClient: connectionMade():zenjmxjava started
2012-10-30 10:30:15,750 DEBUG zen.ZenJMXJavaClient: connectionMade():scheduling callback
2012-10-30 10:30:15,750 DEBUG zen.ZenJMXJavaClient: connectionMade(): done
2012-10-30 10:30:15,753 DEBUG zen.pbclientfactory: perspective ponged
2012-10-30 10:30:15,753 DEBUG zen.pbclientfactory: Cancelling ping timeout
2012-10-30 10:30:15,784 DEBUG zen.zenjmx: Events sent
[2012-10-30 10:30:16,246] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:151 - zenjmxjava configuration:
[2012-10-30 10:30:16,252] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:152 - {hubport=8789, maxqueuelen=5000, hubpassword=zenoss, monitor=localhost, zenhubpinginterval=30, zenjmxjavaport=9988, logTaskStats=0, hubhost=localhost, hubusername=admin, maxparallel=500, eventflushchunksize=50, uid=zenoss, logseverity=20, initialHubTimeout=30, v=10, maxlogsize=10240, cycleInterval=120, concurrentJMXCalls=False, allowduplicateclears=False, duplicateclearinterval=0, duallog=False, maxbackuplogs=3, watchdog=False, parallel=200, eventflushseconds=5.0, portRange=5}
[2012-10-30 10:30:16,253] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:68 - setting root logger to DEBUG
2012-10-30 10:30:16.279::INFO:Â Logging to STDERR via org.mortbay.log.StdErrLog
2012-10-30 10:30:16.369::INFO:Â jetty-6.0.2
2012-10-30 10:30:16.395::WARN:Â failed SocketConnector @ 0.0.0.0:9988
java.net.BindException: Address already in use
   at java.net.PlainSocketImpl.socketBind(Native Method)
   at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:383)
   at java.net.ServerSocket.bind(ServerSocket.java:328)
   at java.net.ServerSocket.<init>(ServerSocket.java:194)
   at java.net.ServerSocket.<init>(ServerSocket.java:150)
   at org.mortbay.jetty.bio.SocketConnector.newServerSocket(SocketConnector.java:78)
   at org.mortbay.jetty.bio.SocketConnector.open(SocketConnector.java:72)
   at org.mortbay.jetty.AbstractConnector.doStart(AbstractConnector.java:251)
   at org.mortbay.jetty.bio.SocketConnector.doStart(SocketConnector.java:145)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at org.mortbay.jetty.Server.doStart(Server.java:239)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at com.zenoss.zenpacks.zenjmx.ZenJmxMain.main(ZenJmxMain.java:95)
2012-10-30 10:30:16.406::WARN:Â failed ***@6b0a2d64
java.net.BindException: Address already in use
   at java.net.PlainSocketImpl.socketBind(Native Method)
   at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:383)
   at java.net.ServerSocket.bind(ServerSocket.java:328)
   at java.net.ServerSocket.<init>(ServerSocket.java:194)
   at java.net.ServerSocket.<init>(ServerSocket.java:150)
   at org.mortbay.jetty.bio.SocketConnector.newServerSocket(SocketConnector.java:78)
   at org.mortbay.jetty.bio.SocketConnector.open(SocketConnector.java:72)
   at org.mortbay.jetty.AbstractConnector.doStart(AbstractConnector.java:251)
   at org.mortbay.jetty.bio.SocketConnector.doStart(SocketConnector.java:145)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at org.mortbay.jetty.Server.doStart(Server.java:239)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at com.zenoss.zenpacks.zenjmx.ZenJmxMain.main(ZenJmxMain.java:95)
2012-10-30 10:30:16,419 DEBUG zen.zenjmx: Queued event (total of 1) {'device': 'localhost', 'eventClass': '/Status/JMX', 'component': 'zenjmx', 'severity': 3, 'summary': 'zenjmxjava ended unexpectedly: A process has ended with a probable error condition: process ended with exit code 10.'}
2012-10-30 10:30:16,420 WARNING zen.ZenJMXJavaClient: processEnded():zenjmxjava process ended [Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 10.
]
2012-10-30 10:30:16,420 DEBUG zen.zenjmx: Java client didn't start; port 9988 occupied
2012-10-30 10:30:16,420 DEBUG zen.zenjmx: Attempting java client startup on port 9989
2012-10-30 10:30:16,421 INFO zen.ZenJMXJavaClient: run():starting zenjmxjava
2012-10-30 10:30:16,421 DEBUG zen.ZenJMXJavaClient: run():spawn process ('/opt/zenoss/ZenPacks/ZenPacks.zenoss.ZenJMX-3.5.3.egg/ZenPacks/zenoss/ZenJMX/bin/zenjmxjava', 'run', '-zenjmxjavaport', '9989', '--configfile', '/opt/zenoss/etc/zenjmx.conf', '-v', '10')
2012-10-30 10:30:16,458 DEBUG zen.ZenJMXJavaClient: connectionMade():zenjmxjava started
2012-10-30 10:30:16,460 DEBUG zen.ZenJMXJavaClient: connectionMade():scheduling callback
2012-10-30 10:30:16,461 DEBUG zen.ZenJMXJavaClient: connectionMade(): done
[2012-10-30 10:30:16,845] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:151 - zenjmxjava configuration:
[2012-10-30 10:30:16,850] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:152 - {hubport=8789, maxqueuelen=5000, hubpassword=zenoss, monitor=localhost, zenhubpinginterval=30, zenjmxjavaport=9989, logTaskStats=0, hubhost=localhost, hubusername=admin, maxparallel=500, eventflushchunksize=50, uid=zenoss, logseverity=20, initialHubTimeout=30, v=10, maxlogsize=10240, cycleInterval=120, concurrentJMXCalls=False, allowduplicateclears=False, duplicateclearinterval=0, duallog=False, maxbackuplogs=3, watchdog=False, parallel=200, eventflushseconds=5.0, portRange=5}
[2012-10-30 10:30:16,851] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:68 - setting root logger to DEBUG
2012-10-30 10:30:16.873::INFO:Â Logging to STDERR via org.mortbay.log.StdErrLog
2012-10-30 10:30:16.952::INFO:Â jetty-6.0.2
2012-10-30 10:30:16.972::INFO:Â Started SocketConnector @ 0.0.0.0:9989
2012-10-30 10:30:18,753 DEBUG zen.ZenJMXJavaClient: doCallback(): callback on deferred zenjmxjava proc is up
2012-10-30 10:30:19,461 DEBUG zen.ZenJMXJavaClient: doCallback(): callback on deferred zenjmxjava proc is up
2012-10-30 10:30:19,462 DEBUG zen.zenjmx: Java jmx client started
2012-10-30 10:30:19,474 DEBUG zen.collector.config: Heartbeat timeout set to 900s
2012-10-30 10:30:19,475 DEBUG zen.collector.scheduler: add task configLoader, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890> using 1200 second interval
2012-10-30 10:30:19,476 DEBUG zen.zenjmx: Performing periodic maintenance
2012-10-30 10:30:19,477 DEBUG zen.zenjmx: Queued event (total of 2) {'device': 'localhost', 'eventClass': '/Status/JMX', 'component': 'zenjmx', 'severity': 0, 'summary': 'zenjmxjava started'}
2012-10-30 10:30:19,478 DEBUG zen.collector.scheduler: Task configLoader starting on 1200 second intervals
2012-10-30 10:30:19,478 DEBUG zen.collector.scheduler: Task configLoader changing state from IDLE to QUEUED
2012-10-30 10:30:19,479 DEBUG zen.collector.scheduler: Task configLoader changing state from QUEUED to RUNNING
2012-10-30 10:30:19,480 DEBUG zen.collector.config: configLoader gathering configuration
2012-10-30 10:30:19,480 DEBUG zen.collector.config: Fetching daemon configuration properties
2012-10-30 10:30:19,495 DEBUG zen.collector.scheduler: Task configLoader changing state from RUNNING to FETCHING_MISC_CONFIG
2012-10-30 10:30:19,496 DEBUG zen.zenjmx: Updated configCycleInterval preference to 360
2012-10-30 10:30:19,497 DEBUG zen.zenjmx: Changing config task interval from 20 to 360 minutes
2012-10-30 10:30:19,497 DEBUG zen.collector.scheduler: Stopping task configLoader, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890>
2012-10-30 10:30:19,497 DEBUG zen.collector.scheduler: call finished LoopingCall<1200>(CallableTask: configLoader, *(), **{}) : LoopingCall<1200>(CallableTask: configLoader, *(), **{})
2012-10-30 10:30:19,497 INFO zen.collector.scheduler: Detailed Task Statistics:
configLoader Current State: FETCHING_MISC_CONFIG Successful_Runs: 1 Failed_Runs: 0 Missed_Runs: 0
Detailed Task States:
configLoader State: RUNNING Total: 1 Total Elapsed: 0.0168 Min: 0.0168 Max: 0.0168 Mean: 0.0168 StdDev: 0.0000
configLoader State: QUEUED Total: 1 Total Elapsed: 0.0009 Min: 0.0009 Max: 0.0009 Mean: 0.0009 StdDev: 0.0000
2012-10-30 10:30:19,498 DEBUG zen.collector.config: Heartbeat timeout set to 900s
2012-10-30 10:30:19,498 DEBUG zen.collector.scheduler: add task configLoader, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x54a41d0> using 21600 second interval
2012-10-30 10:30:19,499 DEBUG zen.zenjmx: Updated defaultRRDCreateCommand preference to ('RRA:AVERAGE:0.5:1:600', 'RRA:AVERAGE:0.5:6:600', 'RRA:AVERAGE:0.5:24:600', 'RRA:AVERAGE:0.5:288:600', 'RRA:MAX:0.5:6:600', 'RRA:MAX:0.5:24:600', 'RRA:MAX:0.5:288:600')
2012-10-30 10:30:19,499 DEBUG zen.collector.config: Fetching threshold classes
2012-10-30 10:30:19,505 DEBUG zen.zenjmx: Loading classes ['Products.ZenModel.MinMaxThreshold', 'Products.ZenModel.ValueChangeThreshold']
2012-10-30 10:30:19,508 DEBUG zen.collector.config: Fetching collector thresholds
2012-10-30 10:30:19,521 DEBUG zen.thresholds: Updating threshold ('high event queue', ('localhost collector', ''))
2012-10-30 10:30:19,521 DEBUG zen.thresholds: Updating threshold ('zenmodeler cycle time', ('localhost collector', ''))
2012-10-30 10:30:19,521 DEBUG zen.collector.config: Fetching configurations
2012-10-30 10:30:19,904 DEBUG zen.zenjmx: updateDeviceConfigs: updatedConfigs=['<ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService.JMXDeviceConfig instance at 0x541aa70>']
2012-10-30 10:30:19,920 ERROR zen.collector.config: Task configLoader configure failed: JMXDeviceConfig instance has no attribute 'configId'
2012-10-30 10:30:19,920 DEBUG zen.zenjmx: Shutting down JMX Java client zenjmxjavaclient
2012-10-30 10:30:19,921 INFO zen.ZenJMXJavaClient: stop():stopping zenjmxjava
2012-10-30 10:30:19,921 DEBUG zen.zenjmx: Sending 2 events, 0 perfevents, 0 heartbeats.
2012-10-30 10:30:19,922 DEBUG zen.zenjmx: Sent a 'stop' event
2012-10-30 10:30:19,922 DEBUG zen.collector.scheduler: Task configLoader finished, result: <twisted.python.failure.Failure <type 'exceptions.AttributeError'>>
2012-10-30 10:30:19,925 DEBUG zen.collector.scheduler: Traceback (most recent call last):
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1141, in unwindGenerator
   return _inlineCallbacks(None, f(*args, **kwargs), Deferred())
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1020, in _inlineCallbacks
   result = g.send(result)
 File "/opt/zenoss/Products/ZenCollector/config.py", line 263, in _processConfig
   yield self._daemon._updateDeviceConfigs(configs, purgeOmitted)
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1141, in unwindGenerator
   return _inlineCallbacks(None, f(*args, **kwargs), Deferred())
--- <exception caught here> ---
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1020, in _inlineCallbacks
   result = g.send(result)
 File "/opt/zenoss/Products/ZenCollector/daemon.py", line 405, in _updateDeviceConfigs
   self._updateConfig(cfg)
 File "/opt/zenoss/Products/ZenCollector/daemon.py", line 365, in _updateConfig
   configId = cfg.configId
exceptions.AttributeError: JMXDeviceConfig instance has no attribute 'configId'
2012-10-30 10:30:19,925 DEBUG zen.collector.scheduler: <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890> - configLoader failed [Failure instance: Traceback: <type 'exceptions.AttributeError'>: JMXDeviceConfig instance has no attribute 'configId'
/opt/zenoss/lib/python/twisted/internet/defer.py:1141:unwindGenerator
/opt/zenoss/lib/python/twisted/internet/defer.py:1020:_inlineCallbacks
/opt/zenoss/Products/ZenCollector/config.py:263:_processConfig
/opt/zenoss/lib/python/twisted/internet/defer.py:1141:unwindGenerator
--- <exception caught here> ---
/opt/zenoss/lib/python/twisted/internet/defer.py:1020:_inlineCallbacks
/opt/zenoss/Products/ZenCollector/daemon.py:405:_updateDeviceConfigs
/opt/zenoss/Products/ZenCollector/daemon.py:365:_updateConfig
]
2012-10-30 10:30:19,950 DEBUG zen.zenjmx: Events sent
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: In shutdown stage before
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: Removing task configLoader
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: tasks to clean set([<Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890>, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x54a41d0>])
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: Cleanup on task configLoader <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890>
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: Scheduler._cleanupTaskComplete: result=None task.name=configLoader
2012-10-30 10:30:19,955 DEBUG zen.collector.scheduler: Cleanup on task configLoader <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x54a41d0>
2012-10-30 10:30:19,955 DEBUG zen.collector.scheduler: Scheduler._cleanupTaskComplete: result=None task.name=configLoader
2012-10-30 10:30:19,961 DEBUG zen.zenjmx: Shutting down JMX Java client zenjmxjavaclient
2012-10-30 10:30:19,961 INFO zen.ZenJMXJavaClient: stop():stopping zenjmxjava
2012-10-30 10:30:19,961 DEBUG zen.ZenJMXJavaClient: stop():no zenjmxjava process to stop
2012-10-30 10:30:19,961 DEBUG zen.zenjmx: stop() called when not running
2012-10-30 10:30:19,961 INFO zen.zenjmx: Daemon CollectorDaemon shutting down
2012-10-30 10:30:19,966 DEBUG zen.zenjmx: Removing service EventService
2012-10-30 10:30:19,966 DEBUG zen.zenjmx: Removing service ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService
2012-10-30 10:30:19,966 DEBUG zen.pbclientfactory: Lost connection to ::1:8789 - [Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ConnectionLost'>: Connection to the other side was lost in a non-clean fashion: Connection lost.
]
2012-10-30 10:30:19,967 DEBUG zen.collector.scheduler: In shutdown stage during
2012-10-30 10:30:19,967 DEBUG zen.collector.scheduler: In shutdown stage after
--------------------------------------------------------------
Reply to this message by replying to this email -or- go to the discussion on Zenoss Community
[http://community.zenoss.org/message/69607#69607]
Start a new discussion in zenoss-users by email
[discussions-community-forums-zenoss--***@community.zenoss.org] -or- at Zenoss Community
[http://community.zenoss.org/choose-container!input.jspa?contentType=1&containerType=14&container=2003]
"ZenJMX - Not generating graphs -[zenjmxjavaport vs jmremoteport]]"
To view the discussion, visit: http://community.zenoss.org/message/69607#69607
--------------------------------------------------------------
Dear All
I'm using Zenoss JMX zenpack : http://community.zenoss.org/servlet/JiveServlet/download/3396-10-3491/ZenPacks.zenoss.ZenJMX-3.5.3-py2.6.egg.zip ZenPacks.zenoss.ZenJMX-3.5.3-py2.6.egg.zip to monitor JVM heap memory size on a Windows Machine , Non-Heap , my customized Mbean attribute called CacheSize.
I attached the ZenJMX template and my locally created template to the device i wish to monitor. In the zconfiguration i changed the default port from 1234 to 1099 . Using Jconsole I'm able to monitor this Mbean and JVM's heap size etc.. But I get no graphs(ZenJMZ Non-Heap Memory etc) on Zenoss for this device.
Please help me understand I have what the difference between zJmxManagementPort and zenjmxjavaPort which is set to 9988 in the Zenjmx Configuration tab in Zenoss Version 4
I ran the following command as Zenoss user : zenjmx run -v10 but Im gettings these error , ConfigID error...:
I cannot figure out where I'm going wrong, I'm not able to get any ZenJMX graph. :( Â ... Your help is much appreciated.
Also my VM arguments are :
       -Dcom.sun.management.jmxremote
       -Dcom.sun.management.jmxremote.authentication=false
      -Dcom.sun.management.jmxremote.ssl=false
       -Dcom.sun.management.jmxremote.port=1099
       ------ Result when I ran zenjmx run -v10 --------
2012-10-30 10:30:15,680 DEBUG zen.zenjmx: Starting PBDaemon initialization
2012-10-30 10:30:15,681 INFO zen.zenjmx: Connecting to localhost:8789
2012-10-30 10:30:15,683 DEBUG zen.pbclientfactory: Starting connection...
2012-10-30 10:30:15,684 DEBUG zen.zenjmx: Logging in as admin
2012-10-30 10:30:15,686 DEBUG zen.pbclientfactory: Connected
2012-10-30 10:30:15,686 DEBUG zen.pbclientfactory: Cancelling connect timeout
2012-10-30 10:30:15,686 DEBUG zen.pbclientfactory: Sending credentials
2012-10-30 10:30:15,690 DEBUG zen.pbclientfactory: Cancelling connect timeout
2012-10-30 10:30:15,690 INFO zen.zenjmx: Connected to ZenHub
2012-10-30 10:30:15,690 DEBUG zen.zenjmx: Setting up initial services: EventService, ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService
2012-10-30 10:30:15,691 DEBUG zen.zenjmx: Chaining getInitialServices with d2
2012-10-30 10:30:15,691 DEBUG zen.pbclientfactory: pinging perspective
2012-10-30 10:30:15,693 DEBUG zen.zenjmx: Loaded service EventService from zenhub
2012-10-30 10:30:15,693 DEBUG zen.zenjmx: Loaded service ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService from zenhub
2012-10-30 10:30:15,693 DEBUG zen.zenjmx: Queued event (total of 1) {'device': 'localhost', 'eventClass': '/App/Start', 'component': 'zenjmx', 'severity': 0, 'summary': 'started'}
2012-10-30 10:30:15,694 DEBUG zen.zenjmx: Sending 1 events, 0 perfevents, 0 heartbeats.
2012-10-30 10:30:15,695 DEBUG zen.zenjmx: Calling connected.
2012-10-30 10:30:15,695 DEBUG zen.zenjmx: Retrieving java client startup args
2012-10-30 10:30:15,695 DEBUG zen.zenjmx: Attempting java client startup on port 9988
2012-10-30 10:30:15,698 INFO zen.ZenJMXJavaClient: run():starting zenjmxjava
2012-10-30 10:30:15,699 DEBUG zen.ZenJMXJavaClient: run():spawn process ('/opt/zenoss/ZenPacks/ZenPacks.zenoss.ZenJMX-3.5.3.egg/ZenPacks/zenoss/ZenJMX/bin/zenjmxjava', 'run', '-zenjmxjavaport', '9988', '--configfile', '/opt/zenoss/etc/zenjmx.conf', '-v', '10')
2012-10-30 10:30:15,742 DEBUG zen.ZenJMXJavaClient: connectionMade():zenjmxjava started
2012-10-30 10:30:15,750 DEBUG zen.ZenJMXJavaClient: connectionMade():scheduling callback
2012-10-30 10:30:15,750 DEBUG zen.ZenJMXJavaClient: connectionMade(): done
2012-10-30 10:30:15,753 DEBUG zen.pbclientfactory: perspective ponged
2012-10-30 10:30:15,753 DEBUG zen.pbclientfactory: Cancelling ping timeout
2012-10-30 10:30:15,784 DEBUG zen.zenjmx: Events sent
[2012-10-30 10:30:16,246] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:151 - zenjmxjava configuration:
[2012-10-30 10:30:16,252] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:152 - {hubport=8789, maxqueuelen=5000, hubpassword=zenoss, monitor=localhost, zenhubpinginterval=30, zenjmxjavaport=9988, logTaskStats=0, hubhost=localhost, hubusername=admin, maxparallel=500, eventflushchunksize=50, uid=zenoss, logseverity=20, initialHubTimeout=30, v=10, maxlogsize=10240, cycleInterval=120, concurrentJMXCalls=False, allowduplicateclears=False, duplicateclearinterval=0, duallog=False, maxbackuplogs=3, watchdog=False, parallel=200, eventflushseconds=5.0, portRange=5}
[2012-10-30 10:30:16,253] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:68 - setting root logger to DEBUG
2012-10-30 10:30:16.279::INFO:Â Logging to STDERR via org.mortbay.log.StdErrLog
2012-10-30 10:30:16.369::INFO:Â jetty-6.0.2
2012-10-30 10:30:16.395::WARN:Â failed SocketConnector @ 0.0.0.0:9988
java.net.BindException: Address already in use
   at java.net.PlainSocketImpl.socketBind(Native Method)
   at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:383)
   at java.net.ServerSocket.bind(ServerSocket.java:328)
   at java.net.ServerSocket.<init>(ServerSocket.java:194)
   at java.net.ServerSocket.<init>(ServerSocket.java:150)
   at org.mortbay.jetty.bio.SocketConnector.newServerSocket(SocketConnector.java:78)
   at org.mortbay.jetty.bio.SocketConnector.open(SocketConnector.java:72)
   at org.mortbay.jetty.AbstractConnector.doStart(AbstractConnector.java:251)
   at org.mortbay.jetty.bio.SocketConnector.doStart(SocketConnector.java:145)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at org.mortbay.jetty.Server.doStart(Server.java:239)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at com.zenoss.zenpacks.zenjmx.ZenJmxMain.main(ZenJmxMain.java:95)
2012-10-30 10:30:16.406::WARN:Â failed ***@6b0a2d64
java.net.BindException: Address already in use
   at java.net.PlainSocketImpl.socketBind(Native Method)
   at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:383)
   at java.net.ServerSocket.bind(ServerSocket.java:328)
   at java.net.ServerSocket.<init>(ServerSocket.java:194)
   at java.net.ServerSocket.<init>(ServerSocket.java:150)
   at org.mortbay.jetty.bio.SocketConnector.newServerSocket(SocketConnector.java:78)
   at org.mortbay.jetty.bio.SocketConnector.open(SocketConnector.java:72)
   at org.mortbay.jetty.AbstractConnector.doStart(AbstractConnector.java:251)
   at org.mortbay.jetty.bio.SocketConnector.doStart(SocketConnector.java:145)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at org.mortbay.jetty.Server.doStart(Server.java:239)
   at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
   at com.zenoss.zenpacks.zenjmx.ZenJmxMain.main(ZenJmxMain.java:95)
2012-10-30 10:30:16,419 DEBUG zen.zenjmx: Queued event (total of 1) {'device': 'localhost', 'eventClass': '/Status/JMX', 'component': 'zenjmx', 'severity': 3, 'summary': 'zenjmxjava ended unexpectedly: A process has ended with a probable error condition: process ended with exit code 10.'}
2012-10-30 10:30:16,420 WARNING zen.ZenJMXJavaClient: processEnded():zenjmxjava process ended [Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 10.
]
2012-10-30 10:30:16,420 DEBUG zen.zenjmx: Java client didn't start; port 9988 occupied
2012-10-30 10:30:16,420 DEBUG zen.zenjmx: Attempting java client startup on port 9989
2012-10-30 10:30:16,421 INFO zen.ZenJMXJavaClient: run():starting zenjmxjava
2012-10-30 10:30:16,421 DEBUG zen.ZenJMXJavaClient: run():spawn process ('/opt/zenoss/ZenPacks/ZenPacks.zenoss.ZenJMX-3.5.3.egg/ZenPacks/zenoss/ZenJMX/bin/zenjmxjava', 'run', '-zenjmxjavaport', '9989', '--configfile', '/opt/zenoss/etc/zenjmx.conf', '-v', '10')
2012-10-30 10:30:16,458 DEBUG zen.ZenJMXJavaClient: connectionMade():zenjmxjava started
2012-10-30 10:30:16,460 DEBUG zen.ZenJMXJavaClient: connectionMade():scheduling callback
2012-10-30 10:30:16,461 DEBUG zen.ZenJMXJavaClient: connectionMade(): done
[2012-10-30 10:30:16,845] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:151 - zenjmxjava configuration:
[2012-10-30 10:30:16,850] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:152 - {hubport=8789, maxqueuelen=5000, hubpassword=zenoss, monitor=localhost, zenhubpinginterval=30, zenjmxjavaport=9989, logTaskStats=0, hubhost=localhost, hubusername=admin, maxparallel=500, eventflushchunksize=50, uid=zenoss, logseverity=20, initialHubTimeout=30, v=10, maxlogsize=10240, cycleInterval=120, concurrentJMXCalls=False, allowduplicateclears=False, duplicateclearinterval=0, duallog=False, maxbackuplogs=3, watchdog=False, parallel=200, eventflushseconds=5.0, portRange=5}
[2012-10-30 10:30:16,851] [INFO ] [main] com.zenoss.zenpacks.zenjmx.ZenJmxMain:68 - setting root logger to DEBUG
2012-10-30 10:30:16.873::INFO:Â Logging to STDERR via org.mortbay.log.StdErrLog
2012-10-30 10:30:16.952::INFO:Â jetty-6.0.2
2012-10-30 10:30:16.972::INFO:Â Started SocketConnector @ 0.0.0.0:9989
2012-10-30 10:30:18,753 DEBUG zen.ZenJMXJavaClient: doCallback(): callback on deferred zenjmxjava proc is up
2012-10-30 10:30:19,461 DEBUG zen.ZenJMXJavaClient: doCallback(): callback on deferred zenjmxjava proc is up
2012-10-30 10:30:19,462 DEBUG zen.zenjmx: Java jmx client started
2012-10-30 10:30:19,474 DEBUG zen.collector.config: Heartbeat timeout set to 900s
2012-10-30 10:30:19,475 DEBUG zen.collector.scheduler: add task configLoader, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890> using 1200 second interval
2012-10-30 10:30:19,476 DEBUG zen.zenjmx: Performing periodic maintenance
2012-10-30 10:30:19,477 DEBUG zen.zenjmx: Queued event (total of 2) {'device': 'localhost', 'eventClass': '/Status/JMX', 'component': 'zenjmx', 'severity': 0, 'summary': 'zenjmxjava started'}
2012-10-30 10:30:19,478 DEBUG zen.collector.scheduler: Task configLoader starting on 1200 second intervals
2012-10-30 10:30:19,478 DEBUG zen.collector.scheduler: Task configLoader changing state from IDLE to QUEUED
2012-10-30 10:30:19,479 DEBUG zen.collector.scheduler: Task configLoader changing state from QUEUED to RUNNING
2012-10-30 10:30:19,480 DEBUG zen.collector.config: configLoader gathering configuration
2012-10-30 10:30:19,480 DEBUG zen.collector.config: Fetching daemon configuration properties
2012-10-30 10:30:19,495 DEBUG zen.collector.scheduler: Task configLoader changing state from RUNNING to FETCHING_MISC_CONFIG
2012-10-30 10:30:19,496 DEBUG zen.zenjmx: Updated configCycleInterval preference to 360
2012-10-30 10:30:19,497 DEBUG zen.zenjmx: Changing config task interval from 20 to 360 minutes
2012-10-30 10:30:19,497 DEBUG zen.collector.scheduler: Stopping task configLoader, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890>
2012-10-30 10:30:19,497 DEBUG zen.collector.scheduler: call finished LoopingCall<1200>(CallableTask: configLoader, *(), **{}) : LoopingCall<1200>(CallableTask: configLoader, *(), **{})
2012-10-30 10:30:19,497 INFO zen.collector.scheduler: Detailed Task Statistics:
configLoader Current State: FETCHING_MISC_CONFIG Successful_Runs: 1 Failed_Runs: 0 Missed_Runs: 0
Detailed Task States:
configLoader State: RUNNING Total: 1 Total Elapsed: 0.0168 Min: 0.0168 Max: 0.0168 Mean: 0.0168 StdDev: 0.0000
configLoader State: QUEUED Total: 1 Total Elapsed: 0.0009 Min: 0.0009 Max: 0.0009 Mean: 0.0009 StdDev: 0.0000
2012-10-30 10:30:19,498 DEBUG zen.collector.config: Heartbeat timeout set to 900s
2012-10-30 10:30:19,498 DEBUG zen.collector.scheduler: add task configLoader, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x54a41d0> using 21600 second interval
2012-10-30 10:30:19,499 DEBUG zen.zenjmx: Updated defaultRRDCreateCommand preference to ('RRA:AVERAGE:0.5:1:600', 'RRA:AVERAGE:0.5:6:600', 'RRA:AVERAGE:0.5:24:600', 'RRA:AVERAGE:0.5:288:600', 'RRA:MAX:0.5:6:600', 'RRA:MAX:0.5:24:600', 'RRA:MAX:0.5:288:600')
2012-10-30 10:30:19,499 DEBUG zen.collector.config: Fetching threshold classes
2012-10-30 10:30:19,505 DEBUG zen.zenjmx: Loading classes ['Products.ZenModel.MinMaxThreshold', 'Products.ZenModel.ValueChangeThreshold']
2012-10-30 10:30:19,508 DEBUG zen.collector.config: Fetching collector thresholds
2012-10-30 10:30:19,521 DEBUG zen.thresholds: Updating threshold ('high event queue', ('localhost collector', ''))
2012-10-30 10:30:19,521 DEBUG zen.thresholds: Updating threshold ('zenmodeler cycle time', ('localhost collector', ''))
2012-10-30 10:30:19,521 DEBUG zen.collector.config: Fetching configurations
2012-10-30 10:30:19,904 DEBUG zen.zenjmx: updateDeviceConfigs: updatedConfigs=['<ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService.JMXDeviceConfig instance at 0x541aa70>']
2012-10-30 10:30:19,920 ERROR zen.collector.config: Task configLoader configure failed: JMXDeviceConfig instance has no attribute 'configId'
2012-10-30 10:30:19,920 DEBUG zen.zenjmx: Shutting down JMX Java client zenjmxjavaclient
2012-10-30 10:30:19,921 INFO zen.ZenJMXJavaClient: stop():stopping zenjmxjava
2012-10-30 10:30:19,921 DEBUG zen.zenjmx: Sending 2 events, 0 perfevents, 0 heartbeats.
2012-10-30 10:30:19,922 DEBUG zen.zenjmx: Sent a 'stop' event
2012-10-30 10:30:19,922 DEBUG zen.collector.scheduler: Task configLoader finished, result: <twisted.python.failure.Failure <type 'exceptions.AttributeError'>>
2012-10-30 10:30:19,925 DEBUG zen.collector.scheduler: Traceback (most recent call last):
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1141, in unwindGenerator
   return _inlineCallbacks(None, f(*args, **kwargs), Deferred())
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1020, in _inlineCallbacks
   result = g.send(result)
 File "/opt/zenoss/Products/ZenCollector/config.py", line 263, in _processConfig
   yield self._daemon._updateDeviceConfigs(configs, purgeOmitted)
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1141, in unwindGenerator
   return _inlineCallbacks(None, f(*args, **kwargs), Deferred())
--- <exception caught here> ---
 File "/opt/zenoss/lib/python/twisted/internet/defer.py", line 1020, in _inlineCallbacks
   result = g.send(result)
 File "/opt/zenoss/Products/ZenCollector/daemon.py", line 405, in _updateDeviceConfigs
   self._updateConfig(cfg)
 File "/opt/zenoss/Products/ZenCollector/daemon.py", line 365, in _updateConfig
   configId = cfg.configId
exceptions.AttributeError: JMXDeviceConfig instance has no attribute 'configId'
2012-10-30 10:30:19,925 DEBUG zen.collector.scheduler: <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890> - configLoader failed [Failure instance: Traceback: <type 'exceptions.AttributeError'>: JMXDeviceConfig instance has no attribute 'configId'
/opt/zenoss/lib/python/twisted/internet/defer.py:1141:unwindGenerator
/opt/zenoss/lib/python/twisted/internet/defer.py:1020:_inlineCallbacks
/opt/zenoss/Products/ZenCollector/config.py:263:_processConfig
/opt/zenoss/lib/python/twisted/internet/defer.py:1141:unwindGenerator
--- <exception caught here> ---
/opt/zenoss/lib/python/twisted/internet/defer.py:1020:_inlineCallbacks
/opt/zenoss/Products/ZenCollector/daemon.py:405:_updateDeviceConfigs
/opt/zenoss/Products/ZenCollector/daemon.py:365:_updateConfig
]
2012-10-30 10:30:19,950 DEBUG zen.zenjmx: Events sent
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: In shutdown stage before
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: Removing task configLoader
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: tasks to clean set([<Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890>, <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x54a41d0>])
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: Cleanup on task configLoader <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x50ca890>
2012-10-30 10:30:19,952 DEBUG zen.collector.scheduler: Scheduler._cleanupTaskComplete: result=None task.name=configLoader
2012-10-30 10:30:19,955 DEBUG zen.collector.scheduler: Cleanup on task configLoader <Products.ZenCollector.config.ConfigurationLoaderTask object at 0x54a41d0>
2012-10-30 10:30:19,955 DEBUG zen.collector.scheduler: Scheduler._cleanupTaskComplete: result=None task.name=configLoader
2012-10-30 10:30:19,961 DEBUG zen.zenjmx: Shutting down JMX Java client zenjmxjavaclient
2012-10-30 10:30:19,961 INFO zen.ZenJMXJavaClient: stop():stopping zenjmxjava
2012-10-30 10:30:19,961 DEBUG zen.ZenJMXJavaClient: stop():no zenjmxjava process to stop
2012-10-30 10:30:19,961 DEBUG zen.zenjmx: stop() called when not running
2012-10-30 10:30:19,961 INFO zen.zenjmx: Daemon CollectorDaemon shutting down
2012-10-30 10:30:19,966 DEBUG zen.zenjmx: Removing service EventService
2012-10-30 10:30:19,966 DEBUG zen.zenjmx: Removing service ZenPacks.zenoss.ZenJMX.services.ZenJMXConfigService
2012-10-30 10:30:19,966 DEBUG zen.pbclientfactory: Lost connection to ::1:8789 - [Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ConnectionLost'>: Connection to the other side was lost in a non-clean fashion: Connection lost.
]
2012-10-30 10:30:19,967 DEBUG zen.collector.scheduler: In shutdown stage during
2012-10-30 10:30:19,967 DEBUG zen.collector.scheduler: In shutdown stage after
--------------------------------------------------------------
Reply to this message by replying to this email -or- go to the discussion on Zenoss Community
[http://community.zenoss.org/message/69607#69607]
Start a new discussion in zenoss-users by email
[discussions-community-forums-zenoss--***@community.zenoss.org] -or- at Zenoss Community
[http://community.zenoss.org/choose-container!input.jspa?contentType=1&containerType=14&container=2003]