Newslists

[Alan DeKok]

Keith Dovale - HostworX.co.za wrote:
> Ok you asked for the debug log here it is.

  The extra '-x' (which prints the time) is unnecessary, and makes it
harder to read the output.  Still..

> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: attribute =
> "Configuration-Token"
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: searchfor =
> "UNSHAPED_NORMAL"
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: searchin = "reply"
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: replacewith =
> "SHAPED_NORMAL"
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: append = no
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: ignore_case = yes
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: new_attribute = no
> Fri Feb  8 17:24:47 2008 : Debug:  attr_rewrite: max_matches = 1
> Fri Feb  8 17:24:47 2008 : Debug: Module: Instantiated attr_rewrite
> (AttrRewrite_MonthlyBlendedShaped) 

  One instance of attr_rewrite...

> Fri Feb  8 17:27:26 2008 : Debug:   modsingle[authorize]: calling
> AttrRewrite_MonthlyBlendedShaped (rlm_attr_rewrite) for request 3
> Fri Feb  8 17:27:26 2008 : Debug: radius_xlat:  'UNSHAPED_NORMAL'
> Fri Feb  8 17:27:26 2008 : Debug: rlm_attr_rewrite: No match found for
> attribute Configuration-Token with value 'SHAPED_NORMAL'
> Fri Feb  8 17:27:26 2008 : Debug: radius_xlat:  'UNSHAPED_NORMAL'
> Fri Feb  8 17:27:26 2008 : Debug: radius_xlat:  'SHAPED_NORMAL'
> Fri Feb  8 17:27:26 2008 : Debug: rlm_attr_rewrite: Changed value for
> attribute Configuration-Token from 'UNSHAPED_NORMAL' to 'SHAPED_NORMAL'
> Fri Feb  8 17:27:26 2008 : Debug: rlm_attr_rewrite: Could not find value
> pair for attribute Configuration-Token
> Fri Feb  8 17:27:26 2008 : Debug:   modsingle[authorize]: returned from
> AttrRewrite_MonthlyBlendedShaped (rlm_attr_rewrite) for request 3

  The thing about the attr_rewrite module is that it looks at ALL
attributes in the list.  In this case, you have two
Configuration-Tokens.  One has value "SHAPED_NORMAL", and the other
"UNSHAPED_NORMAL".  It doesn't match the first, but it does match the
second.  After that, it says it couldn't find any more.

  There is a bug.  The first "no match found" line prints the value of
the attribute that didn't match, NOT the value it was looking for.
Carefully reading the debug output makes this clear:

  - it says "no match"
  - it says "changed value from UNSHAPED_NORMAL"
  - returns from module AttrRewrite_MonthlyBlendedShaped

  i.e. the FIRST line is wrong.  You were getting confused because you
have *other* attr_rewrite modules which re-write SHAPED_NORMAL.  So
reading the debug log here, it looked like it was trying to re-write
SHAPED_NORMAL.  But it wasn't, because it was NOT running the
AttrRewrite_MonthlyLocal module.

  The only issue I see is that one debug line is wrong, and therefore
confusing.

  Is there anything else?

  Alan DeKok.