[AGENT++] SNMPv3_MP_UNKNOWN_PDU_HANDLERS error
Anurag Jain
anuragjain026 at hotmail.com
Tue Nov 13 18:59:43 CET 2007
I am not sure we are gettng a TIMEOUT , we are actually being returned the status -
SNMPv3_MP_UNKNOWN_PDU_HANDLERS,
Here are some additional snippets of log for msg -id - 1022660...
Thanks
Anurag
---------------------------------------------------------------------------------------------------
20071112.16:48:46: 13123: (1)DEBUG : Setting contextEngineID of Pdu to (192.168.1.161#8001)20071112.16:48:46: 13123: (1)DEBUG : Snmp::snmp_engine: engineID (192.168.1.161#8001), securityName (noAuthUser)securityModel (3) security_level (1)20071112.16:48:46: 13123: (1)DEBUG : Addr/Port (10.104.1.7/161)20071112.16:48:46: 13123: (5)DEBUG : v3MP: Building message with (SecurityEngineID) (securityName) (securityLevel) (contextEngineID) (contextName): (192.168.1.161#8001), (noAuthUser), (1), (192.168.1.161#8001), (noAuth)20071112.16:48:46: 13123: (A)DEBUG : ASN1: coding (context engine id) (context name): (192.168.1.161#8001), (noAuth)20071112.16:48:46: 13123: (A)DEBUG : ASN1: Encoding scoped PDU sequence (len): (64)20071112.16:48:46: 13123: (1)DEBUG : Coding msgID(0xf9ac4), maxMessageSize(0x1000), msgFlags(0x4), securityModel(0x3)20071112.16:48:46: 13123: (1)DEBUG : Coding sequence (headerdata), length = 0xf20071112.16:48:46: 13123: (1)DEBUG : USM::get_user: user (noAuthUser) engine_id (192.168.1.161#8001)20071112.16:48:46: 13123: (1)DEBUG : Coding octstr sp.msgAuthoritativeEngineID, length = 0x1220071112.16:48:46: 13123: (1)DEBUG : Coding int sp.msgAuthoritativeEngineBoots = 0x020071112.16:48:46: 13123: (1)DEBUG : Coding int sp.msgAuthoritativeEngineTime = 0x020071112.16:48:46: 13123: (1)DEBUG : Coding octstr sp.msgUserName, length = 0xa20071112.16:48:46: 13123: (1)DEBUG : Coding octstr sp.msgAu..Para.. , length = 0x020071112.16:48:46: 13123: (1)DEBUG : Coding octstr sp.msgPr..Para.. , length = 0x020071112.16:48:46: 13123: (1)DEBUG : Coding sequence (securityPar), length = 0x2a20071112.16:48:46: 13123: (1)DEBUG : Coding int snmpVersion = 0x320071112.16:48:46: 13123: (1)DEBUG : Coding octstr securityParameter, length = 0x2c20071112.16:48:46: 13123: (1)DEBUG : Coding sequence (wholeMsg), length = 0x8420071112.16:48:46: 13123: (8)INFO : v3MP::Cache: adding new entry (n) (msg id) (req id) (type): (7), (1022660), (8136), (local)20071112.16:48:46: 13123: (3)INFO : v3MP: Message built OK20071112.16:48:46: 13123: (4)DEBUG : SNMPMessage: return value for build message: (-1401)20071112.16:48:46: 13123: (3)DEBUG : 15 30 13 06 0F 2B 06 01 04 01 81 21 03 05 0D 01 20071112.16:48:46: 13123: (1)DEBUG : ++ SNMP++: sending to 10.104.1.7/161:-----------------------------------------------------------------------------------------------------20071112.16:48:46: 13123: (1)DEBUG : ++ SNMP++: something received...20071112.16:48:46: 13123: (1)DEBUG : Length received 152 from socket 6; fromlen 1620071112.16:48:46: 13123: (1)DEBUG : ++ SNMP++: data received from 10.104.1.7/161.20071112.16:48:46: 13123: (3)DEBUG : 30 82 00 94 02 01 03 30 82 00 0F 02 03 0F 9A C4 20071112.16:48:46: 13123: (3)DEBUG : 02 02 10 00 04 01 00 02 01 03 04 2E 30 82 00 2A 20071112.16:48:46: 13123: (3)DEBUG : 04 12 31 39 32 2E 31 36 38 2E 31 2E 31 36 31 23 20071112.16:48:46: 13123: (3)DEBUG : 38 30 30 31 02 01 00 02 01 00 04 0A 6E 6F 41 75 20071112.16:48:46: 13123: (3)DEBUG : 74 68 55 73 65 72 04 00 04 00 30 82 00 4A 04 12 20071112.16:48:46: 13123: (3)DEBUG : 31 39 32 2E 31 36 38 2E 31 2E 31 36 31 23 38 30 20071112.16:48:46: 13123: (3)DEBUG : 30 31 04 06 6E 6F 41 75 74 68 A2 82 00 2A 02 02 20071112.16:48:46: 13123: (3)DEBUG : 1F C8 02 01 00 02 01 00 30 82 00 1C 30 82 00 18 20071112.16:48:46: 13123: (3)DEBUG : 06 10 2B 06 01 04 01 81 21 03 06 16 02 01 04 01 20071112.16:48:46: 13123: (1)DEBUG : mp is parsing incoming message:20071112.16:48:46: 13123: (1)DEBUG : Parsed length(91), version(0x3)20071112.16:48:46: 13123: (1)DEBUG : Parsed HeaderData: globalDataLength(0x0), msg_id(0xf9ac4), msg_max_size(0x1000), msg_flags(0x0), msg_security_model(0x3)20071112.16:48:46: 13123: (1)DEBUG : Parsed msgdata length(0x4e), msgSecurityParameters length(0x2e)20071112.16:48:46: 13123: (1)DEBUG : Parsed securityParametersLength = 0x2a20071112.16:48:46: 13123: (1)DEBUG : Parsed securityEngineID, length = 0x1220071112.16:48:46: 13123: (1)DEBUG : Parsed engineBoots(0x0), engineTime(0x0)20071112.16:48:46: 13123: (1)DEBUG : Parsed usmUserName length(0xa) msgAuthenticationParameters length(0x0) msgPrivacyParameters length(0x0)20071112.16:48:46: 13123: (9)INFO : USMUserNameTable: Translated (user name) to (security name): (noAuthUser), (noAuthUser)20071112.16:48:46: 13123: (1)DEBUG : USM::get_user: user (noAuthUser) engine_id (192.168.1.161#8001)20071112.16:48:46: 13123: (1)DEBUG : ErrorCode is 020071112.16:48:46: 13123: (1)DEBUG : Parsed scoped_pdu: context_engine_id length(0x12), context_name length(0x6)20071112.16:48:46: 13123: (8)INFO : v3MP::Cache: Found entry (n) (msg id) (type): (0), (1022660), (local)20071112.16:48:46: 13123: (A)INFO : v3MP::Cache: Moving entry (from) (to): (1), (0)20071112.16:48:46: 13123: (1)DEBUG : mp finished (OK)20071112.16:48:46: 13123: (1)DEBUG : receive_snmp_response: engine_id (192.168.1.161#8001), security_name (noAuthUser), security_model (3), security_level (1)20071112.16:48:46: 13123: (1)DEBUG : addtoengineidtable: (10.104.1.7/161)20071112.16:48:46: 13123: (1)DEBUG : receive_snmp_response requestID = 8136, returning SUCCESS.20071112.16:48:46: 13123: (1)DEBUG : ++ SNMP++: something received...----------------------------------------------------------------------------------20071112.16:49:01: 13123: (3)INFO : v3MP: Message built OK20071112.16:49:01: 13123: (4)DEBUG : SNMPMessage: return value for build message: (-1401)20071112.16:49:01: 13123: (1)DEBUG : ++ SNMP++: sending to 10.104.1.4/161:20071112.16:49:01: 13123: (3)DEBUG : 30 81 84 02 01 03 30 0F 02 03 0F 9C 31 02 02 10 20071112.16:49:01: 13123: (3)DEBUG : 00 04 01 04 02 01 03 04 2C 30 2A 04 12 31 39 32 20071112.16:49:01: 13123: (3)DEBUG : 2E 31 36 38 2E 31 2E 31 36 31 23 38 30 30 31 02 20071112.16:49:01: 13123: (3)DEBUG : 01 00 02 01 00 04 0A 6E 6F 41 75 74 68 55 73 65 20071112.16:49:01: 13123: (3)DEBUG : 72 04 00 04 00 30 40 04 12 31 39 32 2E 31 36 38 20071112.16:49:01: 13123: (3)DEBUG : 2E 31 2E 31 36 31 23 38 30 30 31 04 06 6E 6F 41 20071112.16:49:01: 13123: (3)DEBUG : 75 74 68 A1 22 02 02 21 35 02 01 00 02 01 00 30 20071112.16:49:01: 13123: (3)DEBUG : 16 30 14 06 10 2B 06 01 04 01 81 21 03 06 16 02 20071112.16:49:01: 13123: (1)DEBUG : ++ SNMP++: something received...20071112.16:49:01: 13123: (1)DEBUG : Length received 152 from socket 6; fromlen 1620071112.16:49:01: 13123: (1)DEBUG : ++ SNMP++: data received from 10.104.1.7/161.20071112.16:49:01: 13123: (3)DEBUG : 30 82 00 94 02 01 03 30 82 00 0F 02 03 0F 9A C4 20071112.16:49:01: 13123: (3)DEBUG : 02 02 10 00 04 01 00 02 01 03 04 2E 30 82 00 2A 20071112.16:49:01: 13123: (3)DEBUG : 04 12 31 39 32 2E 31 36 38 2E 31 2E 31 36 31 23 20071112.16:49:01: 13123: (3)DEBUG : 38 30 30 31 02 01 00 02 01 00 04 0A 6E 6F 41 75 20071112.16:49:01: 13123: (3)DEBUG : 74 68 55 73 65 72 04 00 04 00 30 82 00 4A 04 12 20071112.16:49:01: 13123: (3)DEBUG : 31 39 32 2E 31 36 38 2E 31 2E 31 36 31 23 38 30 20071112.16:49:01: 13123: (3)DEBUG : 30 31 04 06 6E 6F 41 75 74 68 A2 82 00 2A 02 02 20071112.16:49:01: 13123: (3)DEBUG : 1F C8 02 01 00 02 01 00 30 82 00 1C 30 82 00 18 20071112.16:49:01: 13123: (3)DEBUG : 06 10 2B 06 01 04 01 81 21 03 06 16 02 01 04 01 20071112.16:49:01: 13123: (1)DEBUG : mp is parsing incoming message:20071112.16:49:01: 13123: (1)DEBUG : Parsed length(91), version(0x3)20071112.16:49:01: 13123: (1)DEBUG : Parsed HeaderData: globalDataLength(0x0), msg_id(0xf9ac4), msg_max_size(0x1000), msg_flags(0x0), msg_security_model(0x3)20071112.16:49:01: 13123: (1)DEBUG : Parsed msgdata length(0x4e), msgSecurityParameters length(0x2e)20071112.16:49:01: 13123: (1)DEBUG : Parsed securityParametersLength = 0x2a20071112.16:49:01: 13123: (1)DEBUG : Parsed securityEngineID, length = 0x1220071112.16:49:01: 13123: (1)DEBUG : Parsed engineBoots(0x0), engineTime(0x0)20071112.16:49:01: 13123: (1)DEBUG : Parsed usmUserName length(0xa) msgAuthenticationParameters length(0x0) msgPrivacyParameters length(0x0)20071112.16:49:01: 13123: (9)INFO : USMUserNameTable: Translated (user name) to (security name): (noAuthUser), (noAuthUser)20071112.16:49:01: 13123: (1)DEBUG : USM::get_user: user (noAuthUser) engine_id (192.168.1.161#8001)20071112.16:49:01: 13123: (1)DEBUG : ErrorCode is 020071112.16:49:01: 13123: (1)DEBUG : Parsed scoped_pdu: context_engine_id length(0x12), context_name length(0x6)20071112.16:49:01: 13123: (5)WARNING: v3MP::Cache: Entry not found (msg id) (type): (1022660), (local)20071112.16:49:01: 13123: (1)DEBUG : Received rspMsg without outstanding request. -> SnmpUnknownPduHandler
From: anuragjain026 at hotmail.comTo: katz at agentpp.com; anuragjain026 at hotmail.comSubject: RE: [AGENT++] SNMPv3_MP_UNKNOWN_PDU_HANDLERS errorDate: Mon, 12 Nov 2007 17:51:24 -0600
Just fyi I am attaching the error log as well with couple of error messages like : 20071112.16:35:05: 13123: (1)DEBUG : Received rspMsg without outstanding request. -> SnmpUnknownPduHandler Appreciate your help! -Anurag
From: anuragjain026 at hotmail.comTo: katz at agentpp.comSubject: RE: [AGENT++] SNMPv3_MP_UNKNOWN_PDU_HANDLERS errorDate: Mon, 12 Nov 2007 16:46:45 -0600
Thanks !! Could you let me know when you have the fix , any estimate on when it would be ? Also one thing we did notice(may help as a debuging symptom) was that when we put the retry around the Snmpv3 unknown pdu handler we seem to encounter (more often than before )NULL syntax in the vb . int v3Retry=1;while(v3Retry<=3&&status==SNMPv3_MP_UNKNOWN_PDU_HANDLERS){status=m_snmp->get(pdu,*m_readTarget);v3Retry++;}if(status!=SNMP_CLASS_SUCCESS){ success=false;}elsesuccess=true; if(success==true){ pdu.get_vb(vb,0); if(vb.get_syntax()==0x05) // this happens very often after the retry code success=false;} Can the retry of uknown pdu handler cause this somehow ? Regards,Anurag> Date: Mon, 12 Nov 2007 23:35:30 +0100> From: katz at agentpp.com> To: anuragjain026 at hotmail.com> Subject: Re: [AGENT++] SNMPv3_MP_UNKNOWN_PDU_HANDLERS error> > Hi,> > > The fix you had suggested (locking of message id creation ) did not help> > in the issue .I will try to get the complet log to you.> > I could reproduce it here (so I don't need your logs) with the> snmpWalkThreads example. For some unknown reason the response is> received in the v3MP but not processed through the message queue. After> the timeout, the message queue resends the message, and when the> response is received, the v3MP detects the error, as it already> processed the first response (and deleted its status information).> Your application should get a timeout.> > > If we retry the gets or sets in a retry- count 3 loop outside the> > snmp++ library it seems to mitigate the problem> > Yes. The new request will work around the bug. You should set the> retries to zero as a temporary 'solution'.> > > Do you think the snmp++ retry mechanism using set_retry method of> > UTarget class could also mitigate the problem if set to a larger value> > i.e 3 or more or it will> > have no impact ?> > Setting a higher retry count will not change anything, as all retries> will trigger the unknown pdu handler error.> > I have to do some more testing, as Ifrom the logs I cannot see where the> message is dropped.> > Regards,> Jochen
Peek-a-boo FREE Tricks & Treats for You! Get 'em!
Windows Live Hotmail and Microsoft Office Outlook – together at last. Get it now!
_________________________________________________________________
Climb to the top of the charts! Play Star Shuffle: the word scramble challenge with star power.
http://club.live.com/star_shuffle.aspx?icid=starshuffle_wlmailtextlink_oct
More information about the AGENTPP
mailing list