Сообщение KorolevVS » 11 янв 2012 02:08
ЗВОНИЛ С SIP телефона 9735360 на 89277132605. После ответа мобильного 89277132605 на телефоне 9735360 слышил около 5 секунд КПВ. На мобильном - тишину.
SMG1016. Software Version: V.2.9.03. L. [SIPu] Build: Dec 28 2011 10:56:24
Log started at: 23:00:30.475129
23:00:43.197743 [INFO] SIP. RX. Callref 02f1. Mode [8001]. [10.10.69.105], '9735360'(7)
23:00:43.198024 [INFO] SIP. RX. Callref 02f1. IAM- Initial Address Message
## Nature of connection: 11
# Satellite: one
# Continuity Check: not required
# Echo control: included
## Forward Call Indicators: 20 00
# Interworking: no encountered
# 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: 02 10 98 72 17 23 06 F5
# Nature of address: 0x02 - unknown
# Numbering plan: 0x10 - ISDN/telephony
# Number: 89277132605 <ST>
[Optional params]
# Hop counter: 1E.
## Calling party number: 82 13 79 53 63 00
# Nature of address: 0x82 - unknown
# Numbering plan: 0x01 - ISDN/telephony
# Screening: 0x03 - network prov
# Presentation: 0x00 - presentation allowed
# Number: 9735360
[end of optional params]
23:00:43.198204 [INFO] SIP. Callref 02f1. New state 'SIPT_WAIT_MEDIA'
23:00:43.198314 [INFO] SIP. Callref 02f1. Incoming seize mode 'SIP-user'(Redir-Callref -1):
# source IP <10.10.69.105> 9735360
# Port SIPT:02f1. UserID 1. Intf: 1.
# CCR(none). Category 0a (10).
# Calling <9735360> Called <89277132605> 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 [0x4f0c8adb], credit [0xffffffff]
RawCalled: -not filled-
23:00:43.198360 [ERR ] SIP. Callref 02f1. Interface 1, mode 3
23:00:43.198421 [ERR ] SIP. Callref 02f1. Update category for SIP-user: cat <6>
23:00:43.198467 [ERR ] SIP. Callref 02f1. Update calling for SIP-user: num <9735360>
23:00:43.198505 [ERR ] SIP. Callref 02f1. Update calling for SIP-user: type <subscriber>
23:00:43.198579 [INFO] Port SIPT:02f1. Seize (v-chan:-1, TG:-2). Cld:'<none>'(0). (Subscr) . Clg-len: 7. clg-set: y. COS: -1. RADIUS=-1
23:00:43.198634 [INFO] Port SIPT:02f1. CgPN: <9735360>(7) <set> <subscriber> <allowed> <net.provided>. cat [6]
23:00:43.198685 [INFO] Port SIPT:02f1. Calling Info (pres allowed): <9735360>. <Category=6>
23:00:43.198724 [INFO] Port SIPT:02f1. inModify: CgPN-result: <9735360>(7) <set> <subscriber> <allowed> <net.provided>. cat [6]
23:00:43.198771 [INFO] Port SIPT:02f1. COPM: Check for activate local port
23:00:43.198812 [INFO] Port SIPT:02f1. Set state 'In-Dial'
23:00:43.198858 [INFO] Port SIPT:02f1. Set CallerId: '9735360' (7).
23:00:43.198932 [INFO] Port SIPT:02f1. Create msp-conn [0004].
23:00:43.199098 [INFO] Port SIPT:02f1. Set new IP-conn [0004]
23:00:43.199140 [INFO] SIP. Callref 02f1. IP-conn [0004] created
23:00:43.199193 [INFO] SIP. Callref 02f1. Incoming seize - PLAN 0. COS 29
23:00:43.199259 [INFO] Port SIPT:02f1. Dial In (in=12, need rm=0): <8><9><2><7><7><1><3><2><6><0><5>[.]
23:00:43.199310 [INFO] Port SIPT:02f1. In Digit 12 sym: called buf <89277132605.>
23:00:43.199346 [INFO] Port SIPT:02f1. Dial buf <89277132605>(11)
23:00:43.199387 [INFO] Port SIPT:02f1. SIPT seize chan. Prefix data: type: not set; called type: unknown
23:00:43.199424 [INFO] SIP. Callref 02f1. New state 'SIPT_PRESENTED'
23:00:43.199614 [INFO] SIP. RX. Callref 02f1. MediaInfo
## IP: <10.10.69.105>
## Invite: <1>
## m=audio 8000 RTP/AVP 0 8 18 98 97 101
## a=rtpmap:0 pcmu/8000
## a=rtpmap:8 pcma/8000
## a=rtpmap:18 G729/8000
## a=rtpmap:98 iLBC/8000
## a=rtpmap:97 speex/8000
## a=rtpmap:101 telephone-event/8000
## a=fmtp:101 0-15
## a=sendrecv
23:00:43.199947 [INFO] SIP. Callref 02f1. Set Media-Info. Count[1]
23:00:43.199999 [INFO] SIP. Callref 02f1. Media-Info (offer): [10.10.69.105]
23:00:43.200069 [INFO] Valid
media[0]: audio 8000 RTP/AVP 0 8 18 98 97 101
a[0]:0 G711.U [set]
a[1]:8 G711.A [set]
a[2]:18 G729.B [set]
a[3]:98 none [set]
a[4]:97 none [set]
a[5]:101 NTE [set]
23:00:43.200149 [INFO] SIP. Callref 02f1. Media negotiate. Interface: 1
23:00:43.200200 [INFO] SIP. Callref 02f1. Media negotiate <offer>. [10.10.69.105]. List-count: 1
23:00:43.200242 [INFO] SIP. Callref 02f1. Media negotiate. Codec found: G711.U. PT 0
23:00:43.200448 [INFO] Port SIPT:02f1. IP-conn [0004]. Set Tune
23:00:43.200508 [INFO] Port SIPT:02f1. IP-conn [0004]. Set Media-Info: [10.10.69.105:8000]
23:00:43.200612 [INFO] SIP. Callref 02f1. New state 'SIPT_IN_PROCEEDING'
23:00:43.200664 [INFO] Port SIPT:02f1. Dial Proc (state In-Dial, called len 12): 89277132605. # Compl.
23:00:43.200729 [INFO] Port SIPT:02f1. Search route. Mode: 'calling'. Num: <9735360>(7). # Complete
23:00:43.202130 [INFO] Port SIPT:02f1. Search route. result: -1
23:00:43.202174 [INFO] Port SIPT:02f1. Search route. Mode: 'called'. Num: <89277132605>(12). # Complete
23:00:43.204342 [INFO] Port SIPT:02f1. Search route. result: 11
23:00:43.204402 [INFO] Port SIPT:02f1. Found route: prefix[3]
23:00:43.204448 [INFO] Port SIPT:02f1. Process route to prefix[3].
23:00:43.204494 [INFO] Check Access. 29 => 22. Y. (0x20700001)
23:00:43.204537 [INFO] Port SIPT:02f1. Prefix 03: type 'TRUNK', idx=0
23:00:43.204583 [INFO] Port SIPT:02f1. Trunk outgoing setup: enblock
23:00:43.204653 [INFO] Port SIPT:02f1. SearchOutModify for 'calling'. Num <9735360>(7), <subscriber>, cat <6>
23:00:43.207397 [INFO] Port SIPT:02f1. SearchResult: not found
23:00:43.207439 [INFO] Port SIPT:02f1. Link to TG 00: mode Local. Dir=0, Cld=0 Select Order=first. In buf: ~89277132605.
23:00:43.207482 [INFO] Port SIPT:02f1. TG: Link entry to PRI[00:00]
23:00:43.207530 [INFO] Port SIPT:02f1. Outgoing setup to PRI:00:00.
23:00:43.207583 [INFO] Q931. Line 00/00. Create Call, entry 00. Call 0003 (Outgoing)
23:00:43.207659 [INFO] Port PRI:00:00. Set parent SIPT:02f1
23:00:43.207704 [INFO] Port SIPT:02f1. Call-ind: change[1], obci[-1], isdn: allway[1], fwd_acs[0]
23:00:43.207747 [INFO] Q931. Line 00/00. Setup from SIPT:02f1 (enblock)
23:00:43.207785 [INFO] Q931. Line 00/00. Call 0003. State 'Null'
23:00:43.207827 [INFO] Port PRI:00:00. Set state 'Wait-Num'
23:00:43.207878 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 00
23:00:43.207920 [INFO] Port SIPT:02f1. SearchOutModify for 'called'. Num <89277132605>(12), <unknown>
23:00:43.210654 [INFO] Port SIPT:02f1. SearchResult: not found
23:00:43.210694 [INFO] Port PRI:00:00. Dial Out=8 (ext)
23:00:43.210736 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.210779 [INFO] Port PRI:00:00. Dial Out: called len 00, append 01 (max digit 30)
23:00:43.210820 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.210868 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 01
23:00:43.210907 [INFO] Port PRI:00:00. Dial Out=9 (ext)
23:00:43.210948 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.210990 [INFO] Port PRI:00:00. Dial Out: called len 01, append 01 (max digit 30)
23:00:43.211029 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.211077 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 02
23:00:43.211115 [INFO] Port PRI:00:00. Dial Out=2 (ext)
23:00:43.211156 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.211199 [INFO] Port PRI:00:00. Dial Out: called len 02, append 01 (max digit 30)
23:00:43.211239 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.211287 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 03
23:00:43.211325 [INFO] Port PRI:00:00. Dial Out=7 (ext)
23:00:43.211366 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.211437 [INFO] Port PRI:00:00. Dial Out: called len 03, append 01 (max digit 30)
23:00:43.211480 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.211529 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 04
23:00:43.211567 [INFO] Port PRI:00:00. Dial Out=7 (ext)
23:00:43.211608 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.211651 [INFO] Port PRI:00:00. Dial Out: called len 04, append 01 (max digit 30)
23:00:43.211690 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.211738 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 05
23:00:43.211776 [INFO] Port PRI:00:00. Dial Out=1 (ext)
23:00:43.211816 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.211858 [INFO] Port PRI:00:00. Dial Out: called len 05, append 01 (max digit 30)
23:00:43.211897 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.211944 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 06
23:00:43.211982 [INFO] Port PRI:00:00. Dial Out=3 (ext)
23:00:43.212023 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.212065 [INFO] Port PRI:00:00. Dial Out: called len 06, append 01 (max digit 30)
23:00:43.212104 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.212151 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 07
23:00:43.212188 [INFO] Port PRI:00:00. Dial Out=2 (ext)
23:00:43.212229 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.212270 [INFO] Port PRI:00:00. Dial Out: called len 07, append 01 (max digit 30)
23:00:43.212308 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.212355 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 08
23:00:43.212393 [INFO] Port PRI:00:00. Dial Out=6 (ext)
23:00:43.212432 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.212473 [INFO] Port PRI:00:00. Dial Out: called len 08, append 01 (max digit 30)
23:00:43.212511 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.212557 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 09
23:00:43.212594 [INFO] Port PRI:00:00. Dial Out=0 (ext)
23:00:43.212633 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.212674 [INFO] Port PRI:00:00. Dial Out: called len 09, append 01 (max digit 30)
23:00:43.212712 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [0]
23:00:43.212759 [INFO] Port PRI:00:00. Query Next Digit - peer SIPT:02f1 (next.digit=n). Called len 12, proceed 10
23:00:43.212800 [INFO] Port PRI:00:00. Dial Out=5. (ext)
23:00:43.213129 [INFO] Q931. Line 00/00. Call 0003. 'Dial' in state Null
23:00:43.213186 [INFO] Port PRI:00:00. Dial Out: called len 10, append 02 (max digit 30)
23:00:43.213231 [INFO] Port PRI:00:00. Dial Out: 'End_of_Dial' digit/stop dial
23:00:43.213279 [INFO] Q931. Line 00/00. Call 0003. 'OutDial' result [2]
23:00:43.213336 [INFO] Line 00/00. Search B-chan: chan -1, mask 00010001 {chan mask: 00010001} - chan order: 2, dir=1, start=2
23:00:43.213377 [INFO] Line 00/00. Search B-chan: chan -1, mask 00010001 {chan mask: 00010001} - found free chan 1 (cycle dir 1)
23:00:43.213417 [INFO] Q931, Line 00/00. Call 0003. Create Seize - entry 00, channel 01
23:00:43.213462 [INFO] Q931. Line 00/00. Call 0003. Timer 'T303' (4 sec) start
23:00:43.213503 [INFO] Q931. Line 00/00. Call 0003. State 'Call Initiated'
23:00:43.213548 [INFO] Port PRI:00:00. VChan set (chan 1) - 'line 0, chan 4'
23:00:43.213709 [INFO] Q931. Line 00/00. Call 0003. TX 42
DUMP: 08.02.00.03.05.A1.04.03.90.90.A3.18.03.A9.83.81.
6C.09.41.83.39.37.33.35.33.36.30.70.0C.80.38.39.
32.37.37.31.33.32.36.30.35.
0x0003 (from orig), MSG=SETUP:
[sending complete]
[bearer capability:
cap=3.1 kHz audio
std=CCITT
rate=64 kbit/s
mode=circuit
layer1=G.711 A-law]
[channel id: channel=1 (exclusive)] Coding=CCITT
[calling party number: 9735360 (type=subscriber, plan=ISDN, present.allowed, network provided)]
[called party number: 89277132605 (type=unknown, plan=unknown)]
---------------------------------
23:00:43.213854 [INFO] Port SIPT:02f1. Prefix linked: called len 12, proceed 12
23:00:43.215076 [INFO] Port SIPT:02f1. Created IP-conn [0004]. in-call
23:00:43.231115 [INFO] Q931. Line 00/00. RX
DUMP: 08.02.80.03.0D.18.03.A9.83.81.1E.02.81.82.
0x0003 (to orig), MSG=SETUP ACKNOWLEDGE:
[channel id: channel=1 (exclusive)] Coding=CCITT
[progress ind: Destination not isdn]
---------------------------------
23:00:43.231190 [INFO] Q931. Line 00/00. Call 0003. Timer 'T303' stop
23:00:43.231238 [INFO] Q931. Line 00/00. Call 0003. State 'Overlap Sending'
23:00:43.231283 [INFO] Q931. Line 00/00. Call 0003. SetISDN: allway[1], fwd_acs[0]
23:00:43.231333 [INFO] Port PRI:00:00. Setup Ack (peer=SIPT:02f1)
23:00:43.231379 [INFO] Port PRI:00:00. Set state 'Out-Dial'
23:00:43.231427 [INFO] Port SIPT:02f1. Setup Acked in state In-Dial. (peer=PRI:00:00)
23:00:43.231476 [INFO] Port SIPT:02f1. LinkUp to PRI:00:00
23:00:43.231529 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:43.231571 [INFO] Conn[0004]: try activate. state 'Disabled'. has media
23:00:43.231644 [INFO] Conn[0004]: Activate [10.200.104.10:50002] <-> [10.10.69.105:8000]
MAC_loc.src [02:31:52:63:74:80], MAC_loc.dst [02:00:04:00:00:02]
MAC_ext.src [a8:f9:4b:81:78:e8], MAC_ext.dst [00:1e:4a:8a:a6:1b]
23:00:43.231708 [INFO] Conn[0004]: Start voice. Codec G711U, vad=off, cng=off. Ptime=20 ms. PT [0] SSRC=74b0dc51
23:00:43.233471 [INFO] Conn[0004]: Params set. State 'ActivateReq'
23:00:43.233527 [INFO] Conn[0004]: Enable connection
23:00:43.235861 [INFO] Conn[0004]: Activated
23:00:43.235912 [INFO] Port SIPT:02f1. Changed IP-conn
23:00:43.265115 [INFO] Q931. Line 00/00. RX
DUMP: 08.02.80.03.02.18.03.A9.83.81.1E.02.81.82.
0x0003 (to orig), MSG=CALL PROCEEDING:
[channel id: channel=1 (exclusive)] Coding=CCITT
[progress ind: Destination not isdn]
---------------------------------
23:00:43.265173 [INFO] Q931. Line 00/00. Call 0003. State 'Outgoing Call Proceeding'
23:00:43.265233 [INFO] Port PRI:00:00. LinkUp to SIPT:02f1
23:00:43.265286 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:43.265328 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:00:43.265375 [INFO] Q931. Line 00/00. Call 0003. SetISDN: allway[1], fwd_acs[0]
23:00:43.265421 [INFO] Port PRI:00:00. Stop Dial, reason 'complete'
23:00:43.265469 [INFO] Port SIPT:02f1. Send 'End of Dial' to incoming call
23:00:43.265508 [INFO] SIP. Callref 02f1. End Dial
23:00:43.265731 [INFO] SIP. TX. Callref 02f1. ACM-Address Complete
## Backward call Indicators: 12 05
# Called party's status indicator: No indication
# Interworking: encountered
# ISUP: used all the way
# Terminating access: non-ISDN
[No optional params]
23:00:43.265949 [INFO] SIP. Callref 02f1. Send Media-Info.
23:00:43.266003 [INFO] SIP. Callref 02f1. Media-Info (answer): [10.200.104.10]
23:00:43.266056 [INFO] Valid
media[0]: audio 50002 RTP/AVP 0 101
a[0]:0 G711.U [set]
a[1]:101 NTE [set]
23:00:43.266095 [INFO] SIP. Callref 02f1. Send erl-media-data.
23:00:43.266264 [INFO] SIP. Callref 02f1. Media-Info prepare
23:00:43.266300 [INFO] SIP. Callref 02f1. Media-Info encode. Media-list size: 1
23:00:43.266467 [INFO] SIP. TX. Callref 02f1. MediaInfo
## IP: <10.200.104.10>
## Invite: <1>
## m=audio 50002 RTP/AVP 0 101
## a=rtpmap:0 PCMU/8000
## a=rtpmap:101 telephone-event/8000
## a=fmtp:101 0-15
23:00:43.266667 [INFO] Port SIPT:02f1. LinkUp to PRI:00:00
23:00:43.266724 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:43.266797 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:00:43.384511 [INFO] Q931. Line 00/00. RX
DUMP: 08.02.80.03.01.1E.02.81.82.
0x0003 (to orig), MSG=ALERTING:
[progress ind: Destination not isdn]
---------------------------------
23:00:43.384590 [INFO] Q931. Line 00/00. Call 0003. Timer 'T301' (180 sec) start
23:00:43.384637 [INFO] Q931. Line 00/00. Call 0003. State 'Call Delivered'
23:00:43.384684 [INFO] Q931. Line 00/00. Call 0003. SetISDN: allway[1], fwd_acs[0]
23:00:43.384734 [INFO] Port PRI:00:00. Alert (peer=SIPT:02f1)
23:00:43.384777 [INFO] Port PRI:00:00. Stop Dial, reason 'complete'
23:00:43.384825 [INFO] Port SIPT:02f1. Send 'End of Dial' to incoming call
23:00:43.384865 [INFO] SIP. Callref 02f1. End Dial
23:00:43.384910 [INFO] SIP. Callref 02f1. Send media requested. But already sent
23:00:43.384967 [INFO] Port SIPT:02f1. LinkUp to PRI:00:00
23:00:43.385019 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:43.385061 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:00:43.385109 [INFO] Port PRI:00:00. Set state 'Out-Call'
23:00:43.385152 [INFO] Port SIPT:02f1. Set state 'In-Call'
23:00:43.385204 [INFO] Port SIPT:02f1. LinkUp to PRI:00:00
23:00:43.385253 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:43.385293 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:00:43.385352 [INFO] SIP. Callref 02f1. Alerting
23:00:43.385395 [INFO] SIP. Callref 02f1. New state 'SIPT_RECEIVED'
23:00:43.385630 [INFO] SIP. TX. Callref 02f1. CPG-Call Progress
# Event information: 0x01
[Optional params]
## Backward call Indicators: 16 05
# Called party's status indicator: Subscriber free
# Interworking: encountered
# ISUP: used all the way
# Terminating access: non-ISDN
[end of optional params]
23:00:43.385852 [INFO] SIP. Callref 02f1. Send media requested. But already sent
23:00:59.249863 [INFO] Q931. Line 00/00. RX
DUMP: 08.02.80.03.07.
0x0003 (to orig), MSG=CONNECT:
---------------------------------
23:00:59.249959 [INFO] Port PRI:00:00. LinkUp to SIPT:02f1
23:00:59.250017 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:59.250061 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:00:59.250109 [INFO] Q931. Line 00/00. Call 0003. Timer 'T301' stop
23:00:59.250151 [INFO] Q931. Line 00/00. Call 0003. State 'Active'
23:00:59.250212 [INFO] Q931. Line 00/00. Call 0003. TX 6
DUMP: 08.02.00.03.0F.
0x0003 (from orig), MSG=CONNECT ACKNOWLEDGE:
---------------------------------
23:00:59.250296 [INFO] Port PRI:00:00. Answer
23:00:59.250355 [INFO] Port PRI:00:00. LinkUp to SIPT:02f1
23:00:59.250406 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:59.250445 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:00:59.250493 [INFO] Port PRI:00:00. Set state 'Talk'
23:00:59.250540 [INFO] Port SIPT:02f1. Answer from peer (PRI:00:00)
23:00:59.250577 [INFO] Port SIPT:02f1. Local: y, peer n.
23:00:59.250616 [INFO] Port PRI:00:00. Local: n, peer y.
23:00:59.250661 [INFO] Port SIPT:02f1. Set state 'Talk'
23:00:59.250703 [INFO] Port SIPT:02f1. Connect in state 'SIPT_RECEIVED'
23:00:59.250742 [INFO] SIP. Callref 02f1. New state 'SIPT_ACTIVE'
23:00:59.250931 [INFO] SIP. TX. Callref 02f1. ANM-Answer
[No optional params]
23:00:59.251139 [INFO] SIP. Callref 02f1. Send media requested. But already sent
23:00:59.251206 [INFO] Port SIPT:02f1. LinkUp to PRI:00:00
23:00:59.251258 [INFO] Port SIPT:02f1. Activate IP-conn [0004]
23:00:59.251299 [INFO] Conn[0004]: try activate. state ' Active '. has media
23:01:03.936967 [INFO] SIP. RX. Callref 02f1. REL-Release
# Cause indicators: [user] [cause=Normal call clearing]
[No optional params]
23:01:03.937050 [INFO] SIP. Callref 02f1. 'REL-Release' with cause <Normal call clearing>(16)
23:01:03.937093 [INFO] SIP. Callref 02f1. New state 'SIPT_IDLE'
23:01:03.937137 [INFO] Port SIPT:02f1. Deactivate IP-conn [0004]
23:01:03.937333 [INFO] Port SIPT:02f1. Free IP-conn [0004]
23:01:03.937393 [INFO] Port SIPT:02f1. Release Complete, cause 'Normal call clearing', dcl '0', loc '0'
23:01:03.937439 [INFO] Port SIPT:02f1. SormRelease in state 'Talk'
23:01:03.937605 [INFO] Port SIPT:02f1. CDR:
sign: test;
connect: 2012-01-10 23:00:59;
duration: 5;
disccode: 16;
discinfo: user answer;
incoming: 10.10.69.105;sip-user;undef;
calling: 9735360;9735360;
outgoing: 0.0.0.0;trunk-Q931;trunk_to_tfop;
called: 89277132605;89277132605;
setup: 2012-01-10 23:00:43;
disconn: 2012-01-10 23:01:03;
23:01:03.937677 [INFO] Port SIPT:02f1. Release peer 'PRI:00:00', cause 'Normal call clearing', dcl '1', loc '0'
23:01:03.937743 [INFO] Port PRI:00:00. Disconnect from SIPT:02f1, cause 'Normal call clearing', dcl '1', loc '0'
23:01:03.937788 [INFO] Port PRI:00:00. Disconnect, cause 'Normal call clearing', dcl '1', loc '0'
23:01:03.937831 [INFO] Port PRI:00:00. SormRelease in state 'Talk'
23:01:03.937879 [INFO] Port PRI:00:00. Set signal: 'BUSY'
23:01:03.937928 [INFO] Q931. Line 00/00. Call 0003. State 'Disconnect Indication'
23:01:03.937969 [INFO] Q931. Line 00/00. Call 0003. State 'Release Request'
23:01:03.938040 [INFO] Q931. Line 00/00. Call 0003. TX 10
DUMP: 08.02.00.03.4D.08.02.80.90.
0x0003 (from orig), MSG=RELEASE:
[cause: Normal call clearing (location=user, std=CCITT)]
---------------------------------
23:01:03.938138 [INFO] Q931. Line 00/00. Call 0003. Timer 'T308' (4 sec) start
23:01:03.938196 [INFO] Port SIPT:02f1. Set state 'Ready'
23:01:03.938386 [INFO] SIP. TX. Callref 02f1. RLC-Release_Complete
[No optional params]
23:01:03.938731 [INFO] Port SIPT:02f1. Destroy Conn[0004].
23:01:03.940003 [INFO] Conn[0004]: Stat info
23:01:03.940908 [INFO] Conn[0004]: Stat info2
23:01:03.950310 [INFO] Q931. Line 00/00. RX
DUMP: 08.02.80.03.5A.08.02.81.90.
0x0003 (to orig), MSG=RELEASE COMPLETE:
[cause: Normal call clearing (location=private network serving local user, std=CCITT)]
---------------------------------
23:01:03.950376 [INFO] Q931. Line 00/00. Call 0003. Recv 'Release complete'. Cause: 'Normal call clearing'
23:01:03.950424 [INFO] Port PRI:00:00. Release Complete, cause 'Normal call clearing', dcl '0', loc '0'
23:01:03.950471 [INFO] Port PRI:00:00. SormRelease in state 'Talk'
23:01:03.950537 [INFO] Port PRI:00:00. Set state 'Ready'
23:01:03.950579 [INFO] Q931. Line 00/00. Call 0003. State 'Null'
SMG1016. Software Version: V.2.9.03. L. [SIPu] Build: Dec 28 2011 10:56:24
Log finished at: 23:01:08.158828