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

Andreas Granig agranig at sipwise.com
Wed Jun 28 05:38:59 EDT 2017


Hi,

Depends on what exactly the setup is.

Is the CF destination 34927000800 supposed to be local? If not (and the
decision to route it to a peer is correct), do you have a default
outbound peering rule set, which could match the destination? Seems like
either no outbound peering rule is configured at all (e.g. no default
one), or they are too strict and no rules match.

Andreas

On 06/27/2017 03:01 PM, JL . wrote:
> 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
> 
> 
> 
> 
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> https://lists.sipwise.com/listinfo/spce-user
> 



More information about the Spce-user mailing list