SMG-1016M, проблема с КПВ
Добавлено: 03 мар 2016 20:44
Столкнулся со следующей проблемой на SMG-1016M V.3.4.0
Звонок из SIP-плеча уходит в поток ОКС7. В ответ на IAM из ОКС-а прилетает сначала ACM, затем CPG с PI=1 (Call is not end-to-end ISDN; further call progress information may be available
in-band) и PI=8 (In-band information or an appropriate pattern is now available) вида:
Со стороны ОКС в разговорном тракте идёт внутриполосное акустическое КПВ.
SMG в SIP-плечо отдаёт сначала 183 Session Progress без SDP, затем 180 Ringing также без SDP. RTP-поток от SMG в сторону SIP-плеча отсутствует до ответа абонента Б. В результате у абонента А отсутствует КПВ.
Это можно как-нибудь исправить?
PSTN лог вызова:
Звонок из SIP-плеча уходит в поток ОКС7. В ответ на IAM из ОКС-а прилетает сначала ACM, затем CPG с PI=1 (Call is not end-to-end ISDN; further call progress information may be available
in-band) и PI=8 (In-band information or an appropriate pattern is now available) вида:
Код: Выделить всё
12:17:10.800 Tx1-01 CIC: 11 OPC: XXX DPC: YYY ACM
ISUP Rus
[8-9] -------- CIC............................. 11
10 06 00000110 Message type.................... ACM (6)
-- Backward call indicators
11 00 ......00 Charge indicator................ 0 (no indication)
....00.. Called party's status indicator. 0 (no indication)
..00.... Called party's category indicator.. 0 (no indication)
00...... End-to-end method indicator..... 0 (no end-to-end method available)
12 14 .......0 Interworking indicator.......... 0 (no interworking encountered)
......0. End-to-end information indicator 0 (no end-to-end information available)
.....1.. ISDN user part indicator........ 1 (ISDN user part used all the way)
....0... Holding indicator............... 0 (holding not requested)
...1.... ISDN access indicator........... 1 (terminating access ISDN)
..0..... Echo control device indicator... 0 (incoming echo control device not included)
00...... SCCP method indicator........... 0 (no indication)
-- Pointer to start of optional part
13 00 00000000 Pointer to start of optional part.. 0 (No optional part)
12:17:10.864 Tx1-01 CIC: 11 OPC: XXX DPC: YYY CPG (BCI=1 : subscriber free)
ISUP Rus
[8-9] -------- CIC............................. 11
10 2C 00101100 Message type.................... CPG (44)
-- Event information
11 01 .0000001 Event indicator................. 1 (ALERTING)
0....... Event presentation restricted indicator.. 0 (no indication)
-- Pointer to start of optional part
12 01 00000001 Pointer to start of optional part.. 1
-- Access transport
13 03 00000011 Parameter name.................. Access transport (3)
14 04 00000100 Length indicator................ 4
[15-18] -------- Access transport................ 4 Octets (hex): 1E 02 81 88
-- Backward call indicators
19 11 00010001 Parameter name.................. Backward call indicators (17)
20 02 00000010 Length indicator................ 2
21 04 ......00 Charge indicator................ 0 (no indication)
....01.. Called party's status indicator. 1 (subscriber free)
..00.... Called party's category indicator.. 0 (no indication)
00...... End-to-end method indicator..... 0 (no end-to-end method available)
22 14 .......0 Interworking indicator.......... 0 (no interworking encountered)
......0. End-to-end information indicator 0 (no end-to-end information available)
.....1.. ISDN user part indicator........ 1 (ISDN user part used all the way)
....0... Holding indicator............... 0 (holding not requested)
...1.... ISDN access indicator........... 1 (terminating access ISDN)
..0..... Echo control device indicator... 0 (incoming echo control device not included)
00...... SCCP method indicator........... 0 (no indication)
-- End of optional parameters
23 00 00000000 End of optional parameters...... 0
Со стороны ОКС в разговорном тракте идёт внутриполосное акустическое КПВ.
SMG в SIP-плечо отдаёт сначала 183 Session Progress без SDP, затем 180 Ringing также без SDP. RTP-поток от SMG в сторону SIP-плеча отсутствует до ответа абонента Б. В результате у абонента А отсутствует КПВ.
Это можно как-нибудь исправить?
PSTN лог вызова:
Код: Выделить всё
14:26:35.776250 [INFO] SIP. RX. Callref 0321. Mode [0001:-1]. [1.1.1.1:5062] [5062]. Call-ID: 'c9eaadcae13211e58f7f002590c48856@2.2.2.2'
14:26:35.776464 [INFO] SIP. RX. Callref 0321. IAM- Initial Address Message
## Nature of connection: 11
# Satellite: one
# Continuity Check: not required
# Echo control: included
## Forward Call Indicators: 20 00
# Call to be treated as national call
# EndToEnd method: no end-to-end(only link-by-link)
# Interworking: no encountered
# EndToEnd info: not available
# ISUP: used all the way
# ISUP indicator: preferred all way
# Originating access: non-ISDN
# Calling party category: 0x0a
# Transmission medium req: 0x03 - 3.1 kHz audio
## Called party number: 82 10 11 21 0F
# Nature of address: 0x82 - unknown
# INN indicator: 0x00 - allowed
# Numbering plan: 0x01 - ISDN/telephony
# Number: 1112 <ST>
[Optional params]
# Hop counter: 1E.
## Calling party number: 02 13 94 95 33 76 52
# Nature of address: 0x02 - unknown
# Numbering plan: 0x01 - ISDN/telephony
# Screening: 0x03 - network prov
# Presentation: 0x00 - presentation allowed
# Number: 495XXXXXXX
[end of optional params]
14:26:35.776609 [INFO] SIP. Callref 0321. New state 'SIPT_WAIT_MEDIA'
14:26:35.776782 [INFO] SIP. Callref 0321. Incoming seize mode 'SIP':
# source host <1.1.1.1:5062>
# Port SIPT:0321. UserID -1. Intf: 3.
# CCR(none). Category 0a (10).
# Calling <495XXXXXXX> Called <1112> Generic <none> GenericNotify 0x0
# Orig called <none> Redirecting <none>. Redirecting count 0
# cplt=y; pres=allow; nature=not subscriber.
# SS7 all way: y, ISUP all way: y, ISDN orig: n, ISDN pref: 0
# RADIUS: seize-time [0x56d81f6b], return-code [0x0] credit-time [0x0] [OK]
# RADIUS: session-ID [11000321 56d81f6b 00000000 00000000]
RawCalled: -not filled-
14:26:35.776840 [INFO] SIP. Callref 0321. Interface 3, mode 0, reg-mode 0, sdp_18x: 0
14:26:35.776890 [INFO] SIP. Callref 0321. Set category-AON: cat <1>
14:26:35.776978 [INFO] Port SIPT:0321. Seize (v-chan:-1, TG:08). Cld:'<none>'(0). (Trunk) . Clg-len: 10. clg-set: y. COS: -1. RADIUS=-1
14:26:35.777033 [INFO] Port SIPT:0321. ModTable in [-1:-1], out [-1:-1], out redir:orig [-1:-1]
14:26:35.777089 [INFO] Port SIPT:0321. CgPN: <495XXXXXXX>(10) <set> <unknown> <isdn/telephony> <allowed> <net.provided>. cat [1].
14:26:35.777148 [INFO] Port SIPT:0321. Calling Info (pres allowed): <495XXXXXXX>. <Category=1>
14:26:35.777194 [INFO] Port SIPT:0321. CgPN: <495XXXXXXX>(10) <set> <unknown> <isdn/telephony> <allowed> <net.provided>. cat [1].
14:26:35.777249 [INFO] Port SIPT:0321. Set state 'In-Dial'
14:26:35.777291 [INFO] SIP. Callref 0321. Set display name: <>(0)
14:26:35.777364 [INFO] Port SIPT:0321. Create msp-conn Conn[3043].
14:26:35.777442 [INFO] Conn[3043]: Set state 'Idle'->'AllocReq'
14:26:35.777564 [INFO] Port SIPT:0321. Set new IP-conn [3043]
14:26:35.777626 [INFO] Conn[3043]: set network interface in state 'AllocReq'. IP <3.3.3.3>. net-dev [0]
14:26:35.777705 [INFO] SIP. Callref 0321. IP-conn [3043] created
14:26:35.777754 [INFO] SIP. Callref 0321. Incoming seize - PLAN 2. COS 0
14:26:35.777821 [INFO] Port SIPT:0321. Dial In (in=05, need rm=0): <1><1><1><2>[.]
14:26:35.777873 [INFO] Port SIPT:0321. In Digit 5 sym: called buf <1112.>
14:26:35.777918 [INFO] Port SIPT:0321. Dial buf <1112>(4)
14:26:35.777964 [INFO] Port SIPT:0321. SIPT seize chan. Prefix data: type: not set; called type: unknown
14:26:35.778010 [INFO] SIP. Callref 0321. New state 'SIPT_PRESENTED'
14:26:35.778125 [INFO] SIP. RX. Callref 0321. MediaInfo
## IP: <2.2.2.2>
## Offer: <1>
## m=audio 13458 RTP/AVP 8 0 18 96
## a=rtpmap:8 PCMA/8000
## a=rtpmap:0 PCMU/8000
## a=rtpmap:18 G729/8000
## a=fmtp:18 annexb=no
## a=rtpmap:96 telephone-event/8000
## a=fmtp:96 0-15
## a=sendrecv
## a=silenceSupp:off - - - -
14:26:35.778285 [INFO] SIP. Callref 0321. Set Media-Info. Count[1]
14:26:35.778345 [INFO] SIP. Callref 0321. Media-Info (offer): [2.2.2.2]
14:26:35.778419 [INFO] Valid
m[0]: audio 13458 RTP/AVP 8 0 18 96
a[0]:8 G711.A [set]
a[1]:0 G711.U [set]
a[2]:18 G729.A [set]
a[3]:96 NTE [set]
a[4]:silenceSupp:off - - - -
a[5]:sendrecv
14:26:35.778468 [INFO] SIP. Callref 0321. Media negotiate. Interface: 3
14:26:35.778520 [INFO] SIP. Callref 0321. Media negotiate <offer>. [2.2.2.2]. List-count: 1
14:26:35.778587 [INFO] SIP. Callref 0321. Media negotiate. Codec found: G711.A. PT 8. m[1], a[0]. sendrecv
14:26:35.778641 [INFO] Port SIPT:0321. Set IP-conn [3043] recvonly
14:26:35.778686 [INFO] Conn[3043]: SendRecv set 'recvonly' in state 'AllocReq'
14:26:35.778752 [INFO] Conn[3043]: set rtp-cfg. <3.3.3.3:54084>:[02.31.52.63.74.83] <-> <0.0.0.0:0>:[00.00.00.00.00.00]. flg[11]
14:26:35.778821 [INFO] Port SIPT:0321. IP-conn [3043]. Set Tune
14:26:35.778866 [INFO] Conn[3043]: Set tune: vad 'off', cng 'off', ecan 'on'
pte [20], rtcp_period [0], rtcp_ctrl [0], dscp [0]
dtmf_mode 'rfc2833' dtmf_pt rx [96] dtmf_pt tx [96] fax_mode [3]
use_vbd 'off' vbd_codec [0] vbd_pt [0]
NAT: N, t38transit: 1, cc-transit: 0
14:26:35.778922 [INFO] SIP. Callref 0321. Use codec: G711.A sendrecv. PT 8.
14:26:35.778977 [INFO] Port SIPT:0321. IP-conn [3043]. Set Media-Info: [2.2.2.2:13458]
14:26:35.779027 [INFO] Conn[3043]: Set media-info in state 'AllocReq'. [2.2.2.2:13458]
14:26:35.779104 [INFO] Conn[3043]: [3.3.3.3:54084] <-> [2.2.2.2:13458]
14:26:35.779153 [INFO] Conn[3043]: Codec: G.711A , payload [8], DTMF-PT: 96
14:26:35.779204 [INFO] Conn[3043]: try get remote MAC for <2.2.2.2>
14:26:35.779354 [INFO] Conn[3043]: found remote MAC for <2.2.2.2> - [a0:36:9f:24:c7:86]. net-dev [0]
14:26:35.779408 [INFO] Conn[3043]: Set codecs-info in state 'AllocReq'. Count: 2
14:26:35.779457 [INFO] Conn[3043]: G711.A PT [8]
14:26:35.779501 [INFO] Conn[3043]: NTE PT [96]
14:26:35.779546 [INFO] SIP. Callref 0321. New state 'SIPT_IN_PROCEEDING'
14:26:35.779599 [INFO] Port SIPT:0321. Dial Proc (state In-Dial, called len 05): <1112.> Compl.
14:26:35.779654 [INFO] Port SIPT:0321. Search route. Mode: 'calling'. Num: <495XXXXXXX>(10).# Complete
14:26:35.779724 [INFO] Port SIPT:0321. Search route. Result: route not found. res (-1)
14:26:35.779783 [INFO] Port SIPT:0321. Search route. Mode: 'called'. Num: <1112.>(5).# Complete
14:26:35.779895 [INFO] Port SIPT:0321. Search route. Result: found route: prefix[2]. res (4)
14:26:35.779951 [INFO] Port SIPT:0321. Process route to prefix [02].
14:26:35.780004 [INFO] Port SIPT:0321. Prefix 02: type 'TRUNK', idx=2
14:26:35.780051 [INFO] Port SIPT:0321. Accs on
14:26:35.780122 [INFO] Port SIPT:0321. Link to TG 02. Out modifiers-table: clg[6], cld[3], redir[-1], orig[-1], generic[-1]
14:26:35.780175 [INFO] Port SIPT:0321. Link to TG 02. Setup mode: enblock <ST>
14:26:35.780224 [INFO] Port SIPT:0321. Link to TG 02: mode Local. Dir=0, Cld=0 Select Order=undef. In buf: ~1112.
14:26:35.780270 [INFO] Port SIPT:0321. TG: Link entry to SS7-linkset[00]
14:26:35.780322 [INFO] Port SIPT:0321. Outgoing setup to SS7:00:00.
14:26:35.780377 [INFO] LINK/SS7. ISUP-profile 00. Set Link CCI <dis>, CCIfq <0>.
14:26:35.780421 [INFO] LINK/SS7. ISUP-profile 00. Set Link CCR <0>, peerCCR <0>
14:26:35.780494 [INFO] SS7/ISUP. ISUP-profile 00. Create call (mode 3): Link '00/00' (0). Chan '00/03':31. CIC=126 (prio -2)
14:26:35.780573 [INFO] Port SS7:03:31. Check out-calling-modify: '495XXXXXXX'(10). ModTable[6], ClgMod[-1]
14:26:35.780627 [INFO] Port SS7:03:31. Search Modifier at 'outgoing' for 'calling'. ModTable[6].
14:26:35.780677 [INFO] Port SS7:03:31. Search Modifier for num <495XXXXXXX>(10) <unknown> <isdn/telephony> <allowed> <net.provided>. cat '1'
14:26:35.781159 [INFO] Port SS7:03:31. Search Modifier at 'outgoing' for 'calling'. Result: modifier [9]
14:26:35.781209 [INFO] Port SS7:03:31. Modify at 'outgoing': use modifier <calling>, modify num <calling>
14:26:35.781253 [INFO] Port SS7:03:31. Modify at 'outgoing': number mask <([3489]X{9,9})>
14:26:35.781297 [INFO] Port SS7:03:31. Modify at 'outgoing': rule <$>, ptr <0>. Num <495XXXXXXX>(10), nptr <0>
14:26:35.781345 [INFO] Port SS7:03:31. Modify at 'outgoing': result-num: <495XXXXXXX>.(10). res: 10
14:26:35.781391 [INFO] Port SS7:03:31. Modify at 'outgoing': calling-params: type <national>, numplan <isdn/telephony>, pres <no-change>, screen <no-change>, cat 'N'
14:26:35.781439 [INFO] Port SS7:03:31. Check out-calling-modify: ClgMod[-2]
14:26:35.781487 [INFO] Port SS7:03:31. Check out-calling-from-RADIUS-modify: '495XXXXXXX'(10). ClgMod[-1]
14:26:35.781541 [INFO] Port SS7:03:31. Set parent SIPT:0321
14:26:35.781598 [INFO] SS7/ISUP. Chan SS7:03:31. Transit-call
14:26:35.781646 [INFO] SIP. Callref 0321. Transit-call
14:26:35.781689 [INFO] Port SS7:03:31. loc 0, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
14:26:35.781732 [INFO] Port SIPT:0321. loc 0, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
14:26:35.781780 [INFO] Port SS7:03:31. VChan set (chan 31) - 'line 0, chan 127'
14:26:35.781826 [INFO] LINK/SS7. ISUP-profile 00. Set Link CgPN: <495XXXXXXX>(10) <set> <national> <isdn/telephony> <allowed> <net.provided>. cat [1].
14:26:35.781870 [INFO] LINK/SS7. ISUP-profile 00. Set Link - calling category: '0A' (10) (from incoming ISUP)
14:26:35.781913 [INFO] LINK/SS7. ISUP-profile 00. Set Link - enblock mode. Link 0
14:26:35.781962 [INFO] Port SS7:03:31. Set state 'Wait-Num'
14:26:35.782017 [INFO] Port SS7:03:31. Query Next Digit - peer SIPT:0321 (next.digit=n). Called len 05, proceed 00
14:26:35.782065 [INFO] Port SS7:03:31. Dial Out=1 (ext)
14:26:35.782114 [INFO] Port SS7:03:31. Modify at 'outgoing': use modifier <calling>, modify num <called>
14:26:35.782157 [INFO] Port SS7:03:31. Modify at 'outgoing': number mask <([3489]X{9,9})>
14:26:35.782199 [INFO] Port SS7:03:31. Modify at 'outgoing': rule <$>, ptr <0>. Num <1>(1), nptr <0>
14:26:35.782244 [INFO] Port SS7:03:31. Modify at 'outgoing': result-num: <1>.(1). res: 1
14:26:35.782289 [INFO] Port SS7:03:31. Modify at 'outgoing': called-params: type <national>, numplan <isdn/telephony>
14:26:35.782335 [INFO] Port SS7:03:31. Update called params
14:26:35.782382 [INFO] Port SS7:03:31. Check out-called-modify: '1'(1). ModTable[3], CldMod[-1]
14:26:35.782427 [INFO] Port SS7:03:31. Check out-called-modify: number not complete. '1'(1).
14:26:35.782483 [INFO] Port SS7:03:31. Query Next Digit - peer SIPT:0321 (next.digit=n). Called len 05, proceed 01
14:26:35.782529 [INFO] Port SS7:03:31. Dial Out=1 (ext)
14:26:35.782606 [INFO] Port SS7:03:31. Check out-called-modify: '1'(1). ModTable[3], CldMod[-1]
14:26:35.782694 [INFO] Port SS7:03:31. Check out-called-modify: number not complete. '11'(2).
14:26:35.782756 [INFO] Port SS7:03:31. Query Next Digit - peer SIPT:0321 (next.digit=n). Called len 05, proceed 02
14:26:35.782805 [INFO] Port SS7:03:31. Dial Out=1 (ext)
14:26:35.783275 [INFO] Port SS7:03:31. Check out-called-modify: '1'(1). ModTable[3], CldMod[-1]
14:26:35.783339 [INFO] Port SS7:03:31. Check out-called-modify: number not complete. '111'(3).
14:26:35.783402 [INFO] Port SS7:03:31. Query Next Digit - peer SIPT:0321 (next.digit=n). Called len 05, proceed 03
14:26:35.783455 [INFO] Port SS7:03:31. Dial Out=2. (ext)
14:26:35.783508 [INFO] Port SS7:03:31. Check out-called-modify: '2.'(2). ModTable[3], CldMod[-1]
14:26:35.783581 [INFO] Port SS7:03:31. Search Modifier at 'outgoing' for 'called'. ModTable[3].
14:26:35.783635 [INFO] Port SS7:03:31. Search Modifier for num <1112.>(5) <national> <no-change>
14:26:35.784102 [INFO] Port SS7:03:31. Search Modifier at 'outgoing' for 'called'. Result: modifier [12]
14:26:35.784148 [INFO] Port SS7:03:31. Check out-called-modify: ClgMod[-2:12], CldMod[12:-2]
14:26:35.784199 [INFO] Port SS7:03:31. Modify at 'outgoing': use modifier <called>, modify num <calling>
14:26:35.784243 [INFO] Port SS7:03:31. Modify at 'outgoing': number mask <(1112)>
14:26:35.784288 [INFO] Port SS7:03:31. Modify at 'outgoing': rule <+7824!>, ptr <0>. Num <495XXXXXXX>(10), nptr <0>
14:26:35.784339 [INFO] Port SS7:03:31. Modify at 'outgoing': result-num: <7824>.(4). res: 4
14:26:35.784386 [INFO] Port SS7:03:31. Modify at 'outgoing': calling-params: type <no-change>, numplan <no-change>, pres <no-change>, screen <no-change>, cat 'N'
14:26:35.784437 [INFO] Port SS7:03:31. CgPN: <7824>(4) <set> <national> <isdn/telephony> <allowed> <net.provided>. cat [1].
14:26:35.784485 [INFO] Port SS7:03:31. Update calling to <7824> (4)
14:26:35.784529 [INFO] Port SS7:03:31. Check out-called-modify: CldMod[12]
14:26:35.784625 [INFO] Port SS7:03:31. Modify at 'outgoing': use modifier <called>, modify num <called>
14:26:35.784673 [INFO] Port SS7:03:31. Modify at 'outgoing': number mask <(1112)>
14:26:35.784717 [INFO] Port SS7:03:31. Modify at 'outgoing': rule <+0002401111!>, ptr <0>. Num <1112.>(5), nptr <0>
14:26:35.784767 [INFO] Port SS7:03:31. Modify at 'outgoing': result-num: <0002401111.>.(11). res: 11
14:26:35.784814 [INFO] Port SS7:03:31. Modify at 'outgoing': called-params: type <no-change>, numplan <no-change>
14:26:35.784861 [INFO] Port SS7:03:31. Update called params
14:26:35.784914 [INFO] SS7/ISUP. Chan SS7:03:31. Dial 0002401111.
14:26:35.784960 [INFO] Port SS7:03:31. Dial Out: called len 00, append 11 (max digit 30)
14:26:35.785006 [INFO] Port SS7:03:31. Dial Out: 'End_of_Dial' digit/stop dial
14:26:35.785059 [INFO] SS7/ISUP. Chan '00/03':31. New state OG_PROCEEDING
14:26:35.785109 [INFO] Port SS7:03:31. Setup Ack (peer=SIPT:0321)
14:26:35.785157 [INFO] Port SS7:03:31. Set state 'Out-Dial'
14:26:35.785205 [INFO] Port SIPT:0321. Setup Acked in state In-Dial. (peer=SS7:03:31)
14:26:35.785254 [INFO] Port SIPT:0321. LinkUp to SS7:03:31
14:26:35.785312 [INFO] Port SIPT:0321. Activate IP-conn [3043]
14:26:35.785355 [INFO] Conn[3043]: try activate. state 'AllocReq'. (media not connected)
14:26:35.785531 [INFO] SS7/ISUP. TX. ISUP-profile 00. Link '00/00', CIC=126. IAM- Initial Address Message
## Nature of connection: 00
# Satellite: no
# Continuity Check: not required
# Echo control: not included
## Forward Call Indicators: 20 00
# Call to be treated as national call
# EndToEnd method: no end-to-end(only link-by-link)
# Interworking: no encountered
# EndToEnd info: not available
# ISUP: used all the way
# ISUP indicator: preferred all way
# Originating access: non-ISDN
# Calling party category: 0x0a (10)
# Transmission medium req: 0x03 - 3.1 kHz audio
## Called party number: 83 90 00 20 04 11 11 0F
# Nature of address: 0x83 - national
# INN indicator: 0x08 - not allowed
# Numbering plan: 0x01 - ISDN/telephony
# Number: 0002401111 <ST>
[Optional params]
## Calling party number: 03 13 87 42
# Nature of address: 0x03 - national
# Numbering plan: 0x01 - ISDN/telephony
# Screening: 0x03 - network prov
# Presentation: 0x00 - presentation allowed
# Number: 7824
# Hop counter: 1D.
[end of optional params]
14:26:35.785622 [INFO] SS7/MTP3. Line '00/00'. ISUP-profile 00. Data Tx. SI=0xC5 [DPC=390, OPC=4001, SLS=14]
14:26:35.785760 [INFO] SS7/MTP3. Line '00/00'. Data Tx. 'ISUP': C5.86.41.E8.E3.7E.00.01.00.20.00.0A.03.02.0A.08.83.90.00.20.04.11.11.0F.0A.04.03.13.87.42.3D.01.1D.00.
14:26:35.785889 [INFO] Port SIPT:0321. Prefix linked: called len 05, proceed 05.
14:26:35.786434 [INFO] Conn[3043]: Set state 'AllocReq'->'Allocated'
14:26:35.786499 [INFO] Conn[3043]: Created. Need activate [YES]
14:26:35.786551 [INFO] Port SIPT:0321. Created IP-conn [3043]. in-call
14:26:35.786598 [INFO] Conn[3043]: try activate. state 'Allocated'. (media not connected)
14:26:35.786644 [INFO] Conn[3043]: Set state 'Allocated'->'DisableReq'
14:26:35.787861 [INFO] Conn[3043]: Created (disabled). State 'DisableReq'
14:26:35.787930 [INFO] Conn[3043]: Set state 'DisableReq'->'Disabled'
14:26:35.787984 [INFO] Port SIPT:0321. Created IP-conn [3043]. in-call
14:26:35.788031 [INFO] Conn[3043]: try activate. state 'Disabled'. (media not connected)
14:26:35.844241 [INFO] SS7/MTP3. Line '00/00'. Data Rx. SNI=0xC5 (SI=5, NI=3) [DPC=4001, OPC=XXX, SLS=14]. [SLC=0]
0B.C5.A1.8F.61.E0.7E.00.06.00.14.00.
14:26:35.844330 [INFO] SS7/MTP3. Line '00/00'. Data Rx. 'ISUP' (6).
14:26:35.844408 [INFO] SS7/ISUP. RX. ISUP-profile 00. Link '00/00', CIC=126. ACM-Address Complete
## Backward call Indicators: 00 14
# Called party's status indicator: No indication
# Interworking: no encountered
# ISUP: used all the way
# Terminating access: ISDN
[No optional params]
14:26:35.844481 [INFO] Port SS7:03:31. set ISDN: BCI=0x70: 'ISDN All Way' 'ISDN Access' 'SS7 All Way' 'Change' 'Send'
14:26:35.844542 [INFO] Port SS7:03:31. Stop Dial, reason 'complete'
14:26:35.844598 [INFO] Port SIPT:0321. Send 'End of Dial' to incoming call
14:26:35.844652 [INFO] Port SS7:03:31. get ISDN: BCI=0xF0: 'ISDN All Way' 'ISDN Access' 'SS7 All Way' 'Change' 'Send'
14:26:35.844698 [INFO] SIP. Callref 0321. End Dial
14:26:35.844740 [INFO] SIP. Callref 0321. Media will be sent later. Flags: SDP in 18x: 0, NAT: 0, NAT-SDP: 0.
14:26:35.844816 [INFO] Port SIPT:0321. LinkUp to SS7:03:31
14:26:35.844906 [INFO] Port SIPT:0321. Activate IP-conn [3043]
14:26:35.844954 [INFO] Conn[3043]: try activate. state 'Disabled'. (media not connected)
14:26:35.845006 [INFO] SIP. Callref 0321. Transit message [6] from SS7
14:26:35.845350 [INFO] SIP. TX. Callref 0321. ACM-Address Complete
## Backward call Indicators: 00 34
# Called party's status indicator: No indication
# Interworking: no encountered
# ISUP: used all the way
# Terminating access: ISDN
[No optional params]
14:26:36.092675 [INFO] SS7/MTP3. Line '00/00'. Data Rx. SNI=0xC5 (SI=5, NI=3) [DPC=4001, OPC=XXX, SLS=14]. [SLC=0]
15.C5.A1.8F.61.E0.7E.00.2C.01.01.03.04.1E.02.81.88.11.02.04.14.00.
14:26:36.092765 [INFO] SS7/MTP3. Line '00/00'. Data Rx. 'ISUP' (16).
14:26:36.092959 [INFO] SS7/ISUP. RX. ISUP-profile 00. Link '00/00', CIC=126. CPG-Call Progress
# Event information: 0x01 (alerting)
[Optional params]
# Access transport: 1E.02.81.88.
## Backward call Indicators: 04 14
# Called party's status indicator: Subscriber free
# Interworking: no encountered
# ISUP: used all the way
# Terminating access: ISDN
[end of optional params]
14:26:36.093070 [INFO] SS7/ISUP. Chan '00/03':31. New state DELIVERED
14:26:36.093128 [INFO] Port SS7:03:31. set ISDN: BCI=0x70: 'ISDN All Way' 'ISDN Access' 'SS7 All Way' 'Send'
14:26:36.093185 [INFO] Port SS7:03:31. Alert (peer=SIPT:0321)
14:26:36.093266 [INFO] Port SS7:03:31. Stop Dial, reason 'alert'
14:26:36.093341 [INFO] Port SIPT:0321. LinkUp to SS7:03:31
14:26:36.093401 [INFO] Port SIPT:0321. Activate IP-conn [3043]
14:26:36.093447 [INFO] Conn[3043]: try activate. state 'Disabled'. (media not connected)
14:26:36.093502 [INFO] Port SS7:03:31. Set state 'Out-Call'
14:26:36.093579 [INFO] Port SIPT:0321. Alerting from peer SS7:03:31
14:26:36.093631 [INFO] Port SIPT:0321. Set state 'In-Call'
14:26:36.093685 [INFO] Port SS7:03:31. get ISDN: BCI=0x70: 'ISDN All Way' 'ISDN Access' 'SS7 All Way' 'Send'
14:26:36.093731 [INFO] SIP. Callref 0321. Alerting
14:26:36.093773 [INFO] SIP. Callref 0321. Media will be sent later. Flags: SDP in 18x: 0, NAT: 0, NAT-SDP: 0.
14:26:36.093845 [INFO] Port SIPT:0321. LinkUp to SS7:03:31
14:26:36.093901 [INFO] Port SIPT:0321. Activate IP-conn [3043]
14:26:36.093946 [INFO] Conn[3043]: try activate. state 'Disabled'. (media not connected)
14:26:36.094009 [INFO] SIP. Callref 0321. Transit message [44] from SS7
14:26:36.094363 [INFO] SIP. TX. Callref 0321. CPG-Call Progress
# Event information: 0x01 (alerting)
[Optional params]
# Access transport: 1E.02.81.88.
## Backward call Indicators: 04 14
# Called party's status indicator: Subscriber free
# Interworking: no encountered
# ISUP: used all the way
# Terminating access: ISDN
[end of optional params]
14:26:42.345108 [INFO] SS7/MTP3. Line '00/00'. Data Rx. SNI=0xC5 (SI=5, NI=3) [DPC=4001, OPC=XXX, SLS=14]. [SLC=0]
14.C5.A1.8F.61.E0.7E.00.09.01.03.04.1E.02.81.82.11.02.06.14.00.
14:26:42.345201 [INFO] SS7/MTP3. Line '00/00'. Data Rx. 'ISUP' (15).
14:26:42.345293 [INFO] SS7/ISUP. RX. ISUP-profile 00. Link '00/00', CIC=126. ANM-Answer
[Optional params]
# Access transport: 1E.02.81.82.
## Backward call Indicators: 06 14
# Called party's status indicator: Subscriber free
# Interworking: no encountered
# ISUP: used all the way
# Terminating access: ISDN
[end of optional params]
14:26:42.345369 [INFO] SS7/ISUP. Chan '00/03':31. New state ACTIVE
14:26:42.345425 [INFO] Port SS7:03:31. set ISDN: BCI=0x50: 'ISDN All Way' 'SS7 All Way' 'Change' 'Send'
14:26:42.345480 [INFO] Port SS7:03:31. Answer
14:26:42.345547 [INFO] Port SS7:03:31. Set state 'Talk'
14:26:42.345605 [INFO] Port SIPT:0321. Answer from peer (SS7:03:31)
14:26:42.345650 [INFO] Port SIPT:0321. Self: ext, Peer: ext.
14:26:42.345696 [INFO] Port SS7:03:31. Self: ext, Peer: ext.
14:26:42.345803 [INFO] Port SIPT:0321. Set RADIUS-talk flag
14:26:42.345861 [INFO] Port SIPT:0321. Set state 'Talk'
14:26:42.345909 [INFO] SIP. Callref 0321. Connect in state 'SIPT_IN_PROCEEDING'
14:26:42.345954 [INFO] SIP. Callref 0321. Prepare media requested
14:26:42.346000 [INFO] SIP. Callref 0321. Update Media-Info. [core/sipt_proc.c:1841]
14:26:42.346050 [INFO] SIP. Callref 0321. Media-Info (answer): [3.3.3.3]
14:26:42.346110 [INFO] Valid
m[0]: audio 54084 RTP/AVP 8 96
a[0]:8 G711.A [set]
a[1]:96 NTE [set]
a[2]:silenceSupp:off - - - -
a[3]:sendrecv
14:26:42.346154 [INFO] SIP. Callref 0321. Prepare media finished (should send)
14:26:42.346203 [INFO] Port SS7:03:31. get ISDN: BCI=0xD0: 'ISDN All Way' 'SS7 All Way' 'Change' 'Send'
14:26:42.346278 [INFO] SIP. Callref 0321. New state 'SIPT_ACTIVE'
14:26:42.346351 [INFO] Port SIPT:0321. LinkUp to SS7:03:31
14:26:42.346443 [INFO] Port SIPT:0321. Activate IP-conn [3043]
14:26:42.346491 [INFO] Conn[3043]: try activate. state 'Disabled'. (media not connected)
14:26:42.346557 [INFO] SIP. Callref 0321. Transit message [9] from SS7
14:26:42.347111 [INFO] SIP. Callref 0321. Media-Info prepare
14:26:42.347165 [INFO] SIP. Callref 0321. Media-Info encode. Media-list size: 1
14:26:42.347345 [INFO] SIP. TX. Callref 0321. ANM-Answer
[Optional params]
# Access transport: 1E.02.81.82.
## Backward call Indicators: 06 14
# Called party's status indicator: Subscriber free
# Interworking: no encountered
# ISUP: used all the way
# Terminating access: ISDN
[end of optional params]
14:26:42.347474 [INFO] SIP. TX. Callref 0321. MediaInfo
## IP: <3.3.3.3>
## Offer: <0>
## m=audio 54084 RTP/AVP 8 96
## a=rtpmap:8 PCMA/8000
## a=rtpmap:96 telephone-event/8000
## a=fmtp:96 0-15
## a=silenceSupp:off - - - -
## a=sendrecv
14:26:42.352045 [INFO] SIP. RX. Callref 0321. MediaConnected
14:26:42.352122 [INFO] SIP. Callref 0321. Media connected. Mode: 'sendrecv'
14:26:42.352173 [INFO] Port SIPT:0321. Media-connected for IP-conn [3043]
14:26:42.352218 [INFO] Conn[3043]: Media connected indication
14:26:42.352262 [INFO] Conn[3043]: try activate. state 'Disabled'.
14:26:42.352323 [INFO] Conn[3043]: Activate [3.3.3.3:54084] <-> [2.2.2.2:13458]
MAC_loc.src [02:31:52:63:74:83], MAC_loc.dst [02:00:04:00:00:02]
MAC_ext.src [a8:f9:4b:88:78:21], MAC_ext.dst [a0:36:9f:24:c7:86]
14:26:42.352386 [INFO] Conn[3043]: Start voice. Codec G711A, vad=off, cng=off. Ptime=20 ms. PT [8] SSRC=71112cfa
14:26:42.352535 [INFO] Conn[3043]: Set state 'Disabled'->'ActivateReq'
14:26:42.352595 [INFO] Port SIPT:0321. Set IP-conn [3043] sendrecv
14:26:42.352641 [INFO] Conn[3043]: SendRecv set 'sendrecv' in state 'ActivateReq'
14:26:42.353735 [INFO] Conn[3043]: Params set. State 'ActivateReq'
14:26:42.353805 [INFO] Conn[3043]: Enable connection
14:26:42.361971 [INFO] Conn[3043]: Activated
14:26:42.362050 [INFO] Conn[3043]: Set state 'ActivateReq'->'Active'
14:26:42.362125 [INFO] Conn[3043]: set rtp-cfg. <3.3.3.3:54084>:[02.31.52.63.74.83] <-> <2.2.2.2:13458>:[A0.36.9F.24.C7.86]. flg[1]
14:26:42.362187 [INFO] Port SIPT:0321. Changed IP-conn [3043]
14:26:42.371968 [INFO] Conn[3043]: Event 'SSRC Change': new [0x560D6A62], prev [0x00000000]
14:26:42.724195 [INFO] SIP. RX. Callref 0321. Rel-cause code: '200'.
14:26:42.724300 [INFO] SIP. RX. Callref 0321. REL-Release
# Cause indicators: [user] [cause=Normal call clearing]
[No optional params]
14:26:42.724360 [INFO] SIP. Callref 0321. 'REL-Release' with cause <Normal call clearing>(16) in state <SIPT_ACTIVE>
14:26:42.724417 [INFO] Port SIPT:0321. Deactivate IP-conn [3043]
14:26:42.724463 [INFO] Conn[3043]: Deactivate in state 'Active'
14:26:42.724568 [INFO] Conn[3043]: Set state 'Active'->'DisableReq'
14:26:42.724628 [INFO] Port SIPT:0321. Release Complete, cause 'Normal call clearing', dcl '0', loc '0'
14:26:42.724694 [INFO] Port SIPT:0321. Save CDR. disabled. [0x0]
14:26:42.724749 [INFO] Port SIPT:0321. Release peer 'SS7:03:31', cause 'Normal call clearing', dcl '1', loc '0'
14:26:42.724803 [INFO] Port SS7:03:31. Disconnect from SIPT:0321, cause 'Normal call clearing', dcl '1', loc '0'
14:26:42.724877 [INFO] Port SS7:03:31. Disconnect, cause 'Normal call clearing', dcl '1', loc '0'
14:26:42.724970 [INFO] Port SS7:03:31. Save CDR. disabled. [0x0]
14:26:42.725042 [INFO] Port SS7:03:31. Set signal: 'BUSY'
14:26:42.725110 [INFO] SS7/ISUP. Chan SS7:03:31. Release, cause 'Normal call clearing', cause_loc '0'
14:26:42.725161 [INFO] SS7/ISUP. Chan SS7:03:31. Send REL-transit
14:26:42.725272 [INFO] SS7/ISUP. TX. ISUP-profile 00. Link '00/00', CIC=126. REL-Release
# Cause indicators: [80].[90].
# Location [00] - user
# Cause [90] - Normal call clearing
[No optional params]
14:26:42.725329 [INFO] SS7/MTP3. Line '00/00'. ISUP-profile 00. Data Tx. SI=0xC5 [DPC=390, OPC=4001, SLS=14]
14:26:42.725408 [INFO] SS7/MTP3. Line '00/00'. Data Tx. 'ISUP': C5.86.41.E8.E3.7E.00.0C.02.00.02.80.90.
14:26:42.725517 [INFO] SS7/ISUP. Chan '00/03':31. New state RELEASE_REQUEST
14:26:42.725576 [INFO] Port SS7:03:31. Set state 'Release'
14:26:42.725625 [INFO] Port SIPT:0321. Free IP-conn [3043]
14:26:42.725674 [INFO] Conn[3043]: Destroy in state 'DisableReq'. [check] - set destroy-flg
14:26:42.725750 [INFO] Port SIPT:0321. Set state 'Ready'
14:26:42.725793 [INFO] SIP. Callref 0321. New state 'SIPT_IDLE'
14:26:42.726069 [INFO] SIP. TX. Callref 0321. RLC-Release_Complete
[No optional params]
14:26:42.726624 [INFO] Conn[3043]: Close flag set - destroy.
14:26:42.726695 [INFO] Conn[3043]: Destroy in state 'DisableReq'.
14:26:42.726742 [INFO] Port SIPT:0321. Destroy Conn[3043].
14:26:42.726787 [INFO] Conn[3043]: Set state 'DisableReq'->'DeallocReq'
14:26:42.729186 [INFO] Conn[3043]: Destroyed
14:26:42.729282 [INFO] Conn[3043]: clr rtp-cfg. <3.3.3.3:54084>:[02.31.52.63.74.83] <-> <2.2.2.2:13458>:[A0.36.9F.24.C7.86]. flg[1]
14:26:42.729342 [INFO] Conn[3043]: Set state 'DeallocReq'->'Idle'
14:26:42.739038 [INFO] SS7/MTP3. Line '00/00'. Data Rx. SNI=0xC5 (SI=5, NI=3) [DPC=4001, OPC=XXX, SLS=14]. [SLC=0]
09.C5.A1.8F.61.E0.7E.00.10.00.
14:26:42.739109 [INFO] SS7/MTP3. Line '00/00'. Data Rx. 'ISUP' (4).
14:26:42.739171 [INFO] SS7/ISUP. RX. ISUP-profile 00. Link '00/00', CIC=126. RLC-Release_Complete
[No optional params]
14:26:42.739240 [INFO] SS7/ISUP. Chan '00/03':31. Clear v-chan 00:127
14:26:42.739294 [INFO] Port SS7:03:31. Release Complete, cause 'Normal, unspecified', dcl '0', loc '0'
14:26:42.739359 [INFO] Port SS7:03:31. Save CDR. disabled. [0x0]
14:26:42.739440 [INFO] Port SS7:03:31. Set state 'Ready'
14:26:42.739488 [INFO] SS7/ISUP. Chan '00/03':31. New state NULL