I have read your original post, moreover your refer to a registration problem, I pointed about your aother providers registration in one of my reply.
Again if Simonics is not receiving data, registration or otherwise from the FreePBX server, it could be you FreePBX is not pointing to Simonic or somewhere packets are mislay.
You can check with ping command or pingpath or whatever tools to check your FreePBX to Simonics ip path connection.
The options packets he sends are received fine. Asterisk does not send registrations at all, which was already explained. It is not a network path problem but rather seems to be a misconfiguration somewhere that is not evident in the logs. Hmm. Registrations are placed at the bottom of the general section of sip.conf, so I am wondering if something above the registration is blocking Asterisk from getting to that part.
2 Likes
I have attempted to setup one of my simonics trunk with another ip telephony solution [ombutel] (which iād rather not use), and it works fine. Inbound and outbound calling, all registrations are sent, it shows active in the Simonics portal. But the same trunk with exactly the same configuration yields nothing with FreePBX 13 (ISO Installed). Asterisk simply just does not send any registration packets.
Iāve reinstalled this version 7 separate times, several using a stock centos installation, and several using the pre-built ISO, also with and without virtualization. Each time coming to the same issue.
Itās been over a week since my original post.
I am no closer to a solution than I was previously.
Some say itās a misconfiguration, but the configuration is exactly the way the Service Provider (SP) requests it to be.
Some say itās the SP, they have commented on this post, stating his servers are getting information from the server fine.
This is quite upsetting that no one can find a solution.
I donāt remember whether we tried debug level logging.
At the asterisk CLI:
core set debug 99
core set verbose 99
sip reload
This will produce an enormous amount of logging information. Turn it back off by setting those values to 0 instead of 99.
See if any more evidence appears after you do that reload.
Yes. That still only returned the no helpful information.
phone*CLI> sip reload
Reloading SIP
[2017-10-11 17:06:56] DEBUG[87721]: db.c:376 db_get_common: Unable to find key '(bogus_peer)' in family 'SIP/Registry'
[2017-10-11 17:06:56] DEBUG[87721]: chan_sip.c:5240 sip_destroy_peer: Destroying SIP peer (bogus_peer)
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip.conf
== Parsing '/etc/asterisk/sip.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_general_additional.conf
== Parsing '/etc/asterisk/sip_general_additional.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_general_custom.conf
== Parsing '/etc/asterisk/sip_general_custom.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_nat.conf
== Parsing '/etc/asterisk/sip_nat.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_registrations_custom.conf
== Parsing '/etc/asterisk/sip_registrations_custom.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_registrations.conf
== Parsing '/etc/asterisk/sip_registrations.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_custom.conf
== Parsing '/etc/asterisk/sip_custom.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_additional.conf
== Parsing '/etc/asterisk/sip_additional.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: config.c:2193 config_text_file_load: Parsing /etc/asterisk/sip_custom_post.conf
== Parsing '/etc/asterisk/sip_custom_post.conf': Found
[2017-10-11 17:06:56] DEBUG[3604]: chan_sip.c:32123 reload_config: --------------- SIP reload started
[2017-10-11 17:06:56] DEBUG[3604]: chan_sip.c:32135 reload_config: --------------- Done destroying registry list
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0:0' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port '0'.
[2017-10-11 17:06:56] DEBUG[3604]: chan_sip.c:32349 reload_config: Setting SIP channel User-Agent Name to FPBX-14.0.1.4(13.17.2)
[2017-10-11 17:06:56] DEBUG[3604]: chan_sip.c:32412 reload_config: Enabling TCP socket for listening
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '[::]:5061' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '::' and port '5061'.
[2017-10-11 17:06:56] DEBUG[3604]: config.c:3792 ast_parse_arg: extract addr from [::]:5061 gives [::]:5061(0)
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '71.115.150.53' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '71.115.150.53' and port ''.
[2017-10-11 17:06:56] DEBUG[3604]: config.c:3792 ast_parse_arg: extract addr from 71.115.150.53 gives 71.115.150.53:0(0)
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '71.115.150.0' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '71.115.150.0' and port ''.
[2017-10-11 17:06:56] DEBUG[3604]: acl.c:661 ast_append_ha: 71.115.150.0:0/255.255.255.0:0 sense 0 appended to ACL
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '192.168.2.0' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '192.168.2.0' and port ''.
[2017-10-11 17:06:56] DEBUG[3604]: acl.c:661 ast_append_ha: 192.168.2.0:0/255.255.255.0:0 sense 0 appended to ACL
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'phone.punkmedia.net' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'phone.punkmedia.net' and port ''.
== Using SIP TOS bits 96
== Using SIP CoS mark 4
[2017-10-11 17:06:56] DEBUG[3604]: tcptls.c:1223 ast_tcptls_server_start: Nothing changed in SIP TCP server
[2017-10-11 17:06:56] DEBUG[3604]: chan_sip.c:33015 reload_config: SIP TCP server started
== TLS/SSL ECDH initialized (automatic), faster PFS ciphers enabled
== TLS/SSL certificate ok
[2017-10-11 17:06:56] DEBUG[3604]: tcptls.c:1223 ast_tcptls_server_start: Nothing changed in SIP TLS server
[2017-10-11 17:06:56] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'sip.didnumbers.com'
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'sip.didnumbers.com' into...
[2017-10-11 17:06:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'sip.didnumbers.com' and port ''.
[2017-10-11 17:07:01] DEBUG[87731]: manager.c:6323 process_message: Running action 'Login'
phone*CLI> sip reload
[2017-10-11 17:07:04] DEBUG[87721]: db.c:376 db_get_common: Unable to find key '(bogus_peer)' in family 'SIP/Registry'
[2017-10-11 17:07:04] DEBUG[87721]: chan_sip.c:5240 sip_destroy_peer: Destroying SIP peer (bogus_peer)
[2017-10-11 17:07:14] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("gvgw11.simonics.com", "(null)", ...): Name or service not known
[2017-10-11 17:07:14] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'gvgw11.simonics.com'
[2017-10-11 17:07:14] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'gvgw.simonics.com'
[2017-10-11 17:07:14] DEBUG[3517]: srv.c:293 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.gvgw.simonics.com' mapped to host gvgw14.simonics.com, port 5060
[2017-10-11 17:07:14] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'gvgw14.simonics.com' into...
[2017-10-11 17:07:14] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'gvgw14.simonics.com' and port ''.
[2017-10-11 17:07:16] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("sip.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:07:16] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'sip.didnumbers.com'
[2017-10-11 17:07:16] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:07:16] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:07:16] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:07:16] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:07:16] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'eu1.didnumbers.com'
[2017-10-11 17:07:16] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'eu1.didnumbers.com' into...
[2017-10-11 17:07:16] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'eu1.didnumbers.com' and port ''.
[2017-10-11 17:07:34] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("gvgw14.simonics.com", "(null)", ...): Name or service not known
[2017-10-11 17:07:34] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'gvgw14.simonics.com'
[2017-10-11 17:07:34] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'hk1.didnumbers.com'
[2017-10-11 17:07:35] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'hk1.didnumbers.com' into...
[2017-10-11 17:07:35] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'hk1.didnumbers.com' and port ''.
[2017-10-11 17:07:36] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("eu1.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:07:36] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'eu1.didnumbers.com'
[2017-10-11 17:07:36] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:07:36] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:07:36] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:07:36] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:07:36] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'eu2.didnumbers.com'
[2017-10-11 17:07:36] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'eu2.didnumbers.com' into...
[2017-10-11 17:07:36] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'eu2.didnumbers.com' and port ''.
[2017-10-11 17:07:38] DEBUG[87743]: manager.c:6323 process_message: Running action 'Login'
[2017-10-11 17:07:41] DEBUG[3623]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead
[2017-10-11 17:07:55] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("hk1.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:07:55] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'hk1.didnumbers.com'
[2017-10-11 17:07:55] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'us2.didnumbers.com'
[2017-10-11 17:07:55] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'us2.didnumbers.com' into...
[2017-10-11 17:07:55] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'us2.didnumbers.com' and port ''.
[2017-10-11 17:07:56] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("eu2.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:07:56] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'eu2.didnumbers.com'
[2017-10-11 17:07:56] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:07:56] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:07:56] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:07:56] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:07:56] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'us1.didnumbers.com'
[2017-10-11 17:07:56] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'us1.didnumbers.com' into...
[2017-10-11 17:07:56] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'us1.didnumbers.com' and port ''.
[2017-10-11 17:08:01] DEBUG[87753]: manager.c:6323 process_message: Running action 'Login'
[2017-10-11 17:08:15] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("us2.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:08:15] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'us2.didnumbers.com'
[2017-10-11 17:08:16] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("us1.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:08:16] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'us1.didnumbers.com'
[2017-10-11 17:08:16] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:16] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:16] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:16] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:16] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'us1.didnumbers.com'
[2017-10-11 17:08:16] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'us2.didnumbers.com'
[2017-10-11 17:08:16] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'us2.didnumbers.com' into...
[2017-10-11 17:08:16] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'us2.didnumbers.com' and port ''.
[2017-10-11 17:08:16] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'us1.didnumbers.com' into...
[2017-10-11 17:08:16] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'us1.didnumbers.com' and port ''.
[2017-10-11 17:08:36] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("us2.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:08:36] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'us2.didnumbers.com'
[2017-10-11 17:08:36] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:36] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:36] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:36] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:36] WARNING[3604]: sip/config_parser.c:819 sip_parse_nat_option: nat=yes is deprecated, use nat=force_rport,comedia instead
[2017-10-11 17:08:36] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'hk1.didnumbers.com'
[2017-10-11 17:08:36] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("us1.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:08:36] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'us1.didnumbers.com'
[2017-10-11 17:08:36] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'eu2.didnumbers.com'
[2017-10-11 17:08:37] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'eu2.didnumbers.com' into...
[2017-10-11 17:08:37] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'eu2.didnumbers.com' and port ''.
[2017-10-11 17:08:37] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'hk1.didnumbers.com' into...
[2017-10-11 17:08:37] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'hk1.didnumbers.com' and port ''.
[2017-10-11 17:08:57] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("eu2.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:08:57] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'eu2.didnumbers.com'
[2017-10-11 17:08:57] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'eu1.didnumbers.com'
[2017-10-11 17:08:57] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("hk1.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:08:57] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'hk1.didnumbers.com'
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] WARNING[3604]: chan_sip.c:33131 reload_config: Section 'answergv' lacks type
[2017-10-11 17:08:57] DEBUG[3486]: threadpool.c:517 grow: Increasing threadpool stasis-core's size by 1
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: acl.c:661 ast_append_ha: 0.0.0.0:0/0.0.0.0:0 sense 0 appended to ACL
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: acl.c:661 ast_append_ha: 0.0.0.0:0/0.0.0.0:0 sense 1 appended to ACL
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31869 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '71.115.150.34:29474' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '71.115.150.34' and port '29474'.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:16635 reg_source_db: SIP Seeding peer from astdb: '3010' at [email protected] for 3600
[2017-10-11 17:08:57] DEBUG[3604]: db.c:376 db_get_common: Unable to find key '3010' in family 'SIP/RegistryPath'
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:9016 __sip_alloc: Allocating new SIP dialog for [email protected]:5060 - NOTIFY (No RTP)
[2017-10-11 17:08:57] DEBUG[3604]: acl.c:957 ast_ouraddrfor: For destination '71.115.150.34', our source address is '71.115.150.53'.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:3911 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address 71.115.150.53:5060
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:8801 change_callid_pvt: SIP call-id changed from '[email protected]:5060' to '[email protected]:5060'
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:3397 initialize_initreq: Initializing initreq for method NOTIFY - callid [email protected]:5060
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:3754 __sip_xmit: Trying to put 'NOTIFY sip:' onto UDP socket destined for 71.115.150.34:29474
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: acl.c:661 ast_append_ha: 0.0.0.0:0/0.0.0.0:0 sense 0 appended to ACL
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[2017-10-11 17:08:57] DEBUG[3604]: acl.c:661 ast_append_ha: 0.0.0.0:0/0.0.0.0:0 sense 1 appended to ACL
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31869 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '71.115.150.54:5060' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '71.115.150.54' and port '5060'.
[2017-10-11 17:08:57] DEBUG[3604]: chan_sip.c:16635 reg_source_db: SIP Seeding peer from astdb: '5015' at [email protected] for 3600
[2017-10-11 17:08:57] DEBUG[3604]: db.c:376 db_get_common: Unable to find key '5015' in family 'SIP/RegistryPath'
[2017-10-11 17:08:57] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'gvgw.simonics.com'
[2017-10-11 17:08:57] DEBUG[3604]: srv.c:293 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.gvgw.simonics.com' mapped to host gvgw12.simonics.com, port 5060
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'gvgw12.simonics.com' into...
[2017-10-11 17:08:57] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'gvgw12.simonics.com' and port ''.
[2017-10-11 17:08:57] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'eu1.didnumbers.com' into...
[2017-10-11 17:08:57] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'eu1.didnumbers.com' and port ''.
[2017-10-11 17:09:01] DEBUG[87770]: manager.c:6323 process_message: Running action 'Login'
[2017-10-11 17:09:11] DEBUG[3623]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 2 instead
[2017-10-11 17:09:17] DEBUG[87761]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[2017-10-11 17:09:17] DEBUG[3486]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 992
[2017-10-11 17:09:17] ERROR[3604]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("gvgw12.simonics.com", "(null)", ...): Name or service not known
[2017-10-11 17:09:17] WARNING[3604]: acl.c:800 resolve_first: Unable to lookup 'gvgw12.simonics.com'
[2017-10-11 17:09:17] DEBUG[3604]: chan_sip.c:31874 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:09:17] DEBUG[3604]: chan_sip.c:31877 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:09:17] DEBUG[3604]: chan_sip.c:31879 build_peer: Not an IPv4 nor IPv6 address, cannot get port.
[2017-10-11 17:09:17] DEBUG[3604]: chan_sip.c:31882 build_peer: Not an IPv4 nor IPv6 address, cannot set port.
[2017-10-11 17:09:17] DEBUG[3604]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'gvgw.simonics.com'
[2017-10-11 17:09:17] ERROR[3517]: netsock2.c:305 ast_sockaddr_resolve: getaddrinfo("eu1.didnumbers.com", "(null)", ...): Name or service not known
[2017-10-11 17:09:17] WARNING[3517]: acl.c:800 resolve_first: Unable to lookup 'eu1.didnumbers.com'
[2017-10-11 17:09:17] DEBUG[3517]: dnsmgr.c:212 dnsmgr_refresh: refreshing 'sip.didnumbers.com'
[2017-10-11 17:09:17] DEBUG[3604]: srv.c:293 ast_get_srv: ast_get_srv: SRV lookup for '_sip._udp.gvgw.simonics.com' mapped to host gvgw12.simonics.com, port 5060
[2017-10-11 17:09:17] DEBUG[3604]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'gvgw12.simonics.com' into...
[2017-10-11 17:09:17] DEBUG[3604]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'gvgw12.simonics.com' and port ''.
[2017-10-11 17:09:17] DEBUG[3517]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting 'sip.didnumbers.com' into...
[2017-10-11 17:09:17] DEBUG[3517]: netsock2.c:226 ast_sockaddr_split_hostport: ...host 'sip.didnumbers.com' and port ''.
Had a minor DNS error here, fixed that, here are the results of a ping and traceroute to the host:
[root@phone ~]# ping gvgw.simonics.com
PING gvgw.simonics.com (198.199.84.66) 56(84) bytes of data.
64 bytes from gvgw12.simonics.com (198.199.84.66): icmp_seq=1 ttl=58 time=18.9 ms
64 bytes from gvgw12.simonics.com (198.199.84.66): icmp_seq=2 ttl=58 time=13.0 ms
64 bytes from gvgw12.simonics.com (198.199.84.66): icmp_seq=3 ttl=58 time=12.8 ms
64 bytes from gvgw12.simonics.com (198.199.84.66): icmp_seq=4 ttl=58 time=26.7 ms
64 bytes from gvgw12.simonics.com (198.199.84.66): icmp_seq=5 ttl=58 time=18.0 ms
64 bytes from gvgw12.simonics.com (198.199.84.66): icmp_seq=6 ttl=58 time=19.7 ms
^C
ā gvgw.simonics.com ping statistics ā
6 packets transmitted, 6 received, 0% packet loss, time 5008ms
rtt min/avg/max/mdev = 12.898/18.241/26.728/4.672 ms
[root@phone ~]# traceroute gvgw.simonics.com
traceroute to gvgw.simonics.com (198.199.84.66), 30 hops max, 60 byte packets
1 lo0-100.SYRCNY-VFTTP-305.verizon-gni.net (71.115.150.1) 1.179 ms 0.824 ms 0.706 ms
2 B3305.SYRCNY-LCR-22.verizon-gni.net (100.41.5.244) 5.028 ms 4.996 ms B3305.SYRCNY-LCR-21.verizon-gni.net (100.41.223.230) 4.268 ms
3 * * *
4 * * *
5 0.ae13.GW10.EWR6.ALTER.NET (140.222.235.117) 10.747 ms 0.ae8.GW10.EWR6.ALTER.NET (140.222.231.131) 10.968 ms 0.ae12.GW10.EWR6.ALTER.NET (140.222.235.119) 15.925 ms
6 157.130.91.86 (157.130.91.86) 13.172 ms 10.771 ms 10.684 ms
7 digitalocean-ic-306496-nyk-b2.c.telia.net (62.115.45.2) 28.023 ms 21.362 ms 20.777 ms
8 138.197.248.105 (138.197.248.105) 25.931 ms 138.197.248.101 (138.197.248.101) 22.125 ms *
9 gvgw12.simonics.com (198.199.84.66) 22.960 ms !X 21.454 ms !X 27.701 ms !X
[root@phone ~]#
This might be the problem. Did you accidentally put a block of dial plan into your sip config? Or create a trunk/peer with this name and not enter any configuration?
āanswergvā is the code you have linked here:
http://support.simonics.com/support/solutions/articles/3000031917-how-do-i-prevent-google-s-voicemail-from-answering-the-call-asterisk-
Did you enter that into the extensions_custom.conf file? Only the context= part goes into the trunk config.
Yes. I read the tutorial before, during and after. To make sure it was configured properly.
Ok. What Iām trying to figure out is why chan_sip would be complaining about it since itās not part of the sip config.
Got me. Like I said previously, if I try it with a different distro of Asterisk, it works perfectly. But I would prefer to use FPBX, which is why Iām still trying to get it working. Almost a month later 
Hi there! I had a similar isssue. For months couldnāt make a trunk register with a particular provider. I tried so many different things and re-installs that Iām not 100% confident I found the real issue, but for it to work I did a reinstall (from the downloaded iso) and did not enabled the responsive firewall nor whitelisted the ips of the provider. Then it worked, is still working, and I never touched the firewall config again.
I guess there is a BUG on the iptables. The weird is that while troubleshooting I would disable the firewall and it would still not work.
I recommend make a fresh install and without almost any previous configuration, register your trunk, force it with a registration string and check if it is registered.
Please let m know if you get it to work and how you did.
Unbelieveably, this worked! Oh thank you from the bottom of my heart. I was able to get all the trunks registered and everything is now working.
Iām not sure at all why the firewall is so freaking touchy. But it works. Thank you again!
1 Like
Iām glad you got it working. I guess its a clear statement that there is a BUG on the Firewall. People should look into it to get it fixed, unless they are trying to make $$ selling paid support.
There may indeed be a bug in firewall, but nothing I see in this thread indicates such, and all of the hard information herein only exculpates firewall (i.e. outbound OPTIONS packets are being sent successfully through the firewall, but not REGISTRATION packets?, disabling or re-enabling firewall makes no difference when debugging?). There is also the fact that SIP registration and Firewall are heavily used, and there donāt appear to be many systems affected.
Is there a bug ticket filed on this? Once a registration is working, is there a known series of steps that can be taken to reproduce the issue? If those experiencing this issue donāt understand why registration fails, canāt reproduce it, and nobody else is reporting it, what precisely do you expect should be done with the statement, "People should look into it to get it fixed." This is analogous to unnamed government executives who tweet, āDO SOMETHINGā.
lgaetz,
I agree with your previous post.
Additionally, joaocarlosleme I have been using this firewall for years, on many different systems. Several of them using SIP traffic through it. If it was a bug with the firewall, it would have at least made itself known when other systems using Asterisk were tested. For example when Ombutel was tested previously. The trunks registered properly with no issues, while using the same firewall that the previous FreePBX installation was using.
In that case there is no possible way it could have been the firewall blocking anything because it was working fine, inbound and outbound while using Simonics trunks. However when the same configuration was used with FreePBX, there was no registration. Leading me to believe the issue lies with FreePBX, not at all with the firewall. But I could be wrong. These are the observations of someone whoās regularly used this software, and swears by itās ability to dynamically acclimate itself to traffic
We found the reason for Asterisk not registering. Brad T contacted me and let me know the registrations stopped again. I wanted to follow up here for everyoneās information about how this might happen.
The reason was that a SIP peer definition had been added to the sip_general_custom.conf file. If you need to put in custom SIP peers and donāt want to do it through the GUI, then the place to put them would be in sip_custom.conf.
By adding a peer definition to the sip_general_custom.conf file, the [general] section is cut off, causing asterisk to ignore a file that is included later - the sip_registrations.conf file.
In summary - only put SIP [general] section directives in the sip_general_custom.conf file, never peer definitions. And if you donāt understand what this means, donāt put anything in those files until you do understand it. 
2 Likes
I know this is an old thread, but itās the closest to the problems Iāve been experiencing.
FreePBX has been working fine for a couple of years now with a trunk to my VoIP provider (iinet). Both inbound and outbound calls were fine. I donāt often use the service for inbound, mostly outbound, so the last time I knew inbound was working was probably a month or two ago.
After much troubleshooting and tracing, I have concluded that the trunk is failing to register. Iāve taken wireshark traces from the network interface and there is no sign of a register request, so symptoms similar to BradTās. Outbound invites working fine. Iāve tried disabling the firewall and restarting but no joy. Also checked the sip_general_custom.conf file and it is empty. Tried deleting and re-adding the trunk as chan_sip but no joy either. The trunk just wonāt register.
I suspect the problem may have started after I did some module updates, but not 100% sure of that. I usually do test and inbound and outbound call on this trunk after I do updates, so this possibly wasnāt the cause. And of course, I havenāt changed the trunk config since I first installed the system and got it working. About the only time I touch the system in terms of changing it is to do module updates.
Also have confirmed nothing wrong at my ISPās end, as I can register X-Lite with the service and make and receive calls.
Any ideas?