нужна помощь по настройке SMG2

[vladimir74m]

Добрый день Прошу в помощи по настройке SMG2 - Q931 по следующей схеме (транзита нет)
PBX1 -> E1 -> SMG -> SIP ->сеть Ethernet -> SIP -> SMG -> E1 -> PBX 2
весь трафик с SIP идет на E1 и всё с Е1 идет на SIP канал. Где ошибка или что неправильно делаю –опыта по настройке SMG2 нет
канала E1 - в состоянии UP
Вызов с PBX1 36000 на 276503
трассировка
2018-01-29 15:56:48.286 app.c !Eltex pa_sip 3.1.6.67. Log started
2018-01-29 15:56:48.287 app.c set log_level 5
2018-01-29 15:56:48.287 ipc.c pbx-ipc SIP: TX (005)
2018-01-29 15:56:48.287 ipc.c 15 24 01 00 05 .$...
2018-01-29 15:56:48.287 ipc.c PBXIPC-SIP. TX. trace
2018-01-29 15:56:48.287 ipc.c # trace-level [5]
2018-01-29 15:58:06.451 ipc.c !pbx-ipc SIP: RX (373)
2018-01-29 15:58:06.451 ipc.c 01 01 02 00 03 04 03 02 00 01 00 23 0C 00 31 30 ...........#..10
2018-01-29 15:58:06.451 ipc.c 2E 32 35 35 2E 32 35 35 2E 33 05 02 00 C4 13 08 .255.255.3......
2018-01-29 15:58:06.451 ipc.c 2F 00 01 01 00 00 02 00 00 00 00 00 00 06 00 00 /...............
2018-01-29 15:58:06.451 ipc.c 00 32 37 36 35 30 33 00 00 00 00 00 00 00 00 00 .276503.........
2018-01-29 15:58:06.451 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2018-01-29 15:58:06.451 ipc.c 00 0A 2F 00 21 00 02 E3 02 00 00 00 00 00 00 05 ../.!...........
2018-01-29 15:58:06.451 ipc.c 00 00 00 33 36 30 30 30 00 00 00 00 00 00 00 00 ...36000........
2018-01-29 15:58:06.451 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2018-01-29 15:58:06.451 ipc.c 00 00 00 0E EF 00 76 3D 30 0D 0A 6F 3D 6D 67 61 ......v=0..o=mga
2018-01-29 15:58:06.451 ipc.c 70 70 20 39 31 37 30 39 31 33 31 39 20 31 35 39 pp 917091319 159
2018-01-29 15:58:06.451 ipc.c 35 33 33 30 36 38 36 20 49 4E 20 49 50 34 20 31 5330686 IN IP4 1
2018-01-29 15:58:06.451 ipc.c 30 2E 32 30 30 2E 34 2E 31 30 0D 0A 73 3D 2D 0D 0.200.4.10..s=-.
2018-01-29 15:58:06.451 ipc.c 0A 63 3D 49 4E 20 49 50 34 20 31 30 2E 32 30 30 .c=IN IP4 10.200
2018-01-29 15:58:06.451 ipc.c 2E 34 2E 31 30 0D 0A 74 3D 30 20 30 0D 0A 6D 3D .4.10..t=0 0..m=
2018-01-29 15:58:06.452 ipc.c 61 75 64 69 6F 20 32 30 30 33 38 20 52 54 50 2F audio 20038 RTP/
2018-01-29 15:58:06.452 ipc.c 41 56 50 20 38 20 30 20 31 30 31 0D 0A 61 3D 72 AVP 8 0 101..a=r
2018-01-29 15:58:06.452 ipc.c 74 70 6D 61 70 3A 38 20 50 43 4D 41 2F 38 30 30 tpmap:8 PCMA/800
2018-01-29 15:58:06.452 ipc.c 30 0D 0A 61 3D 72 74 70 6D 61 70 3A 30 20 50 43 0..a=rtpmap:0 PC
2018-01-29 15:58:06.452 ipc.c 4D 55 2F 38 30 30 30 0D 0A 61 3D 72 74 70 6D 61 MU/8000..a=rtpma
2018-01-29 15:58:06.452 ipc.c 70 3A 31 30 31 20 74 65 6C 65 70 68 6F 6E 65 2D p:101 telephone-
2018-01-29 15:58:06.452 ipc.c 65 76 65 6E 74 2F 38 30 30 30 0D 0A 61 3D 66 6D event/8000..a=fm
2018-01-29 15:58:06.452 ipc.c 74 70 3A 31 30 31 20 30 2D 31 35 0D 0A 61 3D 70 tp:101 0-15..a=p
2018-01-29 15:58:06.452 ipc.c 74 69 6D 65 3A 33 30 0D 0A 61 3D 73 65 6E 64 72 time:30..a=sendr
2018-01-29 15:58:06.452 ipc.c 65 63 76 0D 0A ecv..
2018-01-29 15:58:06.452 ipc.c PBXIPC-SIP. RX. seize
2018-01-29 15:58:06.452 ipc.c # callref 0403
2018-01-29 15:58:06.452 ipc.c # trunkID 01
2018-01-29 15:58:06.452 ipc.c # Hostname '10.255.255.3'
2018-01-29 15:58:06.452 ipc.c # port 5060
2018-01-29 15:58:06.452 ipc.c # cdpn <276503> (6) cat 0x00
2018-01-29 15:58:06.452 ipc.c # cgpn <36000> (5) cat 0xe3
2018-01-29 15:58:06.452 ipc.c # SDP len (239)
2018-01-29 15:58:06.472 ipc_msg.c !ipc_msg_parse: msg type = seize [1]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [1][callref] size [2]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [3][trunk-id] size [2]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [35][hostname] size [12]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [5][port] size [2]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [8][cdpn] size [47]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [10][cgpn] size [47]
2018-01-29 15:58:06.473 ipc_msg.c get_ie: ie [14][sdp] size [239]
2018-01-29 15:58:06.473 ipc.c ipc_process: msg ptr = 0x1eb150. type [1]
2018-01-29 15:58:06.473 call.c call_seize: call 0403. try acquire. dest: <10.255.255.3:5060>
2018-01-29 15:58:06.473 call.c call_seize: call 0403. sdp [239] <0x276e60>
2018-01-29 15:58:06.473 call.c call_seize: call 0403. try make call
2018-01-29 15:58:06.473 call.c call_seize: call 0403. Encapsulate ISUP: 'NO'
2018-01-29 15:58:06.473 dlg0x2777c4 UAC dialog created
2018-01-29 15:58:06.473 call.c make_call: call 0403.

