Re: Problem to copy attribute Tunnel-Private-Group-ID to inner-tunnel (eap) - freeradius 3.0.7

2 Mar 2015

      Hello Alan,

I'm sorry, I have read and reread the full debug, and I could not see the
problem.
My knowledge in freeradius is intermediate, could you give me some more
help analyzing the full debug and me indicating where should I look for the
solution.

For me it's very strange, because at my previous version (3.0.1) everything
worked well.

At bellow the full debug.

Thanks for your help.

Copyright (C) 1999-2015 The FreeRADIUS server project and contributors
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A
PARTICULAR PURPOSE
You may redistribute copies of FreeRADIUS under the terms of the
GNU General Public License
For more information about these matters, see the file named COPYRIGHT
Starting - reading configuration files ...
including dictionary file /usr/share/freeradius/dictionary
including dictionary file /usr/share/freeradius/dictionary.dhcp
including dictionary file /usr/share/freeradius/dictionary.vqp
including dictionary file /etc/raddb/dictionary
including configuration file /etc/raddb/radiusd.conf
including configuration file /etc/raddb/proxy.conf
including configuration file /etc/raddb/clients.conf
including files in directory /etc/raddb/mods-enabled/
including configuration file /etc/raddb/mods-enabled/always
including configuration file /etc/raddb/mods-enabled/attr_filter
including configuration file /etc/raddb/mods-enabled/cache_eap
including configuration file /etc/raddb/mods-enabled/chap
including configuration file /etc/raddb/mods-enabled/detail
including configuration file /etc/raddb/mods-enabled/detail.log
including configuration file /etc/raddb/mods-enabled/dhcp
including configuration file /etc/raddb/mods-enabled/digest
including configuration file /etc/raddb/mods-enabled/dynamic_clients
including configuration file /etc/raddb/mods-enabled/eap
including configuration file /etc/raddb/mods-enabled/echo
including configuration file /etc/raddb/mods-enabled/exec
including configuration file /etc/raddb/mods-enabled/expiration
including configuration file /etc/raddb/mods-enabled/expr
including configuration file /etc/raddb/mods-enabled/files
including configuration file /etc/raddb/mods-enabled/linelog
including configuration file /etc/raddb/mods-enabled/logintime
including configuration file /etc/raddb/mods-enabled/mschap
including configuration file /etc/raddb/mods-enabled/ntlm_auth
including configuration file /etc/raddb/mods-enabled/pap
including configuration file /etc/raddb/mods-enabled/passwd
including configuration file /etc/raddb/mods-enabled/preprocess
including configuration file /etc/raddb/mods-enabled/radutmp
including configuration file /etc/raddb/mods-enabled/realm
including configuration file /etc/raddb/mods-enabled/replicate
including configuration file /etc/raddb/mods-enabled/soh
including configuration file /etc/raddb/mods-enabled/sradutmp
including configuration file /etc/raddb/mods-enabled/unix
including configuration file /etc/raddb/mods-enabled/unpack
including configuration file /etc/raddb/mods-enabled/utf8
including configuration file /etc/raddb/mods-enabled/sql
including configuration file
/etc/raddb/mods-config/sql/main/postgresql/queries.conf
including configuration file
/etc/raddb/mods-config/sql/main/postgresql/queries.conf
including configuration file
/etc/raddb/mods-config/sql/main/postgresql/queries.conf
including configuration file /etc/raddb/mods-enabled/ldap
including files in directory /etc/raddb/policy.d/
including configuration file /etc/raddb/policy.d/abfab-tr
including configuration file /etc/raddb/policy.d/accounting
including configuration file /etc/raddb/policy.d/canonicalization
including configuration file /etc/raddb/policy.d/control
including configuration file /etc/raddb/policy.d/cui
including configuration file /etc/raddb/policy.d/debug
including configuration file /etc/raddb/policy.d/dhcp
including configuration file /etc/raddb/policy.d/eap
including configuration file /etc/raddb/policy.d/filter
including configuration file /etc/raddb/policy.d/operator-name
including files in directory /etc/raddb/sites-enabled/
including configuration file /etc/raddb/sites-enabled/default
including configuration file /etc/raddb/sites-enabled/inner-tunnel
main {
 security {
     user = "radiusd"
     group = "radiusd"
     allow_core_dumps = no
 }
}
main {
    name = "radiusd"
    prefix = "/usr"
    localstatedir = "/var"
    sbindir = "/usr/sbin"
    logdir = "/var/log/radius"
    run_dir = "/var/run/radiusd"
    libdir = "/usr/lib64/freeradius"
    radacctdir = "/var/log/radius/radacct"
    hostname_lookups = no
    max_request_time = 30
    cleanup_delay = 5
    max_requests = 1024
    pidfile = "/var/run/radiusd/radiusd.pid"
    checkrad = "/usr/sbin/checkrad"
    debug_level = 0
    proxy_requests = yes
 log {
     stripped_names = no
     auth = yes
     auth_badpass = no
     auth_goodpass = no
     colourise = yes
     msg_denied = "You are already logged in - access denied"
 }
 resources {
 }
 security {
     max_attributes = 200
     reject_delay = 1.000000
     status_server = yes
 }
}
radiusd: #### Loading Realms and Home Servers ####
 proxy server {
     retry_delay = 5
     retry_count = 3
     default_fallback = no
     dead_time = 120
     wake_all_if_all_dead = no
 }
 home_server localhost {
     ipaddr = 127.0.0.1
     port = 1812
     type = "auth"
     secret = <<< secret >>>
     response_window = 20.000000
     response_timeouts = 1
     max_outstanding = 65536
     zombie_period = 40
     status_check = "status-server"
     ping_interval = 30
     check_interval = 30
     check_timeout = 4
     num_answers_to_alive = 3
     revive_interval = 120
  limit {
      max_connections = 16
      max_requests = 0
      lifetime = 0
      idle_timeout = 0
  }
  coa {
      irt = 2
      mrt = 16
      mrc = 5
      mrd = 30
  }
 }
 home_server_pool my_auth_failover {
    type = fail-over
    home_server = localhost
 }
 realm example.com {
    auth_pool = my_auth_failover
 }
 realm LOCAL {
 }
 realm fpti {
 }
 realm pti {
 }
 realm vst {
 }
 realm srv {
 }
