Задержка при входящем звонке на Call Group

[ded]

Пауза возникает после проигрывания приветствия, примерно 15-20 секунд. Если приветствие отключить, пауза будет сразу

1) Где-то стоит в настройках IVR в вэб интерфейсе Wait exten 15 sec?
2) кривое приветствие - файл содержит 15 сек тишины в конце?
Всё это проверяется.

[Egor Bogdanov]

IVR не используется, без приветствия пауза все равно есть

[ded]

Консоль всё расскажет.

[virus_net]

Между чем и чем, по логам, пауза то возникает ?
После:

Код: Выделить всё

-- Executing [8000@from-did-direct:22] Playback("SIP/ZAOPROXY-0000001e", "ru/prepaid-enter-number-u-calling-1-or-011") in new stack

или

Код: Выделить всё

-- Goto (macro-dial,s,3)

?
Что обрабатывается (последнее в CLI) во время паузы ?

[Egor Bogdanov]

Пауза возникает после строки:

Код: Выделить всё

   -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi

[ded]

Включайте дебаг AGI и изучайте выхлоп.
agi set debug on

[awsswa]

без временных меток выкхоп с консоли читается очень тяжело

[Egor Bogdanov]

на жирной строке возникает пауза

Код: Выделить всё

    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_request: dialparties.agi
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_channel: SIP/ZAOPROXY-00000012
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_language: en
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_type: SIP
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_uniqueid: 1407399790.18
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_version: 1.8.20.0
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_callerid: Anonymous
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_calleridname: Anonymous
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_callingpres: 0
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_callingani2: 0
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_callington: 0
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_callingtns: 0
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_dnid: ZAOPROXY
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_rdnis: unknown
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_context: macro-dial
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_extension: s
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_priority: 3
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_enhanced: 0.0
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_accountcode:
<SIP/ZAOPROXY-00000012>AGI Tx >> agi_threadid: -1222198384