R-URI <sip:276503@10.255.255.3:5060;transport=tcp>
From "36000" <sip:36000@10.200.4.10:5060;user=phone>
To <sip:276503@10.255.255.3:5060>
Contact <sip:36000@10.200.4.10:5060>

2018-01-29 15:58:06.473 call.c make_call: call 0403. sdp [239] <0x2770cc>

v=0
o=mgapp 917091319 1595330686 IN IP4 10.200.4.10
s=-
c=IN IP4 10.200.4.10
t=0 0
m=audio 20038 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

2018-01-29 15:58:06.473 dlg0x2777c4 Module mod-invite added as dialog usage, data=0x277fc4
2018-01-29 15:58:06.474 dlg0x2777c4 Session count inc to 2 by mod-invite
2018-01-29 15:58:06.474 dlg0x2777c4 Module mod-100rel added as dialog usage, data=0x3070dc
2018-01-29 15:58:06.474 dlg0x2777c4 100rel module attached
2018-01-29 15:58:06.474 inv0x2777c4 UAC invite session created for dialog dlg0x2777c4
2018-01-29 15:58:06.474 endpoint Request msg INVITE/cseq=27470 (tdta0x307198) created.
2018-01-29 15:58:06.474 call.c make_call: call 0403. call <0x404adc04> inv <0x277fc4> dlg <0x2777c4>
2018-01-29 15:58:06.474 inv0x2777c4 Sending Request msg INVITE/cseq=27470 (tdta0x307198)
2018-01-29 15:58:06.474 dlg0x2777c4 Sending Request msg INVITE/cseq=27470 (tdta0x307198)
2018-01-29 15:58:06.474 tsx0x3091ac Transaction created for Request msg INVITE/cseq=27469 (tdta0x307198)
2018-01-29 15:58:06.474 tsx0x3091ac Sending Request msg INVITE/cseq=27469 (tdta0x307198) in state Null
2018-01-29 15:58:06.474 sip_resolve.c Target '10.255.255.3:5060' type=TCP resolved to '10.255.255.3:5060' type=TCP (TCP transport)
2018-01-29 15:58:06.474 tcpc0x309b6c TCP client transport created
2018-01-29 15:58:06.475 tcpc0x309b6c TCP transport 10.200.4.10:56095 is connecting to 10.255.255.3:5060...
2018-01-29 15:58:06.475 tsx0x3091ac State changed from Null to Calling, event=TX_MSG
2018-01-29 15:58:06.475 dlg0x2777c4 Transaction tsx0x3091ac state changed to Calling
2018-01-29 15:58:06.475 call.c call_on_state_changed: call 0403. changed to CALLING state with event TSX_STATE
2018-01-29 15:58:06.475 call.c call_on_tsx_state_changed: call 0403. tsx 'tsx0x3091ac', role UAC, method INVITE, event TX_MSG, tsx-state: Calling
2018-01-29 15:58:06.475 call.c call_on_tsx_state_changed: call 0403. INVITE UAC. status code [0]
2018-01-29 15:58:06.475 call.c call_on_tsx_state_changed: call 0403. Unsupported status code 0 for not-answered call
2018-01-29 15:58:06.481 tcpc0x309b6c TCP connect() error: Connection refused [code=120111]
2018-01-29 15:58:06.481 tsx0x3091ac Failed to send Request msg INVITE/cseq=27469 (tdta0x307198)! err=120111 (Connection refused)
2018-01-29 15:58:06.481 tsx0x3091ac State changed from Calling to Terminated, event=TRANSPORT_ERROR
2018-01-29 15:58:06.481 dlg0x2777c4 Transaction tsx0x3091ac state changed to Terminated
2018-01-29 15:58:06.481 call.c call_on_state_changed: call 0403. changed to DISCONNCTD state with event TSX_STATE
2018-01-29 15:58:06.482 call.c call_state_disconnected: call 0403. Disconnected. Event type TSX_STATE with response 503
2018-01-29 15:58:06.482 dlg0x30e11c UAC dialog created
2018-01-29 15:58:06.482 call.c make_call: call 0403.

