[2015-12-29 22:52:22] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for Call ID: 4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060 (Checking From) --From tag as046dda29 --To-tag [2015-12-29 22:52:22] DEBUG[12881]: acl.c:946 ast_ouraddrfor: For destination '172.16.15.196', our source address is '172.16.15.194'. [2015-12-29 22:52:22] DEBUG[12881]: chan_sip.c:3866 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address 172.16.15.194:5060 [2015-12-29 22:52:22] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196:5060' into... [2015-12-29 22:52:22] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port '5060'. [2015-12-29 22:52:22] DEBUG[12881]: chan_sip.c:8765 __sip_alloc: Allocating new SIP dialog for 4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060 - INVITE (No RTP) [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:28018 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: sip/reqresp_parser.c:1700 parse_sip_options: Begin: parsing SIP "Supported: replaces, timer" [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: sip/reqresp_parser.c:1715 parse_sip_options: Found SIP option: -replaces- [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: sip/reqresp_parser.c:1723 parse_sip_options: Matched SIP option: replaces [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: sip/reqresp_parser.c:1715 parse_sip_options: Found SIP option: -timer- [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: sip/reqresp_parser.c:1723 parse_sip_options: Matched SIP option: timer [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196:5060' into... [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port '5060'. [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196' into... [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port ''. [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:3709 __sip_xmit: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.15.196:5060 [2015-12-29 22:52:22] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for Call ID: 4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060 (Checking From) --From tag as046dda29 --To-tag as616f79db [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:28018 handle_incoming: **** Received ACK (6) - Command in SIP ACK [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:4406 __sip_ack: Stopping retransmission on '4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060' of Response 102: Match Found [2015-12-29 22:52:22] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for Call ID: 4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060 (Checking From) --From tag as046dda29 --To-tag [2015-12-29 22:52:22] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into... [2015-12-29 22:52:22] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''. [2015-12-29 22:52:22] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into... [2015-12-29 22:52:22] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''. [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:28018 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196:5060' into... [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port '5060'. [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196' into... [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port ''. [2015-12-29 22:52:22] WARNING[12881][C-00000006]: chan_sip.c:16653 check_auth: username mismatch, have <1101>, digest has <001002> [2015-12-29 22:52:22] NOTICE[12881][C-00000006]: chan_sip.c:25550 handle_request_invite: Failed to authenticate device "TEST 123" ;tag=as046dda29 [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:3709 __sip_xmit: Trying to put 'SIP/2.0 403' onto UDP socket destined for 172.16.15.196:5060 [2015-12-29 22:52:22] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for Call ID: 4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060 (Checking From) --From tag as046dda29 --To-tag as616f79db [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:28018 handle_incoming: **** Received ACK (6) - Command in SIP ACK [2015-12-29 22:52:22] DEBUG[12881][C-00000006]: chan_sip.c:4406 __sip_ack: Stopping retransmission on '4f4f746d53d0ea6d1d6eecc639c36066@172.16.15.196:5060' of Response 103: Match Found