Перевод звонков

Доброго времени суток коллеги,

не могу понять, почему не работает переадресация c Sipnet.

Если один клиент общается через sip с другим (оба звонка внутри cgp), то перевести звонок на другого клиента внутри cgp не представляет никаких проблем.

Однако, если звонок приходит из вне (в моем случае из sipnet), то происходит следующее:

1. Звонок поступает на pbx@pmh.ru - он переадресует на 101 (псевдоним
учетной записи sekretar@pmh.ru))

2. Секретарь поднимает трубку и переадресует звонок на 301 (псевдоним
учетной записи kondor7@pmh.ru)
3. На kondor7 звонок поступает от секретаря. После того, как секретарь информирует о том, кто звонит, она вешает трубку. 4. Далее, вместо того, чтобы переведенный звонок прошел на kondor7@pmh.ru он почему-то возвращается на секретаря.

Не знаю, имеет ли это отношение к делу, но мелодия ожидания при внешнем звонке воспроизводится с помехами, когда как если переводить звонки между сотрудниками внутри офиса - мелодия играется нормально.

Если честно, то я даже не предполагаю, в какую сторону копать... Логи посмотрел, но ничего интересного не нашел. Офис (клиенты сервера) находится за NATом, сам CGP стоит на выделенном серваке без всяких NATов. Лог во вложении. Имена для целей рассылки я не менял.

Заранее благодарю.

--
Kondratenko Boris <kondor7@pmh.ru>
ПаккерМаш

