[Spce-user] RTP and Voicemail

JL . yacabreao at hotmail.com
Tue Apr 3 15:04:48 EDT 2018


Hi Sipwise team, I want to comment a problem with voicemail. I dial 2000 and when I press number 1 to listen any new messages the call finish immediately and I obtain the below error in rtp.log:


3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: Call branch 'as7a4adf25' (via-branch '') deleted, no more branches remaining
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: Final packet stats:
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --- Tag 'as7a4adf25', created 0:30 ago for branch '', in dialogue with ''
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: ------ Media #1 (audio over RTP/AVP) using unknown codec
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37872 <>      10.250.1.2:17154, 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37873 <>      10.250.1.2:17155 (RTCP), 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: ------ Media #2 (video over RTP/AVP) using unknown codec
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37900 <>      10.250.1.2:12900, 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37901 <>      10.250.1.2:12901 (RTCP), 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --- Tag '', created 0:30 ago for branch 'z9hG4bK705d.d6e4f61b9a3be8e93ddbc7555150421d.0', in dialogue with 'as7a4adf25'
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: ------ Media #1 (audio over RTP/AVP) using unknown codec
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37854 <>          (null):0    , 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37855 <>          (null):0     (RTCP), 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: ------ Media #2 (video over RTP/AVP) using unknown codec
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37892 <>          (null):0    , 0 p, 0 b, 0 e, 1522780134 last_packet
Apr  3 20:29:24 spce (info) rtpengine[2702]: INFO: [3a194bac68879d1834390b7a53546515 at 10.250.1.2]: --------- Port    185.142.44.2:37893 <>          (null):0     (RTCP), 0 p, 0 b, 0 e, 1522780134 last_packet



And the below error in kamalio-proxy.log:

Apr  3 20:16:39 spce proxy[3465]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:2000 at voz.grupowico.net:5060 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:2000 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:2000 at voz.grupowico.net:5060 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:2000 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: User-Provided CLI '34953200880' taken from From-User - R=sip:2000 at voz.grupowico.net:5060 ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: User-provided CLI '34953200880' accepted (matched user preference) - R=sip:2000 at voz.grupowico.net:5060 ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: Setting '34953200880 at voz.grupowico.net' as initiating user-provided CLI - R=sip:2000 at voz.grupowico.net:5060 ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: Setting '34953200880 at voz.grupowico.net' as initiating network-provided CLI - R=sip:2000 at voz.grupowico.net:5060 ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: Load gws matching calling part 'sip:34953200880 at voz.grupowico.net' and called user '342000' and called part 'sip:342000 at voz.grupowico.net:5060' - R=sip:342000 at voz.grupowico.net:5060 ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: Setting acc source-leg for uuid 'a11a2e8b-5e08-442b-aa70-74888c60c060': 'a11a2e8b-5e08-442b-aa70-74888c60c060|34953200880|voz.grupowico.net|34953200880|||2|||0|call|185.142.44.20|1522779399.663952||||||||||||' - R=sip:342000 at 212.225.237.130:5060;transport=udp ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: Rewriting first user-provided CLI '34953200880' to '953200880' - R=sip:342000 at 212.225.237.130:5060;transport=udp ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3460]: NOTICE: <script>: Rewriting first network-provided CLI '34953200880' to '953200880' - R=sip:342000 at 212.225.237.130:5060;transport=udp ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:39 spce proxy[3468]: NOTICE: <script>: New request on proxy - M=ACK R=sip:2000 at voz.grupowico.net:5060 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:2000 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=YjNjMjdlZDA4YWNkZDU1NDYxZjQ4ZDQwZjk2NWY4ODU. UA='<null>'
Apr  3 20:16:57 spce proxy[3480]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:2000 at voz.grupowico.net:5060 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:2000 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:2000 at voz.grupowico.net:5060 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:2000 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: User-Provided CLI '34953200880' taken from From-User - R=sip:2000 at voz.grupowico.net:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: User-provided CLI '34953200880' accepted (matched user preference) - R=sip:2000 at voz.grupowico.net:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Setting '34953200880 at voz.grupowico.net' as initiating user-provided CLI - R=sip:2000 at voz.grupowico.net:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Setting '34953200880 at voz.grupowico.net' as initiating network-provided CLI - R=sip:2000 at voz.grupowico.net:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Call to VoiceBox - R=sip:abc34953200880 at voicebox.local:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Setting acc source-leg for uuid 'a11a2e8b-5e08-442b-aa70-74888c60c060': 'a11a2e8b-5e08-442b-aa70-74888c60c060|34953200880|voz.grupowico.net|34953200880|||2|||0|call|185.142.44.20|1522779417.270206||||||||||||' - R=sip:abc34953200880 at voicebox.local:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Setting acc destination-leg for uuid 'a11a2e8b-5e08-442b-aa70-74888c60c060': '0|||0|2000|a11a2e8b-5e08-442b-aa70-74888c60c060|abc34953200880|voicebox.local|abc34953200880|voicebox.local|0||||||||||||' - R=sip:abc34953200880 at voicebox.local:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Setting From to '<sip:34953200880 at voz.grupowico.net>' - R=sip:abc34953200880 at voicebox.local:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: Request leaving server, D-URI='sip:127.0.0.1:5070' - R=sip:abc34953200880 at voicebox.local:5060 ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:16:57 spce proxy[3462]: NOTICE: <script>: New request on proxy - M=ACK R=sip:127.0.0.1:5070;prxroute=1 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:2000 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='3CXPhone 6.0.26523.0'
Apr  3 20:17:12 spce proxy[3462]: NOTICE: <script>: New request on proxy - M=BYE R=sip:34953200880 at 185.142.44.20:52751;transport=UDP F=sip:2000 at voz.grupowico.net:5060 T=sip:34953200880 at voz.grupowico.net IP=127.0.0.1:5070 (127.0.0.1:5070) ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='NGCP Voicebox Server'
Apr  3 20:17:12 spce proxy[3462]: NOTICE: <script>: [dialog:end] decreased redis counters lua_dlg_callid:[ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU.] - R=sip:34953200880 at 185.142.44.20:52751;transport=UDP ID=ZjI3MWQ3MzVjOWQyZWVjNGQ3NTAzYjlmNTNiNjFiZWU. UA='NGCP Voicebox Server'
Apr  3 20:17:12 spce proxy[3474]: NOTICE: <script>: New request on proxy - M=NOTIFY R=sip:34953200880 at 127.0.0.1:5062;transport=UDP F=sip:voicebox at voicebox.sipwise.local T=sip:34953200880 at 127.0.0.1:5062 IP=127.0.0.1:5081 (127.0.0.1:5081) ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3474]: NOTICE: <script>: Route MWI - R=sip:34953200880 at 127.0.0.1:5062;transport=UDP ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3474]: NOTICE: <script>: Callee is local, uuid='a11a2e8b-5e08-442b-aa70-74888c60c060' - R=sip:34953200880 at voz.grupowico.net:5062;transport=UDP ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3465]: NOTICE: <script>: Failure route for local call - R=sip:34953200880 at 185.142.44.20:52751;transport=UDP;rinstance=36cf76851b28cda9 ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3465]: NOTICE: <script>: CFNA to CF map id '16' found - R=sip:34953200880 at 185.142.44.20:52751;transport=UDP;rinstance=36cf76851b28cda9 ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3465]: NOTICE: <script>: CFNA to destination 'sip:vmu34953200880 at voicebox.local' with timeout '300' activated - R=sip:vmu34953200880 at voicebox.local ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3465]: NOTICE: <script>: Appending P-D-URI 'sip:127.0.0.1:5062' - R=sip:vmu34953200880 at voicebox.local ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3465]: NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:vmu34953200880 at voicebox.local ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:12 spce proxy[3477]: NOTICE: <script>: New request on proxy - M=NOTIFY R=sip:vmu34953200880 at voicebox.local F=sip:voicebox at voicebox.sipwise.local T=sip:34953200880 at 127.0.0.1:5062 IP=127.0.0.1:5080 (127.0.0.1:5080) ID=7C0337F2-5AC3C528000A33DE-25917700 UA='<null>'
Apr  3 20:17:12 spce proxy[3477]: NOTICE: <script>: Route MWI - R=sip:vmu34953200880 at voicebox.local ID=7C0337F2-5AC3C528000A33DE-25917700 UA='<null>'
Apr  3 20:17:12 spce proxy[3477]: ERROR: <script>: IM-Presne for this domain not served here - R=sip:vmu34953200880 at voicebox.local ID=7C0337F2-5AC3C528000A33DE-25917700 UA='<null>'
Apr  3 20:17:12 spce proxy[3460]: NOTICE: <script>: Failure route for hunt call - R=sip:34953200880 at 185.142.44.20:52751;transport=UDP;rinstance=36cf76851b28cda9 ID=3f8f2859-0f31-4307-a2f3-a37188b7c606 at voip.sipwise.local UA='Sipwise NGCP Application Server 4.X'
Apr  3 20:17:29 spce proxy[3468]: NOTICE: <script>: New request on proxy - M=REGISTER R=sip:voz.grupowico.net:5060 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:34953200880 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=YWFhZDhiYmMwNTgxODI3Y2FhYmMwNTMxNjBkOTVkYzk. UA='3CXPhone 6.0.26523.0'
Apr  3 20:17:36 spce proxy[3460]: NOTICE: <script>: New request on proxy - M=SUBSCRIBE R=sip:127.0.0.1:5062 F=sip:34953200880 at voz.grupowico.net:5060 T=sip:34953200880 at voz.grupowico.net:5060 IP=185.142.44.20:52751 (127.0.0.1:5060) ID=MWZhYzhiMThlMjEzZTY5ZWZkNjJkYWYxMjQ0ODc1Y2M. UA='3CXPhone 6.0.26523.0'



Could I help me please?

best regards
Jose Luis



-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/mailman/private/spce-user_lists.sipwise.com/attachments/20180403/0eaf5386/attachment.html>


More information about the Spce-user mailing list