insering the 3 record in radius db my using openser+radius+mysql
Hi I'm using openser and freeradius server . Accounting part in openser and radius by using the mysql. help me ... Below one is radius server in debug mode..... in that is didn't understand what is " unsupported acct-status-type =15 " and " send packet with zero session length " please help me..... rad_recv: Accounting-Request packet from host 192.168.2.55:35094, id=151, length=220 Acct-Status-Type = Failed Service-Type = Sip-Session Sip-Response-Code = 0 Sip-Method = INVITE User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Calling-Station-Id = "sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Called-Station-Id = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-Translated-Request-URI = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-To-Tag = "n/a" Sip-From-Tag = "757680932" Sip-Cseq = "18235" NAS-Port = 5060 Acct-Delay-Time = 0 NAS-IP-Address = 192.168.2.55 Processing the preacct section of radiusd.conf modcall: entering group preacct for request 2 modcall[preacct]: module "preprocess" returns noop for request 2 rlm_realm: Looking up realm "192.168.2.55" for User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " rlm_realm: No such realm "192.168.2.55" modcall[preacct]: module "suffix" returns noop for request 2 modcall: leaving group preacct (returns noop) for request 2 Processing the accounting section of radiusd.conf modcall: entering group accounting for request 2 rlm_acct_unique: Hashing 'NAS-Port = 5060,Client-IP-Address = 192.168.2.55,NAS-IP-Address = 192.168.2.55,Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>",User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> "' rlm_acct_unique: Acct-Unique-Session-ID = "5488f64bd8d99c57". modcall[accounting]: module "acct_unique" returns ok for request 2 radius_xlat: '/usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606' rlm_detail: /usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606 modcall[accounting]: module "detail" returns ok for request 2 modcall[accounting]: module "unix" returns noop for request 2 radius_xlat: '/usr/local/var/log/radius/radutmp' rlm_radutmp: NAS radiusdtest port 5060 unknown packet type 15) modcall[accounting]: module "radutmp" returns noop for request 2 rlm_sql (sql): Unsupported Acct-Status-Type = 15 modcall[accounting]: module "sql" returns noop for request 2 modcall: leaving group accounting (returns ok) for request 2 Sending Accounting-Response of id 151 to 192.168.2.55 port 35094 Finished request 2 Going to the next request --- Walking the entire request list --- Waking up in 6 seconds... --- Walking the entire request list --- Cleaning up request 2 ID 151 with timestamp 44857cc6 Nothing to do. Sleeping until we see a request. rad_recv: Accounting-Request packet from host 192.168.2.55:35096, id=152, length=232 Acct-Status-Type = Start Service-Type = Sip-Session Sip-Response-Code = 200 Sip-Method = ACK User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Calling-Station-Id = "sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Called-Station-Id = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-Translated-Request-URI = "sip:555@192.168.2.21<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> :5060" Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-To-Tag = "2710541959" Sip-From-Tag = "757680932" Sip-Cseq = "18235" NAS-Port = 5060 Acct-Delay-Time = 0 NAS-IP-Address = 192.168.2.55 Processing the preacct section of radiusd.conf modcall: entering group preacct for request 3 modcall[preacct]: module "preprocess" returns noop for request 3 rlm_realm: Looking up realm "192.168.2.55" for User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " rlm_realm: No such realm "192.168.2.55" modcall[preacct]: module "suffix" returns noop for request 3 modcall: leaving group preacct (returns noop) for request 3 Processing the accounting section of radiusd.conf modcall: entering group accounting for request 3 rlm_acct_unique: Hashing 'NAS-Port = 5060,Client-IP-Address = 192.168.2.55,NAS-IP-Address = 192.168.2.55,Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>",User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> "' rlm_acct_unique: Acct-Unique-Session-ID = "5488f64bd8d99c57". modcall[accounting]: module "acct_unique" returns ok for request 3 radius_xlat: '/usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606' rlm_detail: /usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606 modcall[accounting]: module "detail" returns ok for request 3 modcall[accounting]: module "unix" returns ok for request 3 radius_xlat: '/usr/local/var/log/radius/radutmp' radius_xlat: '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' modcall[accounting]: module "radutmp" returns ok for request 3 radius_xlat: '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' rlm_sql (sql): sql_set_user escaped user --> '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' radius_xlat: 'INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, AcctStopDelay) values('893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ', '5488f64bd8d99c57', '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', '', '192.168.2.55', '5060', '', '2006-06-06 18:32:05', '0', '0', '', '', '', '0', '0', 'sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', 'sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', '', 'Sip-Session', '', '', '0', '0')' rlm_sql (sql): Reserving sql socket id: 2 rlm_sql (sql): Released sql socket id: 2 modcall[accounting]: module "sql" returns ok for request 3 modcall: leaving group accounting (returns ok) for request 3 Sending Accounting-Response of id 152 to 192.168.2.55 port 35096 Finished request 3 Going to the next request --- Walking the entire request list --- Waking up in 6 seconds... --- Walking the entire request list --- Cleaning up request 3 ID 152 with timestamp 44857ccd Nothing to do. Sleeping until we see a request. rad_recv: Accounting-Request packet from host 192.168.2.55:35097, id=153, length=232 Acct-Status-Type = Stop Service-Type = Sip-Session Sip-Response-Code = 200 Sip-Method = BYE User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Calling-Station-Id = "sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Called-Station-Id = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-Translated-Request-URI = "sip:555@192.168.2.21<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> :5060" Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-To-Tag = "2710541959" Sip-From-Tag = "757680932" Sip-Cseq = "18236" NAS-Port = 5060 Acct-Delay-Time = 0 NAS-IP-Address = 192.168.2.55 Processing the preacct section of radiusd.conf modcall: entering group preacct for request 4 modcall[preacct]: module "preprocess" returns noop for request 4 rlm_realm: Looking up realm "192.168.2.55" for User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " rlm_realm: No such realm "192.168.2.55" modcall[preacct]: module "suffix" returns noop for request 4 modcall: leaving group preacct (returns noop) for request 4 Processing the accounting section of radiusd.conf modcall: entering group accounting for request 4 rlm_acct_unique: Hashing 'NAS-Port = 5060,Client-IP-Address = 192.168.2.55,NAS-IP-Address = 192.168.2.55,Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>",User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> "' rlm_acct_unique: Acct-Unique-Session-ID = "5488f64bd8d99c57". modcall[accounting]: module "acct_unique" returns ok for request 4 radius_xlat: '/usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606' rlm_detail: /usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606 modcall[accounting]: module "detail" returns ok for request 4 modcall[accounting]: module "unix" returns ok for request 4 radius_xlat: '/usr/local/var/log/radius/radutmp' radius_xlat: '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' modcall[accounting]: module "radutmp" returns ok for request 4 radius_xlat: '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' rlm_sql (sql): sql_set_user escaped user --> '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' radius_xlat: 'UPDATE radacct SET AcctStopTime = '2006-06-06 18:34:11', AcctSessionTime = '', AcctInputOctets = '', AcctOutputOctets = '', AcctTerminateCause = '', AcctStopDelay = '0', ConnectInfo_stop = '' WHERE AcctSessionId = '893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>' AND UserName = '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>' AND NASIPAddress = '192.168.2.55'' rlm_sql (sql): Reserving sql socket id: 1 radius_xlat: 'stop packet with zero session length. [user '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', nas '192.168.2.55']' rlm_sql (sql) in sql_accounting: stop packet with zero session length. [user '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', nas '192.168.2.55'] rlm_sql (sql): Released sql socket id: 1 radius_xlat: 'INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, AcctStopDelay) values('893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ', '5488f64bd8d99c57', '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', '', '192.168.2.55', '5060', '', DATE_SUB('2006-06-06 18:34:11', INTERVAL (0 + 0) SECOND), '2006-06-06 18:34:11', '', '', '', '', '', '', 'sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ', 'sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', '', 'Sip-Session', '', '', '0', '0')' rlm_sql (sql): Released sql socket id: 1 modcall[accounting]: module "sql" returns noop for request 4 modcall: leaving group accounting (returns ok) for request 4 Sending Accounting-Response of id 153 to 192.168.2.55 port 35097 Finished request 4 Going to the next request --- Walking the entire request list --- Waking up in 6 seconds... rad_recv: Accounting-Request packet from host 192.168.2.55:35098, id=154, length=232 Acct-Status-Type = Stop Service-Type = Sip-Session Sip-Response-Code = 200 Sip-Method = BYE User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Calling-Station-Id = "sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Called-Station-Id = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-Translated-Request-URI = "sip:555@192.168.2.21<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> :5060" Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-To-Tag = "2710541959" Sip-From-Tag = "757680932" Sip-Cseq = "18236" NAS-Port = 5060 Acct-Delay-Time = 0 NAS-IP-Address = 192.168.2.55 Processing the preacct section of radiusd.conf modcall: entering group preacct for request 5 modcall[preacct]: module "preprocess" returns noop for request 5 rlm_realm: Looking up realm "192.168.2.55" for User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " rlm_realm: No such realm "192.168.2.55" modcall[preacct]: module "suffix" returns noop for request 5 modcall: leaving group preacct (returns noop) for request 5 Processing the accounting section of radiusd.conf modcall: entering group accounting for request 5 rlm_acct_unique: Hashing 'NAS-Port = 5060,Client-IP-Address = 192.168.2.55,NAS-IP-Address = 192.168.2.55,Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>",User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> "' rlm_acct_unique: Acct-Unique-Session-ID = "5488f64bd8d99c57". modcall[accounting]: module "acct_unique" returns ok for request 5 radius_xlat: '/usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606' rlm_detail: /usr/local/var/log/radius/radacct/%{Client-IP-Address}/detail-%Y%m%d expands to /usr/local/var/log/radius/radacct/192.168.2.55/detail-20060606 modcall[accounting]: module "detail" returns ok for request 5 modcall[accounting]: module "unix" returns ok for request 5 radius_xlat: '/usr/local/var/log/radius/radutmp' radius_xlat: '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' rlm_radutmp: Logout for NAS radiusdtest port 5060, but no Login record modcall[accounting]: module "radutmp" returns ok for request 5 radius_xlat: '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' rlm_sql (sql): sql_set_user escaped user --> '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ' radius_xlat: 'UPDATE radacct SET AcctStopTime = '2006-06-06 18:34:11', AcctSessionTime = '', AcctInputOctets = '', AcctOutputOctets = '', AcctTerminateCause = '', AcctStopDelay = '0', ConnectInfo_stop = '' WHERE AcctSessionId = '893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>' AND UserName = '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>' AND NASIPAddress = '192.168.2.55'' rlm_sql (sql): Reserving sql socket id: 0 radius_xlat: 'stop packet with zero session length. [user '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', nas '192.168.2.55']' rlm_sql (sql) in sql_accounting: stop packet with zero session length. [user '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', nas '192.168.2.55'] rlm_sql (sql): Released sql socket id: 0 radius_xlat: 'INSERT into radacct (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctStopTime, AcctSessionTime, AcctAuthentic, ConnectInfo_start, ConnectInfo_stop, AcctInputOctets, AcctOutputOctets, CalledStationId, CallingStationId, AcctTerminateCause, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, AcctStopDelay) values('893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ', '5488f64bd8d99c57', '666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', '', '192.168.2.55', '5060', '', DATE_SUB('2006-06-06 18:34:11', INTERVAL (0 + 0) SECOND), '2006-06-06 18:34:11', '', '', '', '', '', '', 'sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> ', 'sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose>', '', 'Sip-Session', '', '', '0', '0')' rlm_sql (sql): Released sql socket id: 0 -- Thanks and Regards with cheers Sunkara Ravi Prakash (Voip Developer) Hyperion Technology www.hyperion-tech.com <b>
Hi,
rad_recv: Accounting-Request packet from host 192.168.2.55:35094, id=151, length=220 Acct-Status-Type = Failed Service-Type = Sip-Session Sip-Response-Code = 0 Sip-Method = INVITE User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent _Items&uid=70#Compose> " Calling-Station-Id = "sip:666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX. Sent_Items&uid=70#Compose> " Called-Station-Id = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX. Sent_Items&uid=70#Compose> " Sip-Translated-Request-URI = "sip:555@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX. Sent_Items&uid=70#Compose> " Acct-Session-Id = "893D5817-07A7-40D2-B0A8-987237749015@192.168.2.20<https://email.secureserv er.net/view.php?folder=INBOX.Sent_Items&uid=70#Compose> " Sip-To-Tag = "n/a" Sip-From-Tag = "757680932" Sip-Cseq = "18235" NAS-Port = 5060 Acct-Delay-Time = 0 NAS-IP-Address = 192.168.2.55
This request looks incredibly messy. My guess is that something is very wrong on the NAS device.
rlm_realm: Looking up realm "192.168.2.55" for User-Name = "666@192.168.2.55<https://email.secureserver.net/view.php?folder=INBOX.Sent _Items&uid=70#Compose> " rlm_realm: No such realm "192.168.2.55" modcall[preacct]: module "suffix" returns noop for request 2
Hm, this is a server behaivour I don't understand. Why does the server think the realm ends at the "<" character? It's the suffix module after all. Everything but @ should be opaque?
rlm_radutmp: NAS radiusdtest port 5060 unknown packet type 15) modcall[accounting]: module "radutmp" returns noop for request 2 rlm_sql (sql): Unsupported Acct-Status-Type = 15 modcall[accounting]: module "sql" returns noop for request 2 modcall: leaving group accounting (returns ok) for request 2 Sending Accounting-Response of id 151 to 192.168.2.55 port 35094 Finished request 2 Going to the next request
Well, Acct-Status-Type = 15 is nothing the server can interpret. 15 stands for failed. Your NAS admitted itself that something went incredibly wrong. Here's what RFC 2866 says about this attribute: Value The Value field is four octets. 1 Start 2 Stop 3 Interim-Update 7 Accounting-On 8 Accounting-Off 9-14 Reserved for Tunnel Accounting 15 Reserved for Failed So, your NAS shouldn't even send 15: it's _reserved_. Conclusion: go get a sane NAS device. -- Stefan WINTER Fondation RESTENA - Réseau Téléinformatique de l'Education Nationale et de la Recherche - Ingénieur de recherche 6, rue Richard Coudenhove-Kalergi L-1359 Luxembourg
participants (2)
-
raviprakash sunkara -
Stefan Winter