16 12:32:32] DEBUG[26114] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[16 12:32:32] DEBUG[26114] chan_sip.c: Setting NAT on RTP to On
[16 12:32:32] DEBUG[26114] chan_sip.c: Setting NAT on UDPTL to On
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing session-level SDP o=- 311412364 311412364 IN IP4 10.210.13.249... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing session-level SDP c=IN IP4 10.210.13.249... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:2 G726-32/8000... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:4 G723/8000... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729a/8000... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 G726-40/8000... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:97 G726-24/8000... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:98 G726-16/8000... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=ptime:30... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK.
[16 12:32:32] DEBUG[26114] chan_sip.c: We're settling with these formats: 0x8 (alaw)
[16 12:32:32] DEBUG[26114] chan_sip.c: Checking SIP call limits for device 4017
[16 12:32:32] DEBUG[26114] chan_sip.c: Updating call counter for incoming call
[16 12:32:32] DEBUG[26114] devicestate.c: Notification of state change to be queued on device/channel SIP/4017
[16 12:32:32] DEBUG[26114] chan_sip.c: *** Our native formats are 0x8 (alaw)
[16 12:32:32] DEBUG[26114] chan_sip.c: *** Joint capabilities are 0x8 (alaw)
[16 12:32:32] DEBUG[26114] chan_sip.c: *** Our capabilities are 0x8 (alaw)
[16 12:32:32] DEBUG[26088] devicestate.c: No provider found, checking channel drivers for SIP - 4017
[16 12:32:32] DEBUG[26114] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x8 (alaw)
[16 12:32:32] DEBUG[26088] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[26114] chan_sip.c: This channel will not be able to handle video.
[16 12:32:32] DEBUG[26088] devicestate.c: Changing state for SIP/4017 - state 2 (In use)
[16 12:32:32] DEBUG[28361] audiohook.c: Read factory 0xb79b8018 was pretty quick last time, waiting for them.
[16 12:32:32] DEBUG[26089] devicestate.c: No provider found, checking channel drivers for sip - 4017
[16 12:32:32] DEBUG[26114] chan_sip.c: build_route: Contact hop: "4017" <sip:4017@10.210.13.249:5060>
[16 12:32:32] DEBUG[26089] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[26110] app_queue.c: Device 'SIP/4017' changed to state '2' (In use)
[16 12:32:32] DEBUG[26114] chan_sip.c: SIP/4017-0000035c: New call is still down.... Trying...
[16 12:32:32] DEBUG[26114] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.210.13.249:5060
[16 12:32:32] DEBUG[26114] devicestate.c: Notification of state change to be queued on device/channel SIP/4017
[16 12:32:32] DEBUG[26088] devicestate.c: No provider found, checking channel drivers for SIP - 4017
[16 12:32:32] DEBUG[26088] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[26088] devicestate.c: Changing state for SIP/4017 - state 2 (In use)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] DEBUG[26089] devicestate.c: No provider found, checking channel drivers for sip - 4017
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mKnock-knock. Who is trying to call?
 This might be agent, exten or call from trusted trunkESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[26089] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] DEBUG[26110] app_queue.c: Device 'SIP/4017' changed to state '2' (In use)
