[Apr 13 11:17:11] VERBOSE[17071] chan_sip.c: Reloading SIP [Apr 13 11:17:11] DEBUG[3093] db.c: Unable to find key '(bogus_peer)' in family 'SIP/Registry' [Apr 13 11:17:11] DEBUG[3093] chan_sip.c: Destroying SIP peer (bogus_peer) [Apr 13 11:17:11] DEBUG[17071] config.c: Parsing /etc/asterisk/sip.conf [Apr 13 11:17:11] VERBOSE[17071] config.c: Parsing '/etc/asterisk/sip.conf': Found [Apr 13 11:17:11] DEBUG[17071] config.c: Parsing /etc/asterisk/include/sip_peers.conf [Apr 13 11:17:11] VERBOSE[17071] config.c: Parsing '/etc/asterisk/include/sip_peers.conf': Found [Apr 13 11:17:11] DEBUG[17071] config.c: Parsing /etc/asterisk/include/sip_friends.conf [Apr 13 11:17:11] VERBOSE[17071] config.c: Parsing '/etc/asterisk/include/sip_friends.conf': Found [Apr 13 11:17:11] DEBUG[17071] config.c: Parsing /etc/asterisk/users.conf [Apr 13 11:17:11] VERBOSE[17071] config.c: Parsing '/etc/asterisk/users.conf': Found [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: --------------- SIP reload started [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: --------------- Done destroying registry list [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '0.0.0.0:0' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '0.0.0.0' and port '0'. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Enabling TCP socket for listening [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '192.168.0.177' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '192.168.0.177' and port ''. [Apr 13 11:17:11] DEBUG[17071] config.c: extract addr from 192.168.0.177 gives 192.168.0.177:0(0) [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '192.168.0.177' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '192.168.0.177' and port ''. [Apr 13 11:17:11] DEBUG[17071] config.c: extract addr from 192.168.0.177 gives 192.168.0.177:0(0) [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Setting TCP socket address to 192.168.0.177:0 [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '192.168.0.177' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '192.168.0.177' and port ''. [Apr 13 11:17:11] DEBUG[17071] config.c: extract addr from 192.168.0.177 gives 192.168.0.177:0(0) [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '192.168.0.0' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '192.168.0.0' and port ''. [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '255.255.0.0' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '255.255.0.0' and port ''. [Apr 13 11:17:11] DEBUG[17071] acl.c: 192.168.0.0:0/255.255.0.0:0 sense 0 appended to ACL [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '10.0.0.0' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '10.0.0.0' and port ''. [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '255.0.0.0' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '255.0.0.0' and port ''. [Apr 13 11:17:11] DEBUG[17071] acl.c: 10.0.0.0:0/255.0.0.0:0 sense 0 appended to ACL [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '172.16.0.0' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '172.16.0.0' and port ''. [Apr 13 11:17:11] DEBUG[17071] acl.c: 172.16.0.0:0/255.240.0.0:0 sense 0 appended to ACL [Apr 13 11:17:11] DEBUG[17071] acl.c: Attached to given IP address [Apr 13 11:17:11] VERBOSE[17071] netsock2.c: Using SIP TOS bits 96 [Apr 13 11:17:11] VERBOSE[17071] netsock2.c: Using SIP CoS mark 4 [Apr 13 11:17:11] DEBUG[17071] tcptls.c: Nothing changed in SIP TCP server [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: SIP TCP server started [Apr 13 11:17:11] VERBOSE[17071] tcptls.c: TLS/SSL ECDH initialized (automatic), faster PFS ciphers enabled [Apr 13 11:17:11] VERBOSE[17071] tcptls.c: TLS/SSL certificate ok [Apr 13 11:17:11] DEBUG[17071] netsock2.c: Splitting '192.168.0.30' into... [Apr 13 11:17:11] DEBUG[17071] netsock2.c: ...host '192.168.0.30' and port ''. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Apr 13 11:17:11] DEBUG[17071] db.c: Unable to find key 'ksy-test' in family 'SIP/Registry' [Apr 13 11:17:11] DEBUG[17021] threadpool.c: Increasing threadpool stasis-core's size by 1 [Apr 13 11:17:11] DEBUG[17021] threadpool.c: Increasing threadpool stasis-core's size by 1 [Apr 13 11:17:11] DEBUG[17071] config.c: Parsing /etc/asterisk/sip_notify.conf [Apr 13 11:17:11] VERBOSE[17071] config.c: Parsing '/etc/asterisk/sip_notify.conf': Found [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: SIP reload_config done...Runtime= 0 sec [Apr 13 11:17:11] DEBUG[17071] sched.c: Asterisk Schedule Dump (1 in Q, 668 Total, 12 Cache, 12 high-water) [Apr 13 11:17:11] DEBUG[17071] sched.c: ============================================================= [Apr 13 11:17:11] DEBUG[17071] sched.c: |ID Callback Data Time (sec:ms) | [Apr 13 11:17:11] DEBUG[17071] sched.c: +-----+-----------------+-----------------+-----------------+ [Apr 13 11:17:11] DEBUG[17071] sched.c: |0668 | 0x7f19e4961e20 | 0x55cd1a9dde38 | 000005 : 153178 | [Apr 13 11:17:11] DEBUG[17071] sched.c: ============================================================= [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: --------------- Done destroying pruned peers [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: do_reload finished. peer poke/prune reg contact time = 0 sec. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: --------------- SIP reload done [Apr 13 11:17:11] DEBUG[3103] taskprocessor.c: destroying taskprocessor '76fe4d14-ff2f-4d59-843f-ee3a4a602cd5' [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Allocating new SIP dialog for 3b3e2e98591c2489394f6511075fd325@192.168.0.177:5060 - OPTIONS (No RTP) [Apr 13 11:17:11] DEBUG[17071] acl.c: For destination '192.168.0.30', our source address is '192.168.0.172'. [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Setting AST_TRANSPORT_UDP with address 192.168.0.177:5060 [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: SIP call-id changed from '3b3e2e98591c2489394f6511075fd325@192.168.0.177:5060' to '7d5569894f755eb21eeb46053872d1df@192.168.0.177:5060' [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Initializing initreq for method OPTIONS - callid 7d5569894f755eb21eeb46053872d1df@192.168.0.177:5060 [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.0.30:5060 [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: = Looking for Call ID: 7d5569894f755eb21eeb46053872d1df@192.168.0.177:5060 (Checking To) --From tag as131dcd9f --To-tag as5ba25c4d [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Stopping retransmission on '7d5569894f755eb21eeb46053872d1df@192.168.0.177:5060' of Request 102: Match Found [Apr 13 11:17:11] DEBUG[17071] chan_sip.c: Destroying SIP dialog 7d5569894f755eb21eeb46053872d1df@192.168.0.177:5060 11:17:11.038793 IP 192.168.0.177.5060 > 192.168.0.30.5060: SIP: OPTIONS sip:192.168.0.30 SIP/2.0 11:17:11.039586 IP 192.168.0.30.5060 > 192.168.0.177.5060: SIP: SIP/2.0 404 Not Found [Apr 13 11:18:01] DEBUG[3201] db.c: Unable to find key '(bogus_peer)' in family 'SIP/Registry' [Apr 13 11:18:01] VERBOSE[17071] chan_sip.c: Reloading SIP [Apr 13 11:18:01] DEBUG[3201] chan_sip.c: Destroying SIP peer (bogus_peer) [Apr 13 11:18:01] DEBUG[17071] config.c: Parsing /etc/asterisk/sip.conf [Apr 13 11:18:01] VERBOSE[17071] config.c: Parsing '/etc/asterisk/sip.conf': Found [Apr 13 11:18:01] DEBUG[17071] config.c: Parsing /etc/asterisk/include/sip_peers.conf [Apr 13 11:18:01] VERBOSE[17071] config.c: Parsing '/etc/asterisk/include/sip_peers.conf': Found [Apr 13 11:18:01] DEBUG[17071] config.c: Parsing /etc/asterisk/include/sip_friends.conf [Apr 13 11:18:01] VERBOSE[17071] config.c: Parsing '/etc/asterisk/include/sip_friends.conf': Found [Apr 13 11:18:01] DEBUG[17071] config.c: Parsing /etc/asterisk/users.conf [Apr 13 11:18:01] VERBOSE[17071] config.c: Parsing '/etc/asterisk/users.conf': Found [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: --------------- SIP reload started [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: --------------- Done destroying registry list [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '0.0.0.0:0' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '0.0.0.0' and port '0'. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Enabling TCP socket for listening [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '192.168.0.177' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '192.168.0.177' and port ''. [Apr 13 11:18:01] DEBUG[17071] config.c: extract addr from 192.168.0.177 gives 192.168.0.177:0(0) [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '192.168.0.177' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '192.168.0.177' and port ''. [Apr 13 11:18:01] DEBUG[17071] config.c: extract addr from 192.168.0.177 gives 192.168.0.177:0(0) [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Setting TCP socket address to 192.168.0.177:0 [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '192.168.0.177' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '192.168.0.177' and port ''. [Apr 13 11:18:01] DEBUG[17071] config.c: extract addr from 192.168.0.177 gives 192.168.0.177:0(0) [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '192.168.0.0' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '192.168.0.0' and port ''. [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '255.255.0.0' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '255.255.0.0' and port ''. [Apr 13 11:18:01] DEBUG[17071] acl.c: 192.168.0.0:0/255.255.0.0:0 sense 0 appended to ACL [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '10.0.0.0' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '10.0.0.0' and port ''. [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '255.0.0.0' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '255.0.0.0' and port ''. [Apr 13 11:18:01] DEBUG[17071] acl.c: 10.0.0.0:0/255.0.0.0:0 sense 0 appended to ACL [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '172.16.0.0' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '172.16.0.0' and port ''. [Apr 13 11:18:01] DEBUG[17071] acl.c: 172.16.0.0:0/255.240.0.0:0 sense 0 appended to ACL [Apr 13 11:18:01] DEBUG[17071] acl.c: Attached to given IP address [Apr 13 11:18:01] VERBOSE[17071] netsock2.c: Using SIP TOS bits 96 [Apr 13 11:18:01] VERBOSE[17071] netsock2.c: Using SIP CoS mark 4 [Apr 13 11:18:01] DEBUG[17071] tcptls.c: Nothing changed in SIP TCP server [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: SIP TCP server started [Apr 13 11:18:01] VERBOSE[17071] tcptls.c: TLS/SSL ECDH initialized (automatic), faster PFS ciphers enabled [Apr 13 11:18:01] VERBOSE[17071] tcptls.c: TLS/SSL certificate ok [Apr 13 11:18:01] DEBUG[17071] tcptls.c: Nothing changed in SIP TLS server [Apr 13 11:18:01] DEBUG[17071] netsock2.c: Splitting '192.168.0.30' into... [Apr 13 11:18:01] DEBUG[17071] netsock2.c: ...host '192.168.0.30' and port ''. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Apr 13 11:18:01] DEBUG[17021] threadpool.c: Increasing threadpool stasis-core's size by 1 [Apr 13 11:18:01] DEBUG[17071] db.c: Unable to find key 'ksy-test' in family 'SIP/Registry' [Apr 13 11:18:01] DEBUG[3221] taskprocessor.c: destroying taskprocessor 'd46fd1da-bb9f-4851-a822-d09408fcb6e3' [Apr 13 11:18:01] DEBUG[17071] config.c: Parsing /etc/asterisk/sip_notify.conf [Apr 13 11:18:01] VERBOSE[17071] config.c: Parsing '/etc/asterisk/sip_notify.conf': Found [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: SIP reload_config done...Runtime= 0 sec [Apr 13 11:18:01] DEBUG[17071] sched.c: Asterisk Schedule Dump (1 in Q, 674 Total, 12 Cache, 12 high-water) [Apr 13 11:18:01] DEBUG[17071] sched.c: ============================================================= [Apr 13 11:18:01] DEBUG[17071] sched.c: |ID Callback Data Time (sec:ms) | [Apr 13 11:18:01] DEBUG[17071] sched.c: +-----+-----------------+-----------------+-----------------+ [Apr 13 11:18:01] DEBUG[17071] sched.c: |0674 | 0x7f19e4961e20 | 0x55cd1a9dde38 | 000009 : 361029 | [Apr 13 11:18:01] DEBUG[17071] sched.c: ============================================================= [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: --------------- Done destroying pruned peers [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: do_reload finished. peer poke/prune reg contact time = 0 sec. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: --------------- SIP reload done [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Allocating new SIP dialog for 361634b114d19f163e3dea09636f096d@192.168.0.177:5060 - OPTIONS (No RTP) [Apr 13 11:18:01] DEBUG[17071] acl.c: For destination '192.168.0.30', our source address is '192.168.0.172'. [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Setting AST_TRANSPORT_TCP with address 192.168.0.177:5060 [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: SIP call-id changed from '361634b114d19f163e3dea09636f096d@192.168.0.177:5060' to '4866f1493da3a1cb06722b2c5fa91c10@192.168.0.177:5060' [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Initializing initreq for method OPTIONS - callid 4866f1493da3a1cb06722b2c5fa91c10@192.168.0.177:5060 [Apr 13 11:18:01] DEBUG[17071] chan_sip.c: Trying to put 'OPTIONS sip' onto TCP socket destined for 192.168.0.30:5060 [Apr 13 11:18:01] DEBUG[3222] chan_sip.c: Starting thread for TCP server [Apr 13 11:18:01] DEBUG[3222] chan_sip.c: = Looking for Call ID: 4866f1493da3a1cb06722b2c5fa91c10@192.168.0.177:5060 (Checking To) --From tag as3033ec21 --To-tag as494c2bcf [Apr 13 11:18:02] DEBUG[17071] chan_sip.c: Destroying SIP dialog 4866f1493da3a1cb06722b2c5fa91c10@192.168.0.177:5060 11:18:01.679215 IP 192.168.0.172.46306 > 192.168.0.30.5060: Flags [S], seq 3699117450, win 29200, options [mss 1460,sackOK,TS val 426496197 ecr 0,nop,wscale 7], length 0 11:18:01.680042 IP 192.168.0.30.5060 > 192.168.0.172.46306: Flags [S.], seq 3548730501, ack 3699117451, win 28960, options [mss 1460,sackOK,TS val 129502697 ecr 426496197,nop,wscale 7], length 0 11:18:01.680094 IP 192.168.0.172.46306 > 192.168.0.30.5060: Flags [.], ack 1, win 229, options [nop,nop,TS val 426496198 ecr 129502697], length 0 11:18:01.680169 IP 192.168.0.172.46306 > 192.168.0.30.5060: Flags [P.], seq 1:573, ack 1, win 229, options [nop,nop,TS val 426496198 ecr 129502697], length 572 11:18:01.680678 IP 192.168.0.30.5060 > 192.168.0.172.46306: Flags [.], ack 573, win 236, options [nop,nop,TS val 129502697 ecr 426496198], length 0 11:18:01.680885 IP 192.168.0.30.5060 > 192.168.0.172.46306: Flags [P.], seq 1:512, ack 573, win 236, options [nop,nop,TS val 129502697 ecr 426496198], length 511 11:18:01.680912 IP 192.168.0.172.46306 > 192.168.0.30.5060: Flags [.], ack 512, win 237, options [nop,nop,TS val 426496198 ecr 129502697], length 0