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.