Maintained by: NLnet Labs

[Unbound-users] unbound problem

Shahab Yassemi
Tue Sep 30 22:27:08 CEST 2008


Hi , here is the complete situation . my nsd server is like this :


server:
        database: "/var/lib/nsd3/nsd.db"
 zone:

       name: com
       zonefile: /etc/nsd/com.signed

here is com.signed :

com.                    86400   IN      SOA     ns1.admin.com.
admin.admin.com. (
                                       2001062501      ; serial
                                       21600   ; refresh
                                       3600    ; retry
                                       604800  ; expire
                                       86400 ) ; minimum
com.                    86400   IN      RRSIG   SOA 5 1 86400 (
       20081029171558 20080929171558 56464 com.
       FxtCOXYVx9YWO5szwVM/MDuKnH0ns16bB2450cbuds5i6yd9DagbNfhh72a7MbZN
       Y/dwjZuOnS5mfOQ9dtz2Fw== )
com.                    86400   IN      NS      ns1.admin.com.
com.                    86400   IN      NS      ns2.admin.com.
com.                    86400   IN      RRSIG   NS 5 1 86400 (
       20081029171558 20080929171558 56464 com.
       RF4p255XYpmh1a7EcnCp1mZ3u98JeKpD7bn89BylVoeAXftxcZI80WNPGjruXnnG
       1FuCMCMyOEdCTULi3+s63g== )
com.                    86400   IN      A       192.168.1.2
com.                    86400   IN      RRSIG   A 5 1 86400 (
       20081029171558 20080929171558 56464 com.
       LMaPXdXHwVSh2zRNSHJ/Z2thWwNz/FlR2EBzpAG1/1giDwOetNZGsuhPIVxv5o05
       S8+Yuc3or5BGutDlQC0bTw== )
com.                    86400   IN      MX      10 com.
com.                    86400   IN      RRSIG   MX 5 1 86400 (
       20081029171558 20080929171558 56464 com.
       SsuldE6wcnfFuIOgJkLgnX6L7fkg7OklPL2AuEsBxGBntUMUNRbMniSjgOWWZFVq
       ePrWJZomC8M2MCU1JMIMBQ== )
com.                    86400   IN      DNSKEY  256 3 5 (
       AwEAAZI0wtTKSNEwfHSEiy2bTwczt0/yd+s+CgdJa2s29UfWhK4xmJkUNkon+ReZ
       b17+8aJXOHOWUelrT4ofxFEtQ+k= ) ; key_tag = 12993
com.                    86400   IN      DNSKEY  257 3 5 (
       AwEAAbf7W22wjbzQ25cp23q4Kp7QdEOUWiPm5kDVvE2kOUYCyFUI04oIEA2zs1i0
       jHfaTDxkEOQa810eqgBJQAuCyv0= ) ; key_tag = 56464
com.                    86400   IN      RRSIG   DNSKEY 5 1 86400 (
       20081029171558 20080929171558 12993 com.
       KfqtItRdmPM7vbREtw7kINKFOMQ+/XdXsRBEVTO4YMIgA1aNAxNArGBMgEmQAHz3
       tw9ZBH8kxZvEBcaRt+0waw== )
com.                    86400   IN      NSEC3PARAM      1 0 0 -
com.                    86400   IN      RRSIG   NSEC3PARAM 5 1 86400 (
       20081029171558 20080929171558 56464 com.
       YC4EcAJme/mU5wPOjJygGz4xs+K6OkFZM188kytChrlbvK2mz7j4NniI8bNMFwgA
"/etc/nsd/com.signed" 81L, 3957C
       20081029171558 20080929171558 56464 com.
       YC4EcAJme/mU5wPOjJygGz4xs+K6OkFZM188kytChrlbvK2mz7j4NniI8bNMFwgA
       kNqglbCdZUwn20ZLkNrjTg== )
5nkpcr44qom1o40svp3bpgb4gceqlht3.com.   86400   IN      NSEC3   0 1 0
- bhsvqkr675n9sl8mr4glh2lgn0urfftn A RRSIG ; shahab.com.
5nkpcr44qom1o40svp3bpgb4gceqlht3.com.   86400   IN      RRSIG   NSEC3
5 2 86400 (
       20081029171558 20080929171558 56464 com.
       tgWUHEJ5oNrOhbE/27pjrKEtCIwiIAm3IENAyFYRmTdwuahEwBlHyCdivXVxIo19
       UyAXEvARWjfL7i1WmAb74Q== )
