Re: проблема с очередью

От: Dmitry Akindinov <CGatePro_at_mx_ru>
Дата: Mon 05 Mar 2007 - 20:24:24 MSK

Здравствуйте,

Михаил Платонов wrote:
> Здравствуйте.
> С недавних пор начались проблемы: периодически не можем вытащить
> звонок из очереди и срываются исходящие звонки.
> В логах следующее:
>
> 18:03:57.688 2 PBXLEG-326892 enqueued
> 18:03:57.688 2 PBXLEG-326892 'passivequeue' created for pbx@domain.ru
> 18:03:57.688 4 PBXLEG-326892 INVITE request received
> 18:03:57.688 2 PBXLEG-326892 dialog started with stp3@192.168.2.162(sip:2440@192.168.4.154:5060), refresh=3600(active)(canTransfer)
> 18:03:57.689 2 PBXLEG-326892 peer authenticated as 'stp3@domain.ru'
> 18:03:57.689 4 PBXLEG-326892 remote SDP set: peer
> 18:03:57.689 4 PBXLEG-326892 signalling completed(init)
> 18:03:57.689 2 PBXLEG-326892 started(Main)
> 18:03:57.689 2 MEDIA-003429 created(444463F9) for PBXLEG-326892, audio port [0.0.0.0]:60060
> 18:03:57.689 4 PBXLEG-326892 session timer refreshed
> 18:03:57.689 4 PBXLEG-326892 local SDP: local media
> 18:03:57.689 2 PBXLEG-326892 accepting started
> 18:03:57.711 4 PBXLEG-326892 INVITE response ACK'ed
> 18:03:57.711 4 PBXLEG-326892 signalling completed
> 18:03:57.711 2 PBXLEG-326892 Queue(stp): find
> 18:03:57.742 4 PBXLEG-326892 playing WaitingInQueue_stp(389244 bytes)
> 18:03:59.507 4 PBXLEG-326892 playing 5(112764 bytes)
> 18:04:00.111 4 PBXLEG-326892 waiting for input (10 sec)
> 18:04:00.214 4 PBXLEG-326892 INFO request received
> 18:04:00.214 4 PBXLEG-326892 DTMF: *
> 18:04:00.214 4 PBXLEG-326892 playing ConnectingNow_stp(335996 bytes)
> 18:04:01.982 2 PBXLEG-326892 Queue(stp): head
> 18:04:02.088 2 PBXLEG-326892 starting bridge
> 18:04:02.164 1 PBXLEG-326892 bridge-start rejected by Peer. Error Code=Request Rejected
> 18:04:02.164 1 PBXLEG-326892 bridge response error: Error Code=Request Rejected

Вот в это время надо внимательно посмотреть на транспортный лог SIP: чтобы соединить ждущего в очереди клиента и пришедшего только что агента им обоим будет послан re-INVITE с указанием нового SDP. Так их медиа каналы должна отключиться от програмок и переключиться друг на друга. Но, похоже, со стороны клиента re-INVITE был отвергнут. Это может быть особенностью SIP устройства на той стороне. Например, многи PSTN шлюзы отвергают такие операции. Короче говоря, надо смотреть лог. Решением же обычно бывает соединение с такими клиентами через B2BUA приложение, организующее медиа миксер: gatewayincoming{queue_uri,mixer}

> 18:04:02.164 4 PBXLEG-326892 playing Failure(110318 bytes)
> 18:04:04.401 2 PBXLEG-326892 Queue(stp): find
> 18:04:04.432 4 PBXLEG-326892 playing WaitingInQueue_stp(389244 bytes)
> 18:04:06.442 4 PBXLEG-326892 playing 4(144508 bytes)
> 18:04:07.219 4 PBXLEG-326892 waiting for input (10 sec)
> 18:04:07.570 4 PBXLEG-326892 BYE request received
> 18:04:07.570 2 PBXLEG-326892 peer disconnected
> 18:04:07.570 4 PBXLEG-326892 Event([disconnect]) enqueued
> 18:04:07.570 4 PBXLEG-326892 Event([disconnect]) from 000000
> 18:04:07.570 2 PBXLEG-326892 program stopped
> 18:04:07.570 2 PBXLEG-326892 finishing
> 18:04:07.773 2 PBXLEG-326892 dequeued
> 18:04:07.773 4 PBXLEG-326892 closing
>
> или же

