DHCP with Oracle and sqlippool
Бен Томпсон
b.thompson at latera.ru
Thu Feb 27 06:59:44 CET 2014
Hello everyone, I decided to try the latest version from branch v3.0.x.
However, the problem remains and the circuit ID is somehow lost during the
processing of the request. Please let me know what do. I suppose I should
create a bug report.
Here is the relevant output from v3.0.x:
Received DHCP-Discover of id fa86ba10 from 10.180.1.1:67 to 10.160.0.254:67
0: 01 01 06 01 fa 86 ba 10 00 08 00 00 00 00 00 00
16: 00 00 00 00 00 00 00 00 0a b4 01 01 50 af 73 18
32: ed 98 00 00 00 00 00 00 00 00 00 00 00 00 00 00
48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63
240: 35 01 01 0c 07 73 75 70 70 6f 72 74 37 0d 01 1c
256: 02 03 0f 06 77 0c 2c 2f 1a 79 2a 52 12 01 08 00
272: 06 04 b1 01 00 00 01 02 06 00 03 0f 1a e3 2c ff
288: 00 00 00 00 00 00 00 00 00 00 00 00
DHCP-Opcode = Client-Message
DHCP-Hardware-Type = Ethernet
DHCP-Hardware-Address-Length = 6
DHCP-Hop-Count = 1
DHCP-Transaction-Id = 4203133456
DHCP-Number-of-Seconds = 8
DHCP-Flags = 0
DHCP-Client-IP-Address = 0.0.0.0
DHCP-Your-IP-Address = 0.0.0.0
DHCP-Server-IP-Address = 0.0.0.0
DHCP-Gateway-IP-Address = 10.180.1.1
DHCP-Client-Hardware-Address = 50:af:73:18:ed:98
DHCP-Message-Type += DHCP-Discover
DHCP-Hostname += 'support'
DHCP-Parameter-Request-List += DHCP-Subnet-Mask
DHCP-Parameter-Request-List += DHCP-Broadcast-Address
DHCP-Parameter-Request-List += DHCP-Time-Offset
DHCP-Parameter-Request-List += DHCP-Router-Address
DHCP-Parameter-Request-List += DHCP-Domain-Name
DHCP-Parameter-Request-List += DHCP-Domain-Name-Server
DHCP-Parameter-Request-List += DHCP-Domain-Search
DHCP-Parameter-Request-List += DHCP-Hostname
DHCP-Parameter-Request-List += DHCP-NETBIOS-Name-Servers
DHCP-Parameter-Request-List += DHCP-NETBIOS
DHCP-Parameter-Request-List += DHCP-Interface-MTU-Size
DHCP-Parameter-Request-List += DHCP-Classless-Static-Route
DHCP-Parameter-Request-List += DHCP-NTP-Servers
DHCP-Relay-Circuit-Id = 0x000604b101000001
Thu Feb 27 09:53:15 2014 : Debug: Trying sub-section dhcp DHCP-Discover
{...}
Thu Feb 27 09:53:15 2014 : Debug: (1) dhcp DHCP-Discover {
Thu Feb 27 09:53:15 2014 : Debug: (1) modsingle[post-auth]: calling
linelog-dhcp (rlm_linelog) for request 1
Thu Feb 27 09:53:15 2014 : Debug:
%{%{reply:DHCP-Message-Type}:-%{request:DHCP-Message-Type}}
Thu Feb 27 09:53:15 2014 : Debug: Parsed xlat tree:
Thu Feb 27 09:53:15 2014 : Debug: if {
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Message-Type
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 2
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: else {
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Message-Type
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: (1) linelog-dhcp : expand:
"%{%{reply:DHCP-Message-Type}:-%{request:DHCP-Message-Type}}" ->
'DHCP-Discover'
Thu Feb 27 09:53:15 2014 : Debug: /usr/local/var/log/radius/linelog-dhcp.log
Thu Feb 27 09:53:15 2014 : Debug: Parsed xlat tree:
Thu Feb 27 09:53:15 2014 : Debug: literal:
'/usr/local/var/log/radius/linelog-dhcp.log'
Thu Feb 27 09:53:15 2014 : Debug: (1) linelog-dhcp : expand:
"/usr/local/var/log/radius/linelog-dhcp.log" ->
'/usr/local/var/log/radius/linelog-dhcp.log'
Thu Feb 27 09:53:15 2014 : Debug: %S --> Transaction-ID:
%{DHCP-Transaction-Id} DISCOVER: [%{DHCP-Client-Hardware-Address}] via
(%{DHCP-Gateway-IP-Address}), hop count = %{DHCP-Hop-Count}, Relay =
%{DHCP-Relay-Remote-Id} / %{DHCP-Relay-Circuit-Id}, Hostname =
%{DHCP-Hostname}
Thu Feb 27 09:53:15 2014 : Debug: Parsed xlat tree:
Thu Feb 27 09:53:15 2014 : Debug: literal (with %): 'S'
Thu Feb 27 09:53:15 2014 : Debug: literal: ' --> Transaction-ID: '
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Transaction-Id
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: literal: ' DISCOVER: ['
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Client-Hardware-Address
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: literal: '] via ('
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Gateway-IP-Address
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: literal: '), hop count = '
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Hop-Count
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: literal: ', Relay = '
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Relay-Remote-Id
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: literal: ' / '
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Relay-Circuit-Id
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: literal: ', Hostname = '
Thu Feb 27 09:53:15 2014 : Debug: attribute: DHCP-Hostname
Thu Feb 27 09:53:15 2014 : Debug: {
Thu Feb 27 09:53:15 2014 : Debug: ref 2
Thu Feb 27 09:53:15 2014 : Debug: list 1
Thu Feb 27 09:53:15 2014 : Debug: tag -128
Thu Feb 27 09:53:15 2014 : Debug: }
Thu Feb 27 09:53:15 2014 : Debug: (1) linelog-dhcp : expand: "%S -->
Transaction-ID: %{DHCP-Transaction-Id} DISCOVER:
[%{DHCP-Client-Hardware-Address}] via (%{DHCP-Gateway-IP-Address}), hop
count = %{DHCP-Hop-Count}, Relay = %{DHCP-Relay-Remote-Id} /
%{DHCP-Relay-Circuit-Id}, Hostname = %{DHCP-Hostname}" -> '2014-02-27
09:53:15 --> Transaction-ID: 4203133456 DISCOVER: [50:af:73:18:ed:98] via
(10.180.1.1), hop count = 1, Relay = 0x00030f1ae32c / 0x202f200000000000,
Hostname = support'
Thu Feb 27 09:53:15 2014 : Debug: (1) modsingle[post-auth]: returned from
linelog-dhcp (rlm_linelog) for request 1
Thu Feb 27 09:53:15 2014 : Debug: (1) [linelog-dhcp] = ok
Thu Feb 27 09:53:15 2014 : Debug: (1) update reply {
Thu Feb 27 09:53:15 2014 : Debug: (1) DHCP-Flags = 0
Thu Feb 27 09:53:15 2014 : Debug: (1) } # update reply = noop
Note that the value 0x202f200000000000 for circuit id is not correct.
On 26 February 2014 17:17, Бен Томпсон <b.thompson at latera.ru> wrote:
> Folks, I also noticed that the attribute DHCP-Relay-Remote-Id is not
> printed underneath the hex dumpbelow:
>
>
> Received DHCP-Request of id 721fc604 from 10.180.1.1:67 to 10.160.0.254:67
> 0: 01 01 06 01 72 1f c6 04 00 a5 00 00 0a b4 01 05
> 16: 00 00 00 00 00 00 00 00 0a b4 01 01 50 af 73 18
> 32: ed 98 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63
> 240: 35 01 03 0c 07 73 75 70 70 6f 72 74 37 0d 01 1c
> 256: 02 03 0f 06 77 0c 2c 2f 1a 79 2a 52 12 01 08 00
> 272: 06 04 b1 01 00 00 01 02 06 00 03 0f 1a e3 2c ff
> 288: 00 00 00 00 00 00 00 00 00 00 00 00
> DHCP-Opcode = Client-Message
> DHCP-Hardware-Type = Ethernet
> DHCP-Hardware-Address-Length = 6
> DHCP-Hop-Count = 1
> DHCP-Transaction-Id = 1914684932
> DHCP-Number-of-Seconds = 165
> DHCP-Flags = 0
> DHCP-Client-IP-Address = 10.180.1.5
> DHCP-Your-IP-Address = 0.0.0.0
> DHCP-Server-IP-Address = 0.0.0.0
> DHCP-Gateway-IP-Address = 10.180.1.1
> DHCP-Client-Hardware-Address = 50:af:73:18:ed:98
> DHCP-Message-Type += DHCP-Request
> DHCP-Hostname += 'support'
> DHCP-Parameter-Request-List += DHCP-Subnet-Mask
> DHCP-Parameter-Request-List += DHCP-Broadcast-Address
> DHCP-Parameter-Request-List += DHCP-Time-Offset
> DHCP-Parameter-Request-List += DHCP-Router-Address
> DHCP-Parameter-Request-List += DHCP-Domain-Name
> DHCP-Parameter-Request-List += DHCP-Domain-Name-Server
> DHCP-Parameter-Request-List += DHCP-Domain-Search
> DHCP-Parameter-Request-List += DHCP-Hostname
> DHCP-Parameter-Request-List += DHCP-NETBIOS-Name-Servers
> DHCP-Parameter-Request-List += DHCP-NETBIOS
> DHCP-Parameter-Request-List += DHCP-Interface-MTU-Size
> DHCP-Parameter-Request-List += DHCP-Classless-Static-Route
> DHCP-Parameter-Request-List += DHCP-NTP-Servers
> DHCP-Relay-Circuit-Id = 0x000604b101000001
> Mon Feb 24 16:51:36 2014 : Debug: Trying sub-section dhcp DHCP-Request
> {...}
>
> It can be seen the the hex dump contains both Cicuit-Id (06 04 b1 01 00 00
> 01) and Remote-Id (00 03 0f 1a e3 2c):
>
>
> 272: 06 04 b1 01 00 00 01 02 06 00 03 0f 1a e3 2c ff
>
> But these are not printed underneath. If I can help to debug this please
> let me know what to do.
>
> Thanks
>
> Ben
>
>
>
>
> On 25 February 2014 07:00, Бен Томпсон <b.thompson at latera.ru> wrote:
>
>> Sorry, my previous email was not formatted correctly.
>>
>> Everyone, I have a problem and would would be grateful if you could have
>> a quick look at it for me. I am trying to use the Option 82 data from a
>> DHCP request, but for some reason the Circuit ID is changing during the
>> processing of the request.
>>
>>
>> Here is the reqest coming in and then getting processed by the linelog
>> module:
>>
>> Received DHCP-Request of id 721fc604 from 10.180.1.1:67 to
>> 10.160.0.254:67
>> 0: 01 01 06 01 72 1f c6 04 00 a5 00 00 0a b4 01 05
>> 16: 00 00 00 00 00 00 00 00 0a b4 01 01 50 af 73 18
>> 32: ed 98 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63
>> 240: 35 01 03 0c 07 73 75 70 70 6f 72 74 37 0d 01 1c
>> 256: 02 03 0f 06 77 0c 2c 2f 1a 79 2a 52 12 01 08 00
>> 272: 06 04 b1 01 00 00 01 02 06 00 03 0f 1a e3 2c ff
>> 288: 00 00 00 00 00 00 00 00 00 00 00 00
>> DHCP-Opcode = Client-Message
>> DHCP-Hardware-Type = Ethernet
>> DHCP-Hardware-Address-Length = 6
>> DHCP-Hop-Count = 1
>> DHCP-Transaction-Id = 1914684932
>> DHCP-Number-of-Seconds = 165
>> DHCP-Flags = 0
>> DHCP-Client-IP-Address = 10.180.1.5
>> DHCP-Your-IP-Address = 0.0.0.0
>> DHCP-Server-IP-Address = 0.0.0.0
>> DHCP-Gateway-IP-Address = 10.180.1.1
>> DHCP-Client-Hardware-Address = 50:af:73:18:ed:98
>> DHCP-Message-Type += DHCP-Request
>> DHCP-Hostname += 'support'
>> DHCP-Parameter-Request-List += DHCP-Subnet-Mask
>> DHCP-Parameter-Request-List += DHCP-Broadcast-Address
>> DHCP-Parameter-Request-List += DHCP-Time-Offset
>> DHCP-Parameter-Request-List += DHCP-Router-Address
>> DHCP-Parameter-Request-List += DHCP-Domain-Name
>> DHCP-Parameter-Request-List += DHCP-Domain-Name-Server
>> DHCP-Parameter-Request-List += DHCP-Domain-Search
>> DHCP-Parameter-Request-List += DHCP-Hostname
>> DHCP-Parameter-Request-List += DHCP-NETBIOS-Name-Servers
>> DHCP-Parameter-Request-List += DHCP-NETBIOS
>> DHCP-Parameter-Request-List += DHCP-Interface-MTU-Size
>> DHCP-Parameter-Request-List += DHCP-Classless-Static-Route
>> DHCP-Parameter-Request-List += DHCP-NTP-Servers
>> DHCP-Relay-Circuit-Id = 0x000604b101000001
>> Mon Feb 24 16:51:36 2014 : Debug: Trying sub-section dhcp DHCP-Request
>> {...}
>> Mon Feb 24 16:51:36 2014 : Debug: (0) dhcp DHCP-Request {
>> Mon Feb 24 16:51:36 2014 : Debug: (0) modsingle[post-auth]: calling
>> linelog-dhcp (rlm_linelog) for request 0
>> Mon Feb 24 16:51:36 2014 : Debug: %{%{reply:DHCP-Message-Type}:-
>> %{request:DHCP-Message-Type}}
>> Mon Feb 24 16:51:36 2014 : Debug: Parsed xlat tree:
>> Mon Feb 24 16:51:36 2014 : Debug: if {
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Message-Type
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 2
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: else {
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Message-Type
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: (0) linelog-dhcp : expand:
>> "%{%{reply:DHCP-Message-Type}:-%{request:DHCP-Message-Type}}" ->
>> 'DHCP-Request'
>> Mon Feb 24 16:51:36 2014 : Debug:
>> /usr/local/var/log/radius/linelog-dhcp.log
>> Mon Feb 24 16:51:36 2014 : Debug: Parsed xlat tree:
>> Mon Feb 24 16:51:36 2014 : Debug: literal:
>> '/usr/local/var/log/radius/linelog-dhcp.log'
>> Mon Feb 24 16:51:36 2014 : Debug: (0) linelog-dhcp : expand:
>> "/usr/local/var/log/radius/linelog-dhcp.log" ->
>> '/usr/local/var/log/radius/linelog-dhcp.log'
>> Mon Feb 24 16:51:36 2014 : Debug: %S --> Transaction-ID:
>> %{DHCP-Transaction-Id} REQUEST: [%{DHCP-Client-Hardware-Address}] via
>> (%{DHCP-Gateway-IP-Address}), hop count = %{DHCP-Hop-Count}, Relay =
>> %{DHCP-Relay-Remote-Id} / %{DHCP-Relay-Circuit-Id} ...
>> Mon Feb 24 16:51:36 2014 : Debug: Parsed xlat tree:
>> Mon Feb 24 16:51:36 2014 : Debug: literal (with %): 'S'
>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' --> Transaction-ID: '
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Transaction-Id
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' REQUEST: ['
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Client-Hardware-Address
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: literal: '] via ('
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Gateway-IP-Address
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: literal: '), hop count = '
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Hop-Count
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: literal: ', Relay = '
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Relay-Remote-Id
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' / '
>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Relay-Circuit-Id
>> Mon Feb 24 16:51:36 2014 : Debug: {
>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>> Mon Feb 24 16:51:36 2014 : Debug: }
>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' ...'
>> Mon Feb 24 16:51:36 2014 : Debug: (0) linelog-dhcp : expand: "%S -->
>> Transaction-ID: %{DHCP-Transaction-Id} REQUEST:
>> [%{DHCP-Client-Hardware-Address}] via (%{DHCP-Gateway-IP-Address}), hop
>> count = %{DHCP-Hop-Count}, Relay = %{DHCP-Relay-Remote-Id} /
>> %{DHCP-Relay-Circuit-Id} ..." -> '2014-02-24 16:51:36 --> Transaction-ID:
>> 1914684932 REQUEST: [50:af:73:18:ed:98] via (10.180.1.1), hop count = 1,
>> Relay = 0x00030f1ae32c / 0x5d20766961202800 ...'
>> Mon Feb 24 16:51:36 2014 : Debug: (0) modsingle[post-auth]: returned
>> from linelog-dhcp (rlm_linelog) for request 0
>> Mon Feb 24 16:51:36 2014 : Debug: (0) [linelog-dhcp] = ok
>>
>> As you can see, the attribute DHCP-Relay-Circuit-Id starts of with value
>> "0x000604b101000001" but by the time it is processed by linelog it has
>> value "0x5d20766961202800"
>>
>> Here is the full debug output: http://pastebin.com/b9RXPRD0
>>
>>
>>
>> On 24 February 2014 21:28, Бен Томпсон <b.thompson at latera.ru> wrote:
>>
>>> olks, I have hit a problem and would would be grateful if you could have
>>> a quick look at it for me. I am trying to use the Option 82 data from a
>>> DHCP request, but for some reason the Circuit ID is changing during the
>>> procession for the request.
>>>
>>> Here is the reqest coming in and then getting processed by the linelog
>>> module:
>>>
>>> Received DHCP-Request of id 721fc604 from 10.180.1.1:67 to
>>> 10.160.0.254:67
>>> 0: 01 01 06 01 72 1f c6 04 00 a5 00 00 0a b4 01 05
>>> 16: 00 00 00 00 00 00 00 00 0a b4 01 01 50 af 73 18
>>> 32: ed 98 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 48: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 64: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 96: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 112: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 144: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 160: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 176: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 192: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>>> 224: 00 00 00 00 00 00 00 00 00 00 00 00 63 82 53 63
>>> 240: 35 01 03 0c 07 73 75 70 70 6f 72 74 37 0d 01 1c
>>> 256: 02 03 0f 06 77 0c 2c 2f 1a 79 2a 52 12 01 08 00
>>> 272: 06 04 b1 01 00 00 01 02 06 00 03 0f 1a e3 2c ff
>>> 288: 00 00 00 00 00 00 00 00 00 00 00 00
>>> DHCP-Opcode = Client-Message
>>> DHCP-Hardware-Type = Ethernet
>>> DHCP-Hardware-Address-Length = 6
>>> DHCP-Hop-Count = 1
>>> DHCP-Transaction-Id = 1914684932
>>> DHCP-Number-of-Seconds = 165
>>> DHCP-Flags = 0
>>> DHCP-Client-IP-Address = 10.180.1.5
>>> DHCP-Your-IP-Address = 0.0.0.0
>>> DHCP-Server-IP-Address = 0.0.0.0
>>> DHCP-Gateway-IP-Address = 10.180.1.1
>>> DHCP-Client-Hardware-Address = 50:af:73:18:ed:98
>>> DHCP-Message-Type += DHCP-Request
>>> DHCP-Hostname += 'support'
>>> DHCP-Parameter-Request-List += DHCP-Subnet-Mask
>>> DHCP-Parameter-Request-List += DHCP-Broadcast-Address
>>> DHCP-Parameter-Request-List += DHCP-Time-Offset
>>> DHCP-Parameter-Request-List += DHCP-Router-Address
>>> DHCP-Parameter-Request-List += DHCP-Domain-Name
>>> DHCP-Parameter-Request-List += DHCP-Domain-Name-Server
>>> DHCP-Parameter-Request-List += DHCP-Domain-Search
>>> DHCP-Parameter-Request-List += DHCP-Hostname
>>> DHCP-Parameter-Request-List += DHCP-NETBIOS-Name-Servers
>>> DHCP-Parameter-Request-List += DHCP-NETBIOS
>>> DHCP-Parameter-Request-List += DHCP-Interface-MTU-Size
>>> DHCP-Parameter-Request-List += DHCP-Classless-Static-Route
>>> DHCP-Parameter-Request-List += DHCP-NTP-Servers
>>> DHCP-Relay-Circuit-Id = 0x000604b101000001
>>> Mon Feb 24 16:51:36 2014 : Debug: Trying sub-section dhcp DHCP-Request
>>> {...}
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) dhcp DHCP-Request {
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) modsingle[post-auth]: calling
>>> linelog-dhcp (rlm_linelog) for request 0
>>> Mon Feb 24 16:51:36 2014 : Debug: %{%{reply:DHCP-Message-Type}:-
>>> %{request:DHCP-Message-Type}}
>>> Mon Feb 24 16:51:36 2014 : Debug: Parsed xlat tree:
>>> Mon Feb 24 16:51:36 2014 : Debug: if {
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Message-Type
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 2
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: else {
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Message-Type
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) linelog-dhcp : expand:
>>> "%{%{reply:DHCP-Message-Type}:-%{request:DHCP-Message-Type}}" ->
>>> 'DHCP-Request'
>>> Mon Feb 24 16:51:36 2014 : Debug:
>>> /usr/local/var/log/radius/linelog-dhcp.log
>>> Mon Feb 24 16:51:36 2014 : Debug: Parsed xlat tree:
>>> Mon Feb 24 16:51:36 2014 : Debug: literal:
>>> '/usr/local/var/log/radius/linelog-dhcp.log'
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) linelog-dhcp : expand:
>>> "/usr/local/var/log/radius/linelog-dhcp.log" ->
>>> '/usr/local/var/log/radius/linelog-dhcp.log'
>>> Mon Feb 24 16:51:36 2014 : Debug: %S --> Transaction-ID:
>>> %{DHCP-Transaction-Id} REQUEST: [%{DHCP-Client-Hardware-Address}] via
>>> (%{DHCP-Gateway-IP-Address}), hop count = %{DHCP-Hop-Count}, Relay =
>>> %{DHCP-Relay-Remote-Id} / %{DHCP-Relay-Circuit-Id} ...
>>> Mon Feb 24 16:51:36 2014 : Debug: Parsed xlat tree:
>>> Mon Feb 24 16:51:36 2014 : Debug: literal (with %): 'S'
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' --> Transaction-ID: '
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Transaction-Id
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' REQUEST: ['
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Client-Hardware-Address
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: '] via ('
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Gateway-IP-Address
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: '), hop count = '
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Hop-Count
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: ', Relay = '
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Relay-Remote-Id
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' / '
>>> Mon Feb 24 16:51:36 2014 : Debug: attribute: DHCP-Relay-Circuit-Id
>>> Mon Feb 24 16:51:36 2014 : Debug: {
>>> Mon Feb 24 16:51:36 2014 : Debug: ref 2
>>> Mon Feb 24 16:51:36 2014 : Debug: list 1
>>> Mon Feb 24 16:51:36 2014 : Debug: tag -128
>>> Mon Feb 24 16:51:36 2014 : Debug: }
>>> Mon Feb 24 16:51:36 2014 : Debug: literal: ' ...'
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) linelog-dhcp : expand: "%S -->
>>> Transaction-ID: %{DHCP-Transaction-Id} REQUEST:
>>> [%{DHCP-Client-Hardware-Address}] via (%{DHCP-Gateway-IP-Address}), hop
>>> count = %{DHCP-Hop-Count}, Relay = %{DHCP-Relay-Remote-Id} /
>>> %{DHCP-Relay-Circuit-Id} ..." -> '2014-02-24 16:51:36 --> Transaction-ID:
>>> 1914684932 REQUEST: [50:af:73:18:ed:98] via (10.180.1.1), hop count = 1,
>>> Relay = 0x00030f1ae32c / 0x5d20766961202800 ...'
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) modsingle[post-auth]: returned
>>> from linelog-dhcp (rlm_linelog) for request 0
>>> Mon Feb 24 16:51:36 2014 : Debug: (0) [linelog-dhcp] = ok
>>>
>>> As you can see, the attribute DHCP-Relay-Circuit-Id starts of with value
>>> "0x000604b101000001" but by the time it is processed by linelog it has
>>> value "0x5d20766961202800"
>>>
>>> Here is the full debug output: http://pastebin.com/b9RXPRD0
>>>
>>>
>>> On 21 February 2014 19:02, Бен Томпсон <b.thompson at latera.ru> wrote:
>>>
>>>> Thanks for the info. It is looking better with 3.0.1. I will share my
>>>> oracle configs once I have proved that they work.
>>>>
>>>>
>>>> On 21 February 2014 16:41, Alan DeKok <aland at deployingradius.com>wrote:
>>>>
>>>>> Бен Томпсон wrote:
>>>>> > radiusd: FreeRADIUS Version 3.1.0 (git #10ffd5b), for host
>>>>>
>>>>> Don't use the "master" branch. Use an official release.
>>>>>
>>>>> Alan DeKok.
>>>>> -
>>>>> List info/subscribe/unsubscribe? See
>>>>> http://www.freeradius.org/list/users.html
>>>>
>>>>
>>>>
>>>>
>>>> --
>>>> Бен Томпсон,
>>>> инженер отдела внедрения и техподдержки,
>>>> компания "Латера" | www.latera.ru
>>>>
>>>> тел.: +7 (499) 940-95-05, доб. 221
>>>> E-mail: b.thompson at latera.ru
>>>>
>>>
>>>
>>>
>>> --
>>> Бен Томпсон,
>>> инженер отдела внедрения и техподдержки,
>>> компания "Латера" | www.latera.ru
>>>
>>> тел.: +7 (499) 940-95-05, доб. 221
>>> E-mail: b.thompson at latera.ru
>>>
>>
>>
>>
>> --
>> Бен Томпсон,
>> инженер отдела внедрения и техподдержки,
>> компания "Латера" | www.latera.ru
>>
>> тел.: +7 (499) 940-95-05, доб. 221
>> E-mail: b.thompson at latera.ru
>>
>
>
>
>
--
Бен Томпсон,
инженер отдела внедрения и техподдержки,
компания "Латера" | www.latera.ru
тел.: +7 (499) 940-95-05, доб. 221
E-mail: b.thompson at latera.ru
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeradius.org/pipermail/freeradius-users/attachments/20140227/9ee98f32/attachment-0001.html>
More information about the Freeradius-Users
mailing list