ns1.admin.com.          86400   IN      A       192.168.1.2
ns1.admin.com.          86400   IN      RRSIG   A 5 3 86400 (
       20081029171558 20080929171558 56464 com.
       LCXEfaS8D5nD0a3veNi5PTj6KBZvgMnqK0ygWXaGwSxfkvsb1C7STIVtmUdpivNh
       Ukpv0m5Qz8gH1/CJmJsSRQ== )
ns2.admin.com.          86400   IN      A       192.168.1.2
ns2.admin.com.          86400   IN      RRSIG   A 5 3 86400 (
       20081029171558 20080929171558 56464 com.
       kOO3hTsbMXq7OoZnISpa0PLiXrVpA4epN4LI59Umbh3DBDxq0GsDsCkTiLCXwg42
       67T03eXm6op7ceHF7jKhsA== )
bhsvqkr675n9sl8mr4glh2lgn0urfftn.com.   86400   IN      NSEC3   0 1 0
- ck0pojmg874ljref7efn8430qvit8bsm A RRSIG ; ns1.admin.com.
bhsvqkr675n9sl8mr4glh2lgn0urfftn.com.   86400   IN      RRSIG   NSEC3
5 2 86400 (
       20081029171558 20080929171558 56464 com.
       o/uiUp48pLxY1OsbHYtATHizMtWCAMZj6a/mjXs74bPD3goTGy2nvSqIp+TzJQCh
       eGXNyUpd70PxVLn93kV9KA== )
ck0pojmg874ljref7efn8430qvit8bsm.com.   86400   IN      NSEC3   0 1 0
- ehs2b3ghsso4elusv8iv7p8ogr68kbdj A NS SOA MX RRSIG DNSKEY NSEC3PARAM
; com.
ck0pojmg874ljref7efn8430qvit8bsm.com.   86400   IN      RRSIG   NSEC3
5 2 86400 (
       20081029171558 20080929171558 56464 com.
       t4OLRoTQ/XR9D/AKa5XA8iAvwmgnT7eDplQdbVB3k0WNPBKUu5R7unQ2BEjQ7ecG
       YVEzO6/V+nGHSoDbVmGDWQ== )
ehs2b3ghsso4elusv8iv7p8ogr68kbdj.com.   86400   IN      NSEC3   0 1 0
- v9mioqru9qhl112qcvdp6n9ci3sob7c0 ; admin.com.
ehs2b3ghsso4elusv8iv7p8ogr68kbdj.com.   86400   IN      RRSIG   NSEC3
5 2 86400 (
       20081029171558 20080929171558 56464 com.
       XoEBncAchWYwZkj5XFWCM23m2i3ZECwHKKNPVxZTPFht735RRZUXmljMFfcN2fan
       UFPpUOTfOfcpNBKRZ5Hy2g== )
shahab.com.             86400   IN      A       192.168.1.2
shahab.com.             86400   IN      RRSIG   A 5 2 86400 (
       20081029171558 20080929171558 56464 com.
       WgpfY4W4DlYp/4J/T//bGI1WOZOnMau7eRk1cs/DYmImDRcabXmS9Ev0JSdt520N
       niiyQkWuKOCkvWwIXEihBA== )
v9mioqru9qhl112qcvdp6n9ci3sob7c0.com.   86400   IN      NSEC3   0 1 0
- 5nkpcr44qom1o40svp3bpgb4gceqlht3 A RRSIG ; ns2.admin.com.


here is nsd.zone :

com     com.signed







my unbound resolver is like this :





server:
       verbosity: 4
       interface: 0.0.0.0
       port: 53
       do-ip4: yes
       do-ip6: yes
       do-udp: yes
       do-tcp: yes
       do-daemonize: yes
       access-control: 0.0.0.0/0 allow
       #access-control: 0.0.0.0/0 refuse
       #access-control: 127.0.0.0/8 allow
       trust-anchor: "com. IN DNSKEY 257 3 5