R-URI <sip:276503@10.255.255.3:5060>
From "36000" <sip:36000@10.200.4.10:5060;user=phone>
To <sip:276503@10.255.255.3:5060>
Contact <sip:36000@10.200.4.10:5060>

2018-01-29 15:58:06.482 call.c make_call: call 0403. sdp [239] <0x2770cc>

v=0
o=mgapp 917091319 1595330686 IN IP4 10.200.4.10
s=-
c=IN IP4 10.200.4.10
t=0 0
m=audio 20038 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

2018-01-29 15:58:06.482 dlg0x30e11c Module mod-invite added as dialog usage, data=0x1eb1ac
2018-01-29 15:58:06.482 dlg0x30e11c Session count inc to 2 by mod-invite
2018-01-29 15:58:06.482 dlg0x30e11c Module mod-100rel added as dialog usage, data=0x30f21c
2018-01-29 15:58:06.482 dlg0x30e11c 100rel module attached
2018-01-29 15:58:06.482 inv0x30e11c UAC invite session created for dialog dlg0x30e11c
2018-01-29 15:58:06.482 endpoint Request msg INVITE/cseq=6524 (tdta0x30f2d8) created.
2018-01-29 15:58:06.482 call.c make_call: call 0403. call <0x404adc04> inv <0x1eb1ac> dlg <0x30e11c>
2018-01-29 15:58:06.482 inv0x30e11c Sending Request msg INVITE/cseq=6524 (tdta0x30f2d8)
2018-01-29 15:58:06.482 dlg0x30e11c Sending Request msg INVITE/cseq=6524 (tdta0x30f2d8)
2018-01-29 15:58:06.483 tsx0x3112ec Transaction created for Request msg INVITE/cseq=6523 (tdta0x30f2d8)
2018-01-29 15:58:06.483 tsx0x3112ec Sending Request msg INVITE/cseq=6523 (tdta0x30f2d8) in state Null
2018-01-29 15:58:06.483 sip_resolve.c Target '10.255.255.3:5060' type=Unspecified resolved to '10.255.255.3:5060' type=UDP (UDP transport)
2018-01-29 15:58:06.483 tsx0x3112ec State changed from Null to Calling, event=TX_MSG
2018-01-29 15:58:06.483 dlg0x30e11c Transaction tsx0x3112ec state changed to Calling
2018-01-29 15:58:06.483 call.c call_on_state_changed: call 0403. changed to CALLING state with event TSX_STATE
2018-01-29 15:58:06.483 call.c call_on_tsx_state_changed: call 0403. tsx 'tsx0x3112ec', role UAC, method INVITE, event TX_MSG, tsx-state: Calling
2018-01-29 15:58:06.483 call.c call_on_tsx_state_changed: call 0403. INVITE UAC. status code [0]
2018-01-29 15:58:06.483 call.c call_on_tsx_state_changed: call 0403. Unsupported status code 0 for not-answered call
2018-01-29 15:58:06.483 dlg0x2777c4 Session count dec to 1 by mod-invite
2018-01-29 15:58:06.483 call.c call_on_tsx_state_changed: call 0403. tsx 'tsx0x3091ac', role UAC, method INVITE, event TRANSPORT_ERROR, tsx-state: Terminated
2018-01-29 15:58:06.483 call.c call_on_tsx_state_changed: call 0403. INVITE UAC. status code [503]
2018-01-29 15:58:06.483 call.c call_on_tsx_state_changed: call 0403. Unsupported status code 503 for not-answered call
2018-01-29 15:58:06.483 dlg0x2777c4 Dialog destroyed
2018-01-29 15:58:06.484 tcpc0x309b6c TCP send() error, sent=-120111
2018-01-29 15:58:06.484 tsx0x3091ac !Timeout timer event
2018-01-29 15:58:06.484 tsx0x3091ac State changed from Terminated to Destroyed, event=TIMER
2018-01-29 15:58:06.485 tdta0x307198 Destroying txdata Request msg INVITE/cseq=27469 (tdta0x307198)
2018-01-29 15:58:06.485 tsx0x3091ac Transaction destroyed!
2018-01-29 15:58:06.485 tcpc0x309b6c TCP transport destroyed with reason 120111: Connection refused
2018-01-29 15:58:06.504 sip_endpoint.c !Processing incoming message: Response msg 100/INVITE/cseq=6523 (rdata0x3138ec)
2018-01-29 15:58:06.504 tsx0x3112ec Incoming Response msg 100/INVITE/cseq=6523 (rdata0x3138ec) in state Calling
2018-01-29 15:58:06.504 tsx0x3112ec State changed from Calling to Proceeding, event=RX_MSG
2018-01-29 15:58:06.504 dlg0x30e11c Received Response msg 100/INVITE/cseq=6523 (rdata0x3138ec)
2018-01-29 15:58:06.505 dlg0x30e11c Transaction tsx0x3112ec state changed to Proceeding
2018-01-29 15:58:06.505 call.c call_on_tsx_state_changed: call 0403. tsx 'tsx0x3112ec', role UAC, method INVITE, event RX_MSG, tsx-state: Proceeding
2018-01-29 15:58:06.505 call.c call_check_isup: call 0403. checking for ISUP presence
2018-01-29 15:58:06.505 call.c call_check_isup: call 0403. ISUP message not found
2018-01-29 15:58:06.505 call.c call_on_tsx_state_changed: call 0403. INVITE UAC. status code [100]
2018-01-29 15:58:06.505 call.c call_on_tsx_state_changed: call 0403. Unsupported status code 100 for not-answered call
2018-01-29 15:58:06.515 sip_endpoint.c !Processing incoming message: Response msg 403/INVITE/cseq=6523 (rdata0x311cac)
2018-01-29 15:58:06.516 tsx0x3112ec Incoming Response msg 403/INVITE/cseq=6523 (rdata0x311cac) in state Proceeding
2018-01-29 15:58:06.516 endpoint Request msg ACK/cseq=6523 (tdta0x277760) created.
2018-01-29 15:58:06.516 tsx0x3112ec State changed from Proceeding to Completed, event=RX_MSG
2018-01-29 15:58:06.516 dlg0x30e11c Received Response msg 403/INVITE/cseq=6523 (rdata0x311cac)
2018-01-29 15:58:06.516 dlg0x30e11c Transaction tsx0x3112ec state changed to Completed
2018-01-29 15:58:06.516 call.c call_on_state_changed: call 0403. changed to DISCONNCTD state with event TSX_STATE
2018-01-29 15:58:06.516 call.c call_state_disconnected: call 0403. Disconnected. Event type TSX_STATE with response 403
2018-01-29 15:58:06.516 call.c call_check_isup: call 0403. checking for ISUP presence
2018-01-29 15:58:06.516 call.c call_check_isup: call 0403. ISUP message not found
2018-01-29 15:58:06.516 ipc.c pbx-ipc SIP: TX (011)
2018-01-29 15:58:06.516 ipc.c 06 01 02 00 03 04 10 02 00 93 01 ...........
2018-01-29 15:58:06.516 ipc.c PBXIPC-SIP. TX. rel-req
2018-01-29 15:58:06.516 ipc.c # callref 0403
2018-01-29 15:58:06.516 ipc.c # cause 403
2018-01-29 15:58:06.517 ipc.c !pbx-ipc SIP: RX (016)
2018-01-29 15:58:06.517 ipc.c 07 01 02 00 03 04 29 02 00 15 00 10 02 00 93 01 ......).........
2018-01-29 15:58:06.517 ipc.c PBXIPC-SIP. RX. rel-cplt
2018-01-29 15:58:06.517 ipc.c # callref 0403
2018-01-29 15:58:06.517 ipc.c # q850-cause 21
2018-01-29 15:58:06.517 ipc.c # cause 403
2018-01-29 15:58:06.517 dlg0x30e11c !Session count dec to 1 by mod-invite
2018-01-29 15:58:06.517 call.c call_on_tsx_state_changed: inv <0x1eb1ac>, mod-data <(nil)>
2018-01-29 15:58:06.518 tdta0x30f2d8 Destroying txdata Request msg INVITE/cseq=6523 (tdta0x30f2d8)
2018-01-29 15:58:06.528 ipc_msg.c ipc_msg_parse: msg type = rel-cplt [7]
2018-01-29 15:58:06.528 ipc_msg.c get_ie: ie [1][callref] size [2]
2018-01-29 15:58:06.528 ipc_msg.c get_ie: ie [41][q850-cause] size [2]
2018-01-29 15:58:06.528 ipc_msg.c get_ie: ie [16][cause] size [2]
2018-01-29 15:58:06.528 ipc.c ipc_process: msg ptr = 0x276e60. type [7]
2018-01-29 15:58:06.528 call.c call_release_complete: call 0403. release complete req w/cause 403
2018-01-29 15:58:38.519 tsx0x3112ec !Timeout timer event
2018-01-29 15:58:38.519 tsx0x3112ec State changed from Completed to Terminated, event=TIMER
2018-01-29 15:58:38.519 dlg0x30e11c Transaction tsx0x3112ec state changed to Terminated
2018-01-29 15:58:38.519 call.c call_on_tsx_state_changed: inv <0x1eb1ac>, mod-data <(nil)>
2018-01-29 15:58:38.519 dlg0x30e11c Dialog destroyed
2018-01-29 15:58:38.529 tsx0x3112ec !Timeout timer event
2018-01-29 15:58:38.529 tsx0x3112ec State changed from Terminated to Destroyed, event=TIMER
2018-01-29 15:58:38.529 tdta0x277760 Destroying txdata Request msg ACK/cseq=6523 (tdta0x277760)
2018-01-29 15:58:38.529 tsx0x3112ec Transaction destroyed!
2018-01-29 15:58:49.856 ipc.c !pbx-ipc SIP: RX (372)
2018-01-29 15:58:49.856 ipc.c 01 01 02 00 04 04 03 02 00 01 00 23 0C 00 31 30 ...........#..10
2018-01-29 15:58:49.856 ipc.c 2E 32 35 35 2E 32 35 35 2E 33 05 02 00 C4 13 08 .255.255.3......
2018-01-29 15:58:49.856 ipc.c 2F 00 01 01 00 00 02 00 00 00 00 00 00 06 00 00 /...............
2018-01-29 15:58:49.856 ipc.c 00 32 37 36 35 30 33 00 00 00 00 00 00 00 00 00 .276503.........
2018-01-29 15:58:49.856 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2018-01-29 15:58:49.857 ipc.c 00 0A 2F 00 21 00 02 E3 02 00 00 00 00 00 00 05 ../.!...........
2018-01-29 15:58:49.857 ipc.c 00 00 00 33 36 30 30 30 00 00 00 00 00 00 00 00 ...36000........
2018-01-29 15:58:49.857 ipc.c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2018-01-29 15:58:49.857 ipc.c 00 00 00 0E EE 00 76 3D 30 0D 0A 6F 3D 6D 67 61 ......v=0..o=mga
2018-01-29 15:58:49.857 ipc.c 70 70 20 35 39 33 37 35 33 31 30 33 20 32 33 30 pp 593753103 230
2018-01-29 15:58:49.857 ipc.c 33 38 36 34 38 30 20 49 4E 20 49 50 34 20 31 30 386480 IN IP4 10
2018-01-29 15:58:49.857 ipc.c 2E 32 30 30 2E 34 2E 31 30 0D 0A 73 3D 2D 0D 0A .200.4.10..s=-..
2018-01-29 15:58:49.857 ipc.c 63 3D 49 4E 20 49 50 34 20 31 30 2E 32 30 30 2E c=IN IP4 10.200.
2018-01-29 15:58:49.857 ipc.c 34 2E 31 30 0D 0A 74 3D 30 20 30 0D 0A 6D 3D 61 4.10..t=0 0..m=a
2018-01-29 15:58:49.857 ipc.c 75 64 69 6F 20 32 30 30 34 30 20 52 54 50 2F 41 udio 20040 RTP/A
2018-01-29 15:58:49.857 ipc.c 56 50 20 38 20 30 20 31 30 31 0D 0A 61 3D 72 74 VP 8 0 101..a=rt
2018-01-29 15:58:49.857 ipc.c 70 6D 61 70 3A 38 20 50 43 4D 41 2F 38 30 30 30 pmap:8 PCMA/8000
2018-01-29 15:58:49.857 ipc.c 0D 0A 61 3D 72 74 70 6D 61 70 3A 30 20 50 43 4D ..a=rtpmap:0 PCM
2018-01-29 15:58:49.857 ipc.c 55 2F 38 30 30 30 0D 0A 61 3D 72 74 70 6D 61 70 U/8000..a=rtpmap
2018-01-29 15:58:49.857 ipc.c 3A 31 30 31 20 74 65 6C 65 70 68 6F 6E 65 2D 65 :101 telephone-e
2018-01-29 15:58:49.857 ipc.c 76 65 6E 74 2F 38 30 30 30 0D 0A 61 3D 66 6D 74 vent/8000..a=fmt
2018-01-29 15:58:49.857 ipc.c 70 3A 31 30 31 20 30 2D 31 35 0D 0A 61 3D 70 74 p:101 0-15..a=pt
2018-01-29 15:58:49.857 ipc.c 69 6D 65 3A 33 30 0D 0A 61 3D 73 65 6E 64 72 65 ime:30..a=sendre
2018-01-29 15:58:49.857 ipc.c 63 76 0D 0A cv..
2018-01-29 15:58:49.857 ipc.c PBXIPC-SIP. RX. seize
2018-01-29 15:58:49.857 ipc.c # callref 0404
2018-01-29 15:58:49.857 ipc.c # trunkID 01
2018-01-29 15:58:49.857 ipc.c # Hostname '10.255.255.3'
2018-01-29 15:58:49.857 ipc.c # port 5060
2018-01-29 15:58:49.857 ipc.c # cdpn <276503> (6) cat 0x00
2018-01-29 15:58:49.857 ipc.c # cgpn <36000> (5) cat 0xe3
2018-01-29 15:58:49.857 ipc.c # SDP len (238)
2018-01-29 15:58:49.874 ipc_msg.c !ipc_msg_parse: msg type = seize [1]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [1][callref] size [2]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [3][trunk-id] size [2]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [35][hostname] size [12]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [5][port] size [2]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [8][cdpn] size [47]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [10][cgpn] size [47]
2018-01-29 15:58:49.874 ipc_msg.c get_ie: ie [14][sdp] size [238]
2018-01-29 15:58:49.874 ipc.c ipc_process: msg ptr = 0x1eb150. type [1]
2018-01-29 15:58:49.874 call.c call_seize: call 0404. try acquire. dest: <10.255.255.3:5060>
2018-01-29 15:58:49.874 call.c call_seize: call 0404. sdp [238] <0x276e60>
2018-01-29 15:58:49.874 call.c call_seize: call 0404. try make call
2018-01-29 15:58:49.874 call.c call_seize: call 0404. Encapsulate ISUP: 'NO'
2018-01-29 15:58:49.874 dlg0x2777c4 UAC dialog created
2018-01-29 15:58:49.875 call.c make_call: call 0404.

