vmbix icon indicating copy to clipboard operation
vmbix copied to clipboard
verified publisher icon dav3860

NullPointerException

Open sysHate opened this issue 9 years ago • 15 comments

Hi. vmbix was running good for ~1 month. Now are mostly Items unsupported & vmbix.log is full of NPE Errors:

Exception in thread "Thread-405" java.lang.NullPointerException
        at net.dav3860.VmBix$Connection.getManagedEntityByUuid(VmBix.java:1204)
        at net.dav3860.VmBix$Connection.getManagedEntity(VmBix.java:1113)
        at net.dav3860.VmBix$Connection.getVmPerfCounterValue(VmBix.java:2360)
        at net.dav3860.VmBix$Connection.checkAllPatterns(VmBix.java:1030)
        at net.dav3860.VmBix$Connection.run(VmBix.java:3415)

There is allso a Problem with object script.

[2016-09-28 16:55:07,755] - INFO - Action for extra hosts : simulate
[2016-09-28 16:55:07,755] - INFO - Connecting to Zabbix server at http://zabbix-webpage.com
[2016-09-28 16:55:07,821] - INFO - [vsphere_object_vm] Importing objects of type [vsphere_object_vm] into Zabbix
[2016-09-28 16:55:14,347] - INFO - [vsphere_object_vm] Number of objects to be checked: 223
[2016-09-28 16:55:14,348] - DEBUG - [vsphere_object_vm] Processing host 'Server1' [1/223]
[2016-09-28 16:55:14,348] - DEBUG - [vsphere_object_vm] Checking inclusion rules for host 'Server2'
[2016-09-28 16:55:14,348] - DEBUG - [vsphere_object_vm] Checking exclusion rules for host 'Server3'

Then i have to break a script because nothing is happening.

Do you have any idea, where i have to look? Thank you! Regards!

sysHate avatar Sep 28 '16 15:09 sysHate

Hi, What version do you use ? Please upgrade to the latest version, the error handling was improved.

dav3860 avatar Sep 28 '16 21:09 dav3860

I installed vmbix_2.4.0.80-1_amd64.deb on zabbix_proxy 3.0.3 OS: Ubuntu 16.04.1 LTS I tryed vmbix 2.5 out, thats what i became while testing:

vmbix -P 12050 -u "user@domain" -p "psw" -s "https://vcenter2/sdk"
Exception in thread "main" java.lang.NullPointerException
        at net.dav3860.VmBix.main(VmBix.java:224)
13:15:06.511 [Thread-0] INFO net.dav3860.VmBix - Shutting down
13:15:06.516 [Thread-0] ERROR net.dav3860.VmBix - Error disconnecting: null
13:15:06.516 [Thread-0] INFO net.dav3860.VmBix - Shutted down

sysHate avatar Sep 29 '16 11:09 sysHate

It may be a connection error to the vcenter. Please upgrade to v2.5, the error handling was improved. Have a look at the CHANGELOG as a few things changed.

dav3860 avatar Sep 29 '16 15:09 dav3860

There is a bug when you start VmBix 2.5 in CLI but it works with a config file. It will be fixed in v2.6. Or I can give you the fixed development package.

dav3860 avatar Sep 29 '16 15:09 dav3860

Ah ok, you are right. Thats what i see in Log now:

log4j:WARN No appenders could be found for logger (com.vmware.vim25.ws.XmlGenDom).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
java.rmi.RemoteException: An error occurred getting a response from the connection at url https://vcenter2/sdk; nested exception is:
        java.net.SocketTimeoutException: Read timed out
        at com.vmware.vim25.ws.WSClient.getInputStreamFromConnection(WSClient.java:197)
        at com.vmware.vim25.ws.WSClient.post(WSClient.java:171)
        at com.vmware.vim25.ws.WSClient.invoke(WSClient.java:91)
        at com.vmware.vim25.ws.VimStub.login(VimStub.java:1732)
        at com.vmware.vim25.mo.SessionManager.login(SessionManager.java:153)
        at com.vmware.vim25.mo.ServiceInstance.login(ServiceInstance.java:238)
        at com.vmware.vim25.mo.ServiceInstance.constructServiceInstance(ServiceInstance.java:131)
        at com.vmware.vim25.mo.ServiceInstance.<init>(ServiceInstance.java:104)
        at com.vmware.vim25.mo.ServiceInstance.<init>(ServiceInstance.java:94)
        at net.dav3860.VmBix.updateConnection(VmBix.java:435)
        at net.dav3860.VmBix.server(VmBix.java:487)
        at net.dav3860.VmBix.main(VmBix.java:234)