AwEAAbf7W22wjbzQ25cp23q4Kp7QdEOUWiPm5kDVvE2kOUYCyFUI04oI
EA2zs1i0jHfaTDxkEOQa810eqgBJQAuCyv0="

       #trust-anchor-file: "/var/unbound/Kcom.db.+005+56464.key"
       #trust-anchor-file: "/var/unbound/Kcom.db.+005+12993.key"
       chroot: "/var/unbound"
       username: "unbound"
       directory: "/var/unbound"
       use-syslog: yes
       pidfile: "/var/run/unbound.pid"
       root-hints: "/var/unbound/named.cache"
       stub-zone:
               name: "com"
               stub-addr: 192.168.1.2



and i get this :

root at shahab-desktop:~# unbound-host -r -d -d -d -d com -v
[1222800093] libunbound[6003:0] debug: switching log to stderr
[1222800093] libunbound[6003:0] debug: module config: "validator iterator"
[1222800093] libunbound[6003:0] notice: init module 0: validator
[1222800093] libunbound[6003:0] debug: validator nsec3cfg keysz 1024 mxiter 150
[1222800093] libunbound[6003:0] debug: validator nsec3cfg keysz 2048 mxiter 500
[1222800093] libunbound[6003:0] debug: validator nsec3cfg keysz 4096 mxiter 2500
[1222800093] libunbound[6003:0] notice: init module 1: iterator
[1222800093] libunbound[6003:0] debug: target fetch policy for level 0 is 3
[1222800093] libunbound[6003:0] debug: target fetch policy for level 1 is 2
[1222800093] libunbound[6003:0] debug: target fetch policy for level 2 is 1
[1222800093] libunbound[6003:0] debug: target fetch policy for level 3 is 0
[1222800093] libunbound[6003:0] debug: target fetch policy for level 4 is 0
[1222800093] libunbound[6003:0] debug: no config, using builtin root hints.
[1222800093] libunbound[6003:0] debug: Forward zone server list:
[1222800093] libunbound[6003:0] info: DelegationPoint<.>: 0 names (0
missing), 1 addrs (0 result, 1 avail)
[1222800093] libunbound[6003:0] info:    ip4 192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: libevent mini-event-1.0.2 uses
select method.
[1222800093] libunbound[6003:0] debug: mesh_run: start
[1222800093] libunbound[6003:0] debug: validator[module 0] operate:
extstate:module_state_initial event:module_event_new
[1222800093] libunbound[6003:0] info: validator operate: query <com. A IN>
[1222800093] libunbound[6003:0] debug: validator: pass to next module
[1222800093] libunbound[6003:0] debug: mesh_run: validator module exit
state is module_wait_module
[1222800093] libunbound[6003:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
[1222800093] libunbound[6003:0] debug: process_request: new external
request event
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state INIT REQUEST STATE
[1222800093] libunbound[6003:0] info: resolving <com. A IN>
[1222800093] libunbound[6003:0] debug: request has dependency depth of 0
[1222800093] libunbound[6003:0] debug: forwarding request
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state QUERY TARGETS STATE
[1222800093] libunbound[6003:0] info: processQueryTargets: <com. A IN>
[1222800093] libunbound[6003:0] debug: processQueryTargets:
targetqueries 0, currentqueries 0
[1222800093] libunbound[6003:0] info: DelegationPoint<.>: 0 names (0
missing), 1 addrs (0 result, 1 avail)
[1222800093] libunbound[6003:0] info:    ip4 192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: attempt to get extra 3 targets
[1222800093] libunbound[6003:0] info: sending query: <com. A IN>
[1222800093] libunbound[6003:0] debug: sending to target: <.> 192.168.1.3#53
[1222800093] libunbound[6003:0] debug: serviced query UDP timeout=376 msec
[1222800093] libunbound[6003:0] debug: inserted new pending reply id=8c03
[1222800093] libunbound[6003:0] debug: opened UDP if=0 port=7973
[1222800093] libunbound[6003:0] debug: comm point start listening 7
[1222800093] libunbound[6003:0] debug: mesh_run: iterator module exit
state is module_wait_reply
[1222800093] libunbound[6003:0] info: mesh_run: end 1 recursion states
(1 with reply, 0 detached), 1 waiting replies, 0 recursion replies
sent
[1222800093] libunbound[6003:0] info: 0RDd mod1 cb <com. A IN>
[1222800093] libunbound[6003:0] debug: answer cb
[1222800093] libunbound[6003:0] debug: Incoming reply id = 8c03
[1222800093] libunbound[6003:0] info: Incoming reply addr = ip4
192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: lookup size is 1 entries
[1222800093] libunbound[6003:0] debug: received udp reply.
[1222800093] libunbound[6003:0] info: udp message[518:0]
8C038190000100020003000503636F6D0000010001C00C000100010001515B0004C0A80102C00C002E00010001515B0057000105010001518049089A4E48E10D4EDC9003636F6D002CC68F5DD5C7C154A1DB344D48727F676B615B0373FC5951D84073A401B5FF58220F039EB4D646B2E84F215C6FE68D394BCF98B9CDE8AF9046BAD0E5402D1B4FC00C000200010001515B000C036E73310561646D696EC00CC00C000200010001515B0006036E7332C098C00C002E00010001515B0057000205010001518049089A4E48E10D4EDC9003636F6D00445E29DB9E576299A1D5AEC47270A9D66677BBDF0978AA43EDB9FCF41CA55687805DFB7171923CD1634F1A
[1222800093] libunbound[6003:0] info: udp message[518:256]
3AEE5E79C6D45B823023323847424D42E2DFEB3ADEC094000100010001515B0004C0A80102C0AC000100010001515B0004C0A80102C094002E00010001515B0057000105030001518049089A4E48E10D4EDC9003636F6D002C25C47DA4BC0F99C3D1ADEF78D8B93D38FA28166F80C9EA2B4CA0597686C12C5F92FB1BD42ED24C856D9947698AF361524A6FD26E50CFC807D7F089989B1245C0AC002E00010001515B0057000105030001518049089A4E48E10D4EDC9003636F6D0090E3B7853B1B317ABB3A8667212A5AD0F2E25EB5690387A93782C8E7D5266E1DC3043C6AD06B03B0291388B097C20E36EBB4F4DDE5E6EA8A7B71E1C5EE32A1B00000291000
[1222800093] libunbound[6003:0] info: udp message[518:512] 000080000000
[1222800093] libunbound[6003:0] debug: outnet handle udp reply
[1222800093] libunbound[6003:0] debug: measured roundtrip at 1 msec
[1222800093] libunbound[6003:0] debug: svcd callbacks start
[1222800093] libunbound[6003:0] debug: mesh_run: start
[1222800093] libunbound[6003:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
[1222800093] libunbound[6003:0] info: iterator operate: query <com. A IN>
[1222800093] libunbound[6003:0] debug: process_response: new external
response event
[1222800093] libunbound[6003:0] info: scrub for <. NS IN>
[1222800093] libunbound[6003:0] info: response for <com. A IN>
[1222800093] libunbound[6003:0] info: reply from <.> 192.168.1.3#53
[1222800093] libunbound[6003:0] info: incoming scrubbed packet: ;;
->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0
;; flags: qr rd ra ; QUERY: 1, ANSWER: 2, AUTHORITY: 3, ADDITIONAL: 4
;; QUESTION SECTION:
;; com. IN      A

;; ANSWER SECTION:
com.    86363   IN      A       192.168.1.2
com.    86363   IN      RRSIG   A 5 1 86400 20081029171558 20080929171558 56464
com. LMaPXdXHwVSh2zRNSHJ/Z2thWwNz/FlR2EBzpAG1/1giDwOetNZGsuhPIVxv5o05S8+Yuc3or5BGutDlQC0bTw==
;{id = 56464}

;; AUTHORITY SECTION:
com.    86363   IN      NS      ns1.admin.com.
com.    86363   IN      NS      ns2.admin.com.
com.    86363   IN      RRSIG   NS 5 1 86400 20081029171558 20080929171558 56464
com. RF4p255XYpmh1a7EcnCp1mZ3u98JeKpD7bn89BylVoeAXftxcZI80WNPGjruXnnG1FuCMCMyOEdCTULi3+s63g==
;{id = 56464}

;; ADDITIONAL SECTION:
ns1.admin.com.  86363   IN      A       192.168.1.2
ns2.admin.com.  86363   IN      A       192.168.1.2
ns1.admin.com.  86363   IN      RRSIG   A 5 3 86400 20081029171558
20080929171558 56464 com.
LCXEfaS8D5nD0a3veNi5PTj6KBZvgMnqK0ygWXaGwSxfkvsb1C7STIVtmUdpivNhUkpv0m5Qz8gH1/CJmJsSRQ==
;{id = 56464}
ns2.admin.com.  86363   IN      RRSIG   A 5 3 86400 20081029171558
20080929171558 56464 com.
kOO3hTsbMXq7OoZnISpa0PLiXrVpA4epN4LI59Umbh3DBDxq0GsDsCkTiLCXwg4267T03eXm6op7ceHF7jKhsA==
;{id = 56464}

;; Query time: 0 msec
;; WHEN: Wed Dec 31 19:00:00 1969
;; MSG SIZE  rcvd: 507

[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state QUERY RESPONSE STATE
[1222800093] libunbound[6003:0] info: query response was ANSWER
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state FINISHED RESPONSE STATE
[1222800093] libunbound[6003:0] info: finishing processing for <com. A IN>
[1222800093] libunbound[6003:0] debug: mesh_run: iterator module exit
state is module_finished
[1222800093] libunbound[6003:0] debug: validator[module 0] operate:
extstate:module_wait_module event:module_event_moddone
[1222800093] libunbound[6003:0] info: validator operate: query <com. A IN>
[1222800093] libunbound[6003:0] debug: validator: nextmodule returned
[1222800093] libunbound[6003:0] debug: val handle processing q with
state VAL_INIT_STATE
[1222800093] libunbound[6003:0] debug: validator classification positive
[1222800093] libunbound[6003:0] debug: val handle processing q with
state VAL_FINISHED_STATE
[1222800093] libunbound[6003:0] debug: mesh_run: validator module exit
state is module_finished
[1222800093] libunbound[6003:0] info: mesh_run: end 0 recursion states
(0 with reply, 0 detached), 0 waiting replies, 0 recursion replies
sent
[1222800093] libunbound[6003:0] debug: svcd callbacks end
[1222800093] libunbound[6003:0] debug: close of port 7973
[1222800093] libunbound[6003:0] debug: close fd 7
com has address 192.168.1.2 (insecure)
[1222800093] libunbound[6003:0] debug: libevent mini-event-1.0.2 uses
select method.
[1222800093] libunbound[6003:0] debug: mesh_run: start
[1222800093] libunbound[6003:0] debug: validator[module 0] operate:
extstate:module_state_initial event:module_event_new
[1222800093] libunbound[6003:0] info: validator operate: query <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: validator: pass to next module
[1222800093] libunbound[6003:0] debug: mesh_run: validator module exit
state is module_wait_module
[1222800093] libunbound[6003:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
[1222800093] libunbound[6003:0] debug: process_request: new external
request event
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state INIT REQUEST STATE
[1222800093] libunbound[6003:0] info: resolving <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: request has dependency depth of 0
[1222800093] libunbound[6003:0] debug: forwarding request
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state QUERY TARGETS STATE
[1222800093] libunbound[6003:0] info: processQueryTargets: <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: processQueryTargets:
targetqueries 0, currentqueries 0
[1222800093] libunbound[6003:0] info: DelegationPoint<.>: 0 names (0
missing), 1 addrs (0 result, 1 avail)
[1222800093] libunbound[6003:0] info:    ip4 192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: attempt to get extra 3 targets
[1222800093] libunbound[6003:0] info: sending query: <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: sending to target: <.> 192.168.1.3#53
[1222800093] libunbound[6003:0] debug: serviced query UDP timeout=284 msec
[1222800093] libunbound[6003:0] debug: inserted new pending reply id=2fd0
[1222800093] libunbound[6003:0] debug: opened UDP if=0 port=26302
[1222800093] libunbound[6003:0] debug: comm point start listening 7
[1222800093] libunbound[6003:0] debug: mesh_run: iterator module exit
state is module_wait_reply
[1222800093] libunbound[6003:0] info: mesh_run: end 1 recursion states
(1 with reply, 0 detached), 1 waiting replies, 0 recursion replies
sent
[1222800093] libunbound[6003:0] info: 0RDd mod1 cb <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: answer cb
[1222800093] libunbound[6003:0] debug: Incoming reply id = 2fd0
[1222800093] libunbound[6003:0] info: Incoming reply addr = ip4
192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: lookup size is 1 entries
[1222800093] libunbound[6003:0] debug: received udp reply.
[1222800093] libunbound[6003:0] info: udp message[183:0]
2FD08190000100000002000103636F6D00001C0001C00C00060001000151800028036E73310561646D696EC00C0561646D696EC0257745CA650000546000000E1000093A8000015180C00C002E0001000151800057000605010001518049089A4E48E10D4EDC9003636F6D00171B42397615C7D6163B9B33C1533F303B8A9C7D27B35E9B076E39D1C6EE76CE62EB277D0DA81B35F861EF66BB31B64D63F7708D9B8E9D2E667CE43D76DCF6170000291000000080000000
[1222800093] libunbound[6003:0] debug: outnet handle udp reply
[1222800093] libunbound[6003:0] debug: measured roundtrip at 12 msec
[1222800093] libunbound[6003:0] debug: svcd callbacks start
[1222800093] libunbound[6003:0] debug: mesh_run: start
[1222800093] libunbound[6003:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
[1222800093] libunbound[6003:0] info: iterator operate: query <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: process_response: new external
response event
[1222800093] libunbound[6003:0] info: scrub for <. NS IN>
[1222800093] libunbound[6003:0] info: response for <com. AAAA IN>
[1222800093] libunbound[6003:0] info: reply from <.> 192.168.1.3#53
[1222800093] libunbound[6003:0] info: incoming scrubbed packet: ;;
->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0
;; flags: qr rd ra ; QUERY: 1, ANSWER: 0, AUTHORITY: 2, ADDITIONAL: 0
;; QUESTION SECTION:
;; com. IN      AAAA

;; ANSWER SECTION:

;; AUTHORITY SECTION:
com.    86400   IN      SOA     ns1.admin.com. admin.admin.com. 2001062501 21600
3600 604800 86400
com.    86400   IN      RRSIG   SOA 5 1 86400 20081029171558
20080929171558 56464
com. FxtCOXYVx9YWO5szwVM/MDuKnH0ns16bB2450cbuds5i6yd9DagbNfhh72a7MbZNY/dwjZuOnS5mfOQ9dtz2Fw==
;{id = 56464}

;; ADDITIONAL SECTION:

;; Query time: 0 msec
;; WHEN: Wed Dec 31 19:00:00 1969
;; MSG SIZE  rcvd: 172

[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state QUERY RESPONSE STATE
[1222800093] libunbound[6003:0] info: query response was ANSWER
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state FINISHED RESPONSE STATE
[1222800093] libunbound[6003:0] info: finishing processing for <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: mesh_run: iterator module exit
state is module_finished
[1222800093] libunbound[6003:0] debug: validator[module 0] operate:
extstate:module_wait_module event:module_event_moddone
[1222800093] libunbound[6003:0] info: validator operate: query <com. AAAA IN>
[1222800093] libunbound[6003:0] debug: validator: nextmodule returned
[1222800093] libunbound[6003:0] debug: val handle processing q with
state VAL_INIT_STATE
[1222800093] libunbound[6003:0] debug: validator classification nodata
[1222800093] libunbound[6003:0] debug: val handle processing q with
state VAL_FINISHED_STATE
[1222800093] libunbound[6003:0] debug: mesh_run: validator module exit
state is module_finished
[1222800093] libunbound[6003:0] info: mesh_run: end 0 recursion states
(0 with reply, 0 detached), 0 waiting replies, 0 recursion replies
sent
[1222800093] libunbound[6003:0] debug: svcd callbacks end
[1222800093] libunbound[6003:0] debug: close of port 26302
[1222800093] libunbound[6003:0] debug: close fd 7
com has no IPv6 address (insecure)
[1222800093] libunbound[6003:0] debug: libevent mini-event-1.0.2 uses
select method.
[1222800093] libunbound[6003:0] debug: mesh_run: start
[1222800093] libunbound[6003:0] debug: validator[module 0] operate:
extstate:module_state_initial event:module_event_new
[1222800093] libunbound[6003:0] info: validator operate: query <com. MX IN>
[1222800093] libunbound[6003:0] debug: validator: pass to next module
[1222800093] libunbound[6003:0] debug: mesh_run: validator module exit
state is module_wait_module
[1222800093] libunbound[6003:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
[1222800093] libunbound[6003:0] debug: process_request: new external
request event
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state INIT REQUEST STATE
[1222800093] libunbound[6003:0] info: resolving <com. MX IN>
[1222800093] libunbound[6003:0] debug: request has dependency depth of 0
[1222800093] libunbound[6003:0] debug: forwarding request
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state QUERY TARGETS STATE
[1222800093] libunbound[6003:0] info: processQueryTargets: <com. MX IN>
[1222800093] libunbound[6003:0] debug: processQueryTargets:
targetqueries 0, currentqueries 0
[1222800093] libunbound[6003:0] info: DelegationPoint<.>: 0 names (0
missing), 1 addrs (0 result, 1 avail)
[1222800093] libunbound[6003:0] info:    ip4 192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: attempt to get extra 3 targets
[1222800093] libunbound[6003:0] info: sending query: <com. MX IN>
[1222800093] libunbound[6003:0] debug: sending to target: <.> 192.168.1.3#53
[1222800093] libunbound[6003:0] debug: serviced query UDP timeout=229 msec
[1222800093] libunbound[6003:0] debug: inserted new pending reply id=fc4f
[1222800093] libunbound[6003:0] debug: opened UDP if=0 port=41316
[1222800093] libunbound[6003:0] debug: comm point start listening 7
[1222800093] libunbound[6003:0] debug: mesh_run: iterator module exit
state is module_wait_reply
[1222800093] libunbound[6003:0] info: mesh_run: end 1 recursion states
(1 with reply, 0 detached), 1 waiting replies, 0 recursion replies
sent
[1222800093] libunbound[6003:0] info: 0RDd mod1 cb <com. MX IN>
[1222800093] libunbound[6003:0] debug: answer cb
[1222800093] libunbound[6003:0] debug: Incoming reply id = fc4f
[1222800093] libunbound[6003:0] info: Incoming reply addr = ip4
192.168.1.3 port 53 (len 16)
[1222800093] libunbound[6003:0] debug: lookup size is 1 entries
[1222800093] libunbound[6003:0] debug: received udp reply.
[1222800093] libunbound[6003:0] info: udp message[640:0]
FC4F8190000100020003000703636F6D00000F0001C00C000F000100015180000B000A06736861686162C00CC00C002E0001000151800057000F05010001518049089A4E48E10D4EDC9003636F6D004ACBA5744EB07277C5B883A02642E09D7E8BEDF920ECE9253CBD80B84B01C46067B543143516CC9E24A380E59664556A78FAD6259A260BC33630253524C20C05C00C0002000100015180000C036E73310561646D696EC00CC00C00020001000151800006036E7332C09FC00C002E0001000151800057000205010001518049089A4E48E10D4EDC9003636F6D00445E29DB9E576299A1D5AEC47270A9D66677BBDF0978AA43EDB9FCF41CA55687805DFB71
[1222800093] libunbound[6003:0] info: udp message[640:256]
71923CD1634F1A3AEE5E79C6D45B823023323847424D42E2DFEB3ADEC02300010001000151800004C0A80102C09B00010001000151800004C0A80102C0B300010001000151800004C0A80102C023002E0001000151800057000105020001518049089A4E48E10D4EDC9003636F6D005A0A5F6385B80E5629FF827F4FFFDB188D563993A731ABBB79193572CFC36262260D171A6D7992F44BF425276DE76D0D9E28B24245AE28E0A4BD6C085C48A104C09B002E0001000151800057000105030001518049089A4E48E10D4EDC9003636F6D002C25C47DA4BC0F99C3D1ADEF78D8B93D38FA28166F80C9EA2B4CA0597686C12C5F92FB1BD42ED24C856D9947698A
[1222800093] libunbound[6003:0] info: udp message[640:512]
F361524A6FD26E50CFC807D7F089989B1245C0B3002E0001000151800057000105030001518049089A4E48E10D4EDC9003636F6D0090E3B7853B1B317ABB3A8667212A5AD0F2E25EB5690387A93782C8E7D5266E1DC3043C6AD06B03B0291388B097C20E36EBB4F4DDE5E6EA8A7B71E1C5EE32A1B00000291000000080000000
[1222800093] libunbound[6003:0] debug: outnet handle udp reply
[1222800093] libunbound[6003:0] debug: measured roundtrip at 2 msec
[1222800093] libunbound[6003:0] debug: svcd callbacks start
[1222800093] libunbound[6003:0] debug: mesh_run: start
[1222800093] libunbound[6003:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
[1222800093] libunbound[6003:0] info: iterator operate: query <com. MX IN>
[1222800093] libunbound[6003:0] debug: process_response: new external
response event
[1222800093] libunbound[6003:0] info: scrub for <. NS IN>
[1222800093] libunbound[6003:0] info: response for <com. MX IN>
[1222800093] libunbound[6003:0] info: reply from <.> 192.168.1.3#53
[1222800093] libunbound[6003:0] info: incoming scrubbed packet: ;;
->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0
;; flags: qr rd ra ; QUERY: 1, ANSWER: 2, AUTHORITY: 3, ADDITIONAL: 6
;; QUESTION SECTION:
;; com. IN      MX

;; ANSWER SECTION:
com.    86400   IN      MX      10 shahab.com.
com.    86400   IN      RRSIG   MX 5 1 86400 20081029171558 20080929171558 56464
com. SsuldE6wcnfFuIOgJkLgnX6L7fkg7OklPL2AuEsBxGBntUMUNRbMniSjgOWWZFVqePrWJZomC8M2MCU1JMIMBQ==
;{id = 56464}

;; AUTHORITY SECTION:
com.    86400   IN      NS      ns1.admin.com.
com.    86400   IN      NS      ns2.admin.com.
com.    86400   IN      RRSIG   NS 5 1 86400 20081029171558 20080929171558 56464
com. RF4p255XYpmh1a7EcnCp1mZ3u98JeKpD7bn89BylVoeAXftxcZI80WNPGjruXnnG1FuCMCMyOEdCTULi3+s63g==
;{id = 56464}

;; ADDITIONAL SECTION:
shahab.com.     86400   IN      A       192.168.1.2
ns1.admin.com.  86400   IN      A       192.168.1.2
ns2.admin.com.  86400   IN      A       192.168.1.2
shahab.com.     86400   IN      RRSIG   A 5 2 86400 20081029171558
20080929171558
56464 com. WgpfY4W4DlYp/4J/T//bGI1WOZOnMau7eRk1cs/DYmImDRcabXmS9Ev0JSdt520NniiyQkWuKOCkvWwIXEihBA==
;{id = 56464}
ns1.admin.com.  86400   IN      RRSIG   A 5 3 86400 20081029171558
20080929171558 56464 com.
LCXEfaS8D5nD0a3veNi5PTj6KBZvgMnqK0ygWXaGwSxfkvsb1C7STIVtmUdpivNhUkpv0m5Qz8gH1/CJmJsSRQ==
;{id = 56464}
ns2.admin.com.  86400   IN      RRSIG   A 5 3 86400 20081029171558
20080929171558 56464 com.
kOO3hTsbMXq7OoZnISpa0PLiXrVpA4epN4LI59Umbh3DBDxq0GsDsCkTiLCXwg4267T03eXm6op7ceHF7jKhsA==
;{id = 56464}

;; Query time: 0 msec
;; WHEN: Wed Dec 31 19:00:00 1969
;; MSG SIZE  rcvd: 629

[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state QUERY RESPONSE STATE
[1222800093] libunbound[6003:0] info: query response was ANSWER
[1222800093] libunbound[6003:0] debug: iter_handle processing q with
state FINISHED RESPONSE STATE
[1222800093] libunbound[6003:0] info: finishing processing for <com. MX IN>
[1222800093] libunbound[6003:0] debug: mesh_run: iterator module exit
state is module_finished
[1222800093] libunbound[6003:0] debug: validator[module 0] operate:
extstate:module_wait_module event:module_event_moddone
[1222800093] libunbound[6003:0] info: validator operate: query <com. MX IN>
[1222800093] libunbound[6003:0] debug: validator: nextmodule returned
[1222800093] libunbound[6003:0] debug: val handle processing q with
state VAL_INIT_STATE
[1222800093] libunbound[6003:0] debug: validator classification positive
[1222800093] libunbound[6003:0] debug: val handle processing q with
state VAL_FINISHED_STATE
[1222800093] libunbound[6003:0] debug: mesh_run: validator module exit
state is module_finished
[1222800093] libunbound[6003:0] info: mesh_run: end 0 recursion states
(0 with reply, 0 detached), 0 waiting replies, 0 recursion replies
sent
[1222800093] libunbound[6003:0] debug: svcd callbacks end
[1222800093] libunbound[6003:0] debug: close of port 41316
[1222800093] libunbound[6003:0] debug: close fd 7
com mail is handled by 10 shahab.com. (insecure)




somebod please tell me why. thanks