[SNMP4J] agent with own mib
Martin Fischer
fischerm at it.uts.edu.au
Tue Jan 15 03:32:45 CET 2008
Hi everybody,
I posted earlier in December that I have some issues with our own mib. I
think I have made some progress since then but still it doesn't work
properly.
The MIB contains a scalar that holds an IP adress. I can see in the log
that the value of the scalar is set correctly
org.snmp4j.agent.mo.MOScalar[oid=1.3.6.1.3.1.1.0,access=org.snmp4j.agent.mo.MOAccessImpl at 1837697,value=138.25.13.159,volatile=false]
When I want to poll OID 1.3.6.1.3.1.1.0 with a MIB browser the logs also
look ok
62750 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.transport.DefaultUdpTransportMapping - Received message from
/127.0.0.1/2386 with length 73:
30:47:02:01:03:30:10:02:04:2a:88:11:3f:02:02:40:00:04:01:04:02:01:03:04:1a:30:18:04:00:02:01:00:02:04:47:8c:18:7f:04:07:69:6e:69:74:69:61:6c:04:00:04:00:30:14:04:00:04:00:a0:0e:02:04:2a:88:11:3f:02:01:00:02:01:00:30:00
62750 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- SNMPv3 header decoded: msgId=713560383, msgMaxSize=16384,
msgFlags=04, secModel=3
62750 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.USM - RFC3414 §3.2.3 Unknown engine ID:
62750 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- Adding cache entry:
StateReference[msgID=713560383,pduHandle=PduHandle[1472516069],securityEngineID=,securityModel=org.snmp4j.security.USM at 1ef9f1d,securityName=initial,securityLevel=1,contextEngineID=,contextName=]
62760 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- Removed cache entry:
StateReference[msgID=713560383,pduHandle=PduHandle[1472516069],securityEngineID=,securityModel=org.snmp4j.security.USM at 1ef9f1d,securityName=initial,securityLevel=1,contextEngineID=,contextName=]
62760 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.USM - RFC3414 §3.1.4.b Outgoing message is not
encrypted
62760 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.transport.DefaultUdpTransportMapping - Sending message to
127.0.0.1/2386 with length 94:
30:5c:02:01:03:30:11:02:04:2a:88:11:3f:02:03:00:ff:ff:04:01:00:02:01:03:04:20:30:1e:04:09:80:00:13:70:01:8a:19:0d:55:02:01:00:02:01:00:04:07:69:6e:69:74:69:61:6c:04:00:04:00:30:22:04:00:04:00:a8:1c:02:01:00:02:01:00:02:01:00:30:11:30:0f:06:0a:2b:06:01:06:03:0f:01:01:04:00:41:01:05
62770 [DefaultUDPTransportMapping_0.0.0.0/161] WARN
org.snmp4j.MessageDispatcherImpl - 1.3.6.1.6.3.15.1.1.4.0 = 5
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.transport.DefaultUdpTransportMapping - Received message from
/127.0.0.1/2386 with length 97:
30:5f:02:01:03:30:10:02:04:2a:88:11:41:02:02:40:00:04:01:05:02:01:03:04:29:30:27:04:09:80:00:13:70:01:8a:19:0d:55:02:01:00:02:01:00:04:04:54:45:53:54:04:0c:ca:81:44:b4:5f:ee:2d:df:4d:06:6b:19:04:00:30:1d:04:09:80:00:13:70:01:8a:19:0d:55:04:00:a0:0e:02:04:2a:88:11:41:02:01:00:02:01:00:30:00
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- SNMPv3 header decoded: msgId=713560385, msgMaxSize=16384,
msgFlags=05, secModel=3
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.USM - getUser(engineID=80:00:13:70:01:8a:19:0d:55,
securityName=TEST)
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.UsmTimeTable - CheckTime: received message outside
time window (authorative):engineBoots differ
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.USM - RFC3414 §3.2.7.a Not in time window;
engineID='80:00:13:70:01:8a:19:0d:55', engineBoots=0, engineTime=0
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- Adding cache entry:
StateReference[msgID=713560385,pduHandle=PduHandle[1472516070],securityEngineID=80:00:13:70:01:8a:19:0d:55,securityModel=org.snmp4j.security.USM at 1ef9f1d,securityName=TEST,securityLevel=2,contextEngineID=,contextName=]
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- Removed cache entry:
StateReference[msgID=713560385,pduHandle=PduHandle[1472516070],securityEngineID=80:00:13:70:01:8a:19:0d:55,securityModel=org.snmp4j.security.USM at 1ef9f1d,securityName=TEST,securityLevel=2,contextEngineID=,contextName=]
62770 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.USM - RFC3414 §3.1.4.b Outgoing message is not
encrypted
62780 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.transport.DefaultUdpTransportMapping - Sending message to
127.0.0.1/2386 with length 103:
30:65:02:01:03:30:11:02:04:2a:88:11:41:02:03:00:ff:ff:04:01:01:02:01:03:04:29:30:27:04:09:80:00:13:70:01:8a:19:0d:55:02:01:4b:02:01:3e:04:04:54:45:53:54:04:0c:8f:d5:af:ea:8d:0e:8c:3e:58:87:4a:22:04:00:30:22:04:00:04:00:a8:1c:02:01:00:02:01:00:02:01:00:30:11:30:0f:06:0a:2b:06:01:06:03:0f:01:01:02:00:41:01:05
62790 [DefaultUDPTransportMapping_0.0.0.0/161] WARN
org.snmp4j.MessageDispatcherImpl - 1.3.6.1.6.3.15.1.1.2.0 = 5
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.transport.DefaultUdpTransportMapping - Received message from
/127.0.0.1/2386 with length 124:
30:7a:02:01:03:30:10:02:04:2a:88:11:3e:02:02:40:00:04:01:07:02:01:03:04:31:30:2f:04:09:80:00:13:70:01:8a:19:0d:55:02:01:4b:02:01:3e:04:04:54:45:53:54:04:0c:77:13:d8:b0:f4:66:f2:57:e7:80:dd:51:04:08:37:70:07:07:57:f0:6f:32:04:30:3a:67:da:15:13:e5:d0:7c:92:db:c1:9c:bc:ba:bf:57:d0:a4:20:41:a2:10:57:5a:8b:28:2e:94:67:ee:fe:7f:77:4c:5a:10:a1:9a:14:62:f4:67:db:98:76:7d:03:fb
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- SNMPv3 header decoded: msgId=713560382, msgMaxSize=16384,
msgFlags=07, secModel=3
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.USM - getUser(engineID=80:00:13:70:01:8a:19:0d:55,
securityName=TEST)
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG
org.snmp4j.security.UsmTimeTable - CheckTime: time ok (authorative)
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- RFC3412 §7.2.10 - Received PDU is NOT a response or internal class
message -> unchanged PduHandle = PduHandle[713560382]
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.mp.MPv3
- Adding cache entry:
StateReference[msgID=713560382,pduHandle=PduHandle[713560382],securityEngineID=80:00:13:70:01:8a:19:0d:55,securityModel=org.snmp4j.security.USM at 1ef9f1d,securityName=TEST,securityLevel=3,contextEngineID=80:00:13:70:01:8a:19:0d:55,contextName=]
62790 [DefaultUDPTransportMapping_0.0.0.0/161] DEBUG org.snmp4j.Snmp -
Fire process PDU event:
CommandResponderEvent[transportMapping=org.snmp4j.transport.DefaultUdpTransportMapping at b5dac4peerAddress=127.0.0.1/2386,
processed=false, pdu=[GET[reqestID=713560382, errorStatus=0,
errorIndex=0, VBS[1.3.6.1.3.1.1.0 = Null]]], securityName=TEST,
securityModel=3, securityLevel=3]
62790 [RequestPool.0] DEBUG org.snmp4j.agent.mo.snmp.VacmMIB - Found
group name 'v3test' for secName 'TEST and secModel 3
62790 [RequestPool.0] DEBUG org.snmp4j.agent.mo.snmp.VacmMIB - Got
views
[DefaultMOMutableRow2PC[index=6.118.51.116.101.115.116.0.3.3,values=[1,
testReadView, testWriteView, testNotifyView, 3, 1]] for group name 'v3test'
62790 [RequestPool.0] DEBUG org.snmp4j.agent.mo.snmp.VacmMIB - Matching
view found for group name 'v3test' is 'testReadView'
62790 [RequestPool.0] DEBUG org.snmp4j.agent.request.SnmpRequest -
Created subrequest 0 with scope
org.snmp4j.agent.DefaultMOContextScope[context=,lowerBound=1.3.6.1.3.1.1.0,lowerIncluded=true,upperBound=1.3.6.1.3.1.1.0,upperIncluded=true]
from 1.3.6.1.3.1.1.0 = Null
62790 [RequestPool.0] DEBUG org.snmp4j.agent.request.SnmpRequest -
SnmpSubRequests initialized:
[org.snmp4j.agent.request.SnmpRequest$SnmpSubRequest[scope=org.snmp4j.agent.DefaultMOContextScope[context=,lowerBound=1.3.6.1.3.1.1.0,lowerIncluded=true,upperBound=1.3.6.1.3.1.1.0,upperIncluded=true],vb=1.3.6.1.3.1.1.0
=
Null,status=org.snmp4j.agent.request.RequestStatus at 290fbc,query=null,index=0,targetMO=null]]
62790 [RequestPool.0] DEBUG org.snmp4j.mp.MPv3 - Removed cache entry:
StateReference[msgID=713560382,pduHandle=PduHandle[713560382],securityEngineID=80:00:13:70:01:8a:19:0d:55,securityModel=org.snmp4j.security.USM at 1ef9f1d,securityName=TEST,securityLevel=3,contextEngineID=80:00:13:70:01:8a:19:0d:55,contextName=]
62790 [RequestPool.0] DEBUG org.snmp4j.security.USM - RFC3414 §3.1.4.a
Outgoing message needs to be encrypted
62790 [RequestPool.0] DEBUG org.snmp4j.security.PrivDES - Preparing
decrypt_params.
62790 [RequestPool.0] DEBUG org.snmp4j.security.PrivDES - Preparing iv
for encryption.
62790 [RequestPool.0] DEBUG org.snmp4j.security.PrivDES - Using padding.
62790 [RequestPool.0] DEBUG org.snmp4j.security.PrivDES - Encryption
finished.
62790 [RequestPool.0] DEBUG
org.snmp4j.transport.DefaultUdpTransportMapping - Sending message to
127.0.0.1/2386 with length 125:
30:7b:02:01:03:30:11:02:04:2a:88:11:3e:02:03:00:ff:ff:04:01:03:02:01:03:04:31:30:2f:04:09:80:00:13:70:01:8a:19:0d:55:02:01:4b:02:01:3e:04:04:54:45:53:54:04:0c:a0:dd:ca:cb:35:f9:83:3c:e0:57:d8:a3:04:08:00:00:00:4b:ce:52:49:66:04:30:7d:f4:96:b1:47:38:2d:4e:ea:5b:23:c8:6c:65:20:61:97:8e:8c:f7:a1:3d:da:c5:97:b3:a7:1e:da:ba:51:c5:e1:d0:6d:4e:86:e2:a7:a5:1f:01:f4:89:ce:4c:a2:26
The right user and view is recognized but the MIB browser says:
SNMP no such object
Any ideas?
Thanks in advance
Martin
More information about the SNMP4J
mailing list