Caused by: java.net.SocketTimeoutException: Read timed out
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
        at java.net.SocketInputStream.read(SocketInputStream.java:170)
        at java.net.SocketInputStream.read(SocketInputStream.java:141)
        at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
        at sun.security.ssl.InputRecord.read(InputRecord.java:503)
        at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:973)
        at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:930)
        at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
        at java.io.BufferedInputStream.fill(BufferedInputStream.java:246)
        at java.io.BufferedInputStream.read1(BufferedInputStream.java:286)
        at java.io.BufferedInputStream.read(BufferedInputStream.java:345)
        at sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:704)
        at sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:647)
        at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1536)
        at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1441)
        at sun.net.www.protocol.https.HttpsURLConnectionImpl.getInputStream(HttpsURLConnectionImpl.java:254)
        at com.vmware.vim25.ws.WSClient.getInputStreamFromConnection(WSClient.java:184)
        ... 11 more
2016-09-30 16:03:22,807 INFO  [main] [VmBix.java:489] starting server on 127.0.0.1/12050
2016-09-30 16:03:22,817 INFO  [main] [VmBix.java:496] server started

I'll check the connection now. Thank you a lot!

sysHate avatar Sep 30 '16 14:09 sysHate

There is an error in the default configuration file. The read and connect timeouts must be set in ms. So update their values to 30000.

dav3860 avatar Oct 01 '16 12:10 dav3860

Thats what i get now:

log4j:WARN No appenders could be found for logger (com.vmware.vim25.ws.XmlGenDom).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
2016-10-04 13:07:27,860 INFO  [main] [VmBix.java:489] starting server on 127.0.0.1/12050
2016-10-04 13:07:27,862 INFO  [main] [VmBix.java:496] server started
2016-10-04 13:16:57,527 ERROR [Thread-8] [VmBix.java:2603] An error occurred : null
2016-10-04 13:16:57,649 ERROR [Thread-7] [VmBix.java:3452] An error occurred : null
2016-10-04 13:16:57,813 ERROR [Thread-8] [VmBix.java:2603] An error occurred : null
2016-10-04 13:16:57,933 ERROR [Thread-7] [VmBix.java:3452] An error occurred : null
2016-10-04 13:16:58,288 ERROR [Thread-7] [VmBix.java:2603] An error occurred : null
2016-10-04 13:16:58,408 ERROR [Thread-8] [VmBix.java:3452] An error occurred : null
2016-10-04 13:16:58,602 ERROR [Thread-8] [VmBix.java:2603] An error occurred : null
2016-10-04 13:16:58,724 ERROR [Thread-7] [VmBix.java:3452] An error occurred : null
2016-10-04 13:17:00,207 ERROR [Thread-8] [VmBix.java:2603] An error occurred : null
2016-10-04 13:17:00,328 ERROR [Thread-7] [VmBix.java:3452] An error occurred : null
2016-10-04 13:17:00,512 ERROR [Thread-7] [VmBix.java:2603] An error occurred : null
2016-10-04 13:17:00,621 ERROR [Thread-7] [VmBix.java:3452] An error occurred : null
...
2016-10-05 09:35:38,679 ERROR [Thread-9] [VmBix.java:3769] An error occurred : null
2016-10-05 09:35:38,679 ERROR [Thread-10] [VmBix.java:2742] An error occurred : null
2016-10-05 09:35:38,707 ERROR [Thread-3] [VmBix.java:2742] An error occurred : null
2016-10-05 09:35:38,747 ERROR [Thread-12] [VmBix.java:2742] An error occurred : null
2016-10-05 09:35:38,756 ERROR [Thread-8] [VmBix.java:2742] An error occurred : null
2016-10-05 09:35:39,714 INFO  [Thread-6] [VmBix.java:1136] String 'ZBXD<vm.guest.tools.status[422d40ac-1c1c-c5c0-cf87-02da06727bbd]' not supported
2016-10-05 09:35:39,773 ERROR [Thread-9] [VmBix.java:2742] An error occurred : null
2016-10-05 09:35:39,775 ERROR [Thread-8] [VmBix.java:2657] An error occurred : null
2016-10-05 09:35:39,776 ERROR [Thread-11] [VmBix.java:2657] An error occurred : null
2016-10-05 09:35:39,783 ERROR [Thread-3] [VmBix.java:1739] An error occurred : null
2016-10-05 09:35:39,785 ERROR [Thread-5] [VmBix.java:2742] An error occurred : null
2016-10-05 09:35:39,786 ERROR [Thread-4] [VmBix.java:3302] An error occurred : null
2016-10-05 09:35:39,794 ERROR [Thread-12] [VmBix.java:2467] An error occurred : null
2016-10-05 09:35:39,843 ERROR [Thread-6] [VmBix.java:1739] An error occurred : null
2016-10-05 09:35:39,844 ERROR [Thread-7] [VmBix.java:1739] An error occurred : null
...

