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