<<<<---- START ASTERISK, everythings is ok ---->>>> [USERNAME]*CLI> sip show registry Host dnsmgr Username Refresh State Reg.Time [PROXY_URL]:5060 N [USERNAME] 105 Registered Sat, 24 May 2014 22:25:47 1 SIP registrations. <<<<---- SIP RELOAD ASTERISK ---->>>> [USERNAME]*CLI> sip reload [May 24 22:25:57] DEBUG[5176]: db.c:376 db_get_common: Unable to find key '(bogus_peer)' in family 'SIP/Registry' [May 24 22:25:57] DEBUG[5176]: chan_sip.c:5288 sip_destroy_peer: Destroying SIP peer (bogus_peer) Reloading SIP [May 24 22:25:57] DEBUG[5141]: res_config_mysql.c:921 config_mysql: MySQL RealTime: Static SQL: SELECT category, var_name, var_val, cat_metric FROM conf WHERE filename='sip.conf' and commented=0 ORDER BY filename, category, cat_metric desc, var_metric asc, var_name, var_val, id [May 24 22:25:57] DEBUG[5141]: res_config_mysql.c:1583 mysql_reconnect: MySQL RealTime: Connection okay. [May 24 22:25:57] DEBUG[5141]: res_config_mysql.c:938 config_mysql: MySQL RealTime: Found 8 rows. [May 24 22:25:57] DEBUG[5141]: config.c:1790 config_text_file_load: Parsing /etc/asterisk/users.conf == Parsing '/etc/asterisk/users.conf': Found [May 24 22:25:57] DEBUG[5141]: chan_sip.c:31479 reload_config: --------------- SIP reload started [May 24 22:25:57] DEBUG[5141]: chan_sip.c:3560 registry_unref: SIP Registry [PROXY_URL]: refcount now 1 [May 24 22:25:57] DEBUG[5141]: chan_sip.c:6555 sip_registry_destroy: Destroying registry entry for [USERNAME]@[PROXY_URL] <<<<---- THE ARRAY IS CLEARED (sip_registration array), regobjs is 0 ---->>>> [May 24 22:25:57] DEBUG[5141]: chan_sip.c:31493 reload_config: --------------- Done destroying registry list [May 24 22:25:57] DEBUG[5141]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '0.0.0.0:0' into... [May 24 22:25:57] DEBUG[5141]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port '0'. [May 24 22:25:57] DEBUG[5141]: chan_sip.c:31706 reload_config: Setting SIP channel User-Agent Name to [UA] PBX 1.0 [May 24 22:25:57] DEBUG[5141]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '[USERNAME]' into... [May 24 22:25:57] DEBUG[5141]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '[USERNAME]' and port ''. == Using SIP CoS mark 4 [May 24 22:25:57] DEBUG[5141]: tcptls.c:562 ast_tcptls_server_start: Nothing changed in SIP TCP server [May 24 22:25:57] DEBUG[5141]: chan_sip.c:32364 reload_config: SIP TCP server started [May 24 22:25:57] DEBUG[5141]: res_config_mysql.c:1583 mysql_reconnect: MySQL RealTime: Connection okay. [May 24 22:25:57] DEBUG[5141]: res_config_mysql.c:495 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sippeers WHERE name LIKE '%' AND callbackextension LIKE '%' ORDER BY name [May 24 22:25:57] DEBUG[5141]: chan_sip.c:30657 build_peer: -REALTIME- peer built. Name: [PEER_NAME]. Peer objects: 4 [May 24 22:25:57] DEBUG[5141]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '[PROXY_IP]' into... [May 24 22:25:57] DEBUG[5141]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '[PROXY_IP]' and port ''. [May 24 22:25:57] DEBUG[5141]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '[PROXY_URL]' into... [May 24 22:25:57] DEBUG[5141]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '[PROXY_URL]' and port ''. [May 24 22:25:57] DEBUG[5141]: dnsmgr.c:175 internal_dnsmgr_lookup: doing dnsmgr_lookup for '[PROXY_URL]' [May 24 22:25:57] DEBUG[5141]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '[PROXY_URL]' into... [May 24 22:25:57] DEBUG[5141]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '[PROXY_URL]' and port ''. [May 24 22:25:57] DEBUG[5141]: chan_sip.c:31265 build_peer: Not an IPv4 nor IPv6 address, cannot get port. [May 24 22:25:57] DEBUG[5141]: chan_sip.c:31268 build_peer: Not an IPv4 nor IPv6 address, cannot set port. [May 24 22:25:57] DEBUG[5141]: chan_sip.c:3560 registry_unref: SIP Registry [PROXY_URL]: refcount now 1 [May 24 22:25:57] NOTICE[5141]: chan_sip.c:5641 register_realtime_peers_with_callbackextens: Created realtime peer '[PEER_NAME]' for registration [May 24 22:25:57] DEBUG[5141]: config.c:1790 config_text_file_load: Parsing /etc/asterisk/sip_notify.conf == Parsing '/etc/asterisk/sip_notify.conf': Found [May 24 22:25:57] DEBUG[5141]: chan_sip.c:32561 reload_config: SIP reload_config done...Runtime= 0 sec [May 24 22:25:57] DEBUG[5141]: sched.c:546 ast_sched_dump: Asterisk Schedule Dump (0 in Q, 5 Total, 3 Cache, 2 high-water) [May 24 22:25:57] DEBUG[5141]: sched.c:551 ast_sched_dump: ============================================================= [May 24 22:25:57] DEBUG[5141]: sched.c:552 ast_sched_dump: |ID Callback Data Time (sec:ms) | [May 24 22:25:57] DEBUG[5141]: sched.c:553 ast_sched_dump: +-----+-----------------+-----------------+-----------------+ [May 24 22:25:57] DEBUG[5141]: sched.c:568 ast_sched_dump: ============================================================= [May 24 22:25:57] DEBUG[5141]: chan_sip.c:33229 sip_do_reload: --------------- Done destroying pruned peers [May 24 22:25:57] DEBUG[5141]: chan_sip.c:3568 registry_addref: SIP Registry [PROXY_URL]: refcount now 2 [May 24 22:25:57] DEBUG[5141]: chan_sip.c:33244 sip_do_reload: do_reload finished. peer poke/prune reg contact time = 0 sec. [May 24 22:25:57] DEBUG[5141]: chan_sip.c:33246 sip_do_reload: --------------- SIP reload done <<<<---- in chan_sip, the regobjs now count '-2' so 'ms' var 'sip_send_all_registers' is -12000 (with default_expiry = 120) ---->>>> [USERNAME]*CLI> sip show registry Host dnsmgr Username Refresh State Reg.Time [PROXY_URL]:5060 N [USERNAME] 120 Unregistered 1 SIP registrations.