[16 12:32:32] DEBUG[28361] audiohook.c: Read factory 0xb79b8018 was pretty quick last time, waiting for them.
[16 12:32:32] DEBUG[26089] devicestate.c: No provider found, checking channel drivers for sip - 4017
[16 12:32:32] DEBUG[26114] chan_sip.c: build_route: Contact hop: "4017" <sip:4017@10.210.13.249:5060>
[16 12:32:32] DEBUG[26089] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[26110] app_queue.c: Device 'SIP/4017' changed to state '2' (In use)
[16 12:32:32] DEBUG[26114] chan_sip.c: SIP/4017-0000035c: New call is still down.... Trying...
[16 12:32:32] DEBUG[26114] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.210.13.249:5060
[16 12:32:32] DEBUG[26114] devicestate.c: Notification of state change to be queued on device/channel SIP/4017
[16 12:32:32] DEBUG[26088] devicestate.c: No provider found, checking channel drivers for SIP - 4017
[16 12:32:32] DEBUG[26088] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[26088] devicestate.c: Changing state for SIP/4017 - state 2 (In use)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] DEBUG[26089] devicestate.c: No provider found, checking channel drivers for sip - 4017
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mKnock-knock. Who is trying to call?
 This might be agent, exten or call from trusted trunkESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[26089] chan_sip.c: Checking device state for peer 4017
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] DEBUG[26110] app_queue.c: Device 'SIP/4017' changed to state '2' (In use)
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal:2] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mТолько звонки от внутренних каналов
 должны тут быть. Если транк или Local то это баг!ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal:3] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m__CallUniqueId=1260955952.879ESC[0;3
7;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Gosub'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal:4] ESC[1;36;40mGosubESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mcheckinternalcaller,s,1ESC[0;37;40
m") in new stack
[16 12:32:32] DEBUG[28366] app_stack.c: Channel SIP/4017-0000035c has no datastore, so we're allocating one.
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mПроверяем звонок изнутри. Выставля
ем OurNum и PortNumESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:2] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCDR(userfield)=InternalESC[0;37;40m
") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:3] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCallerType=PortESC[0;37;40m") in ne
w stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '4017'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:4] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mPortNum=4017ESC[0;37;40m") in new s
tack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '321.3'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:5] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mPortName=321.3ESC[0;37;40m") in new
 stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '4017'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:6] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mOurNum=4017ESC[0;37;40m") in new st
ack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '321.3'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:7] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mOurName=321.3ESC[0;37;40m") in new
stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:8] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mNumCos=1ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '172'
[16 12:32:32] DEBUG[28366] pbx.c: Function result is 'no'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:9] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mDb -  172. Db agent only callers -
  noESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Function result is 'no'
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '0'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:10] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m0?cong,1ESC[0;37;40m") in new s
tack
[16 12:32:32] DEBUG[28366] pbx.c: Not taking any branch
[16 12:32:32] DEBUG[28366] pbx.c: Function result is 'yes'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:11] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCallerMonitor=yesESC[0;37;40m") in
 new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:12] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?:portESC[0;37;40m") in new st
ack
[16 12:32:32] DEBUG[28366] pbx.c: Not taking any branch
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '172'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:13] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mOurNum=172ESC[0;37;40m") in new st
ack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:14] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mOurName="Biryukova Natalya"ESC[0;3
7;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:15] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCALLERID(name)=Biryukova Natalya
ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:16] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCALLERID(num)=172ESC[0;37;40m") in
 new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:17] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mNumCos=1ESC[0;37;40m") in new stac
k
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:18] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCallerType=AgentESC[0;37;40m") in
new stack
[16 12:32:32] DEBUG[28363] audiohook.c: Read factory 0x9f4e880 was pretty quick last time, waiting for them.
[16 12:32:32] DEBUG[28366] pbx.c: Function result is 'yes'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:19] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCallerMonitor=yesESC[0;37;40m") in[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Return'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@checkinternalcaller:20] ESC[1;36;40mReturnESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Goto'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal:5] ESC[1;36;40mGotoESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40minternal-cos1,89262337843,1ESC[0;37
;40m") in new stack
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (internal-cos1,89262337843,1)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal-cos1:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Gosub'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [89262337843@internal-cos1:2] ESC[1;36;40mGosubESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mdial-trunk6,00279262337843,1
ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Gosub'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dial-trunk6:1] ESC[1;36;40mGosubESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mdialout,s,1(00279262337843,6
)ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] app_stack.c: Setting 'ARG1' to '00279262337843'
[16 12:32:32] DEBUG[28366] app_stack.c: Setting 'ARG2' to '6'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@dialout:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mArg1 = 00279262337843, Arg2 = 6, Arg3 = , gn =
 sspbxESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Goto'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@dialout:2] ESC[1;36;40mGotoESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m00279262337843,1ESC[0;37;40m") in new stack
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (dialout,00279262337843,1)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mArg1 = 00279262337843, Arg2 = 6,
Arg3 = , gn = sspbxESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Gosub'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:2] ESC[1;36;40mGosubESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mwebcall,s,1ESC[0;37;40m") in new
 stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '0'
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@webcall:1] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?nowebcallESC[0;37;40m") in new stack
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (webcall,s,4)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@webcall:4] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mExit from macro WebCallESC[0;37;40m") in new s
tack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Return'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@webcall:5] ESC[1;36;40mReturnESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:3] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCalledNum=00279262337843ESC[0;37;4
0m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:4] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mTrunkId=6ESC[0;37;40m") in new sta
ck
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:5] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCallType=OUTGOINGESC[0;37;40m") in
 new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:6] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mTrunkOutCid=ESC[0;37;40m") in new
stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '0'
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:7] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?dialESC[0;37;40m") in new sta
ck
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (dialout,00279262337843,9)
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '172'
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '11'
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '0'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:9] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m0?ldpESC[0;37;40m") in new stac
k
[16 12:32:32] DEBUG[28366] pbx.c: Not taking any branch
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '172'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:10] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mTrunkOutCid=74959505678ESC[0;37;4
0m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Goto'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:11] ESC[1;36;40mGotoESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mlogESC[0;37;40m") in new stack
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (dialout,00279262337843,17)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Gosub'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:17] ESC[1;36;40mGosubESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mlogcall,s,1ESC[0;37;40m") in ne
w stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mThis is macro logcallESC[0;37;40m") in new sta
ck
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '172'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:2] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mBLINDTR = . CallerCos - 1. Calltype is OUTGOIN
G. CalleridNum - 172ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:3] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCalled Number = 00279262337843. caller - Agent
. Calledtype - ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:4] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mcalleragentnum - 172. calleragentext - 4017. c
alledagentext - ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:5] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCallerAgentExt=""ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'ExecIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:6] ESC[1;36;40mExecIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?Set(CallerAgentExt="\[4017\]")ESC[0;37;40m
") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [s@logcall:7] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?outgo,1ESC[0;37;40m") in new stack
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (logcall,outgo,1)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [outgo@logcall:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mOutgoing callESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [outgo@logcall:2] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mTrunkLogString=6ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'ExecIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [outgo@logcall:3] ESC[1;36;40mExecIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?Set(TrunkLogString=6[74959505678])ESC[
0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [outgo@logcall:4] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?rec,1:norec,1ESC[0;37;40m") in new sta
ck
[16 12:32:32] VERBOSE[28366] logger.c:     -- Goto (logcall,rec,1)
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mRecording is neededESC[0;37;40m") in new sta
ck
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:2] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mMonitoring OUTGOING call with CallerID  to e
xt. 00279262337843ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1260955952'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:3] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mUNIQUEID1=1260955952ESC[0;37;40m") in new sta
ck
[16 12:32:32] DEBUG[28016] audiohook.c: Read factory 0xb75d72b8 was pretty quick last time, waiting for them.
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '879'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:4] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mUNIQUEID2=879ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Expression result is '0'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:5] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m0?recaftertransfer,1ESC[0;37;40m") in new
stack
[16 12:32:32] DEBUG[28366] pbx.c: Not taking any branch
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:6] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m__CallMonitorFilename=1260955952-879ESC[0;37;
40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '172'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'QueueLog'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:7] ESC[1;36;40mQueueLogESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1260955952.879,172,Agent[4017],OUTGOING,
00279262337843|6[74959505678]ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] res_config_mysql.c: MySQL RealTime: Connection okay.
[16 12:32:32] DEBUG[28366] res_config_mysql.c: MySQL RealTime: Insert SQL: INSERT into queue_log_raw (time, callid, queuename, agent, event, data) values ('1260955952', '1260955952.879', '172', 'Agent[40
17]', 'OUTGOING', '00279262337843|6[74959505678]')
[16 12:32:32] DEBUG[28366] res_config_mysql.c: MySQL RealTime: row inserted on table: queue_log_raw, id: 661103
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'MixMonitor'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:8] ESC[1;36;40mMixMonitorESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m/var/calls/1260955952-879.wav,abESC[0;
37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Return'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [rec@logcall:9] ESC[1;36;40mReturnESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mESC[0;37;40m") in new stack
[16 12:32:32] VERBOSE[28367] logger.c:   == Begin MixMonitor Recording SIP/4017-0000035c
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:18] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '1'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'ExecIf'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:19] ESC[1;36;40mExecIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?Set(CALLERID(num)=7495950567
8)ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Function result is '"Biryukova Natalya" <74959505678>'
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'UserEvent'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:20] ESC[1;36;40mUserEventESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mTrunkOut,Channel: SIP/4017-
0000035c,Extension: 00279262337843,Uniqueid: 1260955952.879,CallerID: "Biryukova Natalya" <74959505678>,TrunkName: sspbxESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:21] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m__TRANSFER_CONTEXT=transfered-con
textESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Set'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:22] ESC[1;36;40mSetESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mGROUP(trunks)=6ESC[0;37;40m") in
new stack
[16 12:32:32] DEBUG[28366] pbx.c: Launching 'Dial'
[16 12:32:32] VERBOSE[28366] logger.c:     -- Executing [00279262337843@dialout:23] ESC[1;36;40mDialESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mSIP/sspbx/00279262337843,100,T
ESC[0;37;40m") in new stack
[16 12:32:32] DEBUG[28366] chan_sip.c: Asked to create a SIP channel with formats: 0x8 (alaw)
[16 12:32:32] VERBOSE[28366] logger.c:   == Using SIP RTP CoS mark 5
[16 12:32:32] DEBUG[28315] audiohook.c: Write factory 0xb78801e4 was pretty quick last time, waiting for them.
[16 12:32:32] VERBOSE[28366] logger.c:   == Using UDPTL CoS mark 5
[16 12:32:32] DEBUG[28366] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[16 12:32:32] DEBUG[28366] chan_sip.c: Setting NAT on RTP to Off
[16 12:32:32] DEBUG[28366] chan_sip.c: Setting NAT on UDPTL to Off
[16 12:32:32] DEBUG[28366] acl.c: Found IP address for this socket
[16 12:32:32] DEBUG[28366] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.220.32.194:5060
[16 12:32:32] DEBUG[28366] chan_sip.c: *** Our native formats are 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: *** Joint capabilities are 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: *** Our capabilities are 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: *** Our preferred formats from the incoming channel are 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: This channel will not be able to handle video.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable DIALEDTIME.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable ANSWEREDTIME.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable DIALEDPEERNAME.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable DIALEDPEERNUMBER.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable DIALSTATUS.
[16 12:32:32] DEBUG[28366] channel.c: Copying hard-transferable variable TRANSFER_CONTEXT.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable GOSUB_RETVAL.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable MIXMONITOR_FILENAME.
[16 12:32:32] DEBUG[28366] channel.c: Copying hard-transferable variable CallMonitorFilename.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable UNIQUEID2.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable UNIQUEID1.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable TrunkLogString.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable CallerAgentExt.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable TrunkOutCid.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable CallType.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable TrunkId.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable CalledNum.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable ARG2.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable ARG1.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable CallerMonitor.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable DB_RESULT.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable CallerType.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable NumCos.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable OurName.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable OurNum.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable PortName.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable PortNum.
[16 12:32:32] DEBUG[28366] channel.c: Copying hard-transferable variable CallUniqueId.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable SIPCALLID.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable SIPDOMAIN.
[16 12:32:32] DEBUG[28366] channel.c: Not copying variable SIPURI.
[16 12:32:32] DEBUG[28366] chan_sip.c: Outgoing Call for 00279262337843
[16 12:32:32] DEBUG[28366] chan_sip.c: Updating call counter for outgoing call
[16 12:32:32] DEBUG[28366] chan_sip.c: ** Our capability: 0x8 (alaw) Video flag: False Text flag: False
[16 12:32:32] DEBUG[28366] chan_sip.c: ** Our prefcodec: 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: -- Done with adding codecs to SDP
[16 12:32:32] DEBUG[28366] channel.c: Internal timing is enabled (option_internal_timing=1048576 chan->timingfd=116)
[16 12:32:32] DEBUG[28366] chan_sip.c: Done building SDP. Settling with this capability: 0x8 (alaw)
[16 12:32:32] DEBUG[28366] chan_sip.c: Initializing initreq for method INVITE - callid 0c512a95415a52632717280a7fb635ed@10.220.32.194
[16 12:32:32] DEBUG[28366] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.220.32.240:5060
[16 12:32:32] VERBOSE[28366] logger.c:     -- Called sspbx/00279262337843
[16 12:32:37] VERBOSE[28366] logger.c:     -- SIP/sspbx-0000035d is making progress passing it to SIP/4017-0000035c
[16 12:32:37] VERBOSE[28366] logger.c:     -- SIP/sspbx-0000035d is making progress passing it to SIP/4017-0000035c
[16 12:32:46] VERBOSE[28366] logger.c:     -- SIP/sspbx-0000035d answered SIP/4017-0000035c
[16 12:35:20] DEBUG[28366] channel.c: Didn't get a frame from channel: SIP/4017-0000035c
[16 12:35:20] DEBUG[28366] channel.c: Bridge stops bridging channels SIP/4017-0000035c and SIP/sspbx-0000035d
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [h@dialout:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mHangup in dialoutESC[0;37;40m") in new stack
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'Gosub'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [h@dialout:2] ESC[1;36;40mGosubESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40msip-transfer-check,s,1ESC[0;37;40m") in new s
tack
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [s@sip-transfer-check:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mПроверим был ли SIP Transfer только
 для отвеченных звонковESC[0;37;40m") in new stack
[16 12:35:20] DEBUG[28366] pbx.c: Function result is 'SIP/sspbx-0000035d'
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [s@sip-transfer-check:2] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCDR(dstchannel) - SIP/sspbx-0000035
d. DIALEDPEERNAME - SIP/sspbx-0000035d. Channel 000035ESC[0;37;40m") in new stack
[16 12:35:20] DEBUG[28366] pbx.c: Expression result is '1'
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [s@sip-transfer-check:3] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m1?:endcheck,1ESC[0;37;40m") in ne
w stack
[16 12:35:20] DEBUG[28366] pbx.c: Not taking any branch
[16 12:35:20] DEBUG[28366] pbx.c: Expression result is '0'
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'GotoIf'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [s@sip-transfer-check:4] ESC[1;36;40mGotoIfESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40m0?:endcheck,1ESC[0;37;40m") in ne
w stack
[16 12:35:20] VERBOSE[28366] logger.c:     -- Goto (sip-transfer-check,endcheck,1)
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'NoOp'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [endcheck@sip-transfer-check:1] ESC[1;36;40mNoOpESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mCall was not transferedESC[0
;37;40m") in new stack
[16 12:35:20] DEBUG[28366] pbx.c: Launching 'Return'
[16 12:35:20] VERBOSE[28366] logger.c:     -- Executing [endcheck@sip-transfer-check:2] ESC[1;36;40mReturnESC[0;37;40m("ESC[1;35;40mSIP/4017-0000035cESC[0;37;40m", "ESC[1;35;40mESC[0;37;40m") in new stac
k
[16 12:35:20] DEBUG[28388] audiohook.c: Write factory 0xb715382c was pretty quick last time, waiting for them.
[16 12:35:20] DEBUG[28366] cdr_addon_mysql.c: Inserting a CDR record.
[16 12:35:20] DEBUG[28366] cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr (calldate,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid,userfie
ld) VALUES ('2009-12-16 12:32:32','4017','00279262337843','dialout','SIP/4017-0000035c','SIP/sspbx-0000035d','Dial','SIP/sspbx/00279262337843,100,T','168','154','ANSWERED','3','1260955952.879','Internal'
)
[16 12:35:20] DEBUG[28366] channel.c: Hanging up channel 'SIP/sspbx-0000035d'
[16 12:35:20] DEBUG[28366] chan_sip.c: Hangup call SIP/sspbx-0000035d, SIP callid 0c512a95415a52632717280a7fb635ed@10.220.32.194
[16 12:35:20] DEBUG[28366] chan_sip.c: Trying to put 'BYE sip:002' onto UDP socket destined for 10.220.32.240:5060