sysHate avatar Oct 04 '16 13:10 sysHate

I deleted all Hosts and runed vmbix-object-sync script again. Now it seems to work for ESX & Datastores but not vor VM's

sysHate avatar Oct 05 '16 08:10 sysHate

Is the parameter useUUID set to true in the configuration file ? I fixed your former issues with the new version 2.6. You can download it. Can you use zabbix_get to query a few VMs and see if it works ?

dav3860 avatar Oct 06 '16 10:10 dav3860

Yes, useUUID is set to true. event.latest[] and vm.discovery[] do works, datastore checks to:

zabbix_get -s 127.0.0.1 -p 12050 -k 'datastore.size[50c05703-90916ee4-e8e5-441ea15267c8,free]'
85033222144

But if i'm asking about specific VM:

zabbix_get -s 127.0.0.1 -p 12050 -k 'vm.guest.os[422dfb39-a8cb-78e6-6e77-9020a04a3465]'
zabbix_get [30248]: Check access restrictions in Zabbix agent configuration

sysHate avatar Oct 07 '16 12:10 sysHate

These kinds of checks only work if the VM is running VM tools. Is it the same for the vm.status method ? What version of vcenter do you have ?

dav3860 avatar Oct 08 '16 09:10 dav3860

Yes, its the same for vm.status. I tried on machine with updated VM tools running. We have Vcenter 6.0.0 installed (Build 3634793).

sysHate avatar Oct 10 '16 13:10 sysHate

Please try the version I just compiled, v2.6.157 : https://bintray.com/dav3860/generic/vmbix#files/2.6 The log messages should me more verbose.

dav3860 avatar Oct 11 '16 07:10 dav3860 

zabbix_get -s 127.0.0.1 -p 12050 -k vm.status[422dd24f-a8b6-eda6-2b75-96f619461efb]
zabbix_get [12522]: Check access restrictions in Zabbix agent configuration

Log:

13:37:00.933 [main] INFO net.dav3860.VmBix - creating pid file /var/run/vmbix.pid 12579
log4j:WARN No appenders could be found for logger (com.vmware.vim25.ws.XmlGenDom).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
Okt. 11, 2016 1:37:06 NACHM. sun.util.PropertyResourceBundleCharset$PropertiesFileDecoder decodeLoop
INFO: Invalid or unmappable UTF-8 sequence detected. Switching encoding from UTF-8 to ISO-8859-1
13:37:06.838 [main] INFO net.dav3860.VmBix - starting server on 127.0.0.1/12050
13:37:06.839 [main] INFO net.dav3860.VmBix - server started
13:37:19.607 [Thread-2] DEBUG net.dav3860.VmBix - Thread created, collecting data in 1 threads
13:37:19.608 [Thread-2] DEBUG net.dav3860.VmBix - Parsing this request : ZBXD0vm.status[422dd24f-a8b6-eda6-2b75-96f619461efb]
13:37:19.818 [Thread-2] ERROR net.dav3860.VmBix - An error occurred : java.lang.NullPointerException
13:37:19.818 [Thread-2] DEBUG net.dav3860.VmBix - Request took 210 ms
13:37:21.841 [Thread-2] DEBUG net.dav3860.VmBix - Thread closed, collecting data in 0 threads

sysHate avatar Oct 11 '16 11:10 sysHate

After i changed useUUID to false it works fine with servernames. Unfortunately we do have many Hosts with names Zabbix doesn't accept.
And there is one more strange thing (its still useruuid = false):

zabbix_get -s 127.0.0.1 -p 12050 -k 'vm.status[422d7658-128a-ddeb-b22a-ca8ebe80178e]'
4

So i get an false answer. Here is a log file:

15:49:06.982 [Thread-48] DEBUG net.dav3860.VmBix - Thread created, collecting data in 1 threads
15:49:06.982 [Thread-48] DEBUG net.dav3860.VmBix - Parsing this request : ZBXD0vm.status[422d7658-128a-ddeb-b22a-ca8ebe80178e]
15:49:07.118 [Thread-48] WARN net.dav3860.VmBix - No vm named '422d7658-128a-ddeb-b22a-ca8ebe80178e' found
15:49:07.119 [Thread-48] DEBUG net.dav3860.VmBix - Request took 137 ms
15:49:09.141 [Thread-48] DEBUG net.dav3860.VmBix - Thread closed, collecting data in 0 threads

Any ideas?

sysHate avatar Oct 13 '16 13:10 sysHate