<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">
<HTML><HEAD>
<META http-equiv=Content-Type content="text/html; charset=iso-8859-1">
<META content="MSHTML 6.00.2900.2963" name=GENERATOR>
<STYLE></STYLE>
</HEAD>
<BODY bgColor=#ffffff>
<DIV><FONT face=Arial size=2>
<DIV><FONT face=Arial size=2>
<DIV><FONT face=Arial size=2>Hi Alan</FONT></DIV>
<DIV><FONT face=Arial size=2></FONT> </DIV>
<DIV><FONT face=Arial size=2>Thank you for you helping on accounting
response</FONT></DIV>
<DIV><FONT face=Arial size=2></FONT> </DIV>
<DIV><FONT face=Arial size=2>i got the advice for freeradius list use the unlang
to modify the Accep Reject</FONT></DIV>
<DIV><FONT face=Arial size=2>i read the man unlang but cannot figure out how to
use it</FONT></DIV>
<DIV><FONT face=Arial size=2>this is the whole call </FONT></DIV>
<DIV><FONT face=Arial size=2></FONT> </DIV>
<DIV><FONT face=Arial size=2>the call flow :</FONT></DIV>
<DIV><FONT face=Arial size=2>NAS ----> Access-Request ------->
FreeRadius</FONT></DIV>
<DIV><FONT face=Arial size=2>Freeradius ------>Access Accept(if the password
is matched) ----> NAS</FONT></DIV>
<DIV><FONT face=Arial size=2>
<DIV><FONT face=Arial size=2>NAS ----> Accounting-Request ------->
FreeRadius(Accounting start)</FONT></DIV>
<DIV>Freeradius -----> Acounting Response------> NAS</DIV>
<DIV>
<DIV><FONT face=Arial size=2>NAS ----> Access-Request -------> FreeRadius
(Reauthorize to check account balance)</FONT></DIV>
<DIV>
<DIV><FONT face=Arial size=2>Freeradius ------>Access Accept(if the password
is matched) ----> NAS</FONT></DIV>
<DIV>
<DIV><FONT face=Arial size=2>Freeradius ------>Access Reject(if the password
isnot matched) ----> NAS</FONT></DIV></DIV></DIV></DIV>
<DIV> </DIV></FONT></DIV>
<DIV><FONT face=Arial size=2>at the line 529 + 530, i should to send back
to NAS server with the Acces Reject with attribute <STRONG><FONT
color=#0000ff>h323-return-code == "h323-return-code=2" not
0</FONT></STRONG><BR></FONT></DIV>
<DIV><FONT face=Arial size=2>Thank you</FONT></DIV>
<DIV><FONT face=Arial size=2>Ha`</DIV></FONT>
<DIV><FONT face=Arial size=2></FONT> </DIV>
<DIV><FONT face=Arial size=2>1 rad_recv: Access-Request packet from host
172.26.0.8 port 1645, id=200,
length=165<BR>2 User-Name =
"0873000001"<BR>3 User-Password
= "Servicecisco"<BR>4
NAS-IP-Address = 0.0.0.0<BR>5
NAS-Port = 0<BR>6 Service-Type =
Dialout-Framed-User<BR>7
h323-call-type =
"h323-call-type=VOIP"<BR>8
Called-Station-Id =
"0873001163"<BR>9
Calling-Station-Id =
"0873000001"<BR>10
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>11 Event-Timestamp = "Dec
16 2008 09:13:03 ICT"<BR>12
NAS-Port-Type = Ethernet<BR>13 +- entering group authorize
{...}<BR>14 ++[preprocess] returns
ok<BR>15 [auth_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>16 [auth_log]
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>17 [auth_log]
expand: %t -> Tue Dec 16 09:13:03 2008<BR>18 ++[auth_log] returns
ok<BR>19 ++[digest] returns noop<BR>20 [suffix] No <A href="">'@'</A>
in User-Name = "0873000001", looking up realm NULL<BR>21 [suffix] No such
realm "NULL"<BR>22 ++[suffix] returns noop<BR>23 ++[unix] returns
notfound<BR>24 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>25 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>26 rlm_sql (pgsql-voip): Reserving sql socket id:
19<BR>27 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radcheck WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radcheck WHERE Username =
'0873000001' ORDER BY id<BR>28 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radcheck
WHERE Username = '0873000001' ORDER BY
id<BR>29 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>30 rlm_sql_postgresql: query affected rows = 1 , fields
= 5<BR>31 [pgsql-voip] User found in radcheck
table<BR>32 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radreply WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radreply WHERE Username =
'0873000001' ORDER BY id<BR>33 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radreply
WHERE Username = '0873000001' ORDER BY
id<BR>34 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>35 rlm_sql_postgresql: query affected rows = 7 , fields
= 5<BR>36 rlm_sql (pgsql-voip): Released sql socket id:
19<BR>37 ++[pgsql-voip] returns ok<BR>38 rlm_counter: Entering module
authorize code<BR>39 rlm_counter: Could not find Check item value
pair<BR>40 ++[daily] returns noop<BR>41 ++[expiration] returns
noop<BR>42 ++[logintime] returns noop<BR>43 ++[pap] returns
updated<BR>44 Found Auth-Type = PAP<BR>45 +- entering group PAP
{...}<BR>46 [pap] login attempt with password
"Servicecisco"<BR>47 [pap] Using clear text password
"Servicecisco"<BR>48 [pap] User authenticated
successfully<BR>49 ++[pap] returns ok<BR>50 +- entering group
post-auth {...}<BR>51 [reply_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>52 [reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>53 [reply_log]
expand: %t -> Tue Dec 16 09:13:03 2008<BR>54 ++[reply_log] returns
ok<BR>55 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>56 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>57 [pgsql-voip] expand: %{User-Password}
-> Servicecisco<BR>58 [pgsql-voip] expand: INSERT INTO
radpostauth (username, pass, reply, authdate, calledstationid,
callingstationid) VALUES ('%{User-Name}',
'%{%{User-Password}:-Chap-Password}', '%{reply:Packet-Type}', NOW(),
'%{Called-Station-Id}', '%{Calling-Station-Id}') -> INSERT INTO radpostauth
(username, pass, reply, authdate, calledstationid, callingstationid)
VALUES ('0873000001', 'Servicecisco', 'Access-Accept', NOW(),
'0873001163', '0873000001')<BR>59 [pgsql-voip] expand:
/var/log/radius/sqltrace.sql ->
/var/log/radius/sqltrace.sql<BR>60 rlm_sql (pgsql-voip) in sql_postauth:
query is INSERT INTO radpostauth (username, pass, reply, authdate,
calledstationid, callingstationid) VALUES ('0873000001',
'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>61 rlm_sql (pgsql-voip): Reserving sql socket id:
18<BR>62 rlm_sql_postgresql: query: INSERT INTO radpostauth (username,
pass, reply, authdate, calledstationid, callingstationid) VALUES
('0873000001', 'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>63 rlm_sql_postgresql: Status:
PGRES_COMMAND_OK<BR>64 rlm_sql_postgresql: query affected rows =
1<BR>65 rlm_sql (pgsql-voip): Released sql socket id:
18<BR>66 ++[pgsql-voip] returns ok<BR>67 ++[exec] returns
noop<BR>68 Sending Access-Accept of id 200 to 172.26.0.8 port
1645<BR>69 Class ==
0x436c6173733d333030<BR>70
h323-credit-amount ==
"h323-credit-amount=1000"<BR>71
h323-return-code ==
"h323-return-code=0"<BR>72
h323-preferred-lang ==
"hh323-preferred-lang=VN"<BR>73
h323-billing-model ==
"h323-billing-model=2"<BR>74
h323-currency ==
"h323-currency=USD"<BR>75
h323-credit-time == "h323-credit-time=10"<BR>76 Finished request
3.<BR>77 Going to the next request<BR>78 Waking up in 4.9
seconds.<BR>79 rad_recv: Accounting-Request packet from host 172.26.0.8
port 1645, id=201,
length=399<BR>80 User-Name =
"0873000001"<BR>81
NAS-IP-Address = 0.0.0.0<BR>82
Service-Type =
Dialout-Framed-User<BR>83 Class
= 0x436c6173733d333030<BR>84
Cisco-AVPair = "h323-incoming-conf-id=e727dc68 ca4d11dd 812dd127 98296413
"<BR>85 h323-conf-id =
"h323-conf-id=e727dc68 ca4d11dd 812dd127 98296413
"<BR>86 h323-setup-time =
"h323-setup-time= 2:13:03.000 UTC Tue Dec 16
2008"<BR>87 h323-connect-time =
"h323-connect-time= 2:13:05.000 UTC Tue Dec 16
2008"<BR>88 h323-call-type =
"h323-call-type=VOIP"<BR>89
Called-Station-Id =
"0873001163"<BR>90
Calling-Station-Id =
"0873000001"<BR>91
Acct-Status-Type = Start<BR>92
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>93 Event-Timestamp = "Dec
16 2008 09:13:05 ICT"<BR>94
NAS-Port-Type = Ethernet<BR>95 +- entering group preacct
{...}<BR>96 ++[preprocess] returns ok<BR>97 [acct_unique] Hashing
'NAS-Port-Type = Ethernet,Client-IP-Address = 172.26.0.8,NAS-IP-Address =
0.0.0.0,Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413 ",User-Name =
"0873000001"'<BR>98 [acct_unique] Acct-Unique-Session-ID =
"bf9a06b98413c5d2".<BR>99 ++[acct_unique] returns ok<BR>100 [suffix]
No <A href="">'@'</A> in User-Name = "0873000001", looking up realm
NULL<BR>101 [suffix] No such realm "NULL"<BR>102 ++[suffix] returns
noop<BR>103 [files] acct_users: Matched entry DEFAULT at line
22<BR>104 [files] WARNING: Deprecated conditional expansion ":-". See
"man unlang" for
details<BR>105 [files]
expand: %{Stripped-User-Name:-%{User-Name}} ->
0873000001<BR>106 ++[files] returns ok<BR>107 +- entering group
accounting {...}<BR>108 [detail]
expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/detail-20081216<BR>109 [detail]
/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/detail-20081216<BR>110 [detail]
expand: %t -> Tue Dec 16 09:13:05 2008<BR>111 ++[detail] returns
ok<BR>112 ++[unix] returns
noop<BR>113 [radutmp] expand:
/var/log/radius/radutmp ->
/var/log/radius/radutmp<BR>114 [radutmp]
expand: %{User-Name} -> 0873000001<BR>115 rlm_radutmp: No
NAS-Port seen. Cannot do anything.<BR>116 rlm_radumtp:
WARNING: checkrad will probably not work!<BR>117 ++[radutmp] returns
noop<BR>118 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>119 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>120 [pgsql-voip] WARNING: Deprecated conditional expansion
":-". See "man unlang" for
details<BR>121 [pgsql-voip] expand: INSERT into
startvoip
(RadiusServerName, UserName, NASIPAddress, AcctTime,
CalledStationId,
CallingStationId, AcctDelayTime, h323gwid, h323callorigin, h323setuptime,
H323ConnectTime, callid)
values('210.245.126.131',
'%{SQL-User-Name}',
'%{NAS-IP-Address}', now(),
'%{Called-Station-Id}',
'%{Calling-Station-Id}', '%{Acct-Delay-Time:-0}',
'%{h323-gw-id}',
'%{h323-call-origin}', strip_dot('%{h323-setup-time}'),
strip_dot('%{h323-connect-time}'), pick_id('%{h323-conf-id}', '%{call-id}'))
-> INSERT into
startvoip
(RadiusServerName, UserName, NASIPAddress, AcctTime,
CalledStationId,
CallingStationId, AcctDelayTime, h323gwid, h323callorigin, h323setuptime,
H323ConnectTime,
callid)
values('210.245.126.131',
'0873000001',
'0.0.0.0', now(),
'0873001163',
'0873000001', '0', '', '',
strip_dot(' 2:13:03.000 UTC Tue Dec 16 2008'), strip_dot(' 2:13:05.000 UTC Tue
Dec 16 2008'), pick_id('e727dc68 ca4d11dd 812dd127 98296413 ',
''))<BR>122 [pgsql-voip] expand:
/var/log/radius/sqltrace.sql ->
/var/log/radius/sqltrace.sql<BR>123 rlm_sql (pgsql-voip): Reserving sql
socket id: 17<BR>124 rlm_sql_postgresql: query: INSERT into
startvoip
(RadiusServerName, UserName, NASIPAddress, AcctTime,
CalledStationId,
CallingStationId, AcctDelayTime, h323gwid, h323callorigin, h323setuptime,
H323ConnectTime, callid) values('210.245.126.131',
'0873000001', '0.0.0.0',
now(),
'0873001163',
'0873000001', '0', '', '', strip_dot(' 2:13:03.000
UTC Tue Dec 16 2008'), strip_dot(' 2:13:05.000 UTC Tue Dec 16 2008'),
pick_id('e727dc68 ca4d11dd 812dd127 98296413 ',
''))<BR>125 rlm_sql_postgresql: Status:
PGRES_COMMAND_OK<BR>126 rlm_sql_postgresql: query affected rows =
1<BR>127 rlm_sql (pgsql-voip): Released sql socket id:
17<BR>128 ++[pgsql-voip] returns ok<BR>129 Sending Accounting-Response
of id 201 to 172.26.0.8 port
1645<BR>130 User-Name :=
"0873000001"<BR>131
h323-return-code :=
"h323-return-code=0"<BR>132
h323-billing-model := "h323-billing-model=2"<BR>133 Finished request
4.<BR>134 Cleaning up request 4 ID 201 with timestamp
+124<BR>135 Going to the next request<BR>136 Waking up in 2.8
seconds.<BR>137 Cleaning up request 3 ID 200 with timestamp
+122<BR>138 Ready to process requests.<BR>139 rad_recv: Access-Request
packet from host 172.26.0.8 port 1645, id=202,
length=202<BR>140 User-Name =
"0873000001"<BR>141
User-Password =
"Servicecisco"<BR>142
NAS-IP-Address = 0.0.0.0<BR>143
NAS-Port = 0<BR>144 Service-Type
= Dialout-Framed-User<BR>145
Class =
0x436c6173733d333030<BR>146
h323-call-type =
"h323-call-type=VOIP"<BR>147
h323-credit-time =
"h323-credit-time=9"<BR>148
Called-Station-Id =
"0873001163"<BR>149
Calling-Station-Id =
"0873000001"<BR>150
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>151 Event-Timestamp = "Dec
16 2008 09:13:14 ICT"<BR>152
NAS-Port-Type = Ethernet<BR>153 +- entering group authorize
{...}<BR>154 ++[preprocess] returns
ok<BR>155 [auth_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>156 [auth_log]
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>157 [auth_log]
expand: %t -> Tue Dec 16 09:13:14 2008<BR>158 ++[auth_log] returns
ok<BR>159 ++[digest] returns noop<BR>160 [suffix] No <A
href="">'@'</A> in User-Name = "0873000001", looking up realm
NULL<BR>161 [suffix] No such realm "NULL"<BR>162 ++[suffix] returns
noop<BR>163 ++[unix] returns
notfound<BR>164 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>165 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>...........</FONT></DIV>
<DIV><FONT face=Arial size=2>171 [pgsql-voip] User found in radcheck
table<BR>172 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radreply WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radreply WHERE Username =
'0873000001' ORDER BY id<BR>173 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radreply
WHERE Username = '0873000001' ORDER BY
id<BR>174 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>175 rlm_sql_postgresql: query affected rows = 7 , fields
= 5<BR>176 rlm_sql (pgsql-voip): Released sql socket id:
16<BR>177 ++[pgsql-voip] returns ok<BR>178 rlm_counter: Entering
module authorize code<BR>179 rlm_counter: Could not find Check item value
pair<BR>180 ++[daily] returns noop<BR>181 ++[expiration] returns
noop<BR>182 ++[logintime] returns noop<BR>183 ++[pap] returns
updated<BR>184 Found Auth-Type = PAP<BR>185 +- entering group PAP
{...}<BR>186 [pap] login attempt with password
"Servicecisco"<BR>187 [pap] Using clear text password
"Servicecisco"<BR>188 [pap] User authenticated
successfully<BR>189 ++[pap] returns ok<BR>190 +- entering group
post-auth {...}<BR>191 [reply_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>192 [reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>193 [reply_log]
expand: %t -> Tue Dec 16 09:13:14 2008<BR>194 ++[reply_log] returns
ok<BR>195 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>196 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>197 [pgsql-voip] expand: %{User-Password}
-> Servicecisco<BR>198 [pgsql-voip] expand: INSERT
INTO radpostauth (username, pass, reply, authdate, calledstationid,
callingstationid) VALUES ('%{User-Name}',
'%{%{User-Password}:-Chap-Password}', '%{reply:Packet-Type}', NOW(),
'%{Called-Station-Id}', '%{Calling-Station-Id}') -> INSERT INTO radpostauth
(username, pass, reply, authdate, calledstationid, callingstationid)
VALUES ('0873000001', 'Servicecisco', 'Access-Accept', NOW(),
'0873001163', '0873000001')<BR>199 [pgsql-voip] expand:
/var/log/radius/sqltrace.sql ->
/var/log/radius/sqltrace.sql<BR>200 rlm_sql (pgsql-voip) in sql_postauth:
query is INSERT INTO radpostauth (username, pass, reply, authdate,
calledstationid, callingstationid) VALUES ('0873000001',
'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>201 rlm_sql (pgsql-voip): Reserving sql socket id:
15<BR>202 rlm_sql_postgresql: query: INSERT INTO radpostauth (username,
pass, reply, authdate, calledstationid, callingstationid) VALUES
('0873000001', 'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>203 rlm_sql_postgresql: Status:
PGRES_COMMAND_OK<BR>204 rlm_sql_postgresql: query affected rows =
1<BR>205 rlm_sql (pgsql-voip): Released sql socket id:
15<BR>206 ++[pgsql-voip] returns ok<BR>207 ++[exec] returns
noop<BR>208 Sending Access-Accept of id 202 to 172.26.0.8 port
1645<BR>209 Class ==
0x436c6173733d333030<BR>210
h323-credit-amount ==
"h323-credit-amount=1000"<BR>211
h323-return-code ==
"h323-return-code=0"<BR>212
h323-preferred-lang ==
"hh323-preferred-lang=VN"<BR>213
h323-billing-model ==
"h323-billing-model=2"<BR>214
h323-currency ==
"h323-currency=USD"<BR>215
h323-credit-time == "h323-credit-time=10"<BR>216 Finished request
5.<BR>217 Going to the next request<BR>218 Waking up in 4.9
seconds.<BR>219 Cleaning up request 5 ID 202 with timestamp
+133<BR>220 Ready to process requests.<BR>221 rad_recv: Access-Request
packet from host 172.26.0.8 port 1645, id=203,
length=203<BR>222 User-Name =
"0873000001"<BR>223
User-Password =
"Servicecisco"<BR>224
NAS-IP-Address = 0.0.0.0<BR>225
NAS-Port = 0<BR>226 Service-Type
= Dialout-Framed-User<BR>227
Class =
0x436c6173733d333030<BR>228
h323-call-type =
"h323-call-type=VOIP"<BR>229
h323-credit-time =
"h323-credit-time=19"<BR>230
Called-Station-Id =
"0873001163"<BR>231
Calling-Station-Id =
"0873000001"<BR>232
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>233 Event-Timestamp = "Dec
16 2008 09:13:24 ICT"<BR>234
NAS-Port-Type = Ethernet<BR>235 +- entering group authorize
{...}<BR>236 ++[preprocess] returns
ok<BR>237 [auth_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>238 [auth_log]
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>239 [auth_log]
expand: %t -> Tue Dec 16 09:13:24 2008<BR>240 ++[auth_log] returns
ok<BR>241 ++[digest] returns noop<BR>242 [suffix] No <A
href="">'@'</A> in User-Name = "0873000001", looking up realm
NULL<BR>243 [suffix] No such realm "NULL"<BR>244 ++[suffix] returns
noop<BR>245 ++[unix] returns
notfound<BR>246 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>247 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>248 rlm_sql (pgsql-voip): Reserving sql socket id:
14<BR>249 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radcheck WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radcheck WHERE Username =
'0873000001' ORDER BY id<BR>250 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radcheck
WHERE Username = '0873000001' ORDER BY
id<BR>251 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>252 rlm_sql_postgresql: query affected rows = 1 , fields
= 5<BR>253 [pgsql-voip] User found in radcheck
table<BR>254 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radreply WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radreply WHERE Username =
'0873000001' ORDER BY id<BR>255 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radreply
WHERE Username = '0873000001' ORDER BY
id<BR>256 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>257 rlm_sql_postgresql: query affected rows = 7 , fields
= 5<BR>258 rlm_sql (pgsql-voip): Released sql socket id:
14<BR>259 ++[pgsql-voip] returns ok<BR>260 rlm_counter: Entering
module authorize code<BR>261 rlm_counter: Could not find Check item value
pair<BR>262 ++[daily] returns noop<BR>263 ++[expiration] returns
noop<BR>264 ++[logintime] returns noop<BR>265 ++[pap] returns
updated<BR>266 Found Auth-Type = PAP<BR>267 +- entering group PAP
{...}<BR>268 [pap] login attempt with password
"Servicecisco"<BR>269 [pap] Using clear text password
"Servicecisco"<BR>270 [pap] User authenticated
successfully<BR>271 ++[pap] returns ok<BR>272 +- entering group
post-auth {...}<BR>273 [reply_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>274 [reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>275 [reply_log]
expand: %t -> Tue Dec 16 09:13:24 2008<BR>276 ++[reply_log] returns
ok<BR>277 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>278 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>279 [pgsql-voip] expand: %{User-Password}
-> Servicecisco<BR>280 [pgsql-voip] expand: INSERT
INTO radpostauth (username, pass, reply, authdate, calledstationid,
callingstationid) VALUES ('%{User-Name}',
'%{%{User-Password}:-Chap-Password}', '%{reply:Packet-Type}', NOW(),
'%{Called-Station-Id}', '%{Calling-Station-Id}') -> INSERT INTO radpostauth
(username, pass, reply, authdate, calledstationid, callingstationid)
VALUES ('0873000001', 'Servicecisco', 'Access-Accept', NOW(),
'0873001163', '0873000001')<BR>281 [pgsql-voip] expand:
/var/log/radius/sqltrace.sql ->
/var/log/radius/sqltrace.sql<BR>282 rlm_sql (pgsql-voip) in sql_postauth:
query is INSERT INTO radpostauth (username, pass, reply, authdate,
calledstationid, callingstationid) VALUES ('0873000001',
'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>283 rlm_sql (pgsql-voip): Reserving sql socket id:
13<BR>284 rlm_sql_postgresql: query: INSERT INTO radpostauth (username,
pass, reply, authdate, calledstationid, callingstationid) VALUES
('0873000001', 'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>285 rlm_sql_postgresql: Status:
PGRES_COMMAND_OK<BR>286 rlm_sql_postgresql: query affected rows =
1<BR>287 rlm_sql (pgsql-voip): Released sql socket id:
13<BR>288 ++[pgsql-voip] returns ok<BR>289 ++[exec] returns
noop<BR>290 Sending Access-Accept of id 203 to 172.26.0.8 port
1645<BR>291 Class ==
0x436c6173733d333030<BR>292
h323-credit-amount ==
"h323-credit-amount=1000"<BR>293
h323-return-code ==
"h323-return-code=0"<BR>294
h323-preferred-lang ==
"hh323-preferred-lang=VN"<BR>295
h323-billing-model ==
"h323-billing-model=2"<BR>296
h323-currency ==
"h323-currency=USD"<BR>297
h323-credit-time == "h323-credit-time=10"<BR>298 Finished request
6.<BR>299 Going to the next request<BR>300 Waking up in 4.9
seconds.<BR>301 Cleaning up request 6 ID 203 with timestamp
+143<BR>302 Ready to process requests.<BR>303 rad_recv: Access-Request
packet from host 172.26.0.8 port 1645, id=204,
length=203<BR>304 User-Name =
"0873000001"<BR>305
User-Password =
"Servicecisco"<BR>306
NAS-IP-Address = 0.0.0.0<BR>307
NAS-Port = 0<BR>308 Service-Type
= Dialout-Framed-User<BR>309
Class =
0x436c6173733d333030<BR>310
h323-call-type =
"h323-call-type=VOIP"<BR>311
h323-credit-time =
"h323-credit-time=29"<BR>312
Called-Station-Id =
"0873001163"<BR>313
Calling-Station-Id =
"0873000001"<BR>314
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>315 Event-Timestamp = "Dec
16 2008 09:13:34 ICT"<BR>316
NAS-Port-Type = Ethernet<BR>317 +- entering group authorize
{...}<BR>318 ++[preprocess] returns
ok<BR>319 [auth_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>320 [auth_log]
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>321 [auth_log]
expand: %t -> Tue Dec 16 09:13:34 2008<BR>322 ++[auth_log] returns
ok<BR>323 ++[digest] returns noop<BR>324 [suffix] No <A
href="">'@'</A> in User-Name = "0873000001", looking up realm
NULL<BR>325 [suffix] No such realm "NULL"<BR>326 ++[suffix] returns
noop<BR>327 ++[unix] returns
notfound<BR>328 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>329 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>330 rlm_sql (pgsql-voip): Reserving sql socket id:
12<BR>331 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radcheck WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radcheck WHERE Username =
'0873000001' ORDER BY id<BR>332 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radcheck
WHERE Username = '0873000001' ORDER BY
id<BR>333 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>334 rlm_sql_postgresql: query affected rows = 1 , fields
= 5<BR>335 [pgsql-voip] User found in radcheck
table<BR>336 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radreply WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radreply WHERE Username =
'0873000001' ORDER BY id<BR>337 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radreply
WHERE Username = '0873000001' ORDER BY
id<BR>338 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>339 rlm_sql_postgresql: query affected rows = 7 , fields
= 5<BR>340 rlm_sql (pgsql-voip): Released sql socket id:
12<BR>341 ++[pgsql-voip] returns ok<BR>342 rlm_counter: Entering
module authorize code<BR>343 rlm_counter: Could not find Check item value
pair<BR>344 ++[daily] returns noop<BR>345 ++[expiration] returns
noop<BR>346 ++[logintime] returns noop<BR>347 ++[pap] returns
updated<BR>348 Found Auth-Type = PAP<BR>349 +- entering group PAP
{...}<BR>350 [pap] login attempt with password
"Servicecisco"<BR>351 [pap] Using clear text password
"Servicecisco"<BR>352 [pap] User authenticated
successfully<BR>353 ++[pap] returns ok<BR>354 +- entering group
post-auth {...}<BR>355 [reply_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>356 [reply_log]
/var/log/radius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/reply-detail-20081216<BR>357 [reply_log]
expand: %t -> Tue Dec 16 09:13:34 2008<BR>358 ++[reply_log] returns
ok<BR>359 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>360 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>361 [pgsql-voip] expand: %{User-Password}
-> Servicecisco<BR>362 [pgsql-voip] expand: INSERT
INTO radpostauth (username, pass, reply, authdate, calledstationid,
callingstationid) VALUES ('%{User-Name}',
'%{%{User-Password}:-Chap-Password}', '%{reply:Packet-Type}', NOW(),
'%{Called-Station-Id}', '%{Calling-Station-Id}') -> INSERT INTO radpostauth
(username, pass, reply, authdate, calledstationid, callingstationid)
VALUES ('0873000001', 'Servicecisco', 'Access-Accept', NOW(),
'0873001163', '0873000001')<BR>363 [pgsql-voip] expand:
/var/log/radius/sqltrace.sql ->
/var/log/radius/sqltrace.sql<BR>364 rlm_sql (pgsql-voip) in sql_postauth:
query is INSERT INTO radpostauth (username, pass, reply, authdate,
calledstationid, callingstationid) VALUES ('0873000001',
'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>365 rlm_sql (pgsql-voip): Reserving sql socket id:
11<BR>366 rlm_sql_postgresql: query: INSERT INTO radpostauth (username,
pass, reply, authdate, calledstationid, callingstationid) VALUES
('0873000001', 'Servicecisco', 'Access-Accept', NOW(), '0873001163',
'0873000001')<BR>367 rlm_sql_postgresql: Status:
PGRES_COMMAND_OK<BR>368 rlm_sql_postgresql: query affected rows =
1<BR>369 rlm_sql (pgsql-voip): Released sql socket id:
11<BR>370 ++[pgsql-voip] returns ok<BR>371 ++[exec] returns
noop<BR>372 Sending Access-Accept of id 204 to 172.26.0.8 port
1645<BR>373 Class ==
0x436c6173733d333030<BR>374
h323-credit-amount ==
"h323-credit-amount=1000"<BR>375
h323-return-code ==
"h323-return-code=0"<BR>376
h323-preferred-lang ==
"hh323-preferred-lang=VN"<BR>377
h323-billing-model ==
"h323-billing-model=2"<BR>378
h323-currency ==
"h323-currency=USD"<BR>379
h323-credit-time == "h323-credit-time=10"<BR>380 Finished request
7.<BR>381 Going to the next request<BR>382 Waking up in 4.9
seconds.<BR>383 Cleaning up request 7 ID 204 with timestamp
+153<BR>384 Ready to process requests.<BR>......</FONT></DIV>
<DIV><FONT face=Arial size=2>465 Cleaning up request 8 ID 205 with
timestamp +163<BR>466 Ready to process requests.<BR>467 rad_recv:
Access-Request packet from host 172.26.0.8 port 1645, id=206,
length=203<BR>468 User-Name =
"0873000001"<BR>469
User-Password =
"\037_Ø\346"<BR>470
NAS-IP-Address = 0.0.0.0<BR>471
NAS-Port = 0<BR>472 Service-Type
= Dialout-Framed-User<BR>473
Class =
0x436c6173733d333030<BR>474
h323-call-type =
"h323-call-type=VOIP"<BR>475
h323-credit-time =
"h323-credit-time=49"<BR>476
Called-Station-Id =
"0873001163"<BR>477
Calling-Station-Id =
"0873000001"<BR>478
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>479 Event-Timestamp = "Dec
16 2008 09:13:54 ICT"<BR>480
NAS-Port-Type = Ethernet<BR>481 +- entering group authorize
{...}<BR>482 ++[preprocess] returns
ok<BR>483 [auth_log] expand:
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>484 [auth_log]
/var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/auth-detail-20081216<BR>485 [auth_log]
expand: %t -> Tue Dec 16 09:13:54 2008<BR>486 ++[auth_log] returns
ok<BR>487 ++[digest] returns noop<BR>488 [suffix] No <A
href="">'@'</A> in User-Name = "0873000001", looking up realm
NULL<BR>489 [suffix] No such realm "NULL"<BR>490 ++[suffix] returns
noop<BR>491 ++[unix] returns
notfound<BR>492 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>493 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>494 rlm_sql (pgsql-voip): Reserving sql socket id:
8<BR>495 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radcheck WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radcheck WHERE Username =
'0873000001' ORDER BY id<BR>496 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radcheck
WHERE Username = '0873000001' ORDER BY
id<BR>497 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>498 rlm_sql_postgresql: query affected rows = 1 , fields
= 5<BR>499 [pgsql-voip] User found in radcheck
table<BR>500 [pgsql-voip] expand: SELECT id, UserName,
Attribute, Value, Op FROM radreply WHERE Username =
'%{SQL-User-Name}' ORDER BY id -> SELECT id, UserName, Attribute,
Value, Op FROM radreply WHERE Username =
'0873000001' ORDER BY id<BR>501 rlm_sql_postgresql: query:
SELECT id, UserName, Attribute, Value, Op FROM radreply
WHERE Username = '0873000001' ORDER BY
id<BR>502 rlm_sql_postgresql: Status:
PGRES_TUPLES_OK<BR>503 rlm_sql_postgresql: query affected rows = 7 , fields
= 5<BR>504 rlm_sql (pgsql-voip): Released sql socket id:
8<BR>505 ++[pgsql-voip] returns ok<BR>506 rlm_counter: Entering module
authorize code<BR>507 rlm_counter: Could not find Check item value
pair<BR>508 ++[daily] returns noop<BR>509 ++[expiration] returns
noop<BR>510 ++[logintime] returns noop<BR>511 ++[pap] returns
updated<BR>512 Found Auth-Type = PAP<BR>513 +- entering group PAP
{...}<BR>514 [pap] login attempt with password "?_Ø?æ"<BR>515 [pap]
Using clear text password "Servicecisco"<BR>516 [pap] Passwords don't
match<BR>517 ++[pap] returns reject<BR>518 Failed to authenticate the
user.<BR>519 WARNING: Unprintable characters in the
password. Double-check the shared
secret on the server and the NAS!<BR>520 Using Post-Auth-Type
Reject<BR>521 +- entering group REJECT
{...}<BR>522 [attr_filter.access_reject] expand:
%{User-Name} -> 0873000001<BR>523 attr_filter: Matched entry DEFAULT at
line 11<BR>524 ++[attr_filter.access_reject] returns
updated<BR>525 Delaying reject of request 9 for 1 seconds<BR>526 Going
to the next request<BR>527 Waking up in 0.9 seconds.<BR>528 Sending
delayed reject for request 9<BR><STRONG><FONT color=#0000ff>529 Sending
Access-Reject of id 206 to 172.26.0.8 port 1645</FONT></STRONG><BR><STRONG><FONT
color=#0000ff>530
h323-return-code == "h323-return-code=0"<BR></FONT></STRONG>531 Waking up
in 4.9 seconds.<BR>532 Cleaning up request 9 ID 206 with timestamp
+173<BR>533 Ready to process requests.<BR>534 rad_recv:
Accounting-Request packet from host 172.26.0.8 port 1645, id=207,
length=498<BR>535 User-Name =
"0873000001"<BR>536
NAS-IP-Address = 0.0.0.0<BR>537
Service-Type =
Dialout-Framed-User<BR>538 Class
= 0x436c6173733d333030<BR>539
Cisco-AVPair = "h323-incoming-conf-id=e727dc68 ca4d11dd 812dd127 98296413
"<BR>540 h323-conf-id =
"h323-conf-id=e727dc68 ca4d11dd 812dd127 98296413
"<BR>541 h323-setup-time =
"h323-setup-time= 2:13:54.000 UTC Tue Dec 16
2008"<BR>542 h323-connect-time =
"h323-connect-time= 2:13:05.000 UTC Tue Dec 16
2008"<BR>543 h323-call-type =
"h323-call-type=VOIP"<BR>544
h323-disconnect-time = "h323-disconnect-time= 2:14:08.000 UTC Tue Dec 16
2008"<BR>545
h323-disconnect-cause =
"h323-disconnect-cause=10"<BR>546
Called-Station-Id =
"0873001163"<BR>547
Calling-Station-Id =
"0873000001"<BR>548
Acct-Status-Type = Stop<BR>549
Acct-Session-Id = "e727dc68 ca4d11dd 812dd127 98296413
"<BR>550 Acct-Authentic =
RADIUS<BR>551 Event-Timestamp =
"Dec 16 2008 09:14:08
ICT"<BR>552 NAS-Port-Type =
Ethernet<BR>553 +- entering group preacct {...}<BR>554 ++[preprocess]
returns ok<BR>555 [acct_unique] Hashing 'NAS-Port-Type =
Ethernet,Client-IP-Address = 172.26.0.8,NAS-IP-Address = 0.0.0.0,Acct-Session-Id
= "e727dc68 ca4d11dd 812dd127 98296413 ",User-Name =
"0873000001"'<BR>556 [acct_unique] Acct-Unique-Session-ID =
"bf9a06b98413c5d2".<BR>557 ++[acct_unique] returns ok<BR>558 [suffix]
No <A href="">'@'</A> in User-Name = "0873000001", looking up realm
NULL<BR>559 [suffix] No such realm "NULL"<BR>560 ++[suffix] returns
noop<BR>561 [files] acct_users: Matched entry DEFAULT at line
22<BR>562 [files] WARNING: Deprecated conditional expansion ":-". See
"man unlang" for
details<BR>563 [files]
expand: %{Stripped-User-Name:-%{User-Name}} ->
0873000001<BR>564 ++[files] returns ok<BR>565 +- entering group
accounting {...}<BR>566 [detail]
expand: /var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d ->
/var/log/radius/radacct/172.26.0.8/detail-20081216<BR>567 [detail]
/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to
/var/log/radius/radacct/172.26.0.8/detail-20081216<BR>568 [detail]
expand: %t -> Tue Dec 16 09:14:09 2008<BR>569 ++[detail] returns
ok<BR>570 ++[unix] returns
noop<BR>571 [radutmp] expand:
/var/log/radius/radutmp ->
/var/log/radius/radutmp<BR>572 [radutmp]
expand: %{User-Name} -> 0873000001<BR>573 rlm_radutmp: No
NAS-Port seen. Cannot do anything.<BR>574 rlm_radumtp:
WARNING: checkrad will probably not work!<BR>575 ++[radutmp] returns
noop<BR>576 [pgsql-voip] expand: %{User-Name} ->
0873000001<BR>577 [pgsql-voip] sql_set_user escaped user -->
'0873000001'<BR>578 [pgsql-voip] WARNING: Deprecated conditional expansion
":-". See "man unlang" for details<BR>579 [pgsql-voip] WARNING:
Deprecated conditional expansion ":-". See "man unlang" for
details<BR>580 [pgsql-voip] WARNING: Deprecated conditional expansion
":-". See "man unlang" for details<BR>581 [pgsql-voip] WARNING:
Deprecated conditional expansion ":-". See "man unlang" for
details<BR>586 rlm_sql_postgresql: Status:
PGRES_COMMAND_OK<BR>587 rlm_sql_postgresql: query affected rows =
1<BR>588 rlm_sql (pgsql-voip): Released sql socket id:
7<BR>589 ++[pgsql-voip] returns ok<BR>590 Sending Accounting-Response
of id 207 to 172.26.0.8 port
1645<BR>591 User-Name :=
"0873000001"<BR>592
h323-return-code :=
"h323-return-code=0"<BR>593
h323-billing-model := "h323-billing-model=2"<BR>594 Finished request
10.<BR>595 Cleaning up request 10 ID 207 with timestamp
+188<BR>596 Going to the next request<BR>597 Ready to process
requests.</FONT></DIV>
<DIV><FONT face=Arial
size=2></FONT> </DIV></FONT></DIV></FONT></DIV></BODY></HTML>