Настраиваю на GrandStream UCM6204 второй SIP транк, регистрация проходит. Исходящие вызовы тоже проходят без проблем.
А вот на входящий вызов получаю ответ: абонент в сети не зарегистрирован.
В ТП билайна ответили, что на их INVITE они получают ответ SIP/2.0 404 Not Found.
Лог с АТС GrandStream при входящем звонке:
Код: Выделить всё
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.852] DEBUG[08399] res_pjsip_log_forwarder.c:97: <PJSIP> sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=572548095 (rdata0x9fb48c)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.852] VERB [08399] res_pjsip_logger.c:138: <--- Received SIP request (1406 bytes) from UDP:212.119.246.230:5060 ---> INVITE sip:SIP00BNMU00KPA@192.168.101.229:5060 SIP/2.0^M Via: SIP/2.0/UDP 212.119.246.230:5060;rport;branch=z9hG4bKmavodi-1-110-316-1-2000000-68f4a4-17e-22d8df-2634902573-5733^M Max-Forwards: 18^M From: <sip:+79889521475@as1;user=phone>;tag=1545220825-1613905315836-^M To: "8 4956413115" <sip:SIP00BNMU00KPA@ip.beeline.ru>^M Call-ID: BW140155836210221418458852@10.255.95.142^M CSeq: 572548095 INVITE^M Min-SE: 900^M Session-Expires: 1800^M Supported: 100rel,timer^M Contact: <sip:as3.sig.msk.ip.beeline.ru:5060>^M Accept: application/media_control+xml,application/sdp,multipart/mixed^M Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY^M Privacy: none^M Recv-Info: x-broadworks-client-session-info^M Record-Route: <sip:mavodi-1-10f-3fffffff-1-ffffffff-0-@212.119.246.230:5060;lr;mavsipodi-1-11b-17e-1-22d8df>^M User-Agent: Mavenir UAG/v1.0 PCSCF/v1.0-14042501t^M
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.852] DEBUG[08399] netsock2.c:175: Splitting '212.119.246.230:5060' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.852] DEBUG[08399] netsock2.c:229: ...host '212.119.246.230' and port '5060'.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.852] DEBUG[08399] netsock2.c:175: Splitting '0.0.0.0:5060' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.852] DEBUG[08399] netsock2.c:229: ...host '0.0.0.0' and port '5060'.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> sip_endpoint.c Distributing rdata to modules: Request msg INVITE/cseq=572548095 (rdata0x17a281c)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] res_pjsip_endpoint_identifier_user.c:177: Could not identify endpoint by username '+79889521475'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] netsock2.c:175: Splitting '212.119.246.230' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] netsock2.c:229: ...host '212.119.246.230' and port ''.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] res_pjsip_endpoint_identifier_ip.c:161: Source address 212.119.246.230:5060 matches identify 'id_trunk_5'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] res_pjsip_endpoint_identifier_ip.c:170: Source address 212.119.246.230:5060 does not match identify 'id_trunk_3'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] res_pjsip_endpoint_identifier_ip.c:170: Source address 212.119.246.230:5060 does not match identify 'id_trunk_4'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] res_pjsip_session.c:1480: session_reinvite_on_rx_request enter...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] VERB [12427] res_pjsip_session.c:3984: starting sip session on receive request ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] VERB [12427] res_pjsip_session.c:3879: handle new sip invite request ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] netsock2.c:175: Splitting '212.119.246.230' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.854] DEBUG[12427] netsock2.c:229: ...host '212.119.246.230' and port ''.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc ..Transaction created for Request msg INVITE/cseq=572548095 (rdata0x17a281c)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc .Incoming Request msg INVITE/cseq=572548095 (rdata0x17a281c) in state Null
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc ..State changed from Null to Trying, event=RX_MSG
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ...Transaction tsx0x40b08ebc state changed to Trying
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac .UAS dialog created
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac .Module mod-invite added as dialog usage, data=0x4781327c
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ..Session count inc to 2 by mod-invite
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> inv0x40b076ac .UAS invite session created for dialog dlg0x40b076ac
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac .Module Session Module added as dialog usage, data=(nil)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] VERB [12427] res_pjsip_session.c:1946: Add media for sip session ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] VERB [12427] res_pjsip_session.c:1946: Add media for sip session ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] VERB [12427] res_pjsip_session.c:1946: Add media for sip session ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.855] VERB [12427] res_pjsip_session.c:1946: Add media for sip session ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ..Session count inc to 2 by Session Module
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_session.c:3524: Set yealink flag begin
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_session.c:3545: User-Agent value:Mavenir UAG/v1.0 PCSCF/v1.0-14042501t
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] VERB [12427] res_pjsip_session.c:3641: new invite starting ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_session.c:2878: didmode = request-line
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip.c:4825: match_direct_media_outbound_prefix begin for prefix_match:SIP00BNMU00KPA
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> endpoint .Response msg 404/INVITE/cseq=572548095 (tdta0x40b1f368) created
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac .Initial answer Response msg 404/INVITE/cseq=572548095 (tdta0x40b1f368)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] VERB [12427] res_pjsip_session.c:1315: sip session starting send response ...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_session.c:4312: Method is INVITE, Response is 404 Not Found
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] db.c:908: Unable to find key 'gs_appid/trunk_5' in family 'GsWave'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> inv0x40b076ac .Sending Response msg 404/INVITE/cseq=572548095 (tdta0x40b1f368)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ..Sending Response msg 404/INVITE/cseq=572548095 (tdta0x40b1f368)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc ..Sending Response msg 404/INVITE/cseq=572548095 (tdta0x40b1f368) in state Trying
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_multihomed.c:308: multihomed_on_tx_message find local addr/port:192.168.101.229:5060, is_ipv6=0
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.856] DEBUG[12427] res_pjsip_multihomed.c:379: not need to rewrite the sdp
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] netsock2.c:175: Splitting '0.0.0.0:5060' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] netsock2.c:229: ...host '0.0.0.0' and port '5060'.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] netsock2.c:175: Splitting '212.119.246.230:5060' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] netsock2.c:229: ...host '212.119.246.230' and port '5060'.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] VERB [12427] res_pjsip_logger.c:118: <--- Transmitting SIP response (591 bytes) to UDP:212.119.246.230:5060 ---> SIP/2.0 404 Not Found^M Via: SIP/2.0/UDP 212.119.246.230:5060;rport=5060;received=212.119.246.230;branch=z9hG4bKmavodi-1-110-316-1-2000000-68f4a4-17e-22d8df-2634902573-5733^M Record-Route: <sip:mavodi-1-10f-3fffffff-1-ffffffff-0-@212.119.246.230:5060;lr;mavsipodi-1-11b-17e-1-22d8df>^M Call-ID: BW140155836210221418458852@10.255.95.142^M From: <sip:+79889521475@as1;user=phone>;tag=1545220825-1613905315836-^M To: "8 4956413115" <sip:SIP00BNMU00KPA@ip.beeline.ru>;tag=4326b8a3-f6a8-4597-8f86-48995709aec4^M CSeq: 572548095 INVITE^M Server: Grandstream UCM6204V1.4A 1.0.19.27^M Content-Length: 0^M ^M
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc ...State changed from Trying to Completed, event=TX_MSG
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ....Transaction tsx0x40b08ebc state changed to Completed
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4071: Function session_inv_on_state_changed called on event TSX_STATE
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4084: The state change pertains to the endpoint 'trunk_5()'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4088: The inv session still has an invite_tsx (0x40b08ebc)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4102: There is no transaction involved in this state change
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4104: The current inv state is DISCONNCTD
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4468: Source of transaction state change is TX_MSG
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4338: Sending response
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4312: Method is INVITE, Response is 404 Not Found
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] db.c:908: Unable to find key 'gs_appid/trunk_5' in family 'GsWave'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] chan_pjsip.c:5265: chan_pjsip_stop_sendcng, session[0x4831575c]
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ......Session count dec to 4 by mod-invite
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4071: Function session_inv_on_tsx_state_changed called on event TSX_STATE
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4084: The state change pertains to the endpoint 'trunk_5()'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4088: The inv session still has an invite_tsx (0x40b08ebc)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4096: The UAS INVITE transaction involved in this state change is 0x40b08ebc
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4098: The current transaction state is Completed
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4100: The transaction state change event is TX_MSG
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4104: The current inv state is DISCONNCTD
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4338: Sending response
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] res_pjsip_session.c:4312: Method is INVITE, Response is 404 Not Found
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.857] DEBUG[12427] db.c:908: Unable to find key 'gs_appid/trunk_5' in family 'GsWave'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.873] DEBUG[08399] res_pjsip_log_forwarder.c:97: <PJSIP> sip_endpoint.c Processing incoming message: Request msg ACK/cseq=572548095 (rdata0x9fb48c)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.873] VERB [08399] res_pjsip_logger.c:138: <--- Received SIP request (550 bytes) from UDP:212.119.246.230:5060 ---> ACK sip:SIP00BNMU00KPA@192.168.101.229:5060 SIP/2.0^M Via: SIP/2.0/UDP 212.119.246.230:5060;rport;branch=z9hG4bKmavodi-1-110-316-1-2000000-68f4a4-17e-22d8df-2634902573-5733^M Max-Forwards: 70^M From: <sip:+79889521475@as1;user=phone>;tag=1545220825-1613905315836-^M To: "8 4956413115" <sip:SIP00BNMU00KPA@ip.beeline.ru>;tag=4326b8a3-f6a8-4597-8f86-48995709aec4^M Call-ID: BW140155836210221418458852@10.255.95.142^M CSeq: 572548095 ACK^M Contact: <sip:as3.sig.msk.ip.beeline.ru:5060>^M User-Agent: Mavenir UAG/v1.0 PCSCF/v1.0-14042501t^M Content-Length: 0^M ^M
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.873] DEBUG[08399] netsock2.c:175: Splitting '212.119.246.230:5060' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.873] DEBUG[08399] netsock2.c:229: ...host '212.119.246.230' and port '5060'.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.873] DEBUG[08399] netsock2.c:175: Splitting '0.0.0.0:5060' into...
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.873] DEBUG[08399] netsock2.c:229: ...host '0.0.0.0' and port '5060'.
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_log_forwarder.c:97: <PJSIP> sip_endpoint.c Distributing rdata to modules: Request msg ACK/cseq=572548095 (rdata0x17a281c)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc .Incoming Request msg ACK/cseq=572548095 (rdata0x17a281c) in state Completed
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_log_forwarder.c:97: <PJSIP> tsx0x40b08ebc ..State changed from Completed to Confirmed, event=RX_MSG
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_log_forwarder.c:97: <PJSIP> dlg0x40b076ac ...Transaction tsx0x40b08ebc state changed to Confirmed
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4071: Function session_inv_on_tsx_state_changed called on event TSX_STATE
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4084: The state change pertains to the endpoint 'trunk_5()'
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4088: The inv session still has an invite_tsx (0x40b08ebc)
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4096: The UAS INVITE transaction involved in this state change is 0x40b08ebc
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4098: The current transaction state is Confirmed
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4100: The transaction state change event is RX_MSG
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4104: The current inv state is DISCONNCTD
Feb 21 14:01:55 UCM6204 user.debug asterisk: [20210221 14:01:55.874] DEBUG[31049] res_pjsip_session.c:4244: Received request
:01:55.874] DEBUG[31049] res_pjsip_session.c:4084: The state change