R-URI <sip:276503@10.255.255.3:5060;transport=tcp>
From "36000" <sip:36000@10.200.4.10:5060;user=phone>
To <sip:276503@10.255.255.3:5060>
Contact <sip:36000@10.200.4.10:5060>

2018-01-29 15:58:49.875 call.c make_call: call 0404. sdp [238] <0x2770cc>

v=0
o=mgapp 593753103 230386480 IN IP4 10.200.4.10
s=-
c=IN IP4 10.200.4.10
t=0 0
m=audio 20040 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

2018-01-29 15:58:49.875 dlg0x2777c4 Module mod-invite added as dialog usage, data=0x277fc4
2018-01-29 15:58:49.875 dlg0x2777c4 Session count inc to 2 by mod-invite
2018-01-29 15:58:49.875 dlg0x2777c4 Module mod-100rel added as dialog usage, data=0x3070dc
2018-01-29 15:58:49.875 dlg0x2777c4 100rel module attached
2018-01-29 15:58:49.875 inv0x2777c4 UAC invite session created for dialog dlg0x2777c4
2018-01-29 15:58:49.875 endpoint Request msg INVITE/cseq=25164 (tdta0x307198) created.
2018-01-29 15:58:49.875 call.c make_call: call 0404. call <0x404adffc> inv <0x277fc4> dlg <0x2777c4>
2018-01-29 15:58:49.875 inv0x2777c4 Sending Request msg INVITE/cseq=25164 (tdta0x307198)
2018-01-29 15:58:49.875 dlg0x2777c4 Sending Request msg INVITE/cseq=25164 (tdta0x307198)
2018-01-29 15:58:49.875 tsx0x3091ac Transaction created for Request msg INVITE/cseq=25163 (tdta0x307198)
2018-01-29 15:58:49.875 tsx0x3091ac Sending Request msg INVITE/cseq=25163 (tdta0x307198) in state Null
2018-01-29 15:58:49.875 sip_resolve.c Target '10.255.255.3:5060' type=TCP resolved to '10.255.255.3:5060' type=TCP (TCP transport)
2018-01-29 15:58:49.876 tcpc0x309b6c TCP client transport created
2018-01-29 15:58:49.876 tcpc0x309b6c TCP transport 10.200.4.10:54027 is connecting to 10.255.255.3:5060...
2018-01-29 15:58:49.876 tsx0x3091ac State changed from Null to Calling, event=TX_MSG
2018-01-29 15:58:49.876 dlg0x2777c4 Transaction tsx0x3091ac state changed to Calling
2018-01-29 15:58:49.876 call.c call_on_state_changed: call 0404. changed to CALLING state with event TSX_STATE
2018-01-29 15:58:49.876 call.c call_on_tsx_state_changed: call 0404. tsx 'tsx0x3091ac', role UAC, method INVITE, event TX_MSG, tsx-state: Calling
2018-01-29 15:58:49.876 call.c call_on_tsx_state_changed: call 0404. INVITE UAC. status code [0]
2018-01-29 15:58:49.876 call.c call_on_tsx_state_changed: call 0404. Unsupported status code 0 for not-answered call
2018-01-29 15:58:49.882 tcpc0x309b6c TCP connect() error: Connection refused [code=120111]
2018-01-29 15:58:49.883 tsx0x3091ac Failed to send Request msg INVITE/cseq=25163 (tdta0x307198)! err=120111 (Connection refused)
2018-01-29 15:58:49.883 tsx0x3091ac State changed from Calling to Terminated, event=TRANSPORT_ERROR
2018-01-29 15:58:49.883 dlg0x2777c4 Transaction tsx0x3091ac state changed to Terminated
2018-01-29 15:58:49.883 call.c call_on_state_changed: call 0404. changed to DISCONNCTD state with event TSX_STATE
2018-01-29 15:58:49.883 call.c call_state_disconnected: call 0404. Disconnected. Event type TSX_STATE with response 503
2018-01-29 15:58:49.883 dlg0x30e11c UAC dialog created
2018-01-29 15:58:49.883 call.c make_call: call 0404.