[b]<SIP/ZAOPROXY-00000012>AGI Tx >>[/b]
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Starting New Dialparties.agi" 1
 dialparties.agi: Starting New Dialparties.agi
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE CWINUSEBUSY
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (true)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ASTVERSION
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (1.8.20.0)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ASTCHANDAHDI
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (1)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE CWIGNORE
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE CFIGNORE
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE HAS_EXTENSION_STATE
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Caller ID name is 'Anonymous' number is 'Anonymous'" 1
 dialparties.agi: Caller ID name is 'Anonymous' number is 'Anonymous'
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE QUEUEWAIT
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << SET VARIABLE __KEEPCID "TRUE"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << SET VARIABLE DIALSTATUS_CW ""
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ARG1
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (60)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ARG2
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (m(default)t)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE RingGroupMethod
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (ringall)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ALERT_INFO
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE SIPADDHEADER
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE FMGRP
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (8000)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE PR_DIALSTATUS
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE NODEST
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (8000)
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE RINGGROUP_INDEX
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE USE_CONFIRMATION
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "USE_CONFIRMATION:  'FALSE'" 5
       > dialparties.agi: USE_CONFIRMATION:  'FALSE'
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "RINGGROUP_INDEX:   ''" 5
       > dialparties.agi: RINGGROUP_INDEX:   ''
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Methodology of ring is  'ringall'" 1
 dialparties.agi: Methodology of ring is  'ringall'
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ARG3
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (8000-8001)
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Added extension 8000 to extension map" 3
    -- dialparties.agi: Added extension 8000 to extension map
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Added extension 8001 to extension map" 3
    -- dialparties.agi: Added extension 8001 to extension map
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE ARG4
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE SCREEN
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE FROM_DID
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (s)
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "AMPUSER" "8000/screen"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8001 has call screening off" 4
       > dialparties.agi: Extension 8001 has call screening off
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CF" "8000"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8000 cf is disabled" 3
    -- dialparties.agi: Extension 8000 cf is disabled
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CF" "8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8001 cf is disabled" 3
    -- dialparties.agi: Extension 8001 cf is disabled
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "DND" "8000"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8000 do not disturb is disabled" 3
    -- dialparties.agi: Extension 8000 do not disturb is disabled
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "DND" "8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8001 do not disturb is disabled" 3
    -- dialparties.agi: Extension 8001 do not disturb is disabled
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CW" "8000"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CFB" "8000"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CFU" "8000"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "extnum 8000 has:  cw: 0; hascfb: 0 [] hascfu: 0 []" 4
       > dialparties.agi: extnum 8000 has:  cw: 0; hascfb: 0 [] hascfu: 0 []
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE EXTENSION_STATE(8000)
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (NOT_INUSE)
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "EXTENSION_STATE: 0 (NOT_INUSE)" 1
 dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8000 has ExtensionState: 0" 1
 dialparties.agi: Extension 8000 has ExtensionState: 0
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Checking CW and CFB status for extension 8000" 3
    -- dialparties.agi: Checking CW and CFB status for extension 8000
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "AMPUSER" "8000/device"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (8000)
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "DEVICE" "8000/dial"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (SIP/8000)
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE DEL "CALLTRACE" "8000"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "DbDel CALLTRACE/8000 - Caller ID is not defined" 3
    -- dialparties.agi: DbDel CALLTRACE/8000 - Caller ID is not defined
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CW" "8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CFB" "8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "CFU" "8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "extnum 8001 has:  cw: 0; hascfb: 0 [] hascfu: 0 []" 4
       > dialparties.agi: extnum 8001 has:  cw: 0; hascfb: 0 [] hascfu: 0 []
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << GET VARIABLE EXTENSION_STATE(8001)
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (NOT_INUSE)
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "EXTENSION_STATE: 0 (NOT_INUSE)" 1
 dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Extension 8001 has ExtensionState: 0" 1
 dialparties.agi: Extension 8001 has ExtensionState: 0
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Checking CW and CFB status for extension 8001" 3
    -- dialparties.agi: Checking CW and CFB status for extension 8001
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "AMPUSER" "8001/device"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (8001)
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE GET "DEVICE" "8001/dial"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1 (SIP/8001)
<SIP/ZAOPROXY-00000012>AGI Rx << DATABASE DEL "CALLTRACE" "8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "DbDel CALLTRACE/8001 - Caller ID is not defined" 3
    -- dialparties.agi: DbDel CALLTRACE/8001 - Caller ID is not defined
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << SET VARIABLE FILTERED_DIAL "8000-8001"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "Filtered ARG3: 8000-8001" 3
    -- dialparties.agi: Filtered ARG3: 8000-8001
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "NODEST: 8000 adding M(auto-blkvm) to dialopts: m(default)tM(auto-blkvm)" 4
       > dialparties.agi: NODEST: 8000 adding M(auto-blkvm) to dialopts: m(default)tM(auto-blkvm)
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << VERBOSE "NODEST: 8000 blkvm enabled macro already in dialopts: m(default)tM(auto-blkvm)" 4
       > dialparties.agi: NODEST: 8000 blkvm enabled macro already in dialopts: m(default)tM(auto-blkvm)
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << SET VARIABLE ds "SIP/8000&SIP/8001,60,m(default)tM(auto-blkvm)"
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=1
<SIP/ZAOPROXY-00000012>AGI Rx << SET PRIORITY normdial
<SIP/ZAOPROXY-00000012>AGI Tx >> 200 result=0

[awsswa]

кроме этого же места пока не вижу где возможна задерка

-- Executing [8000@from-did-direct:22] Playback("SIP/ZAOPROXY-0000001e", "ru/prepaid-enter-number-u-calling-1-or-011") in new stack
-- <SIP/ZAOPROXY-0000001e> Playing 'ru/prepaid-enter-number-u-calling-1-or-011.gsm' (language 'en')
-- Executing [8000@from-did-direct:23] GotoIf("SIP/ZAOPROXY-0000001e", "0?doconfirm") in new stack

[virus_net]

на жирной строке возникает пауза

точно уверены ? т.к. странно это.
а если закомментить строчку с вызовом AGI dialparties.agi в dialplan`е, то пауза пропадает ?