debian1*CLI> [Jan 23 15:46:57] DEBUG[5892]: pjsip:0 : sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=12538 (rdata0x9eae5c4) <--- Received SIP request (662 bytes) from UDP:10.24.18.138:5060 ---> SUBSCRIBE sip:000FD3012445@10.24.17.195:5060 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjf7727aQWmxoO2-1nz0qzjV69Wr6Yv.rt Max-Forwards: 70 From: "Temple Morgan" ;tag=FjMlF2cSwpT4kzAPqRAJr09v1zPzH3a9 To: sip:000FD3012445@10.24.17.195 Contact: "Temple Morgan" Call-ID: 2fRdL.mUqibR1pjgv5TP.oWEnscWfELt CSeq: 12538 SUBSCRIBE Event: presence Expires: 600 Supported: replaces, 100rel, timer, norefersub Accept: application/pidf+xml, application/xpidf+xml Allow-Events: presence, message-summary, refer User-Agent: Digium D40 1_4_2_0_63880 Content-Length: 0 [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : sip_endpoint.c Distributing rdata to modules: Request msg SUBSCRIBE/cseq=12538 (rdata0x9f01174) [Jan 23 15:46:57] DEBUG[5887]: res_pjsip_endpoint_identifier_user.c:104 username_identify: Retrieved endpoint 000FD3012445 [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : endpoint .Response msg 401/SUBSCRIBE/cseq=12538 (tdta0x9f18780) created [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.24.17.195' into... [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.24.17.195' and port ''. [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.24.18.138' into... [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.24.18.138' and port ''. <--- Transmitting SIP response (554 bytes) to UDP:10.24.18.138:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.24.18.138:5060;rport=5060;received=10.24.18.138;branch=z9hG4bKPjf7727aQWmxoO2-1nz0qzjV69Wr6Yv.rt Call-ID: 2fRdL.mUqibR1pjgv5TP.oWEnscWfELt From: "Temple Morgan" ;tag=FjMlF2cSwpT4kzAPqRAJr09v1zPzH3a9 To: ;tag=z9hG4bKPjf7727aQWmxoO2-1nz0qzjV69Wr6Yv.rt CSeq: 12538 SUBSCRIBE WWW-Authenticate: Digest realm="asterisk",nonce="1422049617/22af6a6e6afa8f0d997b8e19a136590e",opaque="5218e55e491e657a",algorithm=md5,qop="auth" Content-Length: 0 [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : tdta0x9f18780 .Destroying txdata Response msg 401/SUBSCRIBE/cseq=12538 (tdta0x9f18780) [Jan 23 15:46:57] DEBUG[5892]: pjsip:0 : sip_endpoint.c Processing incoming message: Request msg SUBSCRIBE/cseq=12539 (rdata0x9eae5c4) <--- Received SIP request (975 bytes) from UDP:10.24.18.138:5060 ---> SUBSCRIBE sip:000FD3012445@10.24.17.195:5060 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.138:5060;rport;branch=z9hG4bKPjiWBpm9xfHCs87WgJM5mDuexD1p5loHCL Max-Forwards: 70 From: "Temple Morgan" ;tag=FjMlF2cSwpT4kzAPqRAJr09v1zPzH3a9 To: sip:000FD3012445@10.24.17.195 Contact: "Temple Morgan" Call-ID: 2fRdL.mUqibR1pjgv5TP.oWEnscWfELt CSeq: 12539 SUBSCRIBE Event: presence Expires: 600 Supported: replaces, 100rel, timer, norefersub Accept: application/pidf+xml, application/xpidf+xml Allow-Events: presence, message-summary, refer User-Agent: Digium D40 1_4_2_0_63880 Authorization: Digest username="000FD3012445", realm="asterisk", nonce="1422049617/22af6a6e6afa8f0d997b8e19a136590e", uri="sip:000FD3012445@10.24.17.195:5060", response="b699c6f02b1c46d3362738b7e12fc893", algorithm=md5, cnonce="Euy5p42US3VBSt9aHlfYncjn4bRqSmmq", opaque="5218e55e491e657a", qop=auth, nc=00000001 Content-Length: 0 [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : sip_endpoint.c Distributing rdata to modules: Request msg SUBSCRIBE/cseq=12539 (rdata0x9f01174) [Jan 23 15:46:57] DEBUG[5887]: res_pjsip_endpoint_identifier_user.c:104 username_identify: Retrieved endpoint 000FD3012445 [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : endpoint .Response msg 401/SUBSCRIBE/cseq=12539 (tdta0x9f18780) created [Jan 23 15:46:57] DEBUG[5887]: res_pjsip_authenticator_digest.c:250 check_nonce: Calculated nonce 1422049617/22af6a6e6afa8f0d997b8e19a136590e. Actual nonce is 1422049617/22af6a6e6afa8f0d997b8e19a136590e [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.24.17.195' into... [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.24.17.195' and port ''. [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.24.18.138' into... [Jan 23 15:46:57] DEBUG[5887]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.24.18.138' and port ''. [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : tdta0x9f18780 .Destroying txdata Response msg 401/SUBSCRIBE/cseq=12539 (tdta0x9f18780) [Jan 23 15:46:57] DEBUG[5887]: res_pjsip_pubsub.c:2448 find_body_generator: Body generator 0xb5c44b60 found for accept type application/pidf+xml [Jan 23 15:46:57] DEBUG[5887]: res_pjsip_pubsub.c:956 build_resource_tree: Subscription to resource 000FD3012445 is not to a list [Jan 23 15:46:57] WARNING[5887]: res_pjsip_exten_state.c:337 new_subscribe: Extension 000FD3012445 does not exist or has no associated hint [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : endpoint .Response msg 404/SUBSCRIBE/cseq=12539 (tdta0x9f18780) created <--- Transmitting SIP response (404 bytes) to UDP:10.24.18.138:5060 ---> SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.24.18.138:5060;rport=5060;received=10.24.18.138;branch=z9hG4bKPjiWBpm9xfHCs87WgJM5mDuexD1p5loHCL Call-ID: 2fRdL.mUqibR1pjgv5TP.oWEnscWfELt From: "Temple Morgan" ;tag=FjMlF2cSwpT4kzAPqRAJr09v1zPzH3a9 To: ;tag=z9hG4bKPjiWBpm9xfHCs87WgJM5mDuexD1p5loHCL CSeq: 12539 SUBSCRIBE Content-Length: 0 [Jan 23 15:46:57] DEBUG[5887]: pjsip:0 : tdta0x9f18780 .Destroying txdata Response msg 404/SUBSCRIBE/cseq=12539 (tdta0x9f18780) [Jan 23 15:47:04] DEBUG[5892]: pjsip:0 : sip_endpoint.c Processing incoming message: Request msg REGISTER/cseq=779 (rdata0x9eae5c4)