[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for  Call ID: 2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060 (Checking From) --From tag as17bda15c --To-tag
[2015-12-29 22:01:15] 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:01:15] DEBUG[12881]: chan_sip.c:3866 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address 172.16.15.194:5060
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196:5060' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port '5060'.
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:8765 __sip_alloc: Allocating new SIP dialog for 2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060 - INVITE (No RTP)
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: chan_sip.c:28018 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: sip/reqresp_parser.c:1700 parse_sip_options: Begin: parsing SIP "Supported: replaces, timer"
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: sip/reqresp_parser.c:1715 parse_sip_options: Found SIP option: -replaces-
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: sip/reqresp_parser.c:1723 parse_sip_options: Matched SIP option: replaces
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: sip/reqresp_parser.c:1715 parse_sip_options: Found SIP option: -timer-
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: sip/reqresp_parser.c:1723 parse_sip_options: Matched SIP option: timer
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196:5060' into...
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port '5060'.
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196' into...
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port ''.
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: 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:01:15] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for  Call ID: 2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060 (Checking From) --From tag as17bda15c --To-tag as20997488
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: chan_sip.c:28018 handle_incoming: **** Received ACK (6) - Command in SIP ACK
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: chan_sip.c:4406 __sip_ack: Stopping retransmission on '2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060' of Response 102: Match Found
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for  Call ID: 2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060 (Checking From) --From tag as17bda15c --To-tag
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''.
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''.
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: chan_sip.c:28018 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196:5060' into...
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port '5060'.
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.196' into...
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.196' and port ''.
[2015-12-29 22:01:15] WARNING[12881][C-00000002]: chan_sip.c:16653 check_auth: username mismatch, have <1101>, digest has <001002>
[2015-12-29 22:01:15] NOTICE[12881][C-00000002]: chan_sip.c:25550 handle_request_invite: Failed to authenticate device "TEST 123" <sip:1101@172.16.15.196>;tag=as17bda15c
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: 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:01:15] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for  Call ID: 2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060 (Checking From) --From tag as17bda15c --To-tag as20997488
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: chan_sip.c:28018 handle_incoming: **** Received ACK (6) - Command in SIP ACK
[2015-12-29 22:01:15] DEBUG[12881][C-00000002]: chan_sip.c:4406 __sip_ack: Stopping retransmission on '2622c80517c01fad5a29c3dd1ac42180@172.16.15.196:5060' of Response 103: Match Found
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for  Call ID: vFCw7F6N9PJXbPupTzHuivEmk3Nop9Ap (Checking From) --From tag DuvNwIWDAiNsNE0mJauKTcnEcd.sWyvy --To-tag as60b06550
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:28018 handle_incoming: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''.
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:3709 __sip_xmit: Trying to put 'SIP/2.0 401' onto UDP socket destined for 172.16.8.11:5060
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:9178 __find_call: = Looking for  Call ID: vFCw7F6N9PJXbPupTzHuivEmk3Nop9Ap (Checking From) --From tag DuvNwIWDAiNsNE0mJauKTcnEcd.sWyvy --To-tag as60b06550
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:28018 handle_incoming: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''.
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194:5060' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''.
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '172.16.15.194' into...
[2015-12-29 22:01:15] DEBUG[12881]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '172.16.15.194' and port ''.
[2015-12-29 22:01:15] DEBUG[12881]: chan_sip.c:3709 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 172.16.8.11:5060