R-URI <sip:276503@10.255.255.3:5060>
From "36000" <sip:36000@10.200.4.10:5060;user=phone>
To <sip:276503@10.255.255.3:5060>
Contact <sip:36000@10.200.4.10:5060>

2018-01-29 15:58:49.883 call.c make_call: call 0404. sdp [238] <0x2770cc>

v=0
o=mgapp 593753103 230386480 IN IP4 10.200.4.10
s=-
c=IN IP4 10.200.4.10
t=0 0
m=audio 20040 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30
a=sendrecv

2018-01-29 15:58:49.883 dlg0x30e11c Module mod-invite added as dialog usage, data=0x1eb1ac
2018-01-29 15:58:49.883 dlg0x30e11c Session count inc to 2 by mod-invite
2018-01-29 15:58:49.883 dlg0x30e11c Module mod-100rel added as dialog usage, data=0x30f21c
2018-01-29 15:58:49.883 dlg0x30e11c 100rel module attached
2018-01-29 15:58:49.883 inv0x30e11c UAC invite session created for dialog dlg0x30e11c
2018-01-29 15:58:49.883 endpoint Request msg INVITE/cseq=25981 (tdta0x311220) created.
2018-01-29 15:58:49.883 call.c make_call: call 0404. call <0x404adffc> inv <0x1eb1ac> dlg <0x30e11c>
2018-01-29 15:58:49.884 inv0x30e11c Sending Request msg INVITE/cseq=25981 (tdta0x311220)
2018-01-29 15:58:49.884 dlg0x30e11c Sending Request msg INVITE/cseq=25981 (tdta0x311220)
2018-01-29 15:58:49.884 tsx0x313234 Transaction created for Request msg INVITE/cseq=25980 (tdta0x311220)
2018-01-29 15:58:49.884 tsx0x313234 Sending Request msg INVITE/cseq=25980 (tdta0x311220) in state Null
2018-01-29 15:58:49.884 sip_resolve.c Target '10.255.255.3:5060' type=Unspecified resolved to '10.255.255.3:5060' type=UDP (UDP transport)
2018-01-29 15:58:49.884 tsx0x313234 State changed from Null to Calling, event=TX_MSG
2018-01-29 15:58:49.884 dlg0x30e11c Transaction tsx0x313234 state changed to Calling
2018-01-29 15:58:49.884 call.c call_on_state_changed: call 0404. changed to CALLING state with event TSX_STATE
2018-01-29 15:58:49.884 call.c call_on_tsx_state_changed: call 0404. tsx 'tsx0x313234', role UAC, method INVITE, event TX_MSG, tsx-state: Calling
2018-01-29 15:58:49.884 call.c call_on_tsx_state_changed: call 0404. INVITE UAC. status code [0]
2018-01-29 15:58:49.884 call.c call_on_tsx_state_changed: call 0404. Unsupported status code 0 for not-answered call
2018-01-29 15:58:49.885 dlg0x2777c4 Session count dec to 1 by mod-invite
2018-01-29 15:58:49.885 call.c call_on_tsx_state_changed: call 0404. tsx 'tsx0x3091ac', role UAC, method INVITE, event TRANSPORT_ERROR, tsx-state: Terminated
2018-01-29 15:58:49.885 call.c call_on_tsx_state_changed: call 0404. INVITE UAC. status code [503]
2018-01-29 15:58:49.885 call.c call_on_tsx_state_changed: call 0404. Unsupported status code 503 for not-answered call
2018-01-29 15:58:49.885 dlg0x2777c4 Dialog destroyed
2018-01-29 15:58:49.885 tcpc0x309b6c TCP send() error, sent=-120111
2018-01-29 15:58:49.886 tsx0x3091ac !Timeout timer event
2018-01-29 15:58:49.887 tsx0x3091ac State changed from Terminated to Destroyed, event=TIMER
2018-01-29 15:58:49.887 tdta0x307198 Destroying txdata Request msg INVITE/cseq=25163 (tdta0x307198)
2018-01-29 15:58:49.887 tsx0x3091ac Transaction destroyed!
2018-01-29 15:58:49.887 tcpc0x309b6c TCP transport destroyed with reason 120111: Connection refused
2018-01-29 15:58:49.893 sip_endpoint.c Processing incoming message: Response msg 100/INVITE/cseq=25980 (rdata0x30f2ec)
2018-01-29 15:58:49.893 tsx0x313234 Incoming Response msg 100/INVITE/cseq=25980 (rdata0x30f2ec) in state Calling
2018-01-29 15:58:49.893 tsx0x313234 State changed from Calling to Proceeding, event=RX_MSG
2018-01-29 15:58:49.893 dlg0x30e11c Received Response msg 100/INVITE/cseq=25980 (rdata0x30f2ec)
2018-01-29 15:58:49.893 dlg0x30e11c Transaction tsx0x313234 state changed to Proceeding
2018-01-29 15:58:49.893 call.c call_on_tsx_state_changed: call 0404. tsx 'tsx0x313234', role UAC, method INVITE, event RX_MSG, tsx-state: Proceeding
2018-01-29 15:58:49.893 call.c call_check_isup: call 0404. checking for ISUP presence
2018-01-29 15:58:49.893 call.c call_check_isup: call 0404. ISUP message not found
2018-01-29 15:58:49.893 call.c call_on_tsx_state_changed: call 0404. INVITE UAC. status code [100]
2018-01-29 15:58:49.893 call.c call_on_tsx_state_changed: call 0404. Unsupported status code 100 for not-answered call
2018-01-29 15:58:49.903 sip_endpoint.c !Processing incoming message: Response msg 403/INVITE/cseq=25980 (rdata0x30f2ec)
2018-01-29 15:58:49.903 tsx0x313234 Incoming Response msg 403/INVITE/cseq=25980 (rdata0x30f2ec) in state Proceeding
2018-01-29 15:58:49.903 endpoint Request msg ACK/cseq=25980 (tdta0x277760) created.
2018-01-29 15:58:49.903 tsx0x313234 State changed from Proceeding to Completed, event=RX_MSG
2018-01-29 15:58:49.903 dlg0x30e11c Received Response msg 403/INVITE/cseq=25980 (rdata0x30f2ec)
2018-01-29 15:58:49.903 dlg0x30e11c Transaction tsx0x313234 state changed to Completed
2018-01-29 15:58:49.903 call.c call_on_state_changed: call 0404. changed to DISCONNCTD state with event TSX_STATE
2018-01-29 15:58:49.903 call.c call_state_disconnected: call 0404. Disconnected. Event type TSX_STATE with response 403
2018-01-29 15:58:49.903 call.c call_check_isup: call 0404. checking for ISUP presence
2018-01-29 15:58:49.903 call.c call_check_isup: call 0404. ISUP message not found
2018-01-29 15:58:49.903 ipc.c pbx-ipc SIP: TX (011)
2018-01-29 15:58:49.903 ipc.c 06 01 02 00 04 04 10 02 00 93 01 ...........
2018-01-29 15:58:49.904 ipc.c PBXIPC-SIP. TX. rel-req
2018-01-29 15:58:49.904 ipc.c # callref 0404
2018-01-29 15:58:49.904 ipc.c # cause 403
2018-01-29 15:58:49.904 ipc.c !pbx-ipc SIP: RX (016)
2018-01-29 15:58:49.904 ipc.c 07 01 02 00 04 04 29 02 00 15 00 10 02 00 93 01 ......).........
2018-01-29 15:58:49.904 ipc.c PBXIPC-SIP. RX. rel-cplt
2018-01-29 15:58:49.904 ipc.c # callref 0404
2018-01-29 15:58:49.904 ipc.c # q850-cause 21
2018-01-29 15:58:49.904 ipc.c # cause 403
2018-01-29 15:58:49.905 ipc_msg.c !ipc_msg_parse: msg type = rel-cplt [7]
2018-01-29 15:58:49.905 ipc_msg.c get_ie: ie [1][callref] size [2]
2018-01-29 15:58:49.905 ipc_msg.c get_ie: ie [41][q850-cause] size [2]
2018-01-29 15:58:49.905 ipc_msg.c get_ie: ie [16][cause] size [2]
2018-01-29 15:58:49.905 ipc.c ipc_process: msg ptr = 0x308cd8. type [7]
2018-01-29 15:58:49.905 call.c call_release_complete: call 0404. release complete req w/cause 403
2018-01-29 15:58:49.905 dlg0x30e11c !Session count dec to 1 by mod-invite
2018-01-29 15:58:49.905 call.c call_on_tsx_state_changed: inv <0x1eb1ac>, mod-data <(nil)>
2018-01-29 15:58:49.905 tdta0x311220 Destroying txdata Request msg INVITE/cseq=25980 (tdta0x311220)
2018-01-29 15:59:00.373 ipc.c !pbx-ipc SIP: RX (005)
2018-01-29 15:59:00.373 ipc.c 15 24 01 00 00 .$...
2018-01-29 15:59:00.373 ipc.c PBXIPC-SIP. RX. trace
2018-01-29 15:59:00.374 ipc.c # trace-level [0]
2018-01-29 15:59:00.387 ipc_msg.c !ipc_msg_parse: msg type = trace [21]
2018-01-29 15:59:00.387 ipc_msg.c get_ie: ie [36][trace-level] size [1]
2018-01-29 15:59:00.387 ipc.c ipc_process: msg ptr = 0x276e60. type [21]
2018-01-29 15:59:00.399 app.c set log_level 0

[vladimir74m]

спасибо проблема решена