[Spce-user] No PSTN gateways available when I set call forwarding

Tue Jun 27 09:01:31 EDT 2017

Hi,

I want to do call forwarding but when I do it I have a error in log and it does'nt run.

The next diagram explains I want to do:

      caller----------------------------called----------------------call forwarding

34637300093---------------34953200880----------------34927000800

This is the log copied:

Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:953200880 at 185.142.44.2:5060 F=sip:637300093 at 212.225.255.163:5060;pstn-params=9084818088 T=sip:953200880 at 185.142.44.2:5060 IP=212.225.255.163:5060 (127.0.0.1:5060) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Call from PSTN - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Dialog managed, lua_dlg_callid:[1862414859_20079365 at 212.225.255.163] - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: User-Provided CLI '637300093' taken from From-User - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Network-Provided CLI '637300093' taken from From-User - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Rewriting user-provided CLI '637300093' to '34637300093' - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Rewriting network-provided CLI '637300093' to '34637300093' - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting '34637300093 at 212.225.255.163' as initiating user-provided CLI - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting '34637300093 at 212.225.255.163' as initiating network-provided CLI - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Rewriting called party '953200880' to '34953200880' - R=sip:953200880 at 185.142.44.2:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Callee was aliased with base '34953200880' - R=sip:34953200880 at voz.grupowico.net:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Callee is local, uuid='ecb9ef11-2983-441f-9cac-4400839c360f' - R=sip:34953200880 at voz.grupowico.net:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting acc source-leg for uuid '0': '0|637300093|212.225.255.163|34637300093|||0|||0|call|212.225.255.163|1498566938.549780||||||||||||' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting acc destination-leg for uuid 'ecb9ef11-2983-441f-9cac-4400839c360f': '0|||3|953200880|ecb9ef11-2983-441f-9cac-4400839c360f|34953200880|voz.grupowico.net|34953200880|185.142.44.2|0||||||||||||' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Rewriting first user-provided CLI '34637300093' to '637300093' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Rewriting first network-provided CLI '34637300093' to '637300093' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting caller_cli_userprov/caller_domain_userprov '637300093 at voz.grupowico.net' for upn - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting From to '<sip:637300093 at voz.grupowico.net>' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting caller_cli_netprov/caller_domain_netprov '637300093 at voz.grupowico.net' for npn - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting PAI to '<sip:637300093 at voz.grupowico.net>' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Rewriting called party '34953200880' to '34953200880' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Setting P-Called-Party-ID '<sip:34953200880 at voz.grupowico.net>' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Appending P-D-URI 'sip:lb at 127.0.0.1;lr;received=sip:185.142.44.20:5060;socket=sip:185.142.44.2:5060' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3522]: NOTICE: <script>: Request leaving server, D-URI='sip:127.0.0.1:5080' - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3533]: NOTICE: <script>: NAT-Reply - S=100 - Connecting M=INVITE IP=212.225.255.163:5060 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:38 spce proxy[3536]: NOTICE: <script>: NAT-Reply - S=180 - Ringing M=INVITE IP=212.225.255.163:5060 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:38 spce proxy[3539]: NOTICE: <script>: New request on proxy - M=REGISTER R=sip:voz.grupowico.net F=sip:34953200880 at voz.grupowico.net T=sip:34953200880 at voz.grupowico.net IP=185.142.44.20:5060 (127.0.0.1:5060) ID=1431250419-5060-2 at BJC.BGI.A.BAA UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:38 spce proxy[3539]: NOTICE: <core> [db_query.c:60]: db_do_submit_query(): alert - query execution too long [250 ms] for [update `location` set `expires`='2017-06-27 14:36:]
Jun 27 14:35:38 spce proxy[3539]: NOTICE: <core> [action.c:1560]: run_actions(): alert - action [save (25)] cfg [/etc/kamailio/proxy/registrar.cfg:159] took too long [250 ms]
Jun 27 14:35:38 spce proxy[3539]: NOTICE: <script>: Contacts successfully updated, expires in 60s - R=sip:voz.grupowico.net ID=1431250419-5060-2 at BJC.BGI.A.BAA UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:42 spce proxy[3530]: NOTICE: <script>: New request on proxy - M=REGISTER R=sip:voz.grupowico.net F=sip:34953516942 at voz.grupowico.net T=sip:34953516942 at voz.grupowico.net IP=185.142.44.225:5060 (127.0.0.1:5060) ID=17851049-5060-2 at BIF.BEC.EE.CCF UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:42 spce proxy[3530]: NOTICE: <script>: Contacts successfully updated, expires in 60s - R=sip:voz.grupowico.net ID=17851049-5060-2 at BIF.BEC.EE.CCF UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:47 spce proxy[3524]: NOTICE: <script>: New request on proxy - M=REGISTER R=sip:voz.grupowico.net F=sip:34953546365 at voz.grupowico.net T=sip:34953546365 at voz.grupowico.net IP=185.142.44.234:5060 (127.0.0.1:5060) ID=1469465829-5060-2 at BIF.BEC.EE.CDE UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:47 spce proxy[3524]: NOTICE: <core> [db_query.c:60]: db_do_submit_query(): alert - query execution too long [187 ms] for [update `location` set `expires`='2017-06-27 14:38:]
Jun 27 14:35:47 spce proxy[3524]: NOTICE: <core> [action.c:1560]: run_actions(): alert - action [save (25)] cfg [/etc/kamailio/proxy/registrar.cfg:159] took too long [187 ms]
Jun 27 14:35:47 spce proxy[3524]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voz.grupowico.net ID=1469465829-5060-2 at BIF.BEC.EE.CDE UA='Grandstream HT701 1.0.8.2'
Jun 27 14:35:48 spce proxy[3546]: NOTICE: <script>: Failure route for local call - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3546]: NOTICE: <script>: CFT to CF map id '12' found - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3546]: NOTICE: <script>: CFT to destination 'sip:34957000800 at voz.grupowico.net' with timeout '300' activated - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3546]: NOTICE: <script>: Appending P-D-URI 'sip:127.0.0.1:5062' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3546]: NOTICE: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3533]: NOTICE: <script>: NAT-Reply - S=487 - Request terminated M=INVITE IP=212.225.255.163:5060 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3536]: NOTICE: <script>: NAT-Reply - S=100 - Connecting M=INVITE IP=212.225.255.163:5060 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: New request on proxy - M=INVITE R=sip:34957000800 at voz.grupowico.net F=sip:637300093 at 212.225.255.163:5060;pstn-params=9084818088 T=sip:953200880 at 185.142.44.2:5060 IP=127.0.0.1:5080 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Internal CF loop - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: User-Provided Redirecting CLI '34953200880' taken from NPN - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Rewriting user-provided CLI '34953200880' to '34953200880' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Rewriting user-provided Redirecting CLI '34953200880' to '34953200880' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: User-provided CLI '34953200880' accepted (matched user preference) - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: User-provided Redirecting CLI '34953200880' accepted (matched user preference) - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Callee is not local - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Call to SIP Peering - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Load gws matching calling part 'sip:34637300093 at voz.grupowico.net' and called user '34957000800' and called part 'sip:34957000800 at voz.grupowico.net' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: WARNING: <script>: Dialog *NOT* managed - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Dialog managed, lua_dlg_callid:[1862414859_20079365 at 212.225.255.163] - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: ERROR: <script>: No PSTN gateways available - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Setting acc source-leg for uuid 'ecb9ef11-2983-441f-9cac-4400839c360f': 'ecb9ef11-2983-441f-9cac-4400839c360f|34953200880|voz.grupowico.net|34953200880|||3|||0|cft|127.0.0.1|1498566948.579413||||||||||||' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: Setting acc destination-leg for uuid '0': '0|||0|34957000800|0|34957000800|voz.grupowico.net|34957000800|voz.grupowico.net|0||||||||||||' - R=sip:34957000800 at voz.grupowico.net ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3539]: NOTICE: <script>: [dialog:failed] decreased redis counters lua_dlg_callid:[1862414859_20079365 at 212.225.255.163] - R=sip:you at kamailio.org ID=123 UA='<null>'
Jun 27 14:35:48 spce proxy[3530]: NOTICE: <script>: New request on proxy - M=ACK R=sip:34957000800 at voz.grupowico.net F=sip:637300093 at 212.225.255.163:5060;pstn-params=9084818088 T=sip:953200880 at 185.142.44.2:5060 IP=127.0.0.1:5080 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3527]: NOTICE: <script>: NAT-Reply - S=503 - PSTN Termination Currently Unavailable M=INVITE IP=212.225.255.163:5060 (127.0.0.1:5080) ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3527]: NOTICE: <script>: Failure route for hunt call - R=sip:34953200880 at 192.168.0.100:5060 ID=1862414859_20079365 at 212.225.255.163 UA='<null>'
Jun 27 14:35:48 spce proxy[3527]: WARNING: app_lua [app_lua_sr.c:98]: lua_sr_log(): central:del[user:ecb9ef11-2983-441f-9cac-4400839c360f] counter was -1
Jun 27 14:35:48 spce proxy[3527]: WARNING: app_lua [app_lua_sr.c:98]: lua_sr_log(): central:del[userout:ecb9ef11-2983-441f-9cac-4400839c360f] counter was -1
Jun 27 14:35:48 spce proxy[3527]: WARNING: app_lua [app_lua_sr.c:98]: lua_sr_log(): central:del[totaluser:ecb9ef11-2983-441f-9cac-4400839c360f] counter was -1
Jun 27 14:35:48 spce proxy[3527]: WARNING: app_lua [app_lua_sr.c:98]: lua_sr_log(): central:del[totaluserout:ecb9ef11-2983-441f-9cac-4400839c360f] counter was -1

Could you help me please?

Best regards

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