вообщем все так же глухо, накатил новый IOS и все те же грабли, скрипт циски находит юзера, определяет баланс, определяет длительность, но блин в конце выводит, что неправильный пин. хз че делать. 
Гляньте логи плиз, куда копать хз5350XM_GL#sh ver
Cisco IOS Software, 5350 Software (C5350-IS-M), Version 12.4(25c), RELEASE SOFTWARE (fc2)
Technical Support: http://www.cisco.com/techsupport
Copyright (c) 1986-2010 by Cisco Systems, Inc.
Compiled Fri 12-Feb-10 05:27 by prod_rel_team
ROM: System Bootstrap, Version 12.3(12r)PI6b, RELEASE SOFTWARE (fc1)
5350XM_GL uptime is 1 hour, 30 minutes
System returned to ROM by reload at 23:52:20 UTC Mon Jul 15 2002
System image file is "flash:c5350-is-mz.124-25c.bin"
Cisco AS5350XM (BCM) processor (revision 0x22) with 393215K/131072K bytes of memory.
Processor board ID JAE1036AZ81
SB-1 CPU at 750MHz, Implementation 1025, Rev 0.3, 256KB L2 Cache
Last reset from IOS reload
Manufacture Cookie Info:
 EEPROM Version 0x4, Board ID 0x4C3,
 Board Hardware Version 2.7, Item Number 800-6571779-03,
 Board Revision A0, Serial Number JAE1036AZ81.
