RE: No module available to handle lease-query

16 May 2019


      Now process crashed upon receiving lease query...

# /sbin/radiusd -X
Info  : FreeRADIUS Version 4.0.0
Info  : Copyright 1999-2019 The FreeRADIUS server project and contributors
Info  : There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
Info  : PARTICULAR PURPOSE
Info  : You may redistribute copies of FreeRADIUS under the terms of the
Info  : GNU General Public License
Info  : For more information about these matters, see the file named COPYRIGHT
Info  : Starting - reading configuration files ...
Debug : Including dictionary file "/etc/raddb/dictionary"
Debug : including configuration file /etc/raddb/radiusd.conf
Debug : including configuration file /etc/raddb/clients.conf
Debug : Including files in directory "/etc/raddb/mods-enabled/"
Debug : including configuration file /etc/raddb/mods-enabled/redis
Debug : including configuration file /etc/raddb/mods-enabled/redis_ippool
Debug : including configuration file /etc/raddb/mods-enabled/stats
Debug : including configuration file /etc/raddb/mods-enabled/dhcpv4
Debug : including configuration file /etc/raddb/mods-enabled/always
Debug : Including files in directory "/etc/raddb/policy.d/"
Debug : including configuration file /etc/raddb/policy.d/abfab-tr
Debug : including configuration file /etc/raddb/policy.d/accounting
Debug : including configuration file /etc/raddb/policy.d/canonicalization
Debug : including configuration file /etc/raddb/policy.d/control
Debug : including configuration file /etc/raddb/policy.d/cui
Debug : including configuration file /etc/raddb/policy.d/debug
Debug : including configuration file /etc/raddb/policy.d/dhcp
Debug : including configuration file /etc/raddb/policy.d/eap
Debug : including configuration file /etc/raddb/policy.d/filter
Debug : including configuration file /etc/raddb/policy.d/operator-name
Debug : including configuration file /etc/raddb/policy.d/time
Debug : including configuration file /etc/raddb/policy.d/vendor
Debug : Including files in directory "/etc/raddb/sites-enabled/"
Debug : including configuration file /etc/raddb/sites-enabled/dhcp
Info  : Loaded module "proto_dhcpv4"
Debug : Parsing security rules to bootstrap UID / GID / chroot / etc.
Debug : main {
Debug :   security {
Debug :     allow_core_dumps = no
Debug :     allow_vulnerable_openssl = "no"
Debug :     openssl_fips_mode = no
Debug :   }
Debug :   name = radiusd
Debug :   name = "radiusd"
Debug :   prefix = "/usr"
Debug :   local_state_dir = "/usr/var"
Debug :   run_dir = "/var/run/radiusd"
Debug : }
Debug : Parsing main configuration.
Debug : main {
Debug :   server dhcp {
Debug :     listen {
Debug :       type = DHCP-Discover
Info  : Loaded module "proto_dhcpv4_base"
Debug :       type = DHCP-Request
Debug :       type = DHCP-Inform
Debug :       type = DHCP-Release
Debug :       type = DHCP-Decline
Debug :       type = DHCP-Lease-Query
Debug :       transport = udp
Info  : Loaded module "proto_dhcpv4_udp"
Debug :       udp {
Debug :         ipaddr = 10.43.18.107
Debug :         src_ipaddr = 10.43.18.107
Debug :         port = 67
Debug :         broadcast = no
Debug :         networks {
Debug :         }
Debug :         max_packet_size = 4096
Debug :         max_attributes = 0
Debug :       }
Debug :       limit {
Debug :         idle_timeout = 30.000000
Debug :         nak_lifetime = 30.000000
Debug :         max_connections = 1024
Debug :         max_clients = 256
Debug :         max_pending_packets = 256
Debug :         priority {
Debug :           DHCP-Discover = normal
Debug :           DHCP-Request = normal
Debug :           DHCP-Decline = normal
Debug :           DHCP-Release = normal
Debug :           DHCP-Inform = normal
Debug :           DHCP-Lease-Query = low
Debug :           DHCP-Bulk-Lease-Query = low
Debug :         }
Debug :       }
Debug :     }
Debug :   }
Debug :   security {
Debug :   }
Debug :   sbin_dir = "/usr/sbin"
Debug :   logdir = "/var/log/radius"
Debug :   libdir = "/usr/lib64/freeradius"
Debug :   radacctdir = "/var/log/radius/radacct"
Debug :   reverse_lookups = no
Debug :   reverse_lookups = no
Debug :   hostname_lookups = yes
Debug :   hostname_lookups = yes
Debug :   max_request_time = 30
Debug :   max_request_time = 30
Debug :   pidfile = "/var/run/radiusd/radiusd.pid"
Debug :   debug_level = 0
Debug :   log {
Debug :     colourise = yes
Debug :   }
Debug :   resources {
Debug :   }
Debug :   thread pool {
Debug :     num_networks = 1
Debug :     num_networks = 1
Debug :     num_workers = 4
Debug :     num_workers = 4
Debug :   }
Debug : }
Switching to configured log settings
radiusd: #### Loading Clients ####
  client localhost {
    ipaddr = 127.0.0.1
    require_message_authenticator = no
    secret = <<< secret >>>
    proto = "*"
    limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
    }
  }
  client localhost_ipv6 {
    ipv6addr = ::1
    require_message_authenticator = no
    secret = <<< secret >>>
    limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
    }
  }