15:48:00.641 2 SIPDATA-151403 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 INVITE(930 bytes) sip:301@mail-g.pmh.ru;user=phone
15:48:00.641 2 SIPDATA-151403 created SIPS-070432
15:48:00.641 2 SIPS-070432 [151403] INVITE sip:301@mail-g.pmh.ru;user=phone from udp[95.215.137.139]:35764
15:48:00.642 2 SIPDATA-151404 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 100-INVITE(333 bytes)
15:48:00.642 2 SIPS-070432 [151404] 100-INVITE(trying) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:00.642 2 SIPS-070432 created SIGNAL-074754
15:48:00.642 2 SIGNAL-074754 SIPS-070432: INVITE sip:301@mail-g.pmh.ru;user=phone
15:48:00.642 3 SIGNAL-074754 rejecting INVITE from sekretar@pmh.ru w/o authentication
15:48:00.642 2 SIGNAL-074754 401 generated
15:48:00.642 2 SIGNAL-074754 401 relaying
15:48:00.642 2 SIGNAL-074754 releasing
15:48:00.642 2 SIPDATA-151405 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 401-INVITE(478 bytes)
15:48:00.642 2 SIPS-070432 [151405] 401-INVITE(final) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:00.755 2 SIPDATA-151406 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 ACK(406 bytes) sip:301@mail-g.pmh.ru;user=phone
15:48:00.755 2 SIPDATA-151406 sent to SIPS-070432
15:48:00.755 2 SIPS-070432 [151406] confirmed: ACK received
15:48:00.755 2 SIPDATA-151407 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 INVITE(1204 bytes) sip:301@mail-g.pmh.ru;user=phone
15:48:00.755 2 SIPDATA-151407 created SIPS-070434
15:48:00.755 2 SIPS-070434 [151407] INVITE sip:301@mail-g.pmh.ru;user=phone from udp[95.215.137.139]:35764
15:48:00.755 2 SIPDATA-151408 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 100-INVITE(332 bytes)
15:48:00.755 2 SIPS-070434 [151408] 100-INVITE(trying) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:00.755 2 SIPS-070434 created SIGNAL-074756
15:48:00.755 2 SIGNAL-074756 SIPS-070434: INVITE sip:301@mail-g.pmh.ru;user=phone
15:48:00.756 2 DIALOG-001425 created(authOut)
15:48:00.756 2 SIGNAL-074756 INVITE sip:301@mail-g.pmh.ru;user=phone via sip:301@mail-g.pmh.ru;user=phone
15:48:00.757 2 SIGNAL-074756 kondor7@pmh.ru has 1 registration(s)
15:48:00.757 2 SIGNAL-074756 {1} sent to SIPC-004788: INVITE sip:kondor7@95.215.137.139:35909;ob
15:48:00.757 2 SIPC-004788 INVITE sip:kondor7@95.215.137.139:35909;ob
15:48:00.757 2 DIALOG-001426 created as DIALOG-001425 copy(copy)
15:48:00.757 2 SIPC-004788 DIALOG-001426 created(copy)
15:48:00.757 2 SIPC-004788 creating media proxy(NAT)
15:48:00.757 2 MEDIAPROXY-000442 created for DIALOG-001426: [95.215.137.139](NATed) ->
15:48:00.757 2 DIALOG-001426 MEDIAPROXY-000442 created
15:48:00.757 2 SIPC-004788 MEDIAPROXY-000442 created
15:48:00.757 2 MEDIAPROXY-000442 processing SDP(1 media) ->
15:48:00.757 2 UDPPROXY-000918 created (port=60004/2)
15:48:00.757 2 UDPPROXY-000918 processing started
15:48:00.757 2 MEDIAPROXY-000442 UDPPROXY-000918 created
15:48:00.757 2 UDPPROXY-000918 [95.215.137.139]:0:0(NATed-flex) <-> [0.0.0.0]:0:0 by SDP
15:48:00.757 2 MEDIAPROXY-000442 set: [95.215.137.139](NATed) <-> [95.215.137.139]
15:48:00.757 2 SIPC-004788 DIALOG-001426 updated request SDP
15:48:00.757 2 SIPDATA-151409 out: req [176.9.83.240]:5060 -> udp[95.215.137.139]:35909 INVITE(1343 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:00.757 2 SIPC-004788 [151409] INVITE sip:kondor7@95.215.137.139:35909;ob sent [176.9.83.240]:5060 -> udp[95.215.137.139]:35909
15:48:00.885 2 SIPDATA-151410 inp: rsp [0.0.0.0]:5060 <- udp[95.215.137.139]:35909 100-INVITE(557 bytes)
15:48:00.885 2 SIPDATA-151410 sent to SIPC-004788
15:48:00.885 2 SIPC-004788 [151410] 100-INVITE received
15:48:00.885 3 SIPC-004788 3 Record-Route field(s) in non-starting response, clearing
15:48:04.786 2 SIPDATA-151412 inp: rsp [0.0.0.0]:5060 <- udp[95.215.137.139]:35909 200-INVITE(1148 bytes)
15:48:04.786 2 SIPDATA-151412 sent to SIPC-004788
15:48:04.786 2 SIPC-004788 [151412] 200-INVITE received
15:48:04.786 2 SIPC-004788 response SDP NAT address [95.215.137.139] set
15:48:04.786 2 SIGNAL-074756 200 received from SIPC-004788
15:48:04.786 2 SIGNAL-074756 DIALOG-001426 updating on 200-response
15:48:04.786 2 MEDIAPROXY-000442 processing SDP(1 media) <-
15:48:04.786 2 UDPPROXY-000918 [95.215.137.139]:41150:33998(NATed) <-> [95.215.137.139]:0:0(NATed-flex) by SDP
15:48:04.786 2 MEDIAPROXY-000442 set: [95.215.137.139](NATed) <-> [95.215.137.139]
15:48:04.786 2 DIALOG-001426 connected
15:48:04.786 2 ACCOUNT(sekretar@pmh.ru) out call connected. alerting=4 sec
15:48:04.787 2 ACCOUNT(kondor7@pmh.ru) inp call connected. alerting=4 sec
15:48:04.787 2 SIGNAL-074756 200 relaying
15:48:04.787 2 SIGNAL-074756 releasing
15:48:04.787 2 SIPDATA-151413 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 200-INVITE(1025 bytes)
15:48:04.787 2 SIPS-070434 [151413] 200-INVITE(final) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:04.939 2 UDPPROXY-000918 [95.215.137.139]:41150:33998(NATed) <-> [95.215.137.139]:49137:0(NATed) by to-data
15:48:04.977 2 SIPDATA-151414 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 ACK(849 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:04.977 2 SIPDATA-151414 stand-alone ACK
15:48:04.977 2 SIPDATA-151414 created SIPC-004790 to send downstream
15:48:04.977 2 SIPC-004790 [151414] ACK sip:kondor7@95.215.137.139:35909;ob
15:48:04.978 2 SIPDATA-151415 out: req [176.9.83.240]:5060 -> udp[95.215.137.139]:35909 ACK(568 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:04.978 2 SIPC-004790 [151415] ACK sip:kondor7@95.215.137.139:35909;ob sent [176.9.83.240]:5060 -> udp[95.215.137.139]:35909
15:48:09.188 2 SIPDATA-151417 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 INVITE(1122 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:09.188 2 SIPDATA-151417 created SIPS-070436
15:48:09.188 2 SIPS-070436 [151417] INVITE sip:kondor7@95.215.137.139:35909;ob from udp[95.215.137.139]:35764
15:48:09.188 2 SIPDATA-151418 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 100-INVITE(369 bytes)
15:48:09.188 2 SIPS-070436 [151418] 100-INVITE(trying) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:09.188 2 SIPS-070436 created SIGNAL-074758
15:48:09.188 2 SIGNAL-074758 SIPS-070436: INVITE sip:kondor7@95.215.137.139:35909;ob
15:48:09.189 2 MEDIA-000067 created(44444487), audio port [0.0.0.0]:60006
15:48:09.189 2 DIALOG-001426 MEDIA-000067 created for music-on-hold
15:48:09.189 2 MEDIA-000067 processor started
15:48:09.189 2 MEDIA-000067 local hold set
15:48:09.189 2 MEDIA-000067 local SDP generated
15:48:09.189 2 DIALOG-001426 MEDIA-000067(music-on-hold,direct) SDP set
15:48:09.189 2 DIALOG-001426 request SDP modified
15:48:09.189 2 SIGNAL-074758 INVITE sip:kondor7@95.215.137.139:35909;ob via sip:udp-95.215.137.139-35909S0.0.0.0-5060.nat.cgatepro;lr
15:48:09.189 2 SIGNAL-074758 relaying sip:kondor7@95.215.137.139:35909;ob via special udp-95.215.137.139-35909S0.0.0.0-5060.nat.cgatepro
15:48:09.189 2 SIGNAL-074758 {1} sent to SIPC-004792: INVITE sip:kondor7@95.215.137.139:35909;ob
15:48:09.189 2 SIPC-004792 INVITE sip:kondor7@95.215.137.139:35909;ob
15:48:09.189 2 SIPDATA-151419 out: req [176.9.83.240]:5060 -> udp[95.215.137.139]:35909 INVITE(990 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:09.189 2 SIPC-004792 [151419] INVITE sip:kondor7@95.215.137.139:35909;ob sent [176.9.83.240]:5060 -> udp[95.215.137.139]:35909
15:48:09.326 2 SIPDATA-151420 inp: rsp [0.0.0.0]:5060 <- udp[95.215.137.139]:35909 200-INVITE(982 bytes)
15:48:09.326 2 SIPDATA-151420 sent to SIPC-004792
15:48:09.326 2 SIPC-004792 [151420] 200-INVITE received
15:48:09.326 2 SIPC-004792 response SDP NAT address [95.215.137.139] set
15:48:09.326 2 SIGNAL-074758 200 received from SIPC-004792
15:48:09.326 2 SIGNAL-074758 DIALOG-001426 updating on 200-response
15:48:09.326 2 MEDIA-000067 set:[95.215.137.139]:0:0(NATed) SDP(101=DTMF 0=PCMU/8000,101=telephone-event/8000,0=)recvonly <-> (PCMU/8000)sendonly
15:48:09.326 2 MEDIAPROXY-000442 processing SDP(1 media) <-
15:48:09.326 2 UDPPROXY-000918 [95.215.137.139]:41150:33998(NATed) <-> [95.215.137.139]:49137:0(NATed-flex)(recvonly) by SDP
15:48:09.326 2 MEDIAPROXY-000442 set: [95.215.137.139](NATed) <-> [95.215.137.139]
15:48:09.326 2 SIGNAL-074758 200 relaying
15:48:09.326 2 SIGNAL-074758 releasing
15:48:09.327 2 SIPDATA-151421 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 200-INVITE(878 bytes)
15:48:09.327 2 SIPS-070436 [151421] 200-INVITE(final) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:09.435 2 MEDIA-000067 set:[95.215.137.139]:41150 by media
15:48:09.435 2 MEDIA-000067 inp:(22,5A525450)243ABE74 sourced
15:48:09.526 2 SIPDATA-151422 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 ACK(852 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:09.526 2 SIPDATA-151422 stand-alone ACK
15:48:09.526 2 SIPDATA-151422 created SIPC-004794 to send downstream
15:48:09.526 2 SIPC-004794 [151422] ACK sip:kondor7@95.215.137.139:35909;ob
15:48:09.526 2 SIPDATA-151423 out: req [176.9.83.240]:5060 -> udp[95.215.137.139]:35909 ACK(567 bytes) sip:kondor7@95.215.137.139:35909;ob
15:48:09.526 2 SIPC-004794 [151423] ACK sip:kondor7@95.215.137.139:35909;ob sent [176.9.83.240]:5060 -> udp[95.215.137.139]:35909
15:48:09.623 2 SIPDATA-151424 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 REFER(961 bytes) sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40
15:48:09.623 2 SIPDATA-151424 created SIPS-070438
15:48:09.623 2 SIPS-070438 [151424] REFER sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40 from udp[95.215.137.139]:35764
15:48:09.623 2 SIPDATA-151425 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 100-REFER(383 bytes)
15:48:09.623 2 SIPS-070438 [151425] 100-REFER(trying) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:09.623 2 SIPS-070438 created SIGNAL-074760
15:48:09.623 2 SIGNAL-074760 SIPS-070438: REFER sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40
15:48:09.623 2 SIGNAL-074760 REFER sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40 via sip:212.53.40.40:5060;lr
15:48:09.623 2 SIGNAL-074760 relaying to sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40 via sip:212.53.40.40:5060;maddr=212.53.40.40;lr
15:48:09.623 2 SIGNAL-074760 {1} sent to SIPC-004796: REFER sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40
15:48:09.623 2 SIPC-004796 REFER sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40 via sip:212.53.40.40:5060;maddr=212.53.40.40;lr
15:48:09.623 2 SIPDATA-151426 out: req [176.9.83.240]:5060 -> udp[212.53.40.40]:5060 REFER(951 bytes) sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40
15:48:09.624 2 SIPC-004796 [151426] REFER sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40 sent [176.9.83.240]:5060 -> udp[212.53.40.40]:5060
15:48:10.104 2 SIPDATA-151427 inp: req [0.0.0.0]:5060 <- udp[95.215.137.139]:35764 REFER(961 bytes) sip:signode-269938-5A7294AD_kmbdctk-463A@212.53.40.40
15:48:10.104 2 SIPDATA-151427 sent to SIPS-070438
15:48:10.104 3 SIPS-070438 [151427] request resent, repeating response
15:48:10.104 2 SIPDATA-151428 out: rsp [0.0.0.0]:5060 -> udp[95.215.137.139]:35764 100-REFER(383 bytes)
15:48:10.104 2 SIPS-070438 [151428] 100-REFER(repeating) sent [0.0.0.0]:5060 -> udp[95.215.137.139]:35764
15:48:10.766 2 SIPDATA-151429 inp: rsp [0.0.0.0]:5060 <- udp[212.53.40.40]:5060 403-REFER(453 bytes)
15:48:10.766 2 SIPDATA-151429 sent to SIPC-004796
15:48:10.766 2 SIPC-004796 [151429] 403-REFER received
15:48:10.766 2 SIGNAL-074760 403 received from SIPC-004796
15:48:10.766 2 SIGNAL-074760 403 relaying
15:48:10.766 2 SIGNAL-074760 releasing