Processor 0x0, MAC Address 0007.0e58.92d6
2 Gigabit Ethernet interfaces
37 Serial interfaces
120 terminal lines
4 Channelized E1/PRI ports
512K bytes of NVRAM.
125440K bytes of ATA External CompactFlash (Read/Write)
Configuration register is 0x2102
*Jul 16 01:21:31.799 UTC: RADIUS/ENCODE(0000001A):Orig. component type = VOICE
*Jul 16 01:21:31.799 UTC: RADIUS(0000001A): Config NAS IP: 0.0.0.0
*Jul 16 01:21:31.799 UTC: RADIUS(0000001A): sending
*Jul 16 01:21:31.799 UTC: RADIUS/ENCODE: Best Local IP-Address 10.0.1.11 for Radius-Server 192.168.110.98
*Jul 16 01:21:31.799 UTC: RADIUS: No secret to encode request (rctx:0x66499D14)
*Jul 16 01:21:31.799 UTC: RADIUS: Unable to encrypt (rctx:0x66499D14)
*Jul 16 01:21:31.799 UTC: RADIUS(0000001A): Send Accounting-Request to 192.168.110.98:1813 id 1646/51, len 550
*Jul 16 01:21:31.799 UTC: RADIUS:  authenticator 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00
*Jul 16 01:21:31.799 UTC: RADIUS:  Acct-Session-Id     [44]  10  "00000021"
*Jul 16 01:21:31.799 UTC: RADIUS:  Calling-Station-Id  [31]  12  "3433100420"
*Jul 16 01:21:31.799 UTC: RADIUS:  Called-Station-Id   [30]  9   "2786401"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  57
*Jul 16 01:21:31.799 UTC: RADIUS:   h323-setup-time    [25]  51  "h323-setup-time=*01:21:31.795 UTC Tue Jul 16 2002"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  29
*Jul 16 01:21:31.799 UTC: RADIUS:   h323-gw-id         [33]  23  "h323-gw-id=5350XM_GL."
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  56
*Jul 16 01:21:31.799 UTC: RADIUS:   Conf-Id            [24]  50  "h323-conf-id=31F144F2 979111D6 80090007 0E5892D6"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  31
*Jul 16 01:21:31.799 UTC: RADIUS:   h323-call-origin   [26]  25  "h323-call-origin=answer"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  32
*Jul 16 01:21:31.799 UTC: RADIUS:   h323-call-type     [27]  26  "h323-call-type=Telephony"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  65
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   59  "h323-incoming-conf-id=31F144F2 979111D6 80090007 0E5892D6"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   24  "subscriber=RegularLine"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  40
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   34  "gw-rxd-cdn=ton:0,npi:1,#:2786401"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  32
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   26  "calling-party-category=9"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  33
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   27  "transmission-medium-req=0"
*Jul 16 01:21:31.799 UTC: RADIUS:  User-Name           [1]   12  "3433100420"
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  32
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   26  "connect-progress=Call Up"
*Jul 16 01:21:31.799 UTC: RADIUS:  Acct-Status-Type    [40]  6   Start                     [1]
*Jul 16 01:21:31.799 UTC: RADIUS:  NAS-Port-Type       [61]  6   Async                     [0]
*Jul 16 01:21:31.799 UTC: RADIUS:  NAS-Port            [5]   6   0
*Jul 16 01:21:31.799 UTC: RADIUS:  NAS-Port-Id         [87]  14  "ISDN 3/0:D:7"
*Jul 16 01:21:31.799 UTC: RADIUS:  Service-Type        [6]   6   Login                     [1]
*Jul 16 01:21:31.799 UTC: RADIUS:  NAS-IP-Address      [4]   6   10.0.1.11
*Jul 16 01:21:31.799 UTC: RADIUS:  Acct-Delay-Time     [41]  6   0
*Jul 16 01:21:31.799 UTC: RADIUS/ENCODE(0000001B):Orig. component type = VOICE
*Jul 16 01:21:31.799 UTC: RADIUS/ENCODE(0000001B): dropping service type, "radius-server attribute 6 on-for-login-auth" is off
*Jul 16 01:21:31.799 UTC: RADIUS(0000001B): Config NAS IP: 0.0.0.0
*Jul 16 01:21:31.799 UTC: RADIUS/ENCODE(0000001B): acct_session_id: 34
*Jul 16 01:21:31.799 UTC: RADIUS(0000001B): sending
*Jul 16 01:21:31.799 UTC: RADIUS/ENCODE: Best Local IP-Address 10.0.1.11 for Radius-Server 192.168.110.98
*Jul 16 01:21:31.799 UTC: RADIUS: No secret to encode request (rctx:0x65763168)
*Jul 16 01:21:31.799 UTC: RADIUS: Unable to encrypt (rctx:0x65763168)
*Jul 16 01:21:31.799 UTC: RADIUS(0000001B): Send Access-Request to 192.168.110.98:1812 id 1645/4, len 92
*Jul 16 01:21:31.799 UTC: RADIUS:  authenticator 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00
*Jul 16 01:21:31.799 UTC: RADIUS:  User-Name           [1]   12  "3433100420"
*Jul 16 01:21:31.799 UTC: RADIUS:  User-Password       [2]   18  *
*Jul 16 01:21:31.799 UTC: RADIUS:  Vendor, Cisco       [26]  36
*Jul 16 01:21:31.799 UTC: RADIUS:   Cisco AVpair       [1]   30  "h323-ivr-out=transactionID:3"
*Jul 16 01:21:31.799 UTC: RADIUS:  NAS-IP-Address      [4]   6   10.0.1.11
*Jul 16 01:21:31.803 UTC: RADIUS: Received from id 1645/4 192.168.110.98:1812, Access-Accept, len 159
*Jul 16 01:21:31.803 UTC: RADIUS:  authenticator 30 9C BC E9 51 26 73 94 - EA 4D E9 34 4F 3F A8 3C
*Jul 16 01:21:31.803 UTC: RADIUS:  Service-Type        [6]   6   Framed                    [2]
*Jul 16 01:21:31.803 UTC: RADIUS:  Framed-Protocol     [7]   6   PPP                       [1]
*Jul 16 01:21:31.803 UTC: RADIUS:  Vendor, Cisco       [26]  12
*Jul 16 01:21:31.803 UTC: RADIUS:   Cisco AVpair       [1]   6   10:"   "
*Jul 16 01:21:31.803 UTC: RADIUS:  Vendor, Cisco       [26]  34
*Jul 16 01:21:31.803 UTC: RADIUS:   h323-credit-amount [101] 28  "h323-credit-amount=9998.55"
*Jul 16 01:21:31.803 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:31.803 UTC: RADIUS:   h323-credit-time   [102] 24  "h323-credit-time=72000"
*Jul 16 01:21:31.803 UTC: RADIUS:  Vendor, Cisco       [26]  26
*Jul 16 01:21:31.803 UTC: RADIUS:   h323-return-code   [103] 20  "h323-return-code=0"
*Jul 16 01:21:31.803 UTC: RADIUS:  Vendor, Cisco       [26]  25
*Jul 16 01:21:31.803 UTC: RADIUS:   h323-currency      [110] 19  "h323-currency=USD" radius_decrypt: null length
*Jul 16 01:21:31.803 UTC: RADIUS: Response (4) failed decrypt
*Jul 16 01:21:31.803 UTC: RADIUS: Received from id 1646/51 192.168.110.98:1813, Accounting-response, len 20
*Jul 16 01:21:31.803 UTC: RADIUS:  authenticator E7 FD 1E DB 67 AB 67 7D - 32 4B 92 7C EE 00 A0 62 radius_decrypt: null length
*Jul 16 01:21:31.803 UTC: RADIUS: Response (51) failed decrypt
*Jul 16 01:21:36.139 UTC: RADIUS: acct-timeout for 202F4D74 now 5, acct-jitter -1, acct-delay-time (at 202F4F94) now 4
*Jul 16 01:21:36.139 UTC: RADIUS: no sg in radius-timers: ctx 0x66499D14 sg 0x0000
*Jul 16 01:21:36.139 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1646/52
*Jul 16 01:21:36.139 UTC: RADIUS: No secret to encode request (rctx:0x66499D14)
*Jul 16 01:21:36.139 UTC: RADIUS: Unable to encrypt (rctx:0x66499D14)
*Jul 16 01:21:36.363 UTC: RADIUS: no sg in radius-timers: ctx 0x65763168 sg 0x0000
*Jul 16 01:21:36.363 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1645/4
*Jul 16 01:21:36.363 UTC: RADIUS: Received from id 1645/4 192.168.110.98:1812, Access-Accept, len 159
*Jul 16 01:21:36.363 UTC: RADIUS:  authenticator 30 9C BC E9 51 26 73 94 - EA 4D E9 34 4F 3F A8 3C
*Jul 16 01:21:36.363 UTC: RADIUS:  Service-Type        [6]   6   Framed                    [2]
*Jul 16 01:21:36.363 UTC: RADIUS:  Framed-Protocol     [7]   6   PPP                       [1]
*Jul 16 01:21:36.363 UTC: RADIUS:  Vendor, Cisco       [26]  12
*Jul 16 01:21:36.363 UTC: RADIUS:   Cisco AVpair       [1]   6   10:"   "
*Jul 16 01:21:36.363 UTC: RADIUS:  Vendor, Cisco       [26]  34
*Jul 16 01:21:36.363 UTC: RADIUS:   h323-credit-amount [101] 28  "h323-credit-amount=9998.55"
*Jul 16 01:21:36.363 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:36.363 UTC: RADIUS:   h323-credit-time   [102] 24  "h323-credit-time=72000"
*Jul 16 01:21:36.363 UTC: RADIUS:  Vendor, Cisco       [26]  26
*Jul 16 01:21:36.363 UTC: RADIUS:   h323-return-code   [103] 20  "h323-return-code=0"
*Jul 16 01:21:36.363 UTC: RADIUS:  Vendor, Cisco       [26]  25
*Jul 16 01:21:36.363 UTC: RADIUS:   h323-currency      [110] 19  "h323-currency=USD" radius_decrypt: null length
*Jul 16 01:21:36.363 UTC: RADIUS: Response (4) failed decrypt
*Jul 16 01:21:41.179 UTC: RADIUS: acct-timeout for 202F4D74 now 9, acct-jitter 0, acct-delay-time (at 202F4F94) now 9
*Jul 16 01:21:41.179 UTC: RADIUS: no sg in radius-timers: ctx 0x66499D14 sg 0x0000
*Jul 16 01:21:41.179 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1646/53
*Jul 16 01:21:41.179 UTC: RADIUS: No secret to encode request (rctx:0x66499D14)
*Jul 16 01:21:41.179 UTC: RADIUS: Unable to encrypt (rctx:0x66499D14)
*Jul 16 01:21:41.211 UTC: RADIUS: no sg in radius-timers: ctx 0x65763168 sg 0x0000
*Jul 16 01:21:41.211 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1645/4
*Jul 16 01:21:41.211 UTC: RADIUS: Received from id 1645/4 192.168.110.98:1812, Access-Accept, len 159
*Jul 16 01:21:41.211 UTC: RADIUS:  authenticator 30 9C BC E9 51 26 73 94 - EA 4D E9 34 4F 3F A8 3C
*Jul 16 01:21:41.211 UTC: RADIUS:  Service-Type        [6]   6   Framed                    [2]
*Jul 16 01:21:41.211 UTC: RADIUS:  Framed-Protocol     [7]   6   PPP                       [1]
*Jul 16 01:21:41.211 UTC: RADIUS:  Vendor, Cisco       [26]  12
*Jul 16 01:21:41.211 UTC: RADIUS:   Cisco AVpair       [1]   6   10:"   "
*Jul 16 01:21:41.211 UTC: RADIUS:  Vendor, Cisco       [26]  34
*Jul 16 01:21:41.211 UTC: RADIUS:   h323-credit-amount [101] 28  "h323-credit-amount=9998.55"
*Jul 16 01:21:41.211 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:41.211 UTC: RADIUS:   h323-credit-time   [102] 24  "h323-credit-time=72000"
*Jul 16 01:21:41.211 UTC: RADIUS:  Vendor, Cisco       [26]  26
*Jul 16 01:21:41.211 UTC: RADIUS:   h323-return-code   [103] 20  "h323-return-code=0"
*Jul 16 01:21:41.211 UTC: RADIUS:  Vendor, Cisco       [26]  25
*Jul 16 01:21:41.211 UTC: RADIUS:   h323-currency      [110] 19  "h323-currency=USD" radius_decrypt: null length
*Jul 16 01:21:41.211 UTC: RADIUS: Response (4) failed decrypt
*Jul 16 01:21:45.883 UTC: RADIUS: no sg in radius-timers: ctx 0x65763168 sg 0x0000
*Jul 16 01:21:45.883 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1645/4
*Jul 16 01:21:45.883 UTC: RADIUS: Received from id 1645/4 192.168.110.98:1812, Access-Accept, len 159
*Jul 16 01:21:45.883 UTC: RADIUS:  authenticator 30 9C BC E9 51 26 73 94 - EA 4D E9 34 4F 3F A8 3C
*Jul 16 01:21:45.883 UTC: RADIUS:  Service-Type        [6]   6   Framed                    [2]
*Jul 16 01:21:45.883 UTC: RADIUS:  Framed-Protocol     [7]   6   PPP                       [1]
*Jul 16 01:21:45.883 UTC: RADIUS:  Vendor, Cisco       [26]  12
*Jul 16 01:21:45.883 UTC: RADIUS:   Cisco AVpair       [1]   6   10:"   "
*Jul 16 01:21:45.883 UTC: RADIUS:  Vendor, Cisco       [26]  34
*Jul 16 01:21:45.883 UTC: RADIUS:   h323-credit-amount [101] 28  "h323-credit-amount=9998.55"
*Jul 16 01:21:45.883 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:45.883 UTC: RADIUS:   h323-credit-time   [102] 24  "h323-credit-time=72000"
*Jul 16 01:21:45.883 UTC: RADIUS:  Vendor, Cisco       [26]  26
*Jul 16 01:21:45.883 UTC: RADIUS:   h323-return-code   [103] 20  "h323-return-code=0"
*Jul 16 01:21:45.883 UTC: RADIUS:  Vendor, Cisco       [26]  25
*Jul 16 01:21:45.883 UTC: RADIUS:   h323-currency      [110] 19  "h323-currency=USD" radius_decrypt: null length
*Jul 16 01:21:45.883 UTC: RADIUS: Response (4) failed decrypt
*Jul 16 01:21:46.011 UTC: RADIUS: acct-timeout for 202F4D74 now 14, acct-jitter 0, acct-delay-time (at 202F4F94) now 14
*Jul 16 01:21:46.011 UTC: RADIUS: no sg in radius-timers: ctx 0x66499D14 sg 0x0000
*Jul 16 01:21:46.011 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1646/54
*Jul 16 01:21:46.011 UTC: RADIUS: No secret to encode request (rctx:0x66499D14)
*Jul 16 01:21:46.011 UTC: RADIUS: Unable to encrypt (rctx:0x66499D14)
*Jul 16 01:21:50.299 UTC: RADIUS: acct-timeout for 202F4D74 now 19, acct-jitter -1, acct-delay-time (at 202F4F94) now 18
*Jul 16 01:21:50.299 UTC: RADIUS: no sg in radius-timers: ctx 0x66499D14 sg 0x0000
*Jul 16 01:21:50.299 UTC: RADIUS: No response from (192.168.110.98:1812,1813) for id 1646/54
*Jul 16 01:21:50.299 UTC: RADIUS/DECODE: No response from radius-server; parse response; FAIL
*Jul 16 01:21:50.299 UTC: RADIUS/DECODE: Case error(no response/ bad packet/ op decode);parse response; FAIL
*Jul 16 01:21:50.363 UTC: RADIUS: no sg in radius-timers: ctx 0x65763168 sg 0x0000
*Jul 16 01:21:50.363: %RADIUS-4-RADIUS_DEAD: RADIUS server 192.168.110.98:1812,1813 is not responding.
*Jul 16 01:21:50.363: %RADIUS-4-RADIUS_ALIVE: RADIUS server 192.168.110.98:1812,1813 is being marked alive.
*Jul 16 01:21:50.363 UTC: RADIUS: No response from (192.168.110.98:1812,1813) for id 1645/4
*Jul 16 01:21:50.363 UTC: RADIUS/DECODE: No response from radius-server; parse response; FAIL
*Jul 16 01:21:50.363 UTC: RADIUS/DECODE: Case error(no response/ bad packet/ op decode);parse response; FAIL
*Jul 16 01:21:52.627 UTC: RADIUS/ENCODE(0000001A):Orig. component type = VOICE
*Jul 16 01:21:52.627 UTC: RADIUS(0000001A): Config NAS IP: 0.0.0.0
*Jul 16 01:21:52.627 UTC: RADIUS(0000001A): sending
*Jul 16 01:21:52.627 UTC: RADIUS/ENCODE: Best Local IP-Address 10.0.1.11 for Radius-Server 192.168.110.98
*Jul 16 01:21:52.627 UTC: RADIUS: No secret to encode request (rctx:0x6570C9BC)
*Jul 16 01:21:52.627 UTC: RADIUS: Unable to encrypt (rctx:0x6570C9BC)
*Jul 16 01:21:52.627 UTC: RADIUS(0000001A): Send Accounting-Request to 192.168.110.98:1813 id 1646/56, len 1188
*Jul 16 01:21:52.627 UTC: RADIUS:  authenticator 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Session-Id     [44]  10  "00000021"
*Jul 16 01:21:52.627 UTC: RADIUS:  Calling-Station-Id  [31]  12  "3433100420"
*Jul 16 01:21:52.627 UTC: RADIUS:  Called-Station-Id   [30]  9   "2786401"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  57
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-setup-time    [25]  51  "h323-setup-time=*01:21:31.795 UTC Tue Jul 16 2002"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  29
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-gw-id         [33]  23  "h323-gw-id=5350XM_GL."
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  56
*Jul 16 01:21:52.627 UTC: RADIUS:   Conf-Id            [24]  50  "h323-conf-id=31F144F2 979111D6 80090007 0E5892D6"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  31
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-call-origin   [26]  25  "h323-call-origin=answer"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  32
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-call-type     [27]  26  "h323-call-type=Telephony"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  65
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   59  "h323-incoming-conf-id=31F144F2 979111D6 80090007 0E5892D6"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   24  "subscriber=RegularLine"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  40
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   34  "gw-rxd-cdn=ton:0,npi:1,#:2786401"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  32
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   26  "calling-party-category=9"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  33
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   27  "transmission-medium-req=0"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  59
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-connect-time  [28]  53  "h323-connect-time=*01:21:31.799 UTC Tue Jul 16 2002"
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Input-Octets   [42]  6   0
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Output-Octets  [43]  6   17520
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Input-Packets  [47]  6   0
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Output-Packets [48]  6   73
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Session-Time   [46]  6   21
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  62
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-disconnect-tim[29]  56  "h323-disconnect-time=*01:21:52.577 UTC Tue Jul 16 2002"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  32
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-disconnect-cau[30]  26  "h323-disconnect-cause=10"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  35
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   29  "h323-ivr-out=Tariff:Unknown"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  24
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   18  "release-source=1"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  28
*Jul 16 01:21:52.627 UTC: RADIUS:   h323-voice-quality [31]  22  "h323-voice-quality=0"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  20
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   14  "dsp-id=1/1:1"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  43
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   37  "gw-rxd-cgn=ton:0,npi:0,#:3433100420"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  23
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   17  "charged-units=0"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  49
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   43  "disconnect-text=normal call clearing (16)"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  31
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   25  "peer-address=3433100420"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  24
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   18  "info-type=speech"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  17
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   11  "peer-id=3"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  25
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   19  "peer-if-index=196"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  28
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   22  "logical-if-index=138"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  21
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   15  "acom-level=47"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  28
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   22  "coder-type-rate=None"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  30
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   24  "noise-level=4294967244"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  33
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   27  "voice-tx-duration=1000 ms"
*Jul 16 01:21:52.627 UTC: RADIUS:  Vendor, Cisco       [26]  28
*Jul 16 01:21:52.627 UTC: RADIUS:   Cisco AVpair       [1]   22  "tx-duration=20665 ms"
*Jul 16 01:21:52.627 UTC: RADIUS:  User-Name           [1]   12  "3433100420"
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Status-Type    [40]  6   Stop                      [2]
*Jul 16 01:21:52.627 UTC: RADIUS:  NAS-Port-Type       [61]  6   Async                     [0]
*Jul 16 01:21:52.627 UTC: RADIUS:  NAS-Port            [5]   6   0
*Jul 16 01:21:52.627 UTC: RADIUS:  NAS-Port-Id         [87]  14  "ISDN 3/0:D:7"
*Jul 16 01:21:52.627 UTC: RADIUS:  Service-Type        [6]   6   Login                     [1]
*Jul 16 01:21:52.627 UTC: RADIUS:  NAS-IP-Address      [4]   6   10.0.1.11
*Jul 16 01:21:52.627 UTC: RADIUS:  Acct-Delay-Time     [41]  6   0
*Jul 16 01:21:52.631 UTC: RADIUS: Received from id 1646/56 192.168.110.98:1813, Accounting-response, len 20
*Jul 16 01:21:52.631 UTC: RADIUS:  authenticator AE 20 0A 65 1C C1 E4 33 - 0E A4 C5 0C 17 0F 26 D4 radius_decrypt: null length
*Jul 16 01:21:52.631 UTC: RADIUS: Response (56) failed decrypt
*Jul 16 01:21:57.179 UTC: RADIUS: acct-timeout for 2042BAF4 now 5, acct-jitter -1, acct-delay-time (at 2042BF92) now 4
*Jul 16 01:21:57.179 UTC: RADIUS: no sg in radius-timers: ctx 0x6570C9BC sg 0x0000
*Jul 16 01:21:57.179 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1646/57
*Jul 16 01:21:57.179 UTC: RADIUS: No secret to encode request (rctx:0x6570C9BC)
*Jul 16 01:21:57.179 UTC: RADIUS: Unable to encrypt (rctx:0x6570C9BC)
*Jul 16 01:21:57.179 UTC: RADIUS: Received from id 1646/57 192.168.110.98:1813, Accounting-response, len 20
*Jul 16 01:21:57.179 UTC: RADIUS:  authenticator 70 D6 A8 52 2F 81 00 95 - 4B 48 3E 2E 29 74 25 B0 radius_decrypt: null length
*Jul 16 01:21:57.179 UTC: RADIUS: Response (57) failed decrypt
*Jul 16 01:22:02.203 UTC: RADIUS: acct-timeout for 2042BAF4 now 9, acct-jitter 0, acct-delay-time (at 2042BF92) now 9
*Jul 16 01:22:02.203 UTC: RADIUS: no sg in radius-timers: ctx 0x6570C9BC sg 0x0000
*Jul 16 01:22:02.203 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1646/58
*Jul 16 01:22:02.203 UTC: RADIUS: No secret to encode request (rctx:0x6570C9BC)
*Jul 16 01:22:02.203 UTC: RADIUS: Unable to encrypt (rctx:0x6570C9BC)
*Jul 16 01:22:02.203 UTC: RADIUS: Received from id 1646/58 192.168.110.98:1813, Accounting-response, len 20
*Jul 16 01:22:02.203 UTC: RADIUS:  authenticator FD 90 E8 8D 73 40 7E 90 - 03 64 A9 8A 2B 96 9B 97 radius_decrypt: null length
*Jul 16 01:22:02.203 UTC: RADIUS: Response (58) failed decrypt
*Jul 16 01:22:06.747 UTC: RADIUS: acct-timeout for 2042BAF4 now 14, acct-jitter 0, acct-delay-time (at 2042BF92) now 14
*Jul 16 01:22:06.747 UTC: RADIUS: no sg in radius-timers: ctx 0x6570C9BC sg 0x0000
*Jul 16 01:22:06.747 UTC: RADIUS: Retransmit to (192.168.110.98:1812,1813) for id 1646/59
*Jul 16 01:22:06.747 UTC: RADIUS: No secret to encode request (rctx:0x6570C9BC)
*Jul 16 01:22:06.747 UTC: RADIUS: Unable to encrypt (rctx:0x6570C9BC)
*Jul 16 01:22:06.747 UTC: RADIUS: Received from id 1646/59 192.168.110.98:1813, Accounting-response, len 20
*Jul 16 01:22:06.747 UTC: RADIUS:  authenticator 33 EA C2 19 E8 01 6D 7F - 7D 99 FB 67 AC AE 6F 84 radius_decrypt: null length
*Jul 16 01:22:06.747 UTC: RADIUS: Response (59) failed decrypt
*Jul 16 01:22:11.387 UTC: RADIUS: acct-timeout for 2042BAF4 now 19, acct-jitter -1, acct-delay-time (at 2042BF92) now 18
*Jul 16 01:22:11.387 UTC: RADIUS: no sg in radius-timers: ctx 0x6570C9BC sg 0x0000
*Jul 16 01:22:11.387 UTC: RADIUS: No response from (192.168.110.98:1812,1813) for id 1646/59
*Jul 16 01:22:11.387 UTC: RADIUS/DECODE: No response from radius-server; parse response; FAIL
*Jul 16 01:22:11.387 UTC: RADIUS/DECODE: Case error(no response/ bad packet/ op decode);parse response; FAIL
а это логи с UTM сервера
?Debug : Jun 07 15:55:57 Radius: init_session_log id <13>
?Debug : Jun 07 15:55:57 DBCtx: <138653696> SQL query: INSERT INTO tel_sessions_log(account_id,slink_id,recv_date,last_updat>
,'1001002','1026760891')
?Debug : Jun 07 15:55:57 Radius: Dynamic IP binding not called because ip or slink is null ! ip <0> slink <21>
?Debug : Jun 07 15:55:57 DBCtx: <138653696> SQL query: INSERT INTO dhs_access_log(recv_date,User_Name,Service_Type,Framed_Pr>
?Debug : Jun 07 15:56:01 FW@127.0.0.1: Ping reply received
?Debug : Jun 07 15:56:02 DBCtx: <138653696> SQL query: INSERT INTO dhs_access_log(recv_date,User_Name,Service_Type,Framed_Pr>
?Debug : Jun 07 15:56:07 DBCtx: <138653696> SQL query: INSERT INTO dhs_access_log(recv_date,User_Name,Service_Type,Framed_Pr>
?Debug : Jun 07 15:56:11 DBCtx: <138653696> SQL query: INSERT INTO dhs_access_log(recv_date,User_Name,Service_Type,Framed_Pr>
?Debug : Jun 07 15:56:18 DBCtx: <138653696> SQL query: INSERT INTO tel_sessions_detail(dhs_sess_id,disc_per_id,slink_id,recv>
?Debug : Jun 07 15:56:18 UTM5 DBA: DBAccess instance created
?Debug : Jun 07 15:56:18 DBA:Ctx: Looking for free context
?Debug : Jun 07 15:56:18 DBCtx: <138804224> SQL query: BEGIN
?Debug : Jun 07 15:56:18 DBCtx: DB transaction begin
?Debug : Jun 07 15:56:18 UTM5 DBA: discount_from_account tel zoneid <1001002> h.base_cost <1.000000>
?Debug : Jun 07 15:56:18 DBCtx: <138653696> SQL query: UPDATE dtagg_telephony SET discounted='1.400000',discounted_without_t>
?Debug : Jun 07 15:56:18 UTM5 DBA: Charge:0.350000 p.u. for link:21 account 2
?Debug : Jun 07 15:56:18 DBCtx: <138804224> SQL query: UPDATE accounts SET balance='9998.199995370369' WHERE id = '2'
?Debug : Jun 07 15:56:18 DBCtx: <138804224> SQL query: INSERT INTO discount_transactions_all(account_id,incoming_rest,outgoi>
?Debug : Jun 07 15:56:18 UTM5 DBA: Checking for rehash: flags 3, balance 9998.200 (old 9998.550), credit 0.000
?Debug : Jun 07 15:56:18 DBAGiga: Rehash: Target: 10; Action: 1; Ptr: bcff096c
?Debug : Jun 07 15:56:18 Radius: Account '2': 9998.200/0.000 send...
?Debug : Jun 07 15:56:18 BusLogic: BLM(37) pushed (comment: ruh block)
?Debug : Jun 07 15:56:18 DBCtx: <138804224> SQL query: COMMIT
?Debug : Jun 07 15:56:18 BusLogic: try to execute 37
?Debug : Jun 07 15:56:18 BusLogic: hw_block_handler with code 37
?Debug : Jun 07 15:56:18 BusLogic: finished unknown
?Debug : Jun 07 15:56:18 DBCtx: DB transaction commit
?Debug : Jun 07 15:56:18 DBA:Ctx: Pushing back free context
-Stats : Jun 07 15:56:18 UTM5 DBA:   Stats: Uptime: 00:00:00. Events: 0; Errors: 0
?Debug : Jun 07 15:56:18 Radius: edit_session_log id <13> input octets <0> output octet <17520>
?Debug : Jun 07 15:56:18 Radius: session log with id <13> found in the cache
?Debug : Jun 07 15:56:18 DBCtx: <138653696> SQL query: UPDATE tel_sessions_log SET  account_id='2', slink_id='21', last_upda>
?Debug : Jun 07 15:56:18 Radius: id2core removing key <13>
?Debug : Jun 07 15:56:23 Radius: edit_session_log id <0> input octets <0> output octet <17520>
?Debug : Jun 07 15:56:23 Radius: session log with id <0> found in the cache
?Debug : Jun 07 15:56:23 DBCtx: <138653696> SQL query: UPDATE tel_sessions_log SET  account_id='0', slink_id='0', last_updat>
?Debug : Jun 07 15:56:23 Radius: id2core removing key <0>
?Debug : Jun 07 15:56:28 Radius: edit_session_log id <0> input octets <0> output octet <17520>
?Debug : Jun 07 15:56:28 Radius: session log with id <0> not found in the cache, inserting
?Debug : Jun 07 15:56:28 DBCtx: <138653696> SQL query: INSERT INTO tel_sessions_log(account_id,slink_id,recv_date,last_updat>
e Jul 16 2002','h323-disconnect-time=*01\:21\:52.577 UTC Tue Jul 16 2002','h323-disconnect-cause=10','h323-gw-id=5350XM_GL.'>
?Debug : Jun 07 15:56:31 FW@127.0.0.1: Ping reply received
?Debug : Jun 07 15:56:32 Radius: edit_session_log id <0> input octets <0> output octet <17520>
?Debug : Jun 07 15:56:32 Radius: session log with id <0> found in the cache
?Debug : Jun 07 15:56:32 DBCtx: <138653696> SQL query: UPDATE tel_sessions_log SET  account_id='0', slink_id='0', last_updat>
?Debug : Jun 07 15:56:32 Radius: id2core removing key <0>