Debugger not attached
trigger { ... } subsection not found, triggers will be disabled
systemd watchdog is disabled
#### Bootstrapping listeners ####
#### Bootstrapping modules ####
modules {
libfreeradius-redis: libhiredis version: 0.12.1
Loaded module "rlm_redis"
    redis {
      server = "devstack08-db-eval1-0001-001.oovb0g.0001.usw2.cache.amazonaws.com"
      port = 6379
      database = 0
      max_nodes = 20
      max_alt = 3
      max_redirects = 2
    }
Bootstrapping module "redis"
libfreeradius-redis: libhiredis version: 0.12.1
Loaded module "rlm_redis_ippool"
    redis_ippool {
      copy_on_update = yes
      redis {
        server = "devstack08-db-eval1-0001-001.oovb0g.0001.usw2.cache.amazonaws.com"
        port = 6379
        database = 0
        max_nodes = 20
        max_alt = 3
        max_redirects = 2
      }
    }
Loaded module "rlm_stats"
    stats {
    }
Loaded module "rlm_dhcpv4"
Loaded module "rlm_always"
    always reject {
      rcode = "reject"
      simulcount = 0
      mpp = no
    }
    always fail {
      rcode = "fail"
      simulcount = 0
      mpp = no
    }
    always ok {
      rcode = "ok"
      simulcount = 0
      mpp = no
    }
    always handled {
      rcode = "handled"
      simulcount = 0
      mpp = no
    }
    always invalid {
      rcode = "invalid"
      simulcount = 0
      mpp = no
    }
    always userlock {
      rcode = "userlock"
      simulcount = 0
      mpp = no
    }
    always notfound {
      rcode = "notfound"
      simulcount = 0
      mpp = no
    }
    always noop {
      rcode = "noop"
      simulcount = 0
      mpp = no
    }
    always updated {
      rcode = "updated"
      simulcount = 0
      mpp = no
    }
  instantiate {
  }
} # modules
#### Instantiating listeners ####
Compiling policies in server dhcp { ... }
Compiling policies in - recv DHCP-Discover {...}
Compiling policies in - recv DHCP-Request {...}
Compiling policies in - recv DHCP-Release {...}
Compiling policies in - recv DHCP-Inform {...}
Compiling policies in - recv DHCP-Lease-Query {...}
/etc/raddb/sites-enabled/dhcp[304]: recv DHCP-Decline { ... } section is unused
#### Instantiating modules ####
Instantiating module "fail"
Instantiating module "handled"
Instantiating module "invalid"
Instantiating module "noop"
Instantiating module "notfound"
Instantiating module "ok"
Instantiating module "redis"
rlm_redis (redis) [1] - Initialising connection pool
      pool {
        start = 4
        min = 4
        max = 4
        max_pending = 0
        spare = 1
        uses = 0
        lifetime = 86400
        cleanup_interval = 300
        idle_timeout = 600
        connect_timeout = 3.000000
        held_trigger_min = 0.000000
        held_trigger_max = 0.500000
       retry_delay = 30
        spread = no
      }
rlm_redis (redis) [1] - Ignoring "spare = 1", forcing to "spare = 0"
rlm_redis (redis) [1] - Opening additional connection (0), 1 of 4 pending slots used
rlm_redis (redis) - [1] Connecting to node 10.43.17.86:6379
rlm_redis (redis) [1] - Opening additional connection (1), 1 of 3 pending slots used
rlm_redis (redis) - [1] Connecting to node 10.43.17.86:6379
rlm_redis (redis) [1] - Opening additional connection (2), 1 of 2 pending slots used
rlm_redis (redis) - [1] Connecting to node 10.43.17.86:6379
rlm_redis (redis) [1] - Opening additional connection (3), 1 of 1 pending slots used
rlm_redis (redis) - [1] Connecting to node 10.43.17.86:6379
rlm_redis (redis) [1] - Reserved connection (3)
rlm_redis (redis) [1] - Released connection (3)
rlm_redis (redis) - Cluster map consists of 1 key ranges
rlm_redis (redis) - 0 - keys 0-16383
rlm_redis (redis) -  master: 10.43.17.86:6379
rlm_redis (redis) -  slave0: 10.43.16.148:6379
rlm_redis (redis) -  slave1: 10.43.16.109:6379
rlm_redis (redis) [2] - Initialising connection pool
      pool {
        start = 4
        min = 4
        max = 4
        max_pending = 0
        spare = 1
        uses = 0
        lifetime = 86400
        cleanup_interval = 300
        idle_timeout = 600
        connect_timeout = 3.000000
        held_trigger_min = 0.000000
        held_trigger_max = 0.500000
        retry_delay = 30
        spread = no
      }
rlm_redis (redis) [2] - Ignoring "spare = 1", forcing to "spare = 0"
rlm_redis (redis) [2] - Opening additional connection (0), 1 of 4 pending slots used
rlm_redis (redis) - [2] Connecting to node 10.43.16.148:6379
rlm_redis (redis) [2] - Opening additional connection (1), 1 of 3 pending slots used
rlm_redis (redis) - [2] Connecting to node 10.43.16.148:6379
rlm_redis (redis) [2] - Opening additional connection (2), 1 of 2 pending slots used
rlm_redis (redis) - [2] Connecting to node 10.43.16.148:6379
rlm_redis (redis) [2] - Opening additional connection (3), 1 of 1 pending slots used
rlm_redis (redis) - [2] Connecting to node 10.43.16.148:6379
rlm_redis (redis) [3] - Initialising connection pool
      pool {
        start = 4
        min = 4
        max = 4
        max_pending = 0
        spare = 1
        uses = 0
        lifetime = 86400
        cleanup_interval = 300
        idle_timeout = 600
        connect_timeout = 3.000000
        held_trigger_min = 0.000000
       held_trigger_max = 0.500000
        retry_delay = 30
        spread = no
      }
rlm_redis (redis) [3] - Ignoring "spare = 1", forcing to "spare = 0"
rlm_redis (redis) [3] - Opening additional connection (0), 1 of 4 pending slots used
rlm_redis (redis) - [3] Connecting to node 10.43.16.109:6379
rlm_redis (redis) [3] - Opening additional connection (1), 1 of 3 pending slots used
rlm_redis (redis) - [3] Connecting to node 10.43.16.109:6379
rlm_redis (redis) [3] - Opening additional connection (2), 1 of 2 pending slots used
rlm_redis (redis) - [3] Connecting to node 10.43.16.109:6379
rlm_redis (redis) [3] - Opening additional connection (3), 1 of 1 pending slots used
rlm_redis (redis) - [3] Connecting to node 10.43.16.109:6379
Instantiating module "redis_ippool"
rlm_redis (redis) [1] - Initialising connection pool
        pool {
          start = 0
          min = 4
          max = 4
          max_pending = 0
          spare = 1
          uses = 0
          lifetime = 0
          cleanup_interval = 30
          idle_timeout = 60
          connect_timeout = 3.000000
          held_trigger_min = 0.000000
          held_trigger_max = 0.500000
          retry_delay = 30
          spread = no
        }
rlm_redis (redis) [1] - Ignoring "spare = 1", forcing to "spare = 0"
rlm_redis (redis) [1] - 0 of 0 connections in use.  You  may need to increase "spare"
rlm_redis (redis) [1] - Opening additional connection (0), 1 of 4 pending slots used
rlm_redis (redis) - [1] Connecting to node 10.43.17.86:6379
rlm_redis (redis) [1] - Reserved connection (0)
rlm_redis (redis) [1] - Released connection (0)
rlm_redis (redis) [1] - Need 3 more connections to reach min connections (4)
rlm_redis (redis) [1] - Opening additional connection (1), 1 of 3 pending slots used
rlm_redis (redis) - [1] Connecting to node 10.43.17.86:6379
Instantiating module "reject"
Instantiating module "stats"
Instantiating module "updated"
Instantiating module "userlock"
Scheduler created in single-threaded mode
#### Opening listener interfaces ####
Listening on dhcpv4 address proto_dhcpv4_udp server 10.43.18.107 port 67 bound to virtual server dhcp
Ready to process requests
Ready to process requests
Ready to process requests
proto_dhcpv4_udp - Received DHCP-Lease-Query XID 0a000029 length 311 proto_dhcpv4_udp server 10.43.18.107 port 67
Resetting worker 30 cleanup timer to +0s
(0)  running request
ASSERT FAILED src/modules/proto_dhcpv4/proto_dhcpv4_base.c[132]: request->packet->code <= FR_DHCP_INFORM
CAUGHT SIGNAL: Aborted
Backtrace of last 9 frames:
/usr/lib64/freeradius/libfreeradius-util.so(fr_fault+0xa2)[0x7faa690e94a2]
/usr/lib64/freeradius/libfreeradius-util.so(+0x12a14)[0x7faa690e9a14]
/usr/lib64/freeradius/proto_dhcpv4_base.so(+0x1a9c)[0x7faa6321ca9c]
/usr/lib64/freeradius/libfreeradius-io.so(+0xaabd)[0x7faa6933babd]
/usr/lib64/freeradius/libfreeradius-util.so(fr_event_service+0x1f8)[0x7faa690f4998]
/usr/lib64/freeradius/libfreeradius-util.so(fr_event_loop+0x20)[0x7faa690f4da0]
/sbin/radiusd(main+0xcc1)[0x404e41]
/lib64/libc.so.6(__libc_start_main+0xf5)[0x7faa6710c445]
/sbin/radiusd[0x405326]
No panic action set
Aborted

Regards,
Nagamani Chinnapaiyan

RE: No module available to handle lease-query

Chinnapaiyan, Nagamani