История изменений [назад]

PRI Span: 1
PRI Span: 
файлы трассировок потока при звонке в очередь и в группу
 
Очередь огранизована из номеров 7114,7115,7116 с линейной стратегией дозвона, по 20 сек на оператора. время ожидания 1 < Protocol Discriminator: Q.931 (8)  len=57
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent from originator)
PRI Span: 1 < Message Type: SETUP (5)
PRI Span: 1 < [a1]
PRI Span: 1 < Sending Complete (len= 1)
PRI Span: 1 < [04 03 80 90 a3]
PRI Span: 1 < Bearer Capability (len= 5) [ Ext: 1  Coding-Std: 0  Info transfer capability: Speech (0)
PRI Span: 1 <                              Ext: 1  Trans mode/rate: 64kbps, circuit-mode (16)
PRI Span: 1 <                                User information layer 1: A-Law (35)
PRI Span: 1 < [18 03 a1 83 86]
PRI Span: 1 < Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Preferred  Dchan: 0
PRI Span: 1 <                       ChanSel: As indicated in following octets
PRI Span: 1 <                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
PRI Span: 1 <                       Ext: 1  Channel: 6 Type: CPE]
PRI Span: 1 < [6c 0d 21 81 38 39 36 37 38 35 30 30 30 30 30]
PRI Span: 1 < Calling Number (len=15) [ Ext: 0  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)
PRI Span: 1 <                           Presentation: Presentation permitted, user number passed network screening (1)  '89678500000' ]
PRI Span: 1 < [70 05 a1 37 30 38 36]
PRI Span: 1 < Called Number (len= 7) [ Ext: 1  TON: National Number (2)  NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1)  '7086' ]
PRI Span: 1 < [7e 09 00 fe 55 01 00 59 01 00 b5]
PRI Span: 1 < User-User Information (len=11) [ 00 7e 55 01 00 59 01 00 35 ]
PRI Span: 1 < [9c]
PRI Span: 1 < Non-Locking Shift (len=01): Requested codeset 4
PRI Span: 1 < [31 01 81]
PRI Span: 1 < Transit Count (len=03): Count=1 (0x01)
PRI Span: 1 < [9d]
PRI Span: 1 < Non-Locking Shift (len=01): Requested codeset 5
PRI Span: 1 < [32 01 80]
PRI Span: 1 < Calling Party Category (len= 3) [ Ext: 1  Cat: Unknown Source (0) ]
PRI Span: 1 -- Making new call for cref 29226
PRI Span: 1 Received message for call 0xa146258 on link 0xaba3b94 TEI/SAPI 0/0
PRI Span: 1 -- Processing Q.931 Call Setup
PRI Span: 1 -- Processing IE 161 (cs0, Sending Complete)
PRI Span: 1 -- Processing IE 4 (cs0, Bearer Capability)
PRI Span: 1 -- Processing IE 24 (cs0, Channel Identification)
PRI Span: 1 -- Processing IE 108 (cs0, Calling Party Number)
PRI Span: 1 -- Processing IE 112 (cs0, Called Party Number)
PRI Span: 1 -- Processing IE 126 (cs0, User-User)
PRI Span: 1 -- Processing IE 49 (cs4, Transit Count)
PRI Span: 1 -- Processing IE 50 (cs5, Calling Party Category)
PRI Span: 1 q931.c:8281 post_handle_q931_message: Call 29226 enters state 6 (Call Present).  Hold state: Idle
Span 1: Processing event PRI_EVENT_RING(5)
PRI Span: 1 q931.c:5477 q931_call_proceeding: Call 29226 enters state 9 (Incoming Call Proceeding).  Hold state: Idle
PRI Span: 1
PRI Span: 1 > DL-DATA request
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=10
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: CALL PROCEEDING (2)
PRI Span: 1 TEI=0 Transmitting N(S)=58, window is open V(A)=58 K=7
PRI Span: 1
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=10
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: CALL PROCEEDING (2)
PRI Span: 1 > [18 03 a9 83 86]
PRI Span: 1 > Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
PRI Span: 1 >                       ChanSel: As indicated in following octets
PRI Span: 1 >                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
PRI Span: 1 >                       Ext: 1  Channel: 6 Type: CPE]
    -- Accepting call from '89678500000' to '7086' on channel 0/6, span 1
    -- Executing [7086@from-internal:1] Macro("DAHDI/i1/89678500000-2603", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("DAHDI/i1/89678500000-2603", "TOUCH_MONITOR=1398274593.93208") in new stack
    -- Executing [s@macro-user-callerid:2] Set("DAHDI/i1/89678500000-2603", "AMPUSER=89678500000") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("DAHDI/i1/89678500000-2603", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("DAHDI/i1/89678500000-2603", "1?Set(REALCALLERIDNUM=89678500000)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("DAHDI/i1/89678500000-2603", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] Set("DAHDI/i1/89678500000-2603", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:7] GotoIf("DAHDI/i1/89678500000-2603", "1?report") in new stack
    -- Goto (macro-user-callerid,s,15)
    -- Executing [s@macro-user-callerid:15] GotoIf("DAHDI/i1/89678500000-2603", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:16] Set("DAHDI/i1/89678500000-2603", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:17] GotoIf("DAHDI/i1/89678500000-2603", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,28)
    -- Executing [s@macro-user-callerid:28] Set("DAHDI/i1/89678500000-2603", "CALLERID(number)=89678500000") in new stack
    -- Executing [s@macro-user-callerid:29] Set("DAHDI/i1/89678500000-2603", "CALLERID(name)=") in new stack
    -- Executing [s@macro-user-callerid:30] Set("DAHDI/i1/89678500000-2603", "CDR(cnum)=89678500000") in new stack
    -- Executing [s@macro-user-callerid:31] Set("DAHDI/i1/89678500000-2603", "CDR(cnam)=") in new stack
    -- Executing [s@macro-user-callerid:32] Set("DAHDI/i1/89678500000-2603", "CHANNEL(language)=ru") in new stack
    -- Executing [7086@from-internal:2] Answer("DAHDI/i1/89678500000-2603", "") in new stack
PRI Span: 1 q931.c:5660 q931_connect: Call 29226 enters state 8 (Connect Request).  Hold state: Idle
PRI Span: 1
PRI Span: 1 > DL-DATA request
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=14
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: CONNECT (7)
PRI Span: 1 TEI=0 Transmitting N(S)=59, window is open V(A)=59 K=7
PRI Span: 1
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=14
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: CONNECT (7)
PRI Span: 1 > [18 03 a9 83 86]
PRI Span: 1 > Channel ID (len= 5) [ Ext: 1  IntID: Implicit  Other(PRI)  Spare: 0  Exclusive  Dchan: 0
PRI Span: 1 >                       ChanSel: As indicated in following octets
PRI Span: 1 >                       Ext: 1  Coding: 0  Number Specified  Channel Type: 3
PRI Span: 1 >                       Ext: 1  Channel: 6 Type: CPE]
PRI Span: 1 > [1e 02 81 82]
PRI Span: 1 > Progress Indicator (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  0: 0  Location: Private network serving the local user (1)
PRI Span: 1 >                               Ext: 1  Progress Description: Called equipment is non-ISDN. (2) ]
    -- Executing [7086@from-internal:3] Macro("DAHDI/i1/89678500000-2603", "blkvm-set,reset") in new stack
    -- Executing [s@macro-blkvm-set:1] ExecIf("DAHDI/i1/89678500000-2603", "1?Set(__BLKVM_CHANNEL=DAHDI/i1/89678500000-2603)") in new stack
    -- Executing [s@macro-blkvm-set:2] Set("DAHDI/i1/89678500000-2603", "SHARED(BLKVM,DAHDI/i1/89678500000-2603)=TRUE") in new stack
    -- Executing [s@macro-blkvm-set:3] Set("DAHDI/i1/89678500000-2603", "GOSUB_RETVAL=TRUE") in new stack
    -- Executing [s@macro-blkvm-set:4] MacroExit("DAHDI/i1/89678500000-2603", "") in new stack
    -- Executing [7086@from-internal:4] ExecIf("DAHDI/i1/89678500000-2603", "1?Set(_DIAL_OPTIONS=TtrM(auto-blkvm))") in new stack
    -- Executing [7086@from-internal:5] Set("DAHDI/i1/89678500000-2603", "__NODEST=7086") in new stack
    -- Executing [7086@from-internal:6] Set("DAHDI/i1/89678500000-2603", "QCIDPP=") in new stack
    -- Executing [7086@from-internal:7] Set("DAHDI/i1/89678500000-2603", "VQ_CIDPP=") in new stack
    -- Executing [7086@from-internal:8] ExecIf("DAHDI/i1/89678500000-2603", "0?Macro(prepend-cid, )") in new stack
    -- Executing [7086@from-internal:9] Set("DAHDI/i1/89678500000-2603", "QAINFO=") in new stack
    -- Executing [7086@from-internal:10] Set("DAHDI/i1/89678500000-2603", "VQ_AINFO=") in new stack
    -- Executing [7086@from-internal:11] ExecIf("DAHDI/i1/89678500000-2603", "0?Set(__ALERT_INFO=)") in new stack
    -- Executing [7086@from-internal:12] Set("DAHDI/i1/89678500000-2603", "QJOINMSG=") in new stack
    -- Executing [7086@from-internal:13] Set("DAHDI/i1/89678500000-2603", "VQ_JOINMSG=") in new stack
    -- Executing [7086@from-internal:14] Set("DAHDI/i1/89678500000-2603", "QMOH=default") in new stack
    -- Executing [7086@from-internal:15] Set("DAHDI/i1/89678500000-2603", "VQ_MOH=") in new stack
    -- Executing [7086@from-internal:16] ExecIf("DAHDI/i1/89678500000-2603", "1?Set(__MOHCLASS=default)") in new stack
    -- Executing [7086@from-internal:17] Set("DAHDI/i1/89678500000-2603", "QRETRY=n") in new stack
    -- Executing [7086@from-internal:18] Set("DAHDI/i1/89678500000-2603", "VQ_RETRY=") in new stack
    -- Executing [7086@from-internal:19] Set("DAHDI/i1/89678500000-2603", "QOPTIONS=tn") in new stack
    -- Executing [7086@from-internal:20] Set("DAHDI/i1/89678500000-2603", "VQ_OPTIONS=") in new stack
    -- Executing [7086@from-internal:21] Set("DAHDI/i1/89678500000-2603", "QGOSUB=") in new stack
    -- Executing [7086@from-internal:22] Set("DAHDI/i1/89678500000-2603", "VQ_GOSUB=") in new stack
    -- Executing [7086@from-internal:23] Set("DAHDI/i1/89678500000-2603", "QAGI=") in new stack
    -- Executing [7086@from-internal:24] Set("DAHDI/i1/89678500000-2603", "VQ_AGI=") in new stack
    -- Executing [7086@from-internal:25] Set("DAHDI/i1/89678500000-2603", "QRULE=") in new stack
    -- Executing [7086@from-internal:26] Set("DAHDI/i1/89678500000-2603", "VQ_RULE=") in new stack
    -- Executing [7086@from-internal:27] Set("DAHDI/i1/89678500000-2603", "QPOSITION=") in new stack
    -- Executing [7086@from-internal:28] Set("DAHDI/i1/89678500000-2603", "VQ_POSITION=") in new stack
    -- Executing [7086@from-internal:29] Gosub("DAHDI/i1/89678500000-2603", "sub-record-check,s,1(q,7086,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] Set("DAHDI/i1/89678500000-2603", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:2] GotoIf("DAHDI/i1/89678500000-2603", "1?check") in new stack
    -- Goto (sub-record-check,s,7)
    -- Executing [s@sub-record-check:7] Set("DAHDI/i1/89678500000-2603", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:8] GotoIf("DAHDI/i1/89678500000-2603", "1?next") in new stack
    -- Goto (sub-record-check,s,11)
    -- Executing [s@sub-record-check:11] ExecIf("DAHDI/i1/89678500000-2603", "0?Return()") in new stack
    -- Executing [s@sub-record-check:12] ExecIf("DAHDI/i1/89678500000-2603", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack
    -- Executing [s@sub-record-check:13] GotoIf("DAHDI/i1/89678500000-2603", "0?q,1") in new stack
    -- Executing [s@sub-record-check:14] Set("DAHDI/i1/89678500000-2603", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:15] Set("DAHDI/i1/89678500000-2603", "NOW=1398274593") in new stack
    -- Executing [s@sub-record-check:16] Set("DAHDI/i1/89678500000-2603", "__DAY=23") in new stack
    -- Executing [s@sub-record-check:17] Set("DAHDI/i1/89678500000-2603", "__MONTH=04") in new stack
    -- Executing [s@sub-record-check:18] Set("DAHDI/i1/89678500000-2603", "__YEAR=2014") in new stack
    -- Executing [s@sub-record-check:19] Set("DAHDI/i1/89678500000-2603", "__TIMESTR=20140423-233633") in new stack
    -- Executing [s@sub-record-check:20] Set("DAHDI/i1/89678500000-2603", "__FROMEXTEN=89678500000") in new stack
    -- Executing [s@sub-record-check:21] Set("DAHDI/i1/89678500000-2603", "__CALLFILENAME=q-7086-89678500000-20140423-233633-1398274593.93208") in new stack
    -- Executing [s@sub-record-check:22] Goto("DAHDI/i1/89678500000-2603", "q,1") in new stack
    -- Goto (sub-record-check,q,1)
    -- Executing [q@sub-record-check:1] GosubIf("DAHDI/i1/89678500000-2603", "0?recq,1(q,7086,89678500000)") in new stack
    -- Executing [q@sub-record-check:2] Return("DAHDI/i1/89678500000-2603", "") in new stack
    -- Executing [7086@from-internal:30] Set("DAHDI/i1/89678500000-2603", "__CWIGNORE=TRUE") in new stack
    -- Executing [7086@from-internal:31] Set("DAHDI/i1/89678500000-2603", "__CFIGNORE=TRUE") in new stack
    -- Executing [7086@from-internal:32] Set("DAHDI/i1/89678500000-2603", "__FORWARD_CONTEXT=block-cf") in new stack
    -- Executing [7086@from-internal:33] ExecIf("DAHDI/i1/89678500000-2603", "0?Playback(, )") in new stack
    -- Executing [7086@from-internal:34] QueueLog("DAHDI/i1/89678500000-2603", "7086,1398274593.93208,NONE,DID,") in new stack
    -- Executing [7086@from-internal:35] Set("DAHDI/i1/89678500000-2603", "QAANNOUNCE=") in new stack
    -- Executing [7086@from-internal:36] Set("DAHDI/i1/89678500000-2603", "VQ_AANNOUNCE=") in new stack
    -- Executing [7086@from-internal:37] Set("DAHDI/i1/89678500000-2603", "QMAXWAIT=60") in new stack
    -- Executing [7086@from-internal:38] Set("DAHDI/i1/89678500000-2603", "VQ_MAXWAIT=") in new stack
    -- Executing [7086@from-internal:39] Queue("DAHDI/i1/89678500000-2603", "7086,tn,,,60,,,,,") in new stack
    -- Started music on hold, class 'default', on DAHDI/i1/89678500000-2603
    -- LazyMembers debugging - Numbusies: 0, Numnoans: 0, Nummems: 3
    -- Executing [7000@from-queue:1] Set("Local/7000@from-queue-00006202;2", "QAGENT=7000") in new stack
    -- Executing [7000@from-queue:2] Goto("Local/7000@from-queue-00006202;2", "7086,1") in new stack
    -- Goto (from-queue,7086,1)
    -- Executing [7086@from-queue:1] Goto("Local/7000@from-queue-00006202;2", "from-queue-exten-internal,7000,1") in new stack
    -- Goto (from-queue-exten-internal,7000,1)
    -- Executing [7000@from-queue-exten-internal:1] Set("Local/7000@from-queue-00006202;2", "RingGroupMethod=none") in new stack
    -- Executing [7000@from-queue-exten-internal:2] Set("Local/7000@from-queue-00006202;2", "QDOPTS=") in new stack
    -- Executing [7000@from-queue-exten-internal:3] Gosub("Local/7000@from-queue-00006202;2", "sub-record-check,s,1(exten,7000)") in new stack
    -- Executing [s@sub-record-check:1] Set("Local/7000@from-queue-00006202;2", "REC_POLICY_MODE_SAVE=dontcare") in new stack
    -- Executing [s@sub-record-check:2] GotoIf("Local/7000@from-queue-00006202;2", "1?check") in new stack
    -- Goto (sub-record-check,s,7)
    -- Executing [s@sub-record-check:7] Set("Local/7000@from-queue-00006202;2", "__MON_FMT=wav") in new stack
PRI Span: 1
PRI Span: 1 < Protocol Discriminator: Q.931 (8)  len=5
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent from originator)
PRI Span: 1 < Message Type: CONNECT ACKNOWLEDGE (15)
PRI Span: 1 Received message for call 0xa146258 on link 0xaba3b94 TEI/SAPI 0/0
PRI Span: 1 q931.c:8484 post_handle_q931_message: Call 29226 enters state мин, "потом положить трубку"
 
Группа огранизована из номеров 7114,7115,7116 с линейной стратегией дозвона, по 15 сек на оператора. При не ответе "положить трубку"
 
Трубку клал после 2-3 гудков(прим. 10 (Active).  Hold state: Idle
Span 1: Processing event PRI_EVENT_CONNECT_ACK(27)
    -- Executing [s@sub-record-check:8] GotoIf("Local/7000@from-queue-00006202;2", "1?next") in new stack
    -- Goto (sub-record-check,s,11)
    -- Executing [s@sub-record-check:11] ExecIf("Local/7000@from-queue-00006202;2", "0?Return()") in new stack
    -- Executing [s@sub-record-check:12] ExecIf("Local/7000@from-queue-00006202;2", "0?Set(__REC_POLICY_MODE=)") in new stack
    -- Executing [s@sub-record-check:13] GotoIf("Local/7000@from-queue-00006202;2", "1?exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] GotoIf("Local/7000@from-queue-00006202;2", "1?callee") in new stack
    -- Goto (sub-record-check,exten,8)
    -- Executing [exten@sub-record-check:8] GosubIf("Local/7000@from-queue-00006202;2", "0?record,1(exten,7000,89678500000)") in new stack
    -- Executing [exten@sub-record-check:9] Return("Local/7000@from-queue-00006202;2", "") in new stack
    -- Executing [7000@from-queue-exten-internal:4] Macro("Local/7000@from-queue-00006202;2", "dial-one,,TtrM(auto-blkvm),7000") in new stack
    -- Executing [s@macro-dial-one:1] Set("Local/7000@from-queue-00006202;2", "DEXTEN=7000") in new stack
    -- Executing [s@macro-dial-one:2] Set("Local/7000@from-queue-00006202;2", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:3] GosubIf("Local/7000@from-queue-00006202;2", "0?screen,1()") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("Local/7000@from-queue-00006202;2", "0?cf,1()") in new stack
    -- Executing [s@macro-dial-one:5] GotoIf("Local/7000@from-queue-00006202;2", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [s@macro-dial-one:8] GotoIf("Local/7000@from-queue-00006202;2", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:9] GotoIf("Local/7000@from-queue-00006202;2", "0?continue") in new stack
    -- Executing [s@macro-dial-one:10] Set("Local/7000@from-queue-00006202;2", "EXTHASCW=") in new stack
    -- Executing [s@macro-dial-one:11] GotoIf("Local/7000@from-queue-00006202;2", "1?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,12)
    -- Executing [s@macro-dial-one:12] GotoIf("Local/7000@from-queue-00006202;2", "0?docfu:skip3") in new stack
    -- Goto (macro-dial-one,s,16)
    -- Executing [s@macro-dial-one:16] GotoIf("Local/7000@from-queue-00006202;2", "1?next2:continue") in new stack
    -- Goto (macro-dial-one,s,17)
    -- Executing [s@macro-dial-one:17] GotoIf("Local/7000@from-queue-00006202;2", "1?continue") in new stack
    -- Goto (macro-dial-one,s,25)
    -- Executing [s@macro-dial-one:25] GotoIf("Local/7000@from-queue-00006202;2", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:26] GosubIf("Local/7000@from-queue-00006202;2", "1?dstring,1():dlocal,1()") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("Local/7000@from-queue-00006202;2", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("Local/7000@from-queue-00006202;2", "DEVICES=7000") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("Local/7000@from-queue-00006202;2", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("Local/7000@from-queue-00006202;2", "0?Set(DEVICES=000)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("Local/7000@from-queue-00006202;2", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("Local/7000@from-queue-00006202;2", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("Local/7000@from-queue-00006202;2", "THISDIAL=SIP/7000") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("Local/7000@from-queue-00006202;2", "1?zap2dahdi,1()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/7000@from-queue-00006202;2", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("Local/7000@from-queue-00006202;2", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("Local/7000@from-queue-00006202;2", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("Local/7000@from-queue-00006202;2", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("Local/7000@from-queue-00006202;2", "THISPART2=SIP/7000") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/7000@from-queue-00006202;2", "0?Set(THISPART2=DAHDI/7000)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("Local/7000@from-queue-00006202;2", "NEWDIAL=SIP/7000&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("Local/7000@from-queue-00006202;2", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/7000@from-queue-00006202;2", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("Local/7000@from-queue-00006202;2", "THISDIAL=SIP/7000") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("Local/7000@from-queue-00006202;2", "") in new stack
    -- Executing [dstring@macro-dial-one:9] Set("Local/7000@from-queue-00006202;2", "DSTRING=SIP/7000&") in new stack
    -- Executing [dstring@macro-dial-one:10] Set("Local/7000@from-queue-00006202;2", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:11] GotoIf("Local/7000@from-queue-00006202;2", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:12] Set("Local/7000@from-queue-00006202;2", "DSTRING=SIP/7000") in new stack
    -- Executing [dstring@macro-dial-one:13] Return("Local/7000@from-queue-00006202;2", "") in new stack
    -- Executing [s@macro-dial-one:27] GotoIf("Local/7000@from-queue-00006202;2", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("Local/7000@from-queue-00006202;2", "0?skiptrace") in new stack
    -- Executing [s@macro-dial-one:29] GosubIf("Local/7000@from-queue-00006202;2", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [ctset@macro-dial-one:1] Set("Local/7000@from-queue-00006202;2", "DB(CALLTRACE/7000)=89678500000") in new stack
    -- Executing [ctset@macro-dial-one:2] Return("Local/7000@from-queue-00006202;2", "") in new stack
    -- Executing [s@macro-dial-one:30] Set("Local/7000@from-queue-00006202;2", "D_OPTIONS=TtrM(auto-blkvm)") in new stack
    -- Executing [s@macro-dial-one:31] ExecIf("Local/7000@from-queue-00006202;2", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [s@macro-dial-one:32] ExecIf("Local/7000@from-queue-00006202;2", "0?SIPAddHeader()") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("Local/7000@from-queue-00006202;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
    -- Executing [s@macro-dial-one:34] GosubIf("Local/7000@from-queue-00006202;2", "0?qwait,1()") in new stack
    -- Executing [s@macro-dial-one:35] Set("Local/7000@from-queue-00006202;2", "__CWIGNORE=TRUE") in new stack
    -- Executing [s@macro-dial-one:36] Set("Local/7000@from-queue-00006202;2", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:37] GotoIf("Local/7000@from-queue-00006202;2", "0?usegoto,1") in new stack
    -- Executing [s@macro-dial-one:38] GotoIf("Local/7000@from-queue-00006202;2", "1?godial") in new stack
    -- Goto (macro-dial-one,s,42)
    -- Executing [s@macro-dial-one:42] Dial("Local/7000@from-queue-00006202;2", "SIP/7000,,TtrM(auto-blkvm)") in new stack
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
  == Extension Changed 7000[ext-local] new state Ringing for Notify User 7103
    -- Called SIP/7000
    -- Local/7000@from-queue-00006202;1 is ringing
PRI Span: 1 Adding facility ie contents to send in FACILITY message:
PRI Span: 1 ASN.1 dump
PRI Span: 1   Context Specific/C [1 0x01] <A1> Len:25 <19>
PRI Span: 1     Integer(2 0x02) <02> Len:2 <02>
PRI Span: 1       <09 66> - "~f"
PRI Span: 1     OID(6 0x06) <06> Len:6 <06>
PRI Span: 1       <04 00 82 71 01 05> - "~~~q~~"
PRI Span: 1     Sequence/C(48 0x30) <30> Len:11 <0B>
PRI Span: 1       Enumerated(10 0x0A) <0A> Len:1 <01>
PRI Span: 1         <01> - "~"
PRI Span: 1       Context Specific/C [0 0x00] <A0> Len:6 <06>
PRI Span: 1         Context Specific [0 0x00] <80> Len:4 <04>
PRI Span: 1           <37 30 30 30> - "7000"
PRI Span: 1 ASN.1 end
PRI Span: 1 INVOKE Component Context Specific/C [1 0x01]
PRI Span: 1   invokeId Integer(2 0x02) = 2406 0x0966
PRI Span: 1   operationValue OID(6 0x06) = 4.0.369.1.5
PRI Span: 1   operationValue = ROSE_ETSI_EctInform
PRI Span: 1   EctInform Sequence/C(48 0x30)
PRI Span: 1   callStatus Enumerated(10 0x0A) = 1 0x0001
PRI Span: 1   redirectionNumber PresentedNumberUnscreened
PRI Span: 1   Explicit Context Specific/C [0 0x00]
PRI Span: 1   presentationAllowedNumber PartyNumber
PRI Span: 1   unknownPartyNumber Context Specific [0 0x00] = "7000"
PRI Span: 1
PRI Span: 1 > DL-DATA request
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=35
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: FACILITY (98)
PRI Span: 1 TEI=0 Transmitting N(S)=60, window is open V(A)=60 K=7
PRI Span: 1
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=35
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: FACILITY (98)
PRI Span: 1 > [1c 1c 91 a1 19 02 02 09 66 06 06 04 00 82 71 01 05 30 0b 0a 01 01 a0 06 80 04 37 30 30 30]
PRI Span: 1 > Facility (len=30, codeset=0) [ 0x91, 0xA1, 0x19, 0x02, 0x02, 0x09, 'f', 0x06, 0x06, 0x04, 0x00, 0x82, 'q', 0x01, 0x05, '0', 0x0B, 0x0A, 0x01, 0x01, 0xA0, 0x06, 0x80, 0x04, '7000' ]
PRI Span: 1
PRI Span: 1 < Protocol Discriminator: Q.931 (8)  len=25
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent from originator)
PRI Span: 1 < Message Type: FACILITY (98)
PRI Span: 1 < [1c 12 91 aa 06 80 01 00 82 01 00 a4 07 02 02 09 66 81 01 01]
PRI Span: 1 < Facility (len=20, codeset=0) [ 0x91, 0xAA, 0x06, 0x80, 0x01, 0x00, 0x82, 0x01, 0x00, 0xA4, 0x07, 0x02, 0x02, 0x09, 'f', 0x81, 0x01, 0x01 ]
PRI Span: 1 Received message for call 0xa146258 on link 0xaba3b94 TEI/SAPI 0/0
PRI Span: 1 -- Processing IE 28 (cs0, Facility)
PRI Span: 1 -- Delayed processing IE 28 (cs0, Facility)
PRI Span: 1 ASN.1 dump
PRI Span: 1   Context Specific/C [10 0x0A] <AA> Len:6 <06>
PRI Span: 1     Context Specific [0 0x00] <80> Len:1 <01>
PRI Span: 1       <00> - "~"
PRI Span: 1     Context Specific [2 0x02] <82> Len:1 <01>
PRI Span: 1       <00> - "~"
PRI Span: 1   Context Specific/C [4 0x04] <A4> Len:7 <07>
PRI Span: 1     Integer(2 0x02) <02> Len:2 <02>
PRI Span: 1       <09 66> - "~f"
PRI Span: 1     Context Specific [1 0x01] <81> Len:1 <01>
PRI Span: 1       <01> - "~"
PRI Span: 1 ASN.1 end
PRI Span: 1   nfe NetworkFacilityExtension Context Specific/C [10 0x0A]
PRI Span: 1   sourceEntity Context Specific [0 0x00] = 0 0x0000
PRI Span: 1   destinationEntity Context Specific [2 0x02] = 0 0x0000
PRI Span: 1 REJECT Component Context Specific/C [4 0x04]
PRI Span: 1   invokeId Integer(2 0x02) = 2406 0x0966
PRI Span: 1   problemInvoke Context Specific [1 0x01] = 1 0x0001
PRI Span: 1   problem = Invoke: Unrecognized Operation
PRI Span: 1 ROSE REJECT:
PRI Span: 1     INVOKE ID: 2406
PRI Span: 1     PROBLEM: Invoke: Unrecognized Operation
    -- SIP/7000-0000789a is ringing
    -- Local/7000@from-queue-00006202;1 is ringing
  == Extension Changed 7000[ext-local] new state Ringing for Notify User 7103

----------- положил трубку, но звонок в очереди остался -----------

    -- Nobody picked up in 20000 ms
    -- LazyMembers debugging - Numbusies: 0, Numnoans2: 0, Nummems: 3
  == Spawn extension (macro-dial-one, s, 42) exited non-zero on 'Local/7000@from-queue-00006202;2' in macro 'dial-one'
  == Spawn extension (from-queue-exten-internal, 7000, 4) exited non-zero on 'Local/7000@from-queue-00006202;2'
    -- Executing [h@from-queue-exten-internal:1] Macro("Local/7000@from-queue-00006202;2", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("Local/7000@from-queue-00006202;2", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [s@macro-hangupcall:3] ExecIf("Local/7000@from-queue-00006202;2", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [s@macro-hangupcall:4] Hangup("Local/7000@from-queue-00006202;2", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/7000@from-queue-00006202;2' in macro 'hangupcall'
  == Spawn extension (from-queue-exten-internal, h, 1) exited non-zero on 'Local/7000@from-queue-00006202;2'
  == Extension Changed 7000[ext-local] new state Idle for Notify User 7103
    -- LazyMembers debugging - Numbusies: 0, Numnoans: 0, Nummems: 3
    -- Executing [7114@from-queue:1] Set("Local/7114@from-queue-00006203;2", "QAGENT=7114") in new stack
    -- Executing [7114@from-queue:2] Goto("Local/7114@from-queue-00006203;2", "7086,1") in new stack
    -- Goto (from-queue,7086,1)
    -- Executing [7086@from-queue:1] Goto("Local/7114@from-queue-00006203;2", "from-queue-exten-internal,7114,1") in new stack
    -- Goto (from-queue-exten-internal,7114,1)
    -- Executing [7114@from-queue-exten-internal:1] Set("Local/7114@from-queue-00006203;2", "RingGroupMethod=none") in new stack
    -- Executing [7114@from-queue-exten-internal:2] Set("Local/7114@from-queue-00006203;2", "QDOPTS=") in new stack
    -- Executing [7114@from-queue-exten-internal:3] Gosub("Local/7114@from-queue-00006203;2", "sub-record-check,s,1(exten,7114)") in new stack
    -- Executing [s@sub-record-check:1] Set("Local/7114@from-queue-00006203;2", "REC_POLICY_MODE_SAVE=dontcare") in new stack
    -- Executing [s@sub-record-check:2] GotoIf("Local/7114@from-queue-00006203;2", "1?check") in new stack
    -- Goto (sub-record-check,s,7)
    -- Executing [s@sub-record-check:7] Set("Local/7114@from-queue-00006203;2", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:8] GotoIf("Local/7114@from-queue-00006203;2", "1?next") in new stack
    -- Goto (sub-record-check,s,11)
    -- Executing [s@sub-record-check:11] ExecIf("Local/7114@from-queue-00006203;2", "0?Return()") in new stack
    -- Executing [s@sub-record-check:12] ExecIf("Local/7114@from-queue-00006203;2", "0?Set(__REC_POLICY_MODE=)") in new stack
    -- Executing [s@sub-record-check:13] GotoIf("Local/7114@from-queue-00006203;2", "1?exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] GotoIf("Local/7114@from-queue-00006203;2", "1?callee") in new stack
    -- Goto (sub-record-check,exten,8)
    -- Executing [exten@sub-record-check:8] GosubIf("Local/7114@from-queue-00006203;2", "0?record,1(exten,7114,89678500000)") in new stack
    -- Executing [exten@sub-record-check:9] Return("Local/7114@from-queue-00006203;2", "") in new stack
    -- Executing [7114@from-queue-exten-internal:4] Macro("Local/7114@from-queue-00006203;2", "dial-one,,TtrM(auto-blkvm),7114") in new stack
    -- Executing [s@macro-dial-one:1] Set("Local/7114@from-queue-00006203;2", "DEXTEN=7114") in new stack
    -- Executing [s@macro-dial-one:2] Set("Local/7114@from-queue-00006203;2", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:3] GosubIf("Local/7114@from-queue-00006203;2", "0?screen,1()") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("Local/7114@from-queue-00006203;2", "0?cf,1()") in new stack
    -- Executing [s@macro-dial-one:5] GotoIf("Local/7114@from-queue-00006203;2", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [s@macro-dial-one:8] GotoIf("Local/7114@from-queue-00006203;2", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:9] GotoIf("Local/7114@from-queue-00006203;2", "0?continue") in new stack
    -- Executing [s@macro-dial-one:10] Set("Local/7114@from-queue-00006203;2", "EXTHASCW=") in new stack
    -- Executing [s@macro-dial-one:11] GotoIf("Local/7114@from-queue-00006203;2", "1?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,12)
    -- Executing [s@macro-dial-one:12] GotoIf("Local/7114@from-queue-00006203;2", "0?docfu:skip3") in new stack
    -- Goto (macro-dial-one,s,16)
    -- Executing [s@macro-dial-one:16] GotoIf("Local/7114@from-queue-00006203;2", "1?next2:continue") in new stack
    -- Goto (macro-dial-one,s,17)
    -- Executing [s@macro-dial-one:17] GotoIf("Local/7114@from-queue-00006203;2", "1?continue") in new stack
    -- Goto (macro-dial-one,s,25)
    -- Executing [s@macro-dial-one:25] GotoIf("Local/7114@from-queue-00006203;2", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:26] GosubIf("Local/7114@from-queue-00006203;2", "1?dstring,1():dlocal,1()") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("Local/7114@from-queue-00006203;2", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("Local/7114@from-queue-00006203;2", "DEVICES=7114") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("Local/7114@from-queue-00006203;2", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("Local/7114@from-queue-00006203;2", "0?Set(DEVICES=114)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("Local/7114@from-queue-00006203;2", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("Local/7114@from-queue-00006203;2", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("Local/7114@from-queue-00006203;2", "THISDIAL=SIP/7114") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("Local/7114@from-queue-00006203;2", "1?zap2dahdi,1()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/7114@from-queue-00006203;2", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("Local/7114@from-queue-00006203;2", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("Local/7114@from-queue-00006203;2", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("Local/7114@from-queue-00006203;2", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("Local/7114@from-queue-00006203;2", "THISPART2=SIP/7114") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/7114@from-queue-00006203;2", "0?Set(THISPART2=DAHDI/7114)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("Local/7114@from-queue-00006203;2", "NEWDIAL=SIP/7114&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("Local/7114@from-queue-00006203;2", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/7114@from-queue-00006203;2", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("Local/7114@from-queue-00006203;2", "THISDIAL=SIP/7114") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("Local/7114@from-queue-00006203;2", "") in new stack
    -- Executing [dstring@macro-dial-one:9] Set("Local/7114@from-queue-00006203;2", "DSTRING=SIP/7114&") in new stack
    -- Executing [dstring@macro-dial-one:10] Set("Local/7114@from-queue-00006203;2", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:11] GotoIf("Local/7114@from-queue-00006203;2", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:12] Set("Local/7114@from-queue-00006203;2", "DSTRING=SIP/7114") in new stack
    -- Executing [dstring@macro-dial-one:13] Return("Local/7114@from-queue-00006203;2", "") in new stack
    -- Executing [s@macro-dial-one:27] GotoIf("Local/7114@from-queue-00006203;2", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("Local/7114@from-queue-00006203;2", "0?skiptrace") in new stack
    -- Executing [s@macro-dial-one:29] GosubIf("Local/7114@from-queue-00006203;2", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [ctset@macro-dial-one:1] Set("Local/7114@from-queue-00006203;2", "DB(CALLTRACE/7114)=89678500000") in new stack
    -- Executing [ctset@macro-dial-one:2] Return("Local/7114@from-queue-00006203;2", "") in new stack
    -- Executing [s@macro-dial-one:30] Set("Local/7114@from-queue-00006203;2", "D_OPTIONS=TtrM(auto-blkvm)") in new stack
    -- Executing [s@macro-dial-one:31] ExecIf("Local/7114@from-queue-00006203;2", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [s@macro-dial-one:32] ExecIf("Local/7114@from-queue-00006203;2", "0?SIPAddHeader()") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("Local/7114@from-queue-00006203;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
    -- Executing [s@macro-dial-one:34] GosubIf("Local/7114@from-queue-00006203;2", "0?qwait,1()") in new stack
    -- Executing [s@macro-dial-one:35] Set("Local/7114@from-queue-00006203;2", "__CWIGNORE=TRUE") in new stack
    -- Executing [s@macro-dial-one:36] Set("Local/7114@from-queue-00006203;2", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:37] GotoIf("Local/7114@from-queue-00006203;2", "0?usegoto,1") in new stack
    -- Executing [s@macro-dial-one:38] GotoIf("Local/7114@from-queue-00006203;2", "1?godial") in new stack
    -- Goto (macro-dial-one,s,42)
    -- Executing [s@macro-dial-one:42] Dial("Local/7114@from-queue-00006203;2", "SIP/7114,,TtrM(auto-blkvm)") in new stack
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Called SIP/7114
    -- Local/7114@from-queue-00006203;1 is ringing
PRI Span: 1 Adding facility ie contents to send in FACILITY message:
PRI Span: 1 ASN.1 dump
PRI Span: 1   Context Specific/C [1 0x01] <A1> Len:25 <19>
PRI Span: 1     Integer(2 0x02) <02> Len:2 <02>
PRI Span: 1       <09 67> - "~g"
PRI Span: 1     OID(6 0x06) <06> Len:6 <06>
PRI Span: 1       <04 00 82 71 01 05> - "~~~q~~"
PRI Span: 1     Sequence/C(48 0x30) <30> Len:11 <0B>
PRI Span: 1       Enumerated(10 0x0A) <0A> Len:1 <01>
PRI Span: 1         <01> - "~"
PRI Span: 1       Context Specific/C [0 0x00] <A0> Len:6 <06>
PRI Span: 1         Context Specific [0 0x00] <80> Len:4 <04>
PRI Span: 1           <37 31 31 34> - "7114"
PRI Span: 1 ASN.1 end
PRI Span: 1 INVOKE Component Context Specific/C [1 0x01]
PRI Span: 1   invokeId Integer(2 0x02) = 2407 0x0967
PRI Span: 1   operationValue OID(6 0x06) = 4.0.369.1.5
PRI Span: 1   operationValue = ROSE_ETSI_EctInform
PRI Span: 1   EctInform Sequence/C(48 0x30)
PRI Span: 1   callStatus Enumerated(10 0x0A) = 1 0x0001
PRI Span: 1   redirectionNumber PresentedNumberUnscreened
PRI Span: 1   Explicit Context Specific/C [0 0x00]
PRI Span: 1   presentationAllowedNumber PartyNumber
PRI Span: 1   unknownPartyNumber Context Specific [0 0x00] = "7114"
PRI Span: 1
PRI Span: 1 > DL-DATA request
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=35
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: FACILITY (98)
PRI Span: 1 TEI=0 Transmitting N(S)=61, window is open V(A)=61 K=7
PRI Span: 1
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=35
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: FACILITY (98)
PRI Span: 1 > [1c 1c 91 a1 19 02 02 09 67 06 06 04 00 82 71 01 05 30 0b 0a 01 01 a0 06 80 04 37 31 31 34]
PRI Span: 1 > Facility (len=30, codeset=0) [ 0x91, 0xA1, 0x19, 0x02, 0x02, 0x09, 'g', 0x06, 0x06, 0x04, 0x00, 0x82, 'q', 0x01, 0x05, '0', 0x0B, 0x0A, 0x01, 0x01, 0xA0, 0x06, 0x80, 0x04, '7114' ]
PRI Span: 1
PRI Span: 1 < Protocol Discriminator: Q.931 (8)  len=25
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent from originator)
PRI Span: 1 < Message Type: FACILITY (98)
PRI Span: 1 < [1c 12 91 aa 06 80 01 00 82 01 00 a4 07 02 02 09 67 81 01 01]
PRI Span: 1 < Facility (len=20, codeset=0) [ 0x91, 0xAA, 0x06, 0x80, 0x01, 0x00, 0x82, 0x01, 0x00, 0xA4, 0x07, 0x02, 0x02, 0x09, 'g', 0x81, 0x01, 0x01 ]
PRI Span: 1 Received message for call 0xa146258 on link 0xaba3b94 TEI/SAPI 0/0
PRI Span: 1 -- Processing IE 28 (cs0, Facility)
PRI Span: 1 -- Delayed processing IE 28 (cs0, Facility)
PRI Span: 1 ASN.1 dump
PRI Span: 1   Context Specific/C [10 0x0A] <AA> Len:6 <06>
PRI Span: 1     Context Specific [0 0x00] <80> Len:1 <01>
PRI Span: 1       <00> - "~"
PRI Span: 1     Context Specific [2 0x02] <82> Len:1 <01>
PRI Span: 1       <00> - "~"
PRI Span: 1   Context Specific/C [4 0x04] <A4> Len:7 <07>
PRI Span: 1     Integer(2 0x02) <02> Len:2 <02>
PRI Span: 1       <09 67> - "~g"
PRI Span: 1     Context Specific [1 0x01] <81> Len:1 <01>
PRI Span: 1       <01> - "~"
PRI Span: 1 ASN.1 end
PRI Span: 1   nfe NetworkFacilityExtension Context Specific/C [10 0x0A]
PRI Span: 1   sourceEntity Context Specific [0 0x00] = 0 0x0000
PRI Span: 1   destinationEntity Context Specific [2 0x02] = 0 0x0000
PRI Span: 1 REJECT Component Context Specific/C [4 0x04]
PRI Span: 1   invokeId Integer(2 0x02) = 2407 0x0967
PRI Span: 1   problemInvoke Context Specific [1 0x01] = 1 0x0001
PRI Span: 1   problem = Invoke: Unrecognized Operation
PRI Span: 1 ROSE REJECT:
PRI Span: 1     INVOKE ID: 2407
PRI Span: 1     PROBLEM: Invoke: Unrecognized Operation
    -- SIP/7114-0000789b is ringing
    -- Local/7114@from-queue-00006203;1 is ringing
    -- SIP/7114-0000789b answered Local/7114@from-queue-00006203;2
    -- Executing [s@macro-auto-blkvm:1] Set("SIP/7114-0000789b", "__MACRO_RESULT=") in new stack
    -- Executing [s@macro-auto-blkvm:2] Set("SIP/7114-0000789b", "CFIGNORE=") in new stack
    -- Executing [s@macro-auto-blkvm:3] Set("SIP/7114-0000789b", "MASTER_CHANNEL(CFIGNORE)=") in new stack
    -- Executing [s@macro-auto-blkvm:4] Set("SIP/7114-0000789b", "FORWARD_CONTEXT=from-internal") in new stack
    -- Executing [s@macro-auto-blkvm:5] Set("SIP/7114-0000789b", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
    -- Executing [s@macro-auto-blkvm:6] Macro("SIP/7114-0000789b", "blkvm-clr,") in new stack
    -- Executing [s@macro-blkvm-clr:1] Set("SIP/7114-0000789b", "SHARED(BLKVM,DAHDI/i1/89678500000-2603)=") in new stack
    -- Executing [s@macro-blkvm-clr:2] Set("SIP/7114-0000789b", "GOSUB_RETVAL=") in new stack
    -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/7114-0000789b", "") in new stack
    -- Executing [s@macro-auto-blkvm:7] ExecIf("SIP/7114-0000789b", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=7114)") in new stack
    -- Executing [s@macro-auto-blkvm:8] ExecIf("SIP/7114-0000789b", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=IT-7114)") in new stack
    -- Local/7114@from-queue-00006203;1 answered DAHDI/i1/89678500000-2603
    -- Stopped music on hold on DAHDI/i1/89678500000-2603
    -- Executing [h@macro-dial-one:1] Macro("Local/7114@from-queue-00006203;2", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("Local/7114@from-queue-00006203;2", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [s@macro-hangupcall:3] ExecIf("Local/7114@from-queue-00006203;2", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [s@macro-hangupcall:4] Hangup("Local/7114@from-queue-00006203;2", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/7114@from-queue-00006203;2' in macro 'hangupcall'
  == Spawn extension (macro-dial-one, h, 1) exited non-zero on 'Local/7114@from-queue-00006203;2'
  == Spawn extension (macro-dial-one, s, 42) exited non-zero on 'Local/7114@from-queue-00006203;2' in macro 'dial-one'
  == Spawn extension (from-queue-exten-internal, 7114, 4) exited non-zero on 'Local/7114@from-queue-00006203;2'
    -- Executing [h@from-internal:1] Hangup("DAHDI/i1/89678500000-2603", "") in new stack
  == Spawn extension (from-internal, h, 1) exited non-zero on 'DAHDI/i1/89678500000-2603'
  == Spawn extension (from-internal, 7086, 39) exited non-zero on 'DAHDI/i1/89678500000-2603'
PRI Span: 1 q931.c:6837 q931_hangup: Hangup other cref:29226
PRI Span: 1 q931.c:6594 __q931_hangup: ourstate Active, peerstate Active, hold-state Idle
PRI Span: 1 q931.c:5783 q931_disconnect: Call 29226 enters state 11 (Disconnect Request).  Hold state: Idle
PRI Span: 1
PRI Span: 1 > DL-DATA request
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=9
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: DISCONNECT (69)
PRI Span: 1 TEI=0 Transmitting N(S)=62, window is open V(A)=62 K=7
PRI Span: 1
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=9
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: DISCONNECT (69)
PRI Span: 1 > [08 02 81 90]
PRI Span: 1 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
PRI Span: 1 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
    -- Hungup 'DAHDI/i1/89678500000-2603'
PRI Span: 1
PRI Span: 1 < Protocol Discriminator: Q.931 (8)  len=14
PRI Span: 1 < TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent from originator)
PRI Span: 1 < Message Type: RELEASE (77)
PRI Span: 1 < [08 02 80 90]
PRI Span: 1 < Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: User (0)
PRI Span: 1 <                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
PRI Span: 1 < [7e 03 00 fe b5]
PRI Span: 1 < User-User Information (len= 5) [ 00 7e 35 ]
PRI Span: 1 Received message for call 0xa146258 on link 0xaba3b94 TEI/SAPI 0/0
PRI Span: 1 -- Processing IE 8 (cs0, Cause)
PRI Span: 1 -- Processing IE 126 (cs0, User-User)
PRI Span: 1 q931.c:8620 post_handle_q931_message: Call 29226 enters state 0 (Null).  Hold state: Idle
Span 1: Processing event PRI_EVENT_HANGUP(6)
PRI Span: 1 q931.c:6837 q931_hangup: Hangup other cref:29226
PRI Span: 1 q931.c:6594 __q931_hangup: ourstate Null, peerstate Release Request, hold-state Idle
PRI Span: 1
PRI Span: 1 > DL-DATA request
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=9
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: RELEASE COMPLETE (90)
PRI Span: 1 TEI=0 Transmitting N(S)=63, window is open V(A)=63 K=7
PRI Span: 1
PRI Span: 1 > Protocol Discriminator: Q.931 (8)  len=9
PRI Span: 1 > TEI=0 Call Ref: len= 2 (reference 29226/0x722A) (Sent to originator)
PRI Span: 1 > Message Type: RELEASE COMPLETE (90)
PRI Span: 1 > [08 02 81 90]
PRI Span: 1 > Cause (len= 4) [ Ext: 1  Coding: CCITT (ITU) standard (0)  Spare: 0  Location: Private network serving the local user (1)
PRI Span: 1 >                  Ext: 1  Cause: Normal Clearing (16), class = Normal Event (1) ]
PRI Span: 1 q931.c:6837 q931_hangup: Hangup other cref:29226
PRI Span: 1 q931.c:6594 __q931_hangup: ourstate Null, peerstate Null, hold-state Idle
PRI Span: 1 Destroying call 0xa146258, ourstate Null, peerstate Null, hold-state Idle