А тут у вас, во-первых, необ очередях, а во-вторых - вообще путаница: звонок явно направлен через B2bUA gatewaycaller, но в настройках PSTN имя домена шлюза указано как gway1.sipgw. Правильнее было бы взять домен шлюза и Via из записи SIP -> Gateways -> gway1 и записать их (вместе с паролем) в настройки PSTN. Сейас же авторизация на шлюзе выполняется средствами той самой записи gway1, что есть хак: производится поаытка использовать для digest аутентификации nonce, который был плучен во время последней регистрации. Неудивительно, что шлюз отвечает: "nonce протух" и выдает новый. Но sipgw новый использовать не может.

> 19:01:48.482 2 PBXLEG-332602 enqueued
> 19:01:48.482 2 PBXLEG-332602 &#39;gatewaycaller&#39; created for pbx@domain.ru
> 19:01:48.482 5 PBXLEG-332602 REQUEST posted
> 19:01:48.484 4 PBXLEG-332602 INVITE request received
> 19:01:48.484 2 PBXLEG-332602 dialog started with 1234@domain.ru (sip:1234@192.168.7.140:5060), refresh=1800(passive)(canUpdate)(canTransfer)
> 19:01:48.484 2 PBXLEG-332602 peer authenticated as &#39;1234@domain.ru&#39;
> 19:01:48.484 4 PBXLEG-332602 remote SDP set: peer
> 19:01:48.484 5 PBXLEG-332602 leg state=8
> 19:01:48.484 4 PBXLEG-332602 signalling completed(init)
> 19:01:48.484 5 PBXLEG-332602 pbx state=1(running)
> 19:01:48.484 2 PBXLEG-332602 started(Main)
> 19:01:48.484 2 PBXLEG-332602 ProgramLog: "calling &#39;89066439281&#39;..."
> 19:01:48.484 2 PBXLEG-332602 ProgramLog: "callerLeg:{\"\"=\"sip:89066439281@gway1.sipgw\";Call-ID=CALL_ID416_0015E9EF1708_T415373273@sip:1234@192.168.7.140.gwout;From=\"sip:1234@domain.ru\";Max-Forwards=#69;activeSide=YES;callBridged=YES;impersonate=1234@domain.ru;}"
[]
> 19:01:48.485 4 PBXLEG-332604 SIGNAL-006464 sending INVITE sip:89066439281@gway1.sipgw
[]
> 19:01:48.773 1 PBXLEG-332602 bridge-start rejected by Peer. Error Code=401-AUTH error: stale nonce
> 19:01:48.773 1 PBXLEG-332602 bridge response error: Error Code=Request Rejected
> 19:01:48.773 5 PBXLEG-332602 pbx state=1(running)
> 19:01:48.773 5 PBXLEG-332602 timeout cleared
> 19:01:48.773 2 PBXLEG-332602 ProgramLog: "call failed: errorCode=401-AUTH error: stale nonce"
> 19:01:49.024 4 PBXLEG-332602 session timer refreshed
> 19:01:49.024 5 PBXLEG-332602 leg state=16
> 19:01:49.024 2 PBXLEG-332602 rejecting done
> 19:01:49.024 5 PBXLEG-332602 pbx state=1(running)
> 19:01:49.024 2 PBXLEG-332602 program stopped
> 19:01:49.024 2 PBXLEG-332602 finishing
> 19:01:49.024 2 PBXLEG-332602 dequeued
> 19:01:49.024 4 PBXLEG-332602 closing
>
>
> Более ничего не говорит. Версия CGP 5.1.7.
> Никто не встречался ни с чем подобным?
>
>

-- 
Best regards,
Dmitry Akindinov
Получено Mon Mar 05 17:24:25 2007

Этот архив был сгенерирован hypermail 2.1.8 : Mon 05 Mar 2007 - 21:13:57 MSK