radiusd: #### Loading Clients ####
 client localhost {
     ipaddr = 127.0.0.1
     require_message_authenticator = no
     secret = <<< secret >>>
     nas_type = "other"
     proto = "*"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client controladora-wlan-1 {
     ipaddr = 172.25.89.1
     require_message_authenticator = no
     secret = <<< secret >>>
     shortname = "controladora-wlan-1"
     nas_type = "cisco"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client controladora-wlan-2 {
     ipaddr = 172.25.89.2
     require_message_authenticator = no
     secret = <<< secret >>>
     shortname = "controladora-wlan-2"
     nas_type = "cisco"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client controladora-wlan-3 {
     ipaddr = 172.25.89.3
     require_message_authenticator = no
     secret = <<< secret >>>
     shortname = "controladora-wlan-3"
     nas_type = "cisco"
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client pcjoao {
     ipaddr = 186.233.15.131
     require_message_authenticator = no
     secret = <<< secret >>>
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
 client pcperuchi {
     ipaddr = 186.233.15.133
     require_message_authenticator = no
     secret = <<< secret >>>
  limit {
      max_connections = 16
      lifetime = 0
      idle_timeout = 30
  }
 }
Debugger not attached
 # Creating Auth-Type = digest
 # Creating Auth-Type = LDAP_PTI
 # Creating Auth-Type = LDAP_FPTI
radiusd: #### Instantiating modules ####
 instantiate {
 }
 modules {
  # Loaded module rlm_always
  # Instantiating module "reject" from file /etc/raddb/mods-enabled/always
  always reject {
      rcode = "reject"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "fail" from file /etc/raddb/mods-enabled/always
  always fail {
      rcode = "fail"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "ok" from file /etc/raddb/mods-enabled/always
  always ok {
      rcode = "ok"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "handled" from file /etc/raddb/mods-enabled/always
  always handled {
      rcode = "handled"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "invalid" from file /etc/raddb/mods-enabled/always
  always invalid {
      rcode = "invalid"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "userlock" from file /etc/raddb/mods-enabled/always
  always userlock {
      rcode = "userlock"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "notfound" from file /etc/raddb/mods-enabled/always
  always notfound {
      rcode = "notfound"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "noop" from file /etc/raddb/mods-enabled/always
  always noop {
      rcode = "noop"
      simulcount = 0
      mpp = no
  }
  # Instantiating module "updated" from file /etc/raddb/mods-enabled/always
  always updated {
      rcode = "updated"
      simulcount = 0
      mpp = no
  }
  # Loaded module rlm_attr_filter
  # Instantiating module "attr_filter.post-proxy" from file
/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.post-proxy {
      filename = "/etc/raddb/mods-config/attr_filter/post-proxy"
      key = "%{Realm}"
      relaxed = no
  }
reading pairlist file /etc/raddb/mods-config/attr_filter/post-proxy
  # Instantiating module "attr_filter.pre-proxy" from file
/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.pre-proxy {
      filename = "/etc/raddb/mods-config/attr_filter/pre-proxy"
      key = "%{Realm}"
      relaxed = no
  }
reading pairlist file /etc/raddb/mods-config/attr_filter/pre-proxy
  # Instantiating module "attr_filter.access_reject" from file
/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.access_reject {
      filename = "/etc/raddb/mods-config/attr_filter/access_reject"
      key = "%{User-Name}"
      relaxed = no
  }
reading pairlist file /etc/raddb/mods-config/attr_filter/access_reject
  # Instantiating module "attr_filter.access_challenge" from file
/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.access_challenge {
      filename = "/etc/raddb/mods-config/attr_filter/access_challenge"
      key = "%{User-Name}"
      relaxed = no
  }
reading pairlist file /etc/raddb/mods-config/attr_filter/access_challenge
  # Instantiating module "attr_filter.accounting_response" from file
/etc/raddb/mods-enabled/attr_filter
  attr_filter attr_filter.accounting_response {
      filename = "/etc/raddb/mods-config/attr_filter/accounting_response"
      key = "%{User-Name}"
      relaxed = no
  }
reading pairlist file /etc/raddb/mods-config/attr_filter/accounting_response
  # Loaded module rlm_cache
  # Instantiating module "cache_eap" from file
/etc/raddb/mods-enabled/cache_eap
  cache cache_eap {
      driver = "rlm_cache_rbtree"
      key = "%{%{control:State}:-%{%{reply:State}:-%{State}}}"
      ttl = 15
      max_entries = 0
      epoch = 0
      add_stats = no
  }
rlm_cache (cache_eap): Driver rlm_cache_rbtree (module rlm_cache_rbtree)
loaded and linked
  # Loaded module rlm_chap
  # Instantiating module "chap" from file /etc/raddb/mods-enabled/chap
  # Loaded module rlm_detail
  # Instantiating module "detail" from file /etc/raddb/mods-enabled/detail
  detail {
      filename =
"/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Instantiating module "auth_log" from file
/etc/raddb/mods-enabled/detail.log
  detail auth_log {
      filename =
"/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/auth-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
rlm_detail (auth_log): 'User-Password' suppressed, will not appear in
detail output
  # Instantiating module "reply_log" from file
/etc/raddb/mods-enabled/detail.log
  detail reply_log {
      filename =
"/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/reply-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Instantiating module "pre_proxy_log" from file
/etc/raddb/mods-enabled/detail.log
  detail pre_proxy_log {
      filename =
"/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/pre-proxy-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Instantiating module "post_proxy_log" from file
/etc/raddb/mods-enabled/detail.log
  detail post_proxy_log {
      filename =
"/var/log/radius/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/post-proxy-detail-%Y%m%d"
      header = "%t"
      permissions = 384
      locking = no
      escape_filenames = no
      log_packet_header = no
  }
  # Loaded module rlm_dhcp
  # Instantiating module "dhcp" from file /etc/raddb/mods-enabled/dhcp
  # Loaded module rlm_digest
  # Instantiating module "digest" from file /etc/raddb/mods-enabled/digest
  # Loaded module rlm_dynamic_clients
  # Instantiating module "dynamic_clients" from file
/etc/raddb/mods-enabled/dynamic_clients
  # Loaded module rlm_eap
  # Instantiating module "eap" from file /etc/raddb/mods-enabled/eap
  eap {
      default_eap_type = "peap"
      timer_expire = 60
      ignore_unknown_eap_types = no
      mod_accounting_username_bug = no
      max_sessions = 1024
  }
   # Linked to sub-module rlm_eap_md5
   # Linked to sub-module rlm_eap_leap
   # Linked to sub-module rlm_eap_gtc
   gtc {
       challenge = "Password: "
       auth_type = "PAP"
   }
   # Linked to sub-module rlm_eap_tls
   tls {
       tls = "tls-common"
   }
   tls-config tls-common {
       rsa_key_exchange = no
       dh_key_exchange = yes
       rsa_key_length = 512
       dh_key_length = 512
       verify_depth = 0
       ca_path = "/etc/raddb/certs"
       pem_file_type = yes
       private_key_file = "/etc/raddb/certs/ssl-pti.org.br/pti.org.br.key"
       certificate_file = "/etc/raddb/certs/ssl-pti.org.br/pti.org.br.pem"
       ca_file = "/etc/raddb/certs/
ssl-pti.org.br/GlobalSign-Intermediate.pem"
       private_key_password = <<< secret >>>
       dh_file = "/etc/raddb/certs/dh"
       random_file = "/etc/raddb/certs/random"
       fragment_size = 1024
       include_length = yes
       check_crl = no
       cipher_list = "DEFAULT"
       ecdh_curve = "prime256v1"
    cache {
        enable = yes
        lifetime = 1
        max_entries = 2000
    }
    verify {
    }
    ocsp {
        enable = no
        override_cert_url = yes
        url = "http://127.0.0.1/ocsp/"
        use_nonce = yes
        timeout = 0
        softfail = no
    }
   }
   # Linked to sub-module rlm_eap_ttls
   ttls {
       tls = "tls-common"
       default_eap_type = "mschapv2"
       copy_request_to_tunnel = yes
       use_tunneled_reply = no
       virtual_server = "inner-tunnel"
       include_length = yes
       require_client_cert = no
   }
Using cached TLS configuration from previous invocation
   # Linked to sub-module rlm_eap_peap
   peap {
       tls = "tls-common"
       default_method = "mschapv2"
       copy_request_to_tunnel = yes
       use_tunneled_reply = no
       proxy_tunneled_request_as_eap = yes
       virtual_server = "inner-tunnel"
       soh = no
       require_client_cert = no
   }
Using cached TLS configuration from previous invocation
   # Linked to sub-module rlm_eap_mschapv2
   mschapv2 {
       with_ntdomain_hack = no
       send_error = no
   }
  # Loaded module rlm_exec
  # Instantiating module "echo" from file /etc/raddb/mods-enabled/echo
  exec echo {
      wait = yes
      program = "/bin/echo %{User-Name}"
      input_pairs = "request"
      output_pairs = "reply"
      shell_escape = yes
  }
  # Instantiating module "exec" from file /etc/raddb/mods-enabled/exec
  exec {
      wait = no
      input_pairs = "request"
      shell_escape = yes
      timeout = 10
  }
  # Loaded module rlm_expiration
  # Instantiating module "expiration" from file
/etc/raddb/mods-enabled/expiration
  # Loaded module rlm_expr
  # Instantiating module "expr" from file /etc/raddb/mods-enabled/expr
  expr {
      safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_:
/äéöüàâæçèéêëîïôœùûüaÿÄÉÖÜßÀÂÆÇÈÉÊËÎÏÔŒÙÛÜŸ"
  }
  # Loaded module rlm_files
  # Instantiating module "files" from file /etc/raddb/mods-enabled/files
  files {
      filename = "/etc/raddb/mods-config/files/authorize"
      usersfile = "/etc/raddb/mods-config/files/authorize"
      acctusersfile = "/etc/raddb/mods-config/files/accounting"
      preproxy_usersfile = "/etc/raddb/mods-config/files/pre-proxy"
      compat = "cistron"
  }
reading pairlist file /etc/raddb/mods-config/files/authorize
[/etc/raddb/mods-config/files/authorize]:182 Cistron compatibility checks
for entry DEFAULT ...
[/etc/raddb/mods-config/files/authorize]:189 Cistron compatibility checks
for entry DEFAULT ...
[/etc/raddb/mods-config/files/authorize]:195 Cistron compatibility checks
for entry DEFAULT ...
[/etc/raddb/mods-config/files/authorize]:221 Cistron compatibility checks
for entry bob ...
reading pairlist file /etc/raddb/mods-config/files/authorize
[/etc/raddb/mods-config/files/authorize]:182 Cistron compatibility checks
for entry DEFAULT ...
[/etc/raddb/mods-config/files/authorize]:189 Cistron compatibility checks
for entry DEFAULT ...
[/etc/raddb/mods-config/files/authorize]:195 Cistron compatibility checks
for entry DEFAULT ...
[/etc/raddb/mods-config/files/authorize]:221 Cistron compatibility checks
for entry bob ...
reading pairlist file /etc/raddb/mods-config/files/accounting
reading pairlist file /etc/raddb/mods-config/files/pre-proxy
  # Loaded module rlm_linelog
  # Instantiating module "linelog" from file /etc/raddb/mods-enabled/linelog
  linelog {
      filename = "/var/log/radius/linelog"
      permissions = 384
      format = "This is a log message for %{User-Name}"
      reference = "messages.%{%{Packet-Type}:-default}"
  }
  # Instantiating module "log_accounting" from file
/etc/raddb/mods-enabled/linelog
  linelog log_accounting {
      filename = "/var/log/radius/linelog-accounting"
      permissions = 384
      format = ""
      reference = "Accounting-Request.%{%{Acct-Status-Type}:-unknown}"
  }
  # Loaded module rlm_logintime
  # Instantiating module "logintime" from file
/etc/raddb/mods-enabled/logintime
  logintime {
      minimum_timeout = 60
  }
  # Loaded module rlm_mschap
  # Instantiating module "mschap" from file /etc/raddb/mods-enabled/mschap
  mschap {
      use_mppe = yes
      require_encryption = no
      require_strong = no
      with_ntdomain_hack = yes
   passchange {
   }
      allow_retry = yes
  }
  # Instantiating module "ntlm_auth" from file
/etc/raddb/mods-enabled/ntlm_auth
  exec ntlm_auth {
      wait = yes
      program = "/path/to/ntlm_auth --request-nt-key --domain=MYDOMAIN
--username=%{mschap:User-Name} --password=%{User-Password}"
      shell_escape = yes
  }
  # Loaded module rlm_pap
  # Instantiating module "pap" from file /etc/raddb/mods-enabled/pap
  pap {
      normalise = yes
  }
  # Loaded module rlm_passwd
  # Instantiating module "etc_passwd" from file
/etc/raddb/mods-enabled/passwd
  passwd etc_passwd {
      filename = "/etc/passwd"
      format = "*User-Name:Crypt-Password:"
      delimiter = ":"
      ignore_nislike = no
      ignore_empty = yes
      allow_multiple_keys = no
      hash_size = 100
  }
rlm_passwd: nfields: 3 keyfield 0(User-Name) listable: no
  # Loaded module rlm_preprocess
  # Instantiating module "preprocess" from file
/etc/raddb/mods-enabled/preprocess
  preprocess {
      huntgroups = "/etc/raddb/mods-config/preprocess/huntgroups"
      hints = "/etc/raddb/mods-config/preprocess/hints"
      with_ascend_hack = no
      ascend_channels_per_line = 23
      with_ntdomain_hack = no
      with_specialix_jetstream_hack = no
      with_cisco_vsa_hack = no
      with_alvarion_vsa_hack = no
  }
reading pairlist file /etc/raddb/mods-config/preprocess/huntgroups
reading pairlist file /etc/raddb/mods-config/preprocess/hints
  # Loaded module rlm_radutmp
  # Instantiating module "radutmp" from file /etc/raddb/mods-enabled/radutmp
  radutmp {
      filename = "/var/log/radius/radutmp"
      username = "%{User-Name}"
      case_sensitive = yes
      check_with_nas = yes
      permissions = 384
      caller_id = yes
  }
  # Loaded module rlm_realm
  # Instantiating module "IPASS" from file /etc/raddb/mods-enabled/realm
  realm IPASS {
      format = "prefix"
      delimiter = "/"
      ignore_default = no
      ignore_null = no
  }
  # Instantiating module "suffix" from file /etc/raddb/mods-enabled/realm
  realm suffix {
      format = "suffix"
      delimiter = "@"
      ignore_default = no
      ignore_null = no
  }
  # Instantiating module "realmpercent" from file
/etc/raddb/mods-enabled/realm
  realm realmpercent {
      format = "suffix"
      delimiter = "%"
      ignore_default = no
      ignore_null = no
  }
  # Instantiating module "ntdomain" from file /etc/raddb/mods-enabled/realm
  realm ntdomain {
      format = "prefix"
      delimiter = "\\"
      ignore_default = no
      ignore_null = no
  }
  # Loaded module rlm_replicate
  # Instantiating module "replicate" from file
/etc/raddb/mods-enabled/replicate
  # Loaded module rlm_soh
  # Instantiating module "soh" from file /etc/raddb/mods-enabled/soh
  soh {
      dhcp = yes
  }
  # Instantiating module "sradutmp" from file
/etc/raddb/mods-enabled/sradutmp
  radutmp sradutmp {
      filename = "/var/log/radius/sradutmp"
      username = "%{User-Name}"
      case_sensitive = yes
      check_with_nas = yes
      permissions = 420
      caller_id = no
  }
  # Loaded module rlm_unix
  # Instantiating module "unix" from file /etc/raddb/mods-enabled/unix
  unix {
      radwtmp = "/var/log/radius/radwtmp"
  }
  # Loaded module rlm_unpack
  # Instantiating module "unpack" from file /etc/raddb/mods-enabled/unpack
  # Loaded module rlm_utf8
  # Instantiating module "utf8" from file /etc/raddb/mods-enabled/utf8
  # Loaded module rlm_sql
  # Instantiating module "sql_accounting" from file
/etc/raddb/mods-enabled/sql
  sql sql_accounting {
      driver = "rlm_sql_postgresql"
      server = "ptipgsql.pti"
      port = "5432"
      login = "sa_radius_accounting"
      password = <<< secret >>>
      radius_db = "radius_accounting"
      read_groups = yes
      read_profiles = yes
      read_clients = no
      delete_stale_sessions = yes
      sql_user_name = "%{User-Name}"
      logfile = "/var/log/radius/sqllog.sql"
      default_user_profile = ""
      client_query = "SELECT id, nasname, shortname, type, secret, server
FROM nas"
      authorize_check_query = "SELECT id, UserName, Attribute, Value, Op
FROM radcheck WHERE Username = '%{SQL-User-Name}' ORDER BY id"
      authorize_reply_query = "SELECT id, UserName, Attribute, Value, Op
FROM radreply WHERE Username = '%{SQL-User-Name}' ORDER BY id"
      authorize_group_check_query = "SELECT id, GroupName, Attribute,
Value, op FROM radgroupcheck WHERE GroupName = '%{Sql-Group}' ORDER BY id"
      authorize_group_reply_query = "SELECT id, GroupName, Attribute,
Value, op FROM radgroupreply WHERE GroupName = '%{Sql-Group}' ORDER BY id"
      group_membership_query = "SELECT GroupName FROM radusergroup WHERE
UserName='%{SQL-User-Name}' ORDER BY priority"
      safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
   accounting {
       reference = "%{tolower:type.%{%{Acct-Status-Type}:-none}.query}"
    type {
     accounting-on {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
(%{integer:Event-Timestamp} - EXTRACT(EPOCH FROM(AcctStartTime))),
AcctTerminateCause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}', WHERE
AcctStopTime IS NULL AND NASIPAddress=
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}' AND AcctStartTime <=
'%S'::timestamp"
     }
     accounting-off {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
(%{integer:Event-Timestamp} - EXTRACT(EPOCH FROM(AcctStartTime))),
AcctTerminateCause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}', WHERE
AcctStopTime IS NULL AND NASIPAddress=
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}' AND AcctStartTime <=
'%S'::timestamp"
     }
     start {
         query = "INSERT INTO radacct (AcctSessionId,
AcctUniqueId,        UserName, Realm,            NASIPAddress,
NASPortId, NASPortType,        AcctStartTime,        AcctUpdateTime,
AcctStopTime,        AcctSessionTime,     AcctAuthentic,
ConnectInfo_start,    ConnectInfo_Stop,     AcctInputOctets,
AcctOutputOctets,    CalledStationId,     CallingStationId,
AcctTerminateCause,    ServiceType,        FramedProtocol, FramedIpAddress)
VALUES('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',
'%{SQL-User-Name}', NULLIF('%{Realm}', ''),
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}', %{%{NAS-Port}:-NULL},
'%{NAS-Port-Type}', TO_TIMESTAMP(%{integer:Event-Timestamp}),
TO_TIMESTAMP(%{integer:Event-Timestamp}), NULL, 0, '%{Acct-Authentic}',
'%{Connect-Info}', NULL, 0, 0, '%{Called-Station-Id}',
'%{Calling-Station-Id}', NULL, '%{Service-Type}', '%{Framed-Protocol}',
NULLIF('%{Framed-IP-Address}', '')::inet)"
     }
     interim-update {
         query = "UPDATE radacct SET FramedIPAddress =
NULLIF('%{Framed-IP-Address}', '')::inet, AcctSessionTime =
%{%{Acct-Session-Time}:-NULL}, AcctInterval = (%{integer:Event-Timestamp} -
EXTRACT(EPOCH FROM (COALESCE(AcctUpdateTime, AcctStartTime)))),
AcctUpdateTime = TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctInputOctets
= (('%{%{Acct-Input-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Input-Octets}:-0}'::bigint), AcctOutputOctets =
(('%{%{Acct-Output-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Output-Octets}:-0}'::bigint) WHERE (AcctUniqueId =
'%{Acct-Unique-Session-Id}') AND AcctStopTime IS NULL"
     }
     stop {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
COALESCE(%{%{Acct-Session-Time}:-NULL}, (%{integer:Event-Timestamp} -
EXTRACT(EPOCH FROM(AcctStartTime)))), AcctInputOctets =
(('%{%{Acct-Input-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Input-Octets}:-0}'::bigint), AcctOutputOctets =
(('%{%{Acct-Output-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Output-Octets}:-0}'::bigint), AcctTerminateCause =
'%{Acct-Terminate-Cause}', FramedIPAddress = NULLIF('%{Framed-IP-Address}',
'')::inet, ConnectInfo_stop = '%{Connect-Info}' WHERE (AcctUniqueId =
'%{Acct-Unique-Session-Id}') AND AcctStopTime IS NULL"
     }
    }
   }
   post-auth {
       reference = ".query"
       query = "INSERT INTO radpostauth (username, pass, reply, authdate)
VALUES('%{User-Name}', '%{%{User-Password}:-Chap-Password}',
'%{reply:Packet-Type}', NOW())"
   }
  }
rlm_sql (sql_accounting): Creating new attribute sql_accounting-SQL-Group
rlm_sql (sql_accounting): Registering sql_groupcmp for
sql_accounting-SQL-Group
   postgresql {
       send_application_name = no
   }
rlm_sql (sql_accounting): Driver rlm_sql_postgresql (module
rlm_sql_postgresql) loaded and linked
rlm_sql (sql_accounting): Attempting to connect to database
"radius_accounting"
rlm_sql (sql_accounting): Initialising connection pool
   pool {
       start = 5
       min = 4
       max = 20
       spare = 3
       uses = 0
       lifetime = 0
       cleanup_interval = 30
       idle_timeout = 60
       retry_delay = 1
       spread = no
   }
rlm_sql (sql_accounting): Opening additional connection (0), 1 of 20
pending slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_accounting'
host='ptipgsql.pti' port=5432 user='sa_radius_accounting' password='#r4dact@
.'
Connected to database 'radius_accounting' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13706
rlm_sql (sql_accounting): Opening additional connection (1), 1 of 19
pending slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_accounting'
host='ptipgsql.pti' port=5432 user='sa_radius_accounting' password='#r4dact@
.'
Connected to database 'radius_accounting' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13707
rlm_sql (sql_accounting): Opening additional connection (2), 1 of 18
pending slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_accounting'
host='ptipgsql.pti' port=5432 user='sa_radius_accounting' password='#r4dact@
.'
Connected to database 'radius_accounting' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13708
rlm_sql (sql_accounting): Opening additional connection (3), 1 of 17
pending slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_accounting'
host='ptipgsql.pti' port=5432 user='sa_radius_accounting' password='#r4dact@
.'
Connected to database 'radius_accounting' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13709
rlm_sql (sql_accounting): Opening additional connection (4), 1 of 16
pending slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_accounting'
host='ptipgsql.pti' port=5432 user='sa_radius_accounting' password='#r4dact@
.'
Connected to database 'radius_accounting' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13710
  # Instantiating module "sql_visitantes" from file
/etc/raddb/mods-enabled/sql
  sql sql_visitantes {
      driver = "rlm_sql_postgresql"
      server = "ptipgsql.pti"
      port = "5432"
      login = "sa_radius_visitors"
      password = <<< secret >>>
      radius_db = "radius_visitors"
      read_groups = yes
      read_profiles = yes
      read_clients = no
      delete_stale_sessions = yes
      sql_user_name = "%{Stripped-User-Name}"
      logfile = "/var/log/radius/sqllog.sql"
      default_user_profile = ""
      client_query = "SELECT id, nasname, shortname, type, secret, server
FROM nas"
      authorize_check_query = "SELECT id, UserName, Attribute, Value,
op               FROM radcheck               WHERE Username =
'%{SQL-User-Name}'               AND ( (SELECT (ptime -
(date_part('epoch',CURRENT_TIMESTAMP)::int-date_part('epoch',authdate)::int))
as tempo                         FROM radpostauth LEFT JOIN
radpermissiontime ON radpermissiontime.username =
radpostauth.username                         WHERE radpostauth.username =
'%{SQL-User-Name}@%{Realm}' AND reply = 'Access-Accept' ORDER BY authdate
LIMIT 1) > 0                    OR (SELECT COUNT(UserName)FROM radpostauth
WHERE radpostauth.username = '%{SQL-User-Name}@%{Realm}' AND reply =
'Access-Accept') = 0               AND (SELECT COUNT(username) FROM
radpermissiontime WHERE username='%{SQL-User-Name}@%{Realm}' AND
active=TRUE) > 0)               ORDER BY id"
      authorize_reply_query = "SELECT id, UserName, Attribute, Value, Op
FROM radreply WHERE Username = '%{SQL-User-Name}' ORDER BY id"
      authorize_group_check_query = "SELECT id, GroupName, Attribute,
Value, op FROM radgroupcheck WHERE GroupName = '%{Sql-Group}' ORDER BY id"
      authorize_group_reply_query = "SELECT id, GroupName, Attribute,
Value, op FROM radgroupreply WHERE GroupName = '%{Sql-Group}' ORDER BY id"
      group_membership_query = "SELECT GroupName FROM radusergroup WHERE
UserName='%{SQL-User-Name}' ORDER BY priority"
      safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
   accounting {
       reference = "%{tolower:type.%{%{Acct-Status-Type}:-none}.query}"
    type {
     accounting-on {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
(%{integer:Event-Timestamp} - EXTRACT(EPOCH FROM(AcctStartTime))),
AcctTerminateCause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}', WHERE
AcctStopTime IS NULL AND NASIPAddress=
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}' AND AcctStartTime <=
'%S'::timestamp"
     }
     accounting-off {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
(%{integer:Event-Timestamp} - EXTRACT(EPOCH FROM(AcctStartTime))),
AcctTerminateCause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}', WHERE
AcctStopTime IS NULL AND NASIPAddress=
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}' AND AcctStartTime <=
'%S'::timestamp"
     }
     start {
         query = "INSERT INTO radacct (AcctSessionId,
AcctUniqueId,        UserName, Realm,            NASIPAddress,
NASPortId, NASPortType,        AcctStartTime,        AcctUpdateTime,
AcctStopTime,        AcctSessionTime,     AcctAuthentic,
ConnectInfo_start,    ConnectInfo_Stop,     AcctInputOctets,
AcctOutputOctets,    CalledStationId,     CallingStationId,
AcctTerminateCause,    ServiceType,        FramedProtocol, FramedIpAddress)
VALUES('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',
'%{SQL-User-Name}', NULLIF('%{Realm}', ''),
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}', %{%{NAS-Port}:-NULL},
'%{NAS-Port-Type}', TO_TIMESTAMP(%{integer:Event-Timestamp}),
TO_TIMESTAMP(%{integer:Event-Timestamp}), NULL, 0, '%{Acct-Authentic}',
'%{Connect-Info}', NULL, 0, 0, '%{Called-Station-Id}',
'%{Calling-Station-Id}', NULL, '%{Service-Type}', '%{Framed-Protocol}',
NULLIF('%{Framed-IP-Address}', '')::inet)"
     }
     interim-update {
         query = "UPDATE radacct SET FramedIPAddress =
NULLIF('%{Framed-IP-Address}', '')::inet, AcctSessionTime =
%{%{Acct-Session-Time}:-NULL}, AcctInterval = (%{integer:Event-Timestamp} -
EXTRACT(EPOCH FROM (COALESCE(AcctUpdateTime, AcctStartTime)))),
AcctUpdateTime = TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctInputOctets
= (('%{%{Acct-Input-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Input-Octets}:-0}'::bigint), AcctOutputOctets =
(('%{%{Acct-Output-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Output-Octets}:-0}'::bigint) WHERE (AcctUniqueId =
'%{Acct-Unique-Session-Id}') AND AcctStopTime IS NULL"
     }
     stop {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
COALESCE(%{%{Acct-Session-Time}:-NULL}, (%{integer:Event-Timestamp} -
EXTRACT(EPOCH FROM(AcctStartTime)))), AcctInputOctets =
(('%{%{Acct-Input-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Input-Octets}:-0}'::bigint), AcctOutputOctets =
(('%{%{Acct-Output-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Output-Octets}:-0}'::bigint), AcctTerminateCause =
'%{Acct-Terminate-Cause}', FramedIPAddress = NULLIF('%{Framed-IP-Address}',
'')::inet, ConnectInfo_stop = '%{Connect-Info}' WHERE (AcctUniqueId =
'%{Acct-Unique-Session-Id}') AND AcctStopTime IS NULL"
     }
    }
   }
   post-auth {
       reference = ".query"
       query = "INSERT INTO radpostauth (username, pass, reply, authdate)
VALUES('%{User-Name}', '%{%{User-Password}:-Chap-Password}',
'%{reply:Packet-Type}', NOW())"
   }
  }
rlm_sql (sql_visitantes): Creating new attribute sql_visitantes-SQL-Group
rlm_sql (sql_visitantes): Registering sql_groupcmp for
sql_visitantes-SQL-Group
   postgresql {
       send_application_name = no
   }
rlm_sql (sql_visitantes): Driver rlm_sql_postgresql (module
rlm_sql_postgresql) loaded and linked
rlm_sql (sql_visitantes): Attempting to connect to database
"radius_visitors"
rlm_sql (sql_visitantes): Initialising connection pool
   pool {
       start = 5
       min = 1
       max = 10
       spare = 3
       uses = 0
       lifetime = 0
       cleanup_interval = 30
       idle_timeout = 60
       retry_delay = 1
       spread = no
   }
rlm_sql (sql_visitantes): Opening additional connection (0), 1 of 10
pending slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_visitors'
host='ptipgsql.pti' port=5432 user='sa_radius_visitors' password='#r4dvst@.'
Connected to database 'radius_visitors' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13711
rlm_sql (sql_visitantes): Opening additional connection (1), 1 of 9 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_visitors'
host='ptipgsql.pti' port=5432 user='sa_radius_visitors' password='#r4dvst@.'
Connected to database 'radius_visitors' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13712
rlm_sql (sql_visitantes): Opening additional connection (2), 1 of 8 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_visitors'
host='ptipgsql.pti' port=5432 user='sa_radius_visitors' password='#r4dvst@.'
Connected to database 'radius_visitors' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13713
rlm_sql (sql_visitantes): Opening additional connection (3), 1 of 7 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_visitors'
host='ptipgsql.pti' port=5432 user='sa_radius_visitors' password='#r4dvst@.'
Connected to database 'radius_visitors' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13714
rlm_sql (sql_visitantes): Opening additional connection (4), 1 of 6 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_visitors'
host='ptipgsql.pti' port=5432 user='sa_radius_visitors' password='#r4dvst@.'
Connected to database 'radius_visitors' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13715
  # Instantiating module "sql_servicos" from file
/etc/raddb/mods-enabled/sql
  sql sql_servicos {
      driver = "rlm_sql_postgresql"
      server = "ptipgsql.pti"
      port = "5432"
      login = "sa_radius_services"
      password = <<< secret >>>
      radius_db = "radius_services"
      read_groups = yes
      read_profiles = yes
      read_clients = no
      delete_stale_sessions = yes
      sql_user_name = "%{User-Name}"
      logfile = "/var/log/radius/sqllog.sql"
      default_user_profile = ""
      client_query = "SELECT id, nasname, shortname, type, secret, server
FROM nas"
      authorize_check_query = "SELECT id, UserName, Attribute, Value, Op
FROM radcheck WHERE Username = '%{SQL-User-Name}' ORDER BY id"
      authorize_reply_query = "SELECT id, UserName, Attribute, Value, Op
FROM radreply WHERE Username = '%{SQL-User-Name}' ORDER BY id"
      authorize_group_check_query = "SELECT id, GroupName, Attribute,
Value, op FROM radgroupcheck WHERE GroupName = '%{Sql-Group}' ORDER BY id"
      authorize_group_reply_query = "SELECT id, GroupName, Attribute,
Value, op FROM radgroupreply WHERE GroupName = '%{Sql-Group}' ORDER BY id"
      group_membership_query = "SELECT GroupName FROM radusergroup WHERE
UserName='%{SQL-User-Name}' ORDER BY priority"
      safe_characters =
"@abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789.-_: /"
   accounting {
       reference = "%{tolower:type.%{%{Acct-Status-Type}:-none}.query}"
    type {
     accounting-on {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
(%{integer:Event-Timestamp} - EXTRACT(EPOCH FROM(AcctStartTime))),
AcctTerminateCause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}', WHERE
AcctStopTime IS NULL AND NASIPAddress=
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}' AND AcctStartTime <=
'%S'::timestamp"
     }
     accounting-off {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
(%{integer:Event-Timestamp} - EXTRACT(EPOCH FROM(AcctStartTime))),
AcctTerminateCause = '%{%{Acct-Terminate-Cause}:-NAS-Reboot}', WHERE
AcctStopTime IS NULL AND NASIPAddress=
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}' AND AcctStartTime <=
'%S'::timestamp"
     }
     start {
         query = "INSERT INTO radacct (AcctSessionId,
AcctUniqueId,        UserName, Realm,            NASIPAddress,
NASPortId, NASPortType,        AcctStartTime,        AcctUpdateTime,
AcctStopTime,        AcctSessionTime,     AcctAuthentic,
ConnectInfo_start,    ConnectInfo_Stop,     AcctInputOctets,
AcctOutputOctets,    CalledStationId,     CallingStationId,
AcctTerminateCause,    ServiceType,        FramedProtocol, FramedIpAddress)
VALUES('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}',
'%{SQL-User-Name}', NULLIF('%{Realm}', ''),
'%{%{NAS-IPv6-Address}:-%{NAS-IP-Address}}', %{%{NAS-Port}:-NULL},
'%{NAS-Port-Type}', TO_TIMESTAMP(%{integer:Event-Timestamp}),
TO_TIMESTAMP(%{integer:Event-Timestamp}), NULL, 0, '%{Acct-Authentic}',
'%{Connect-Info}', NULL, 0, 0, '%{Called-Station-Id}',
'%{Calling-Station-Id}', NULL, '%{Service-Type}', '%{Framed-Protocol}',
NULLIF('%{Framed-IP-Address}', '')::inet)"
     }
     interim-update {
         query = "UPDATE radacct SET FramedIPAddress =
NULLIF('%{Framed-IP-Address}', '')::inet, AcctSessionTime =
%{%{Acct-Session-Time}:-NULL}, AcctInterval = (%{integer:Event-Timestamp} -
EXTRACT(EPOCH FROM (COALESCE(AcctUpdateTime, AcctStartTime)))),
AcctUpdateTime = TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctInputOctets
= (('%{%{Acct-Input-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Input-Octets}:-0}'::bigint), AcctOutputOctets =
(('%{%{Acct-Output-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Output-Octets}:-0}'::bigint) WHERE (AcctUniqueId =
'%{Acct-Unique-Session-Id}') AND AcctStopTime IS NULL"
     }
     stop {
         query = "UPDATE radacct SET AcctStopTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctUpdateTime =
TO_TIMESTAMP(%{integer:Event-Timestamp}), AcctSessionTime =
COALESCE(%{%{Acct-Session-Time}:-NULL}, (%{integer:Event-Timestamp} -
EXTRACT(EPOCH FROM(AcctStartTime)))), AcctInputOctets =
(('%{%{Acct-Input-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Input-Octets}:-0}'::bigint), AcctOutputOctets =
(('%{%{Acct-Output-Gigawords}:-0}'::bigint << 32) +
'%{%{Acct-Output-Octets}:-0}'::bigint), AcctTerminateCause =
'%{Acct-Terminate-Cause}', FramedIPAddress = NULLIF('%{Framed-IP-Address}',
'')::inet, ConnectInfo_stop = '%{Connect-Info}' WHERE (AcctUniqueId =
'%{Acct-Unique-Session-Id}') AND AcctStopTime IS NULL"
     }
    }
   }
   post-auth {
       reference = ".query"
       query = "INSERT INTO radpostauth (username, pass, reply, authdate)
VALUES('%{User-Name}', '%{%{User-Password}:-Chap-Password}',
'%{reply:Packet-Type}', NOW())"
   }
  }
rlm_sql (sql_servicos): Creating new attribute sql_servicos-SQL-Group
rlm_sql (sql_servicos): Registering sql_groupcmp for sql_servicos-SQL-Group
   postgresql {
       send_application_name = no
   }
rlm_sql (sql_servicos): Driver rlm_sql_postgresql (module
rlm_sql_postgresql) loaded and linked
rlm_sql (sql_servicos): Attempting to connect to database "radius_services"
rlm_sql (sql_servicos): Initialising connection pool
   pool {
       start = 5
       min = 1
       max = 10
       spare = 3
       uses = 0
       lifetime = 0
       cleanup_interval = 30
       idle_timeout = 60
       retry_delay = 1
       spread = no
   }
rlm_sql (sql_servicos): Opening additional connection (0), 1 of 10 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_services'
host='ptipgsql.pti' port=5432 user='sa_radius_services'
password='srQ7tTUr4TmXj6TzPxmgSTvyGigw'
Connected to database 'radius_services' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13716
rlm_sql (sql_servicos): Opening additional connection (1), 1 of 9 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_services'
host='ptipgsql.pti' port=5432 user='sa_radius_services'
password='srQ7tTUr4TmXj6TzPxmgSTvyGigw'
Connected to database 'radius_services' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13717
rlm_sql (sql_servicos): Opening additional connection (2), 1 of 8 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_services'
host='ptipgsql.pti' port=5432 user='sa_radius_services'
password='srQ7tTUr4TmXj6TzPxmgSTvyGigw'
Connected to database 'radius_services' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13718
rlm_sql (sql_servicos): Opening additional connection (3), 1 of 7 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_services'
host='ptipgsql.pti' port=5432 user='sa_radius_services'
password='srQ7tTUr4TmXj6TzPxmgSTvyGigw'
Connected to database 'radius_services' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13719
rlm_sql (sql_servicos): Opening additional connection (4), 1 of 6 pending
slots used
rlm_sql_postgresql: Connecting using parameters: dbname='radius_services'
host='ptipgsql.pti' port=5432 user='sa_radius_services'
password='srQ7tTUr4TmXj6TzPxmgSTvyGigw'
Connected to database 'radius_services' on 'ptipgsql.pti' server version
80418, protocol version 3, backend PID 13720
  # Loaded module rlm_ldap
  # Instantiating module "ldap_fpti" from file /etc/raddb/mods-enabled/ldap
  ldap ldap_fpti {
      server = "ptipdc.pti"
      password = <<< secret >>>
      identity = "cn=Manager,dc=pti"
   user {
       scope = "sub"
       access_positive = yes
   }
   group {
       scope = "sub"
       name_attribute = "cn"
       cacheable_name = no
       cacheable_dn = no
   }
   client {
       scope = "sub"
       base_dn = ""
   }
   profile {
   }
   options {
       ldap_debug = 0
       chase_referrals = yes
       rebind = yes
       net_timeout = 1
       res_timeout = 20
       srv_timelimit = 20
       idle = 60
       probes = 3
       interval = 3
   }
   tls {
       start_tls = no
   }
  }
rlm_ldap: libldap vendor: OpenLDAP, version: 20439
rlm_ldap (ldap_fpti): Couldn't find configuration for accounting, will
return NOOP for calls from this section
rlm_ldap (ldap_fpti): Couldn't find configuration for post-auth, will
return NOOP for calls from this section
rlm_ldap (ldap_fpti): Initialising connection pool
   pool {
       start = 3
       min = 1
       max = 10
       spare = 3
       uses = 0
       lifetime = 0
       cleanup_interval = 30
       idle_timeout = 60
       retry_delay = 1
       spread = no
   }
rlm_ldap (ldap_fpti): Opening additional connection (0), 1 of 10 pending
slots used
rlm_ldap (ldap_fpti): Connecting to ldap://ptipdc.pti:389
rlm_ldap (ldap_fpti): Waiting for bind result...
rlm_ldap (ldap_fpti): Bind successful
rlm_ldap (ldap_fpti): Opening additional connection (1), 1 of 9 pending
slots used
rlm_ldap (ldap_fpti): Connecting to ldap://ptipdc.pti:389
rlm_ldap (ldap_fpti): Waiting for bind result...
rlm_ldap (ldap_fpti): Bind successful
rlm_ldap (ldap_fpti): Opening additional connection (2), 1 of 8 pending
slots used
rlm_ldap (ldap_fpti): Connecting to ldap://ptipdc.pti:389
rlm_ldap (ldap_fpti): Waiting for bind result...
rlm_ldap (ldap_fpti): Bind successful
  # Instantiating module "ldap_pti" from file /etc/raddb/mods-enabled/ldap
  ldap ldap_pti {
      server = "ptildap-dmz.pti"
      password = <<< secret >>>
      identity = "cn=import,dc=parque"
   user {
       scope = "sub"
       access_positive = yes
   }
   group {
       scope = "sub"
       name_attribute = "cn"
       cacheable_name = no
       cacheable_dn = no
   }
   client {
       scope = "sub"
       base_dn = ""
   }
   profile {
   }
   options {
       ldap_debug = 0
       chase_referrals = yes
       rebind = yes
       net_timeout = 1
       res_timeout = 20
       srv_timelimit = 20
       idle = 60
       probes = 3
       interval = 3
   }
   tls {
       start_tls = no
   }
  }
rlm_ldap (ldap_pti): Couldn't find configuration for accounting, will
return NOOP for calls from this section
rlm_ldap (ldap_pti): Couldn't find configuration for post-auth, will return
NOOP for calls from this section
rlm_ldap (ldap_pti): Initialising connection pool
   pool {
       start = 5
       min = 4
       max = 20
       spare = 3
       uses = 0
       lifetime = 0
       cleanup_interval = 30
       idle_timeout = 60
       retry_delay = 1
       spread = no
   }
rlm_ldap (ldap_pti): Opening additional connection (0), 1 of 20 pending
slots used
rlm_ldap (ldap_pti): Connecting to ldap://ptildap-dmz.pti:389
rlm_ldap (ldap_pti): Waiting for bind result...
rlm_ldap (ldap_pti): Bind successful
rlm_ldap (ldap_pti): Opening additional connection (1), 1 of 19 pending
slots used
rlm_ldap (ldap_pti): Connecting to ldap://ptildap-dmz.pti:389
rlm_ldap (ldap_pti): Waiting for bind result...
rlm_ldap (ldap_pti): Bind successful
rlm_ldap (ldap_pti): Opening additional connection (2), 1 of 18 pending
slots used
rlm_ldap (ldap_pti): Connecting to ldap://ptildap-dmz.pti:389
rlm_ldap (ldap_pti): Waiting for bind result...
rlm_ldap (ldap_pti): Bind successful
rlm_ldap (ldap_pti): Opening additional connection (3), 1 of 17 pending
slots used
rlm_ldap (ldap_pti): Connecting to ldap://ptildap-dmz.pti:389
rlm_ldap (ldap_pti): Waiting for bind result...
rlm_ldap (ldap_pti): Bind successful
rlm_ldap (ldap_pti): Opening additional connection (4), 1 of 16 pending
slots used
rlm_ldap (ldap_pti): Connecting to ldap://ptildap-dmz.pti:389
rlm_ldap (ldap_pti): Waiting for bind result...
rlm_ldap (ldap_pti): Bind successful
 } # modules
radiusd: #### Loading Virtual Servers ####
server { # from file /etc/raddb/radiusd.conf
} # server
server default { # from file /etc/raddb/sites-enabled/default
 # Loading authenticate {...}
 # Loading authorize {...}
 # Loading preacct {...}
 # Loading accounting {...}
 # Loading session {...}
 # Loading post-proxy {...}
 # Loading post-auth {...}
} # server default
server inner-tunnel { # from file /etc/raddb/sites-enabled/inner-tunnel
 # Loading authenticate {...}
 # Loading authorize {...}
 # Loading session {...}
 # Loading post-proxy {...}
 # Loading post-auth {...}
} # server inner-tunnel
radiusd: #### Opening IP addresses and Ports ####
listen {
      type = "auth"
      ipaddr = *
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "acct"
      ipaddr = *
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "auth"
      ipv6addr = ::
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "acct"
      ipv6addr = ::
      port = 0
   limit {
       max_connections = 16
       lifetime = 0
       idle_timeout = 30
   }
}
listen {
      type = "auth"
      ipaddr = 127.0.0.1
      port = 18120
}
Listening on auth address * port 1812 bound to server default
Listening on acct address * port 1813 bound to server default
Listening on auth address :: port 1812 bound to server default
Listening on acct address :: port 1813 bound to server default
Listening on auth address 127.0.0.1 port 18120 bound to server inner-tunnel
Opening new proxy socket 'proxy address * port 0'
Listening on proxy address * port 55782
Ready to process requests
(0) Received Access-Request Id 86 from 172.25.89.1:32768 to
186.233.12.201:1812 length 230
(0)   User-Name = '0013006'
(0)   Chargeable-User-Identity = 0x00
(0)   Location-Capable = Civix-Location
(0)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(0)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(0)   NAS-Port = 4
(0)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(0)   NAS-IP-Address = 172.25.89.1
(0)   NAS-Identifier = 'WLC-PTI3'
(0)   Airespace-Wlan-Id = 11
(0)   Service-Type = Framed-User
(0)   Framed-MTU = 1300
(0)   NAS-Port-Type = Wireless-802.11
(0)   Tunnel-Type:0 = VLAN
(0)   Tunnel-Medium-Type:0 = IEEE-802
(0)   Tunnel-Private-Group-Id:0 = '81'
(0)   EAP-Message = 0x0201000c0130303133303036
(0)   Message-Authenticator = 0x88f647d8d73a9e4542ea0c86085d46f6
(0) # Executing section authorize from file /etc/raddb/sites-enabled/default
(0)   authorize {
(0)     if (User-Name !~ /@/){
(0)     if (User-Name !~ /@/) -> TRUE
(0)     if (User-Name !~ /@/) {
(0)       update request {
(0)         Realm := 'pti'
(0)       } # update request = noop
(0)     } # if (User-Name !~ /@/) = noop
(0)     [preprocess] = ok
(0)     [chap] = noop
(0)     [mschap] = noop
(0)     [digest] = noop
(0) suffix: Request already has destination realm set.  Ignoring
(0)     [suffix] = noop
(0)     update control {
(0)       Proxy-To-Realm := 'LOCAL'
(0)     } # update control = noop
(0) eap: Peer sent code Response (2) ID 1 length 12
(0) eap: EAP-Identity reply, returning 'ok' so we can short-circuit the
rest of authorize
(0)     [eap] = ok
(0)   } # authorize = ok
(0) Found Auth-Type = EAP
(0) # Executing group from file /etc/raddb/sites-enabled/default
(0)   authenticate {
(0) eap: Peer sent method Identity (1)
(0) eap: Calling eap_peap to process EAP data
(0) eap_peap: Flushing SSL sessions (of #0)
(0) eap_peap: Initiate
(0) eap_peap: Start returned 1
(0) eap: EAP session adding &reply:State = 0x11f1caf111f3d30a
(0)     [eap] = handled
(0)   } # authenticate = handled
(0) Using Post-Auth-Type Challenge
(0) # Executing group from file /etc/raddb/sites-enabled/default
(0) Sent Access-Challenge Id 86 from 186.233.12.201:1812 to
172.25.89.1:32768 length 64
(0)   EAP-Message = 0x010200061920
(0)   Message-Authenticator = 0x00000000000000000000000000000000
(0)   State = 0x11f1caf111f3d30a116635786c55106c
(0) Finished request
Waking up in 0.3 seconds.
(1) Received Access-Request Id 87 from 172.25.89.1:32768 to
186.233.12.201:1812 length 388
(1)   User-Name = '0013006'
(1)   Chargeable-User-Identity = 0x00
(1)   Location-Capable = Civix-Location
(1)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(1)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(1)   NAS-Port = 4
(1)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(1)   NAS-IP-Address = 172.25.89.1
(1)   NAS-Identifier = 'WLC-PTI3'
(1)   Airespace-Wlan-Id = 11
(1)   Service-Type = Framed-User
(1)   Framed-MTU = 1300
(1)   NAS-Port-Type = Wireless-802.11
(1)   Tunnel-Type:0 = VLAN
(1)   Tunnel-Medium-Type:0 = IEEE-802
(1)   Tunnel-Private-Group-Id:0 = '81'
(1)   EAP-Message =
0x0202009819800000008e160301008901000085030154f4a17d8014d43fc16e2c9746375a0eb938d29239271c8c83b89551ee6b3f7300004a00ffc024c023c00ac009c008c028c027c014c013c012c026c025c005c004c003c02ac029c00fc00ec00d006b0067003900330016003d003c0035002f000ac0
(1)   State = 0x11f1caf111f3d30a116635786c55106c
(1)   Message-Authenticator = 0xc0bd10eb720fe9895212f06ede236a38
(1) session-state: No cached attributes
(1) # Executing section authorize from file /etc/raddb/sites-enabled/default
(1)   authorize {
(1)     if (User-Name !~ /@/){
(1)     if (User-Name !~ /@/) -> TRUE
(1)     if (User-Name !~ /@/) {
(1)       update request {
(1)         Realm := 'pti'
(1)       } # update request = noop
(1)     } # if (User-Name !~ /@/) = noop
(1)     [preprocess] = ok
(1)     [chap] = noop
(1)     [mschap] = noop
(1)     [digest] = noop
(1) suffix: Request already has destination realm set.  Ignoring
(1)     [suffix] = noop
(1)     update control {
(1)       Proxy-To-Realm := 'LOCAL'
(1)     } # update control = noop
(1) eap: Peer sent code Response (2) ID 2 length 152
(1) eap: Continuing tunnel setup
(1)     [eap] = ok
(1)   } # authorize = ok
(1) Found Auth-Type = EAP
(1) # Executing group from file /etc/raddb/sites-enabled/default
(1)   authenticate {
(1) eap: Expiring EAP session with state 0x11f1caf111f3d30a
(1) eap: Finished EAP session with state 0x11f1caf111f3d30a
(1) eap: Previous EAP request found for state 0x11f1caf111f3d30a, released
from the list
(1) eap: Peer sent method PEAP (25)
(1) eap: EAP PEAP (25)
(1) eap: Calling eap_peap to process EAP data
(1) eap_peap: processing EAP-TLS
(1) eap_peap: TLS Length 142
(1) eap_peap: Length Included
(1) eap_peap: eaptls_verify returned 11
(1) eap_peap: (other): before/accept initialization
(1) eap_peap: TLS_accept: before/accept initialization
(1) eap_peap: <<< TLS 1.0 Handshake [length 0089], ClientHello
(1) eap_peap: TLS_accept: SSLv3 read client hello A
(1) eap_peap: >>> TLS 1.0 Handshake [length 0059], ServerHello
(1) eap_peap: TLS_accept: SSLv3 write server hello A
(1) eap_peap: >>> TLS 1.0 Handshake [length 0a0a], Certificate
(1) eap_peap: TLS_accept: SSLv3 write certificate A
(1) eap_peap: >>> TLS 1.0 Handshake [length 014b], ServerKeyExchange
(1) eap_peap: TLS_accept: SSLv3 write key exchange A
(1) eap_peap: >>> TLS 1.0 Handshake [length 0004], ServerHelloDone
(1) eap_peap: TLS_accept: SSLv3 write server done A
(1) eap_peap: TLS_accept: SSLv3 flush data
(1) eap_peap: TLS_accept: Need to read more data: SSLv3 read client
certificate A
(1) eap_peap: TLS_accept: Need to read more data: SSLv3 read client
certificate A
In SSL Handshake Phase
In SSL Accept mode
(1) eap_peap: eaptls_process returned 13
(1) eap_peap: FR_TLS_HANDLED
(1) eap: EAP session adding &reply:State = 0x11f1caf110f2d30a
(1)     [eap] = handled
(1)   } # authenticate = handled
(1) Using Post-Auth-Type Challenge
(1) # Executing group from file /etc/raddb/sites-enabled/default
(1) Sent Access-Challenge Id 87 from 186.233.12.201:1812 to
172.25.89.1:32768 length 1068
(1)   EAP-Message =
0x010303ec19c000000bc6160301005902000055030154f4a17e465173b7c109aa2868ad0cd3dcc0596c0f97d7a3f467c0d163b75af920d3b3bb62b9ace61b29da617f7294f0c60bd88df045ef8716c9c912b417639260c01400000dff01000100000b0004030001021603010a0a0b000a06000a0300056e
(1)   Message-Authenticator = 0x00000000000000000000000000000000
(1)   State = 0x11f1caf110f2d30a116635786c55106c
(1) Finished request
Waking up in 0.3 seconds.
(2) Received Access-Request Id 88 from 172.25.89.1:32768 to
186.233.12.201:1812 length 242
(2)   User-Name = '0013006'
(2)   Chargeable-User-Identity = 0x00
(2)   Location-Capable = Civix-Location
(2)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(2)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(2)   NAS-Port = 4
(2)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(2)   NAS-IP-Address = 172.25.89.1
(2)   NAS-Identifier = 'WLC-PTI3'
(2)   Airespace-Wlan-Id = 11
(2)   Service-Type = Framed-User
(2)   Framed-MTU = 1300
(2)   NAS-Port-Type = Wireless-802.11
(2)   Tunnel-Type:0 = VLAN
(2)   Tunnel-Medium-Type:0 = IEEE-802
(2)   Tunnel-Private-Group-Id:0 = '81'
(2)   EAP-Message = 0x020300061900
(2)   State = 0x11f1caf110f2d30a116635786c55106c
(2)   Message-Authenticator = 0x4c14dbcfe0701b1675daa502d90d686f
(2) session-state: No cached attributes
(2) # Executing section authorize from file /etc/raddb/sites-enabled/default
(2)   authorize {
(2)     if (User-Name !~ /@/){
(2)     if (User-Name !~ /@/) -> TRUE
(2)     if (User-Name !~ /@/) {
(2)       update request {
(2)         Realm := 'pti'
(2)       } # update request = noop
(2)     } # if (User-Name !~ /@/) = noop
(2)     [preprocess] = ok
(2)     [chap] = noop
(2)     [mschap] = noop
(2)     [digest] = noop
(2) suffix: Request already has destination realm set.  Ignoring
(2)     [suffix] = noop
(2)     update control {
(2)       Proxy-To-Realm := 'LOCAL'
(2)     } # update control = noop
(2) eap: Peer sent code Response (2) ID 3 length 6
(2) eap: Continuing tunnel setup
(2)     [eap] = ok
(2)   } # authorize = ok
(2) Found Auth-Type = EAP
(2) # Executing group from file /etc/raddb/sites-enabled/default
(2)   authenticate {
(2) eap: Expiring EAP session with state 0x11f1caf110f2d30a
(2) eap: Finished EAP session with state 0x11f1caf110f2d30a
(2) eap: Previous EAP request found for state 0x11f1caf110f2d30a, released
from the list
(2) eap: Peer sent method PEAP (25)
(2) eap: EAP PEAP (25)
(2) eap: Calling eap_peap to process EAP data
(2) eap_peap: processing EAP-TLS
(2) eap_peap: Received TLS ACK
(2) eap_peap: Received TLS ACK
(2) eap_peap: ACK handshake fragment handler
(2) eap_peap: eaptls_verify returned 1
(2) eap_peap: eaptls_process returned 13
(2) eap_peap: FR_TLS_HANDLED
(2) eap: EAP session adding &reply:State = 0x11f1caf113f5d30a
(2)     [eap] = handled
(2)   } # authenticate = handled
(2) Using Post-Auth-Type Challenge
(2) # Executing group from file /etc/raddb/sites-enabled/default
(2) Sent Access-Challenge Id 88 from 186.233.12.201:1812 to
172.25.89.1:32768 length 1064
(2)   EAP-Message =
0x010403e81940696f6e76616c67322e63726c30819606082b06010505070101048189308186304706082b06010505073002863b687474703a2f2f7365637572652e676c6f62616c7369676e2e636f6d2f6361636572742f67736f7267616e697a6174696f6e76616c67322e637274303b06082b06010505
(2)   Message-Authenticator = 0x00000000000000000000000000000000
(2)   State = 0x11f1caf113f5d30a116635786c55106c
(2) Finished request
Waking up in 0.3 seconds.
(3) Received Access-Request Id 89 from 172.25.89.1:32768 to
186.233.12.201:1812 length 242
(3)   User-Name = '0013006'
(3)   Chargeable-User-Identity = 0x00
(3)   Location-Capable = Civix-Location
(3)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(3)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(3)   NAS-Port = 4
(3)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(3)   NAS-IP-Address = 172.25.89.1
(3)   NAS-Identifier = 'WLC-PTI3'
(3)   Airespace-Wlan-Id = 11
(3)   Service-Type = Framed-User
(3)   Framed-MTU = 1300
(3)   NAS-Port-Type = Wireless-802.11
(3)   Tunnel-Type:0 = VLAN
(3)   Tunnel-Medium-Type:0 = IEEE-802
(3)   Tunnel-Private-Group-Id:0 = '81'
(3)   EAP-Message = 0x020400061900
(3)   State = 0x11f1caf113f5d30a116635786c55106c
(3)   Message-Authenticator = 0x8502f53a8a7164a1a13924ef599e763f
(3) session-state: No cached attributes
(3) # Executing section authorize from file /etc/raddb/sites-enabled/default
(3)   authorize {
(3)     if (User-Name !~ /@/){
(3)     if (User-Name !~ /@/) -> TRUE
(3)     if (User-Name !~ /@/) {
(3)       update request {
(3)         Realm := 'pti'
(3)       } # update request = noop
(3)     } # if (User-Name !~ /@/) = noop
(3)     [preprocess] = ok
(3)     [chap] = noop
(3)     [mschap] = noop
(3)     [digest] = noop
(3) suffix: Request already has destination realm set.  Ignoring
(3)     [suffix] = noop
(3)     update control {
(3)       Proxy-To-Realm := 'LOCAL'
(3)     } # update control = noop
(3) eap: Peer sent code Response (2) ID 4 length 6
(3) eap: Continuing tunnel setup
(3)     [eap] = ok
(3)   } # authorize = ok
(3) Found Auth-Type = EAP
(3) # Executing group from file /etc/raddb/sites-enabled/default
(3)   authenticate {
(3) eap: Expiring EAP session with state 0x11f1caf113f5d30a
(3) eap: Finished EAP session with state 0x11f1caf113f5d30a
(3) eap: Previous EAP request found for state 0x11f1caf113f5d30a, released
from the list
(3) eap: Peer sent method PEAP (25)
(3) eap: EAP PEAP (25)
(3) eap: Calling eap_peap to process EAP data
(3) eap_peap: processing EAP-TLS
(3) eap_peap: Received TLS ACK
(3) eap_peap: Received TLS ACK
(3) eap_peap: ACK handshake fragment handler
(3) eap_peap: eaptls_verify returned 1
(3) eap_peap: eaptls_process returned 13
(3) eap_peap: FR_TLS_HANDLED
(3) eap: EAP session adding &reply:State = 0x11f1caf112f4d30a
(3)     [eap] = handled
(3)   } # authenticate = handled
(3) Using Post-Auth-Type Challenge
(3) # Executing group from file /etc/raddb/sites-enabled/default
(3) Sent Access-Challenge Id 89 from 186.233.12.201:1812 to
172.25.89.1:32768 length 1064
(3)   EAP-Message =
0x010503e8194028eb01a7823cd98e1de5470dba8b5914a31f1f4beae2274686ce9d39c46641a7e215236b5647c1edc553e4d4801f6bfa804698b209a60f95be6688930203010001a38201503082014c300e0603551d0f0101ff04040302010630120603551d130101ff040830060101ff020100301d0603
(3)   Message-Authenticator = 0x00000000000000000000000000000000
(3)   State = 0x11f1caf112f4d30a116635786c55106c
(3) Finished request
Waking up in 0.2 seconds.
(4) Received Access-Request Id 90 from 172.25.89.1:32768 to
186.233.12.201:1812 length 242
(4)   User-Name = '0013006'
(4)   Chargeable-User-Identity = 0x00
(4)   Location-Capable = Civix-Location
(4)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(4)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(4)   NAS-Port = 4
(4)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(4)   NAS-IP-Address = 172.25.89.1
(4)   NAS-Identifier = 'WLC-PTI3'
(4)   Airespace-Wlan-Id = 11
(4)   Service-Type = Framed-User
(4)   Framed-MTU = 1300
(4)   NAS-Port-Type = Wireless-802.11
(4)   Tunnel-Type:0 = VLAN
(4)   Tunnel-Medium-Type:0 = IEEE-802
(4)   Tunnel-Private-Group-Id:0 = '81'
(4)   EAP-Message = 0x020500061900
(4)   State = 0x11f1caf112f4d30a116635786c55106c
(4)   Message-Authenticator = 0x2d8c7a700408130e1165807f2d273dcf
(4) session-state: No cached attributes
(4) # Executing section authorize from file /etc/raddb/sites-enabled/default
(4)   authorize {
(4)     if (User-Name !~ /@/){
(4)     if (User-Name !~ /@/) -> TRUE
(4)     if (User-Name !~ /@/) {
(4)       update request {
(4)         Realm := 'pti'
(4)       } # update request = noop
(4)     } # if (User-Name !~ /@/) = noop
(4)     [preprocess] = ok
(4)     [chap] = noop
(4)     [mschap] = noop
(4)     [digest] = noop
(4) suffix: Request already has destination realm set.  Ignoring
(4)     [suffix] = noop
(4)     update control {
(4)       Proxy-To-Realm := 'LOCAL'
(4)     } # update control = noop
(4) eap: Peer sent code Response (2) ID 5 length 6
(4) eap: Continuing tunnel setup
(4)     [eap] = ok
(4)   } # authorize = ok
(4) Found Auth-Type = EAP
(4) # Executing group from file /etc/raddb/sites-enabled/default
(4)   authenticate {
(4) eap: Expiring EAP session with state 0x11f1caf112f4d30a
(4) eap: Finished EAP session with state 0x11f1caf112f4d30a
(4) eap: Previous EAP request found for state 0x11f1caf112f4d30a, released
from the list
(4) eap: Peer sent method PEAP (25)
(4) eap: EAP PEAP (25)
(4) eap: Calling eap_peap to process EAP data
(4) eap_peap: processing EAP-TLS
(4) eap_peap: Received TLS ACK
(4) eap_peap: Received TLS ACK
(4) eap_peap: ACK handshake fragment handler
(4) eap_peap: eaptls_verify returned 1
(4) eap_peap: eaptls_process returned 13
(4) eap_peap: FR_TLS_HANDLED
(4) eap: EAP session adding &reply:State = 0x11f1caf115f7d30a
(4)     [eap] = handled
(4)   } # authenticate = handled
(4) Using Post-Auth-Type Challenge
(4) # Executing group from file /etc/raddb/sites-enabled/default
(4) Sent Access-Challenge Id 90 from 186.233.12.201:1812 to
172.25.89.1:32768 length 96
(4)   EAP-Message =
0x010600261900229e9713f21e7ee4c6792f2e7fdf8d7bd8316e1a73edbb16030100040e000000
(4)   Message-Authenticator = 0x00000000000000000000000000000000
(4)   State = 0x11f1caf115f7d30a116635786c55106c
(4) Finished request
Waking up in 0.2 seconds.
(5) Received Access-Request Id 91 from 172.25.89.1:32768 to
186.233.12.201:1812 length 380
(5)   User-Name = '0013006'
(5)   Chargeable-User-Identity = 0x00
(5)   Location-Capable = Civix-Location
(5)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(5)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(5)   NAS-Port = 4
(5)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(5)   NAS-IP-Address = 172.25.89.1
(5)   NAS-Identifier = 'WLC-PTI3'
(5)   Airespace-Wlan-Id = 11
(5)   Service-Type = Framed-User
(5)   Framed-MTU = 1300
(5)   NAS-Port-Type = Wireless-802.11
(5)   Tunnel-Type:0 = VLAN
(5)   Tunnel-Medium-Type:0 = IEEE-802
(5)   Tunnel-Private-Group-Id:0 = '81'
(5)   EAP-Message =
0x020600901980000000861603010046100000424104eb7711a6e6d228e65c787d7a36ee3afbf8535dc31358fdee74047aaae78504cecac919394a83f1df13045cd580160afcc5e21d34e44e38d61640165262fd50fc1403010001011603010030a08884d653700a23f032841650102573f20217644d8273
(5)   State = 0x11f1caf115f7d30a116635786c55106c
(5)   Message-Authenticator = 0x8703cc5a7887191cff40ce9abcb49da3
(5) session-state: No cached attributes
(5) # Executing section authorize from file /etc/raddb/sites-enabled/default
(5)   authorize {
(5)     if (User-Name !~ /@/){
(5)     if (User-Name !~ /@/) -> TRUE
(5)     if (User-Name !~ /@/) {
(5)       update request {
(5)         Realm := 'pti'
(5)       } # update request = noop
(5)     } # if (User-Name !~ /@/) = noop
(5)     [preprocess] = ok
(5)     [chap] = noop
(5)     [mschap] = noop
(5)     [digest] = noop
(5) suffix: Request already has destination realm set.  Ignoring
(5)     [suffix] = noop
(5)     update control {
(5)       Proxy-To-Realm := 'LOCAL'
(5)     } # update control = noop
(5) eap: Peer sent code Response (2) ID 6 length 144
(5) eap: Continuing tunnel setup
(5)     [eap] = ok
(5)   } # authorize = ok
(5) Found Auth-Type = EAP
(5) # Executing group from file /etc/raddb/sites-enabled/default
(5)   authenticate {
(5) eap: Expiring EAP session with state 0x11f1caf115f7d30a
(5) eap: Finished EAP session with state 0x11f1caf115f7d30a
(5) eap: Previous EAP request found for state 0x11f1caf115f7d30a, released
from the list
(5) eap: Peer sent method PEAP (25)
(5) eap: EAP PEAP (25)
(5) eap: Calling eap_peap to process EAP data
(5) eap_peap: processing EAP-TLS
(5) eap_peap: TLS Length 134
(5) eap_peap: Length Included
(5) eap_peap: eaptls_verify returned 11
(5) eap_peap: <<< TLS 1.0 Handshake [length 0046], ClientKeyExchange
(5) eap_peap: TLS_accept: SSLv3 read client key exchange A
(5) eap_peap: <<< TLS 1.0 ChangeCipherSpec [length 0001]
(5) eap_peap: <<< TLS 1.0 Handshake [length 0010], Finished
(5) eap_peap: TLS_accept: SSLv3 read finished A
(5) eap_peap: >>> TLS 1.0 ChangeCipherSpec [length 0001]
(5) eap_peap: TLS_accept: SSLv3 write change cipher spec A
(5) eap_peap: >>> TLS 1.0 Handshake [length 0010], Finished
(5) eap_peap: TLS_accept: SSLv3 write finished A
(5) eap_peap: TLS_accept: SSLv3 flush data
  SSL: adding session
d3b3bb62b9ace61b29da617f7294f0c60bd88df045ef8716c9c912b417639260 to cache
(5) eap_peap: (other): SSL negotiation finished successfully
SSL Connection Established
(5) eap_peap: eaptls_process returned 13
(5) eap_peap: FR_TLS_HANDLED
(5) eap: EAP session adding &reply:State = 0x11f1caf114f6d30a
(5)     [eap] = handled
(5)   } # authenticate = handled
(5) Using Post-Auth-Type Challenge
(5) # Executing group from file /etc/raddb/sites-enabled/default
(5) Sent Access-Challenge Id 91 from 186.233.12.201:1812 to
172.25.89.1:32768 length 123
(5)   EAP-Message =
0x0107004119001403010001011603010030f7bcac9973a7dd3f77ba867a82350e590b7844b4873dfcca3ae96575ee0511223c5e97995ffcf40a54188baa3ab10706
(5)   Message-Authenticator = 0x00000000000000000000000000000000
(5)   State = 0x11f1caf114f6d30a116635786c55106c
(5) Finished request
Waking up in 0.2 seconds.
(6) Received Access-Request Id 92 from 172.25.89.1:32768 to
186.233.12.201:1812 length 242
(6)   User-Name = '0013006'
(6)   Chargeable-User-Identity = 0x00
(6)   Location-Capable = Civix-Location
(6)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(6)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(6)   NAS-Port = 4
(6)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(6)   NAS-IP-Address = 172.25.89.1
(6)   NAS-Identifier = 'WLC-PTI3'
(6)   Airespace-Wlan-Id = 11
(6)   Service-Type = Framed-User
(6)   Framed-MTU = 1300
(6)   NAS-Port-Type = Wireless-802.11
(6)   Tunnel-Type:0 = VLAN
(6)   Tunnel-Medium-Type:0 = IEEE-802
(6)   Tunnel-Private-Group-Id:0 = '81'
(6)   EAP-Message = 0x020700061900
(6)   State = 0x11f1caf114f6d30a116635786c55106c
(6)   Message-Authenticator = 0xf76141c4854a0c8d3bb47a5085346971
(6) session-state: No cached attributes
(6) # Executing section authorize from file /etc/raddb/sites-enabled/default
(6)   authorize {
(6)     if (User-Name !~ /@/){
(6)     if (User-Name !~ /@/) -> TRUE
(6)     if (User-Name !~ /@/) {
(6)       update request {
(6)         Realm := 'pti'
(6)       } # update request = noop
(6)     } # if (User-Name !~ /@/) = noop
(6)     [preprocess] = ok
(6)     [chap] = noop
(6)     [mschap] = noop
(6)     [digest] = noop
(6) suffix: Request already has destination realm set.  Ignoring
(6)     [suffix] = noop
(6)     update control {
(6)       Proxy-To-Realm := 'LOCAL'
(6)     } # update control = noop
(6) eap: Peer sent code Response (2) ID 7 length 6
(6) eap: Continuing tunnel setup
(6)     [eap] = ok
(6)   } # authorize = ok
(6) Found Auth-Type = EAP
(6) # Executing group from file /etc/raddb/sites-enabled/default
(6)   authenticate {
(6) eap: Expiring EAP session with state 0x11f1caf114f6d30a
(6) eap: Finished EAP session with state 0x11f1caf114f6d30a
(6) eap: Previous EAP request found for state 0x11f1caf114f6d30a, released
from the list
(6) eap: Peer sent method PEAP (25)
(6) eap: EAP PEAP (25)
(6) eap: Calling eap_peap to process EAP data
(6) eap_peap: processing EAP-TLS
(6) eap_peap: Received TLS ACK
(6) eap_peap: Received TLS ACK
(6) eap_peap: ACK handshake is finished
(6) eap_peap: eaptls_verify returned 3
(6) eap_peap: eaptls_process returned 3
(6) eap_peap: FR_TLS_SUCCESS
(6) eap_peap: Session established.  Decoding tunneled attributes
(6) eap_peap: PEAP state TUNNEL ESTABLISHED
(6) eap: EAP session adding &reply:State = 0x11f1caf117f9d30a
(6)     [eap] = handled
(6)   } # authenticate = handled
(6) Using Post-Auth-Type Challenge
(6) # Executing group from file /etc/raddb/sites-enabled/default
(6) Sent Access-Challenge Id 92 from 186.233.12.201:1812 to
172.25.89.1:32768 length 101
(6)   EAP-Message =
0x0108002b1900170301002093aae564487184c346e9e5a3ec78545e3b38446e576f63fccbbc085bce80abef
(6)   Message-Authenticator = 0x00000000000000000000000000000000
(6)   State = 0x11f1caf117f9d30a116635786c55106c
(6) Finished request
Waking up in 0.2 seconds.
(7) Received Access-Request Id 93 from 172.25.89.1:32768 to
186.233.12.201:1812 length 279
(7)   User-Name = '0013006'
(7)   Chargeable-User-Identity = 0x00
(7)   Location-Capable = Civix-Location
(7)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(7)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(7)   NAS-Port = 4
(7)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(7)   NAS-IP-Address = 172.25.89.1
(7)   NAS-Identifier = 'WLC-PTI3'
(7)   Airespace-Wlan-Id = 11
(7)   Service-Type = Framed-User
(7)   Framed-MTU = 1300
(7)   NAS-Port-Type = Wireless-802.11
(7)   Tunnel-Type:0 = VLAN
(7)   Tunnel-Medium-Type:0 = IEEE-802
(7)   Tunnel-Private-Group-Id:0 = '81'
(7)   EAP-Message =
0x0208002b19001703010020499073891c26328e79864e57253e3903b3bf8634e10bceea8c3a06ed59534a54
(7)   State = 0x11f1caf117f9d30a116635786c55106c
(7)   Message-Authenticator = 0xe4b075e2c838b4da11dab936400fc9ea
(7) session-state: No cached attributes
(7) # Executing section authorize from file /etc/raddb/sites-enabled/default
(7)   authorize {
(7)     if (User-Name !~ /@/){
(7)     if (User-Name !~ /@/) -> TRUE
(7)     if (User-Name !~ /@/) {
(7)       update request {
(7)         Realm := 'pti'
(7)       } # update request = noop
(7)     } # if (User-Name !~ /@/) = noop
(7)     [preprocess] = ok
(7)     [chap] = noop
(7)     [mschap] = noop
(7)     [digest] = noop
(7) suffix: Request already has destination realm set.  Ignoring
(7)     [suffix] = noop
(7)     update control {
(7)       Proxy-To-Realm := 'LOCAL'
(7)     } # update control = noop
(7) eap: Peer sent code Response (2) ID 8 length 43
(7) eap: Continuing tunnel setup
(7)     [eap] = ok
(7)   } # authorize = ok
(7) Found Auth-Type = EAP
(7) # Executing group from file /etc/raddb/sites-enabled/default
(7)   authenticate {
(7) eap: Expiring EAP session with state 0x11f1caf117f9d30a
(7) eap: Finished EAP session with state 0x11f1caf117f9d30a
(7) eap: Previous EAP request found for state 0x11f1caf117f9d30a, released
from the list
(7) eap: Peer sent method PEAP (25)
(7) eap: EAP PEAP (25)
(7) eap: Calling eap_peap to process EAP data
(7) eap_peap: processing EAP-TLS
(7) eap_peap: eaptls_verify returned 7
(7) eap_peap: Done initial handshake
(7) eap_peap: eaptls_process returned 7
(7) eap_peap: FR_TLS_OK
(7) eap_peap: Session established.  Decoding tunneled attributes
(7) eap_peap: PEAP state WAITING FOR INNER IDENTITY
(7) eap_peap: Identity - 0013006
(7) eap_peap: Got inner identity '0013006'
(7) eap_peap: Setting default EAP type for tunneled EAP session
(7) eap_peap: Got tunneled request
(7) eap_peap:   EAP-Message = 0x0208000c0130303133303036
(7) eap_peap: Setting User-Name to 0013006
(7) eap_peap: Sending tunneled request to inner-tunnel
(7) eap_peap:   EAP-Message = 0x0208000c0130303133303036
(7) eap_peap:   FreeRADIUS-Proxied-To = 127.0.0.1
(7) eap_peap:   User-Name = '0013006'
(7) eap_peap:   Chargeable-User-Identity = 0x00
(7) eap_peap:   Location-Capable = Civix-Location
(7) eap_peap:   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(7) eap_peap:   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(7) eap_peap:   NAS-Port = 4
(7) eap_peap:   NAS-IP-Address = 172.25.89.1
(7) eap_peap:   NAS-Identifier = 'WLC-PTI3'
(7) eap_peap:   Service-Type = Framed-User
(7) eap_peap:   Framed-MTU = 1300
(7) eap_peap:   NAS-Port-Type = Wireless-802.11
(7) eap_peap:   Event-Timestamp = 'Mar  2 2015 14:44:30 BRT'
(7) Virtual server received request
(7)   EAP-Message = 0x0208000c0130303133303036
(7)   FreeRADIUS-Proxied-To = 127.0.0.1
(7)   User-Name = '0013006'
(7)   Chargeable-User-Identity = 0x00
(7)   Location-Capable = Civix-Location
(7)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(7)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(7)   NAS-Port = 4
(7)   NAS-IP-Address = 172.25.89.1
(7)   NAS-Identifier = 'WLC-PTI3'
(7)   Service-Type = Framed-User
(7)   Framed-MTU = 1300
(7)   NAS-Port-Type = Wireless-802.11
(7)   Event-Timestamp = 'Mar  2 2015 14:44:30 BRT'
(7) server inner-tunnel {
(7)   # Executing section authorize from file
/etc/raddb/sites-enabled/inner-tunnel
(7)     authorize {
(7)       if (User-Name !~ /@/){
(7)       if (User-Name !~ /@/) -> TRUE
(7)       if (User-Name !~ /@/) {
(7)         update request {
(7)           Realm := 'pti'
(7)         } # update request = noop
(7)       } # if (User-Name !~ /@/) = noop
(7)       [preprocess] = ok
(7)       [chap] = noop
(7)       [mschap] = noop
(7) suffix: Request already has destination realm set.  Ignoring
(7)       [suffix] = noop
(7)       update control {
(7)         &Proxy-To-Realm := 'LOCAL'
(7)       } # update control = noop
(7) eap: Peer sent code Response (2) ID 8 length 12
(7) eap: EAP-Identity reply, returning 'ok' so we can short-circuit the
rest of authorize
(7)       [eap] = ok
(7)     } # authorize = ok
(7)   Found Auth-Type = EAP
(7)   # Executing group from file /etc/raddb/sites-enabled/inner-tunnel
(7)     authenticate {
(7) eap: Peer sent method Identity (1)
(7) eap: Calling eap_mschapv2 to process EAP data
(7) eap_mschapv2: Issuing Challenge
(7) eap: EAP session adding &reply:State = 0xbf3362e8bf3a787e
(7)       [eap] = handled
(7)     } # authenticate = handled
(7) } # server inner-tunnel
(7) Virtual server sending reply
(7)   EAP-Message =
0x010900211a0109001c109a790dc3bc538b11e35779c84b7e3b1230303133303036
(7)   Message-Authenticator = 0x00000000000000000000000000000000
(7)   State = 0xbf3362e8bf3a787eb47a3fd9c407c117
(7) eap_peap: Got tunneled reply code 11
(7) eap_peap:   EAP-Message =
0x010900211a0109001c109a790dc3bc538b11e35779c84b7e3b1230303133303036
(7) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(7) eap_peap:   State = 0xbf3362e8bf3a787eb47a3fd9c407c117
(7) eap_peap: Got tunneled reply RADIUS code 11
(7) eap_peap:   EAP-Message =
0x010900211a0109001c109a790dc3bc538b11e35779c84b7e3b1230303133303036
(7) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(7) eap_peap:   State = 0xbf3362e8bf3a787eb47a3fd9c407c117
(7) eap_peap: Got tunneled Access-Challenge
(7) eap: EAP session adding &reply:State = 0x11f1caf116f8d30a
(7)     [eap] = handled
(7)   } # authenticate = handled
(7) Using Post-Auth-Type Challenge
(7) # Executing group from file /etc/raddb/sites-enabled/default
(7) Sent Access-Challenge Id 93 from 186.233.12.201:1812 to
172.25.89.1:32768 length 133
(7)   EAP-Message =
0x0109004b19001703010040a0d799fe1df8727959fe9c1e26ee786a747e5625bd60c873e2b2754d3898e3bd73b77a19d6c6b3647bf342f8264dacdbeb8c9b6c05fac0a5175c13d2ff9a794d
(7)   Message-Authenticator = 0x00000000000000000000000000000000
(7)   State = 0x11f1caf116f8d30a116635786c55106c
(7) Finished request
Waking up in 0.2 seconds.
(8) Received Access-Request Id 94 from 172.25.89.1:32768 to
186.233.12.201:1812 length 343
(8)   User-Name = '0013006'
(8)   Chargeable-User-Identity = 0x00
(8)   Location-Capable = Civix-Location
(8)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(8)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(8)   NAS-Port = 4
(8)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(8)   NAS-IP-Address = 172.25.89.1
(8)   NAS-Identifier = 'WLC-PTI3'
(8)   Airespace-Wlan-Id = 11
(8)   Service-Type = Framed-User
(8)   Framed-MTU = 1300
(8)   NAS-Port-Type = Wireless-802.11
(8)   Tunnel-Type:0 = VLAN
(8)   Tunnel-Medium-Type:0 = IEEE-802
(8)   Tunnel-Private-Group-Id:0 = '81'
(8)   EAP-Message =
0x0209006b1900170301006023a891298361811366837e49b397c839f4ac62d0521731e49968047decb1e23de4f828d9c15ff88b8dd5b281ee2f39a852c28d785a5d97398b64c46482c063a284ece82d35c7103ee3db59be87cf0185e8af49784ba60487e47efe6904c03801
(8)   State = 0x11f1caf116f8d30a116635786c55106c
(8)   Message-Authenticator = 0x6f6e947ebe2a47a230a371368517a62b
(8) session-state: No cached attributes
(8) # Executing section authorize from file /etc/raddb/sites-enabled/default
(8)   authorize {
(8)     if (User-Name !~ /@/){
(8)     if (User-Name !~ /@/) -> TRUE
(8)     if (User-Name !~ /@/) {
(8)       update request {
(8)         Realm := 'pti'
(8)       } # update request = noop
(8)     } # if (User-Name !~ /@/) = noop
(8)     [preprocess] = ok
(8)     [chap] = noop
(8)     [mschap] = noop
(8)     [digest] = noop
(8) suffix: Request already has destination realm set.  Ignoring
(8)     [suffix] = noop
(8)     update control {
(8)       Proxy-To-Realm := 'LOCAL'
(8)     } # update control = noop
(8) eap: Peer sent code Response (2) ID 9 length 107
(8) eap: Continuing tunnel setup
(8)     [eap] = ok
(8)   } # authorize = ok
(8) Found Auth-Type = EAP
(8) # Executing group from file /etc/raddb/sites-enabled/default
(8)   authenticate {
(8) eap: Expiring EAP session with state 0xbf3362e8bf3a787e
(8) eap: Finished EAP session with state 0x11f1caf116f8d30a
(8) eap: Previous EAP request found for state 0x11f1caf116f8d30a, released
from the list
(8) eap: Peer sent method PEAP (25)
(8) eap: EAP PEAP (25)
(8) eap: Calling eap_peap to process EAP data
(8) eap_peap: processing EAP-TLS
(8) eap_peap: eaptls_verify returned 7
(8) eap_peap: Done initial handshake
(8) eap_peap: eaptls_process returned 7
(8) eap_peap: FR_TLS_OK
(8) eap_peap: Session established.  Decoding tunneled attributes
(8) eap_peap: PEAP state phase2
(8) eap_peap: EAP type MSCHAPv2 (26)
(8) eap_peap: Got tunneled request
(8) eap_peap:   EAP-Message =
0x020900421a0209003d31c176b1ade94bc2c8f24314e1eed2260a000000000000000086d848dfd13109285f3ca7b600bf0ee8fe7689020c445bd60030303133303036
(8) eap_peap: Setting User-Name to 0013006
(8) eap_peap: Sending tunneled request to inner-tunnel
(8) eap_peap:   EAP-Message =
0x020900421a0209003d31c176b1ade94bc2c8f24314e1eed2260a000000000000000086d848dfd13109285f3ca7b600bf0ee8fe7689020c445bd60030303133303036
(8) eap_peap:   FreeRADIUS-Proxied-To = 127.0.0.1
(8) eap_peap:   User-Name = '0013006'
(8) eap_peap:   State = 0xbf3362e8bf3a787eb47a3fd9c407c117
(8) eap_peap:   Chargeable-User-Identity = 0x00
(8) eap_peap:   Location-Capable = Civix-Location
(8) eap_peap:   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(8) eap_peap:   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(8) eap_peap:   NAS-Port = 4
(8) eap_peap:   NAS-IP-Address = 172.25.89.1
(8) eap_peap:   NAS-Identifier = 'WLC-PTI3'
(8) eap_peap:   Service-Type = Framed-User
(8) eap_peap:   Framed-MTU = 1300
(8) eap_peap:   NAS-Port-Type = Wireless-802.11
(8) eap_peap:   Event-Timestamp = 'Mar  2 2015 14:44:30 BRT'
(8) Virtual server received request
(8)   EAP-Message =
0x020900421a0209003d31c176b1ade94bc2c8f24314e1eed2260a000000000000000086d848dfd13109285f3ca7b600bf0ee8fe7689020c445bd60030303133303036
(8)   FreeRADIUS-Proxied-To = 127.0.0.1
(8)   User-Name = '0013006'
(8)   State = 0xbf3362e8bf3a787eb47a3fd9c407c117
(8)   Chargeable-User-Identity = 0x00
(8)   Location-Capable = Civix-Location
(8)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(8)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(8)   NAS-Port = 4
(8)   NAS-IP-Address = 172.25.89.1
(8)   NAS-Identifier = 'WLC-PTI3'
(8)   Service-Type = Framed-User
(8)   Framed-MTU = 1300
(8)   NAS-Port-Type = Wireless-802.11
(8)   Event-Timestamp = 'Mar  2 2015 14:44:30 BRT'
(8) server inner-tunnel {
(8)   session-state: No cached attributes
(8)   # Executing section authorize from file
/etc/raddb/sites-enabled/inner-tunnel
(8)     authorize {
(8)       if (User-Name !~ /@/){
(8)       if (User-Name !~ /@/) -> TRUE
(8)       if (User-Name !~ /@/) {
(8)         update request {
(8)           Realm := 'pti'
(8)         } # update request = noop
(8)       } # if (User-Name !~ /@/) = noop
(8)       [preprocess] = ok
(8)       [chap] = noop
(8)       [mschap] = noop
(8) suffix: Request already has destination realm set.  Ignoring
(8)       [suffix] = noop
(8)       update control {
(8)         &Proxy-To-Realm := 'LOCAL'
(8)       } # update control = noop
(8) eap: Peer sent code Response (2) ID 9 length 66
(8) eap: No EAP Start, assuming it's an on-going EAP conversation
(8)       [eap] = updated
(8)       [files] = noop
(8)       if (Called-Station-Id =~ /:FPTI/) {
(8)       if (Called-Station-Id =~ /:FPTI/)  -> FALSE
(8)       if (Tunnel-Private-Group-ID == "81"){
(8)       ERROR: Failed retrieving values required to evaluate condition
(8)       if (Called-Station-Id =~ /:SRV/) {
(8)       if (Called-Station-Id =~ /:SRV/)  -> FALSE
(8)       [expiration] = noop
(8)       [logintime] = noop
(8)       [pap] = noop
(8)     } # authorize = updated
(8)   Found Auth-Type = EAP
(8)   # Executing group from file /etc/raddb/sites-enabled/inner-tunnel
(8)     authenticate {
(8) eap: Expiring EAP session with state 0xbf3362e8bf3a787e
(8) eap: Finished EAP session with state 0xbf3362e8bf3a787e
(8) eap: Previous EAP request found for state 0xbf3362e8bf3a787e, released
from the list
(8) eap: Peer sent method MSCHAPv2 (26)
(8) eap: EAP MSCHAPv2 (26)
(8) eap: Calling eap_mschapv2 to process EAP data
(8) eap_mschapv2: # Executing group from file
/etc/raddb/sites-enabled/inner-tunnel
(8) eap_mschapv2:   Auth-Type MS-CHAP {
(8) mschap: WARNING: No Cleartext-Password configured.  Cannot create
NT-Password
(8) mschap: WARNING: No Cleartext-Password configured.  Cannot create
LM-Password
(8) mschap: Creating challenge hash with username: 0013006
(8) mschap: Client is using MS-CHAPv2
(8) mschap: ERROR: FAILED: No NT/LM-Password.  Cannot perform authentication
(8) mschap: ERROR: MS-CHAP2-Response is incorrect
(8)     [mschap] = reject
(8)   } # Auth-Type MS-CHAP = reject
(8) eap: Freeing handler
(8)       [eap] = reject
(8)     } # authenticate = reject
(8)   Failed to authenticate the user
(8)   Login incorrect (Failed retrieving values required to evaluate
condition): [0013006] (from client controladora-wlan-1 port 4 cli
e0-f8-47-30-8d-82 via TLS tunnel)
(8)   Using Post-Auth-Type Reject
(8)   # Executing group from file /etc/raddb/sites-enabled/inner-tunnel
(8)     Post-Auth-Type REJECT {
(8)       if ( Realm == "vst" ) {
(8)       if ( Realm == "vst" )  -> FALSE
(8)       if ( Realm == "srv" ) {
(8)       if ( Realm == "srv" )  -> FALSE
(8) sql_accounting: EXPAND .query
(8) sql_accounting:    --> .query
(8) sql_accounting: Using query template 'query'
rlm_sql (sql_accounting): Reserved connection (4)
(8) sql_accounting: EXPAND %{User-Name}
(8) sql_accounting:    --> 0013006
(8) sql_accounting: SQL-User-Name set to '0013006'
(8) sql_accounting: EXPAND INSERT INTO radpostauth (username, pass, reply,
authdate) VALUES('%{User-Name}', '%{%{User-Password}:-Chap-Password}',
'%{reply:Packet-Type}', NOW())
(8) sql_accounting:    --> INSERT INTO radpostauth (username, pass, reply,
authdate) VALUES('0013006', 'Chap-Password', 'Access-Reject', NOW())
(8) sql_accounting: Executing query: INSERT INTO radpostauth (username,
pass, reply, authdate) VALUES('0013006', 'Chap-Password', 'Access-Reject',
NOW())
rlm_sql_postgresql: Status: PGRES_COMMAND_OK
rlm_sql_postgresql: query affected rows = 1
(8) sql_accounting: SQL query returned: success
(8) sql_accounting: 1 record(s) updated
rlm_sql (sql_accounting): Released connection (4)
(8)       [sql_accounting] = ok
(8) attr_filter.access_reject: EXPAND %{User-Name}
(8) attr_filter.access_reject:    --> 0013006
(8) attr_filter.access_reject: Matched entry DEFAULT at line 17
(8)       [attr_filter.access_reject] = updated
(8)       update outer.session-state {
(8)         Module-Failure-Message := &Module-Failure-Message -> 'Failed
retrieving values required to evaluate condition'
(8)       } # update outer.session-state = noop
(8)     } # Post-Auth-Type REJECT = updated
(8) } # server inner-tunnel
(8) Virtual server sending reply
(8)   MS-CHAP-Error = '\tE=691 R=1'
(8)   EAP-Message = 0x04090004
(8)   Message-Authenticator = 0x00000000000000000000000000000000
(8) eap_peap: Got tunneled reply code 3
(8) eap_peap:   MS-CHAP-Error = '\tE=691 R=1'
(8) eap_peap:   EAP-Message = 0x04090004
(8) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(8) eap_peap: Got tunneled reply RADIUS code 3
(8) eap_peap:   MS-CHAP-Error = '\tE=691 R=1'
(8) eap_peap:   EAP-Message = 0x04090004
(8) eap_peap:   Message-Authenticator = 0x00000000000000000000000000000000
(8) eap_peap: Tunneled authentication was rejected
(8) eap_peap: FAILURE
(8) eap: EAP session adding &reply:State = 0x11f1caf119fbd30a
(8)     [eap] = handled
(8)   } # authenticate = handled
(8) session-state: Saving cached attributes
(8)   Module-Failure-Message := 'Failed retrieving values required to
evaluate condition'
(8) Using Post-Auth-Type Challenge
(8) # Executing group from file /etc/raddb/sites-enabled/default
(8) Sent Access-Challenge Id 94 from 186.233.12.201:1812 to
172.25.89.1:32768 length 101
(8)   EAP-Message =
0x010a002b1900170301002047ed27ba048bf30d7698c82097b18a94a4bcf997f7def88fd9f566c47a54b988
(8)   Message-Authenticator = 0x00000000000000000000000000000000
(8)   State = 0x11f1caf119fbd30a116635786c55106c
(8) Finished request
Waking up in 0.2 seconds.
(9) Received Access-Request Id 95 from 172.25.89.1:32768 to
186.233.12.201:1812 length 279
(9)   User-Name = '0013006'
(9)   Chargeable-User-Identity = 0x00
(9)   Location-Capable = Civix-Location
(9)   Calling-Station-Id = 'e0-f8-47-30-8d-82'
(9)   Called-Station-Id = 'dc-a5-f4-1d-1b-60:PTI2'
(9)   NAS-Port = 4
(9)   Cisco-AVPair = 'audit-session-id=ac1959010025d7c954f4a43d'
(9)   NAS-IP-Address = 172.25.89.1
(9)   NAS-Identifier = 'WLC-PTI3'
(9)   Airespace-Wlan-Id = 11
(9)   Service-Type = Framed-User
(9)   Framed-MTU = 1300
(9)   NAS-Port-Type = Wireless-802.11
(9)   Tunnel-Type:0 = VLAN
(9)   Tunnel-Medium-Type:0 = IEEE-802
(9)   Tunnel-Private-Group-Id:0 = '81'
(9)   EAP-Message =
0x020a002b19001703010020ccb6d5654b65b728eedf9c937b4fd72f31b0d33b93cf70c382a7b6dfabdb1f0d
(9)   State = 0x11f1caf119fbd30a116635786c55106c
(9)   Message-Authenticator = 0x3593dc49bca49c6ecce114c1965698be
(9) session-state: Found cached attributes
(9)   Module-Failure-Message := 'Failed retrieving values required to
evaluate condition'
(9) # Executing section authorize from file /etc/raddb/sites-enabled/default
(9)   authorize {
(9)     if (User-Name !~ /@/){
(9)     if (User-Name !~ /@/) -> TRUE
(9)     if (User-Name !~ /@/) {
(9)       update request {
(9)         Realm := 'pti'
(9)       } # update request = noop
(9)     } # if (User-Name !~ /@/) = noop
(9)     [preprocess] = ok
(9)     [chap] = noop
(9)     [mschap] = noop
(9)     [digest] = noop
(9) suffix: Request already has destination realm set.  Ignoring
(9)     [suffix] = noop
(9)     update control {
(9)       Proxy-To-Realm := 'LOCAL'
(9)     } # update control = noop
(9) eap: Peer sent code Response (2) ID 10 length 43
(9) eap: Continuing tunnel setup
(9)     [eap] = ok
(9)   } # authorize = ok
(9) Found Auth-Type = EAP
(9) # Executing group from file /etc/raddb/sites-enabled/default
(9)   authenticate {
(9) eap: Expiring EAP session with state 0x11f1caf119fbd30a
(9) eap: Finished EAP session with state 0x11f1caf119fbd30a
(9) eap: Previous EAP request found for state 0x11f1caf119fbd30a, released
from the list
(9) eap: Peer sent method PEAP (25)
(9) eap: EAP PEAP (25)
(9) eap: Calling eap_peap to process EAP data
(9) eap_peap: processing EAP-TLS
(9) eap_peap: eaptls_verify returned 7
(9) eap_peap: Done initial handshake
(9) eap_peap: eaptls_process returned 7
(9) eap_peap: FR_TLS_OK
(9) eap_peap: Session established.  Decoding tunneled attributes
(9) eap_peap: PEAP state send tlv failure
(9) eap_peap: Received EAP-TLV response
(9) eap_peap:   The users session was previously rejected: returning reject
(again.)
(9) eap_peap:   This means you need to read the PREVIOUS messages in the
debug output
(9) eap_peap:   to find out the reason why the user was rejected
(9) eap_peap:   Look for "reject" or "fail".  Those earlier messages will
tell you
(9) eap_peap:   what went wrong, and how to fix the problem
  SSL: Removing session
d3b3bb62b9ace61b29da617f7294f0c60bd88df045ef8716c9c912b417639260 from the
cache
(9) eap: ERROR: Failed continuing EAP PEAP (25) session. EAP sub-module
failed
(9) eap: Failed in EAP select
(9)     [eap] = invalid
(9)   } # authenticate = invalid
(9) Failed to authenticate the user
(9) Login incorrect (eap: Failed continuing EAP PEAP (25) session. EAP
sub-module failed): [0013006] (from client controladora-wlan-1 port 4 cli
e0-f8-47-30-8d-82)
(9) Using Post-Auth-Type Reject
(9) # Executing group from file /etc/raddb/sites-enabled/default
(9)   Post-Auth-Type REJECT {
(9) attr_filter.access_reject: EXPAND %{User-Name}
(9) attr_filter.access_reject:    --> 0013006
(9) attr_filter.access_reject: Matched entry DEFAULT at line 17
(9)     [attr_filter.access_reject] = updated
(9) eap: Reply already contained an EAP-Message, not inserting EAP-Failure
(9)     [eap] = noop
(9)     policy remove_reply_message_if_eap {
(9)       if (&reply:EAP-Message && &reply:Reply-Message) {
(9)       if (&reply:EAP-Message && &reply:Reply-Message)  -> FALSE
(9)       else {
(9)         [noop] = noop
(9)       } # else = noop
(9)     } # policy remove_reply_message_if_eap = noop
(9)   } # Post-Auth-Type REJECT = updated
(9) Delaying response for 1.000000 seconds
Waking up in 0.2 seconds.
Waking up in 0.6 seconds.
(9) Sending delayed response
(9) Sent Access-Reject Id 95 from 186.233.12.201:1812 to 172.25.89.1:32768
length 44
(9)   EAP-Message = 0x040a0004
(9)   Message-Authenticator = 0x00000000000000000000000000000000
Waking up in 3.8 seconds.
(0) <done>: Cleaning up request packet ID 86 with timestamp +8
(1) <done>: Cleaning up request packet ID 87 with timestamp +8
(2) <done>: Cleaning up request packet ID 88 with timestamp +8
(3) <done>: Cleaning up request packet ID 89 with timestamp +8
(4) <done>: Cleaning up request packet ID 90 with timestamp +8
(5) <done>: Cleaning up request packet ID 91 with timestamp +8
(6) <done>: Cleaning up request packet ID 92 with timestamp +8
(7) <done>: Cleaning up request packet ID 93 with timestamp +8
(8) <done>: Cleaning up request packet ID 94 with timestamp +8
(9) Cleaning up request packet ID 95 with timestamp +8
Ready to process requests

2015-03-02 12:28 GMT-03:00 Alan DeKok <aland@deployingradius.com>:
...
On Mar 2, 2015, at 9:32 AM, joaocdc@gmail.com wrote:
...
copy_request_to_tunnel is already setted to 'yes'.
I do not change this options when do upgrade from 3.0.1 to 3.0.7
OK.  Then look at the *full* debug log.  See what it prints out for the
tunnelled request.
The debug log is there for a reason. PLEASE read it.  All.  And try to
understand what it means.
Alan DeKok.
-
List info/subscribe/unsubscribe? See
http://www.freeradius.org/list/users.html
-- 
João Paulo de Lima Barbosa

"Para chegar aonde a maioria não chega, você precisa fazer o que a maioria
não faz."

Re: Problem to copy attribute Tunnel-Private-Group-ID to inner-tunnel (eap) - freeradius 3.0.7

joaocdc＠gmail.com