[Sep 7 15:51:58] VERBOSE[27519] res_pjsip_logger.c: <--- Received SIP request (597 bytes) from TLS:10.0.0.132:1180 ---> REGISTER sip:sip0.srv.local:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 10.0.0.132:1180;rport;branch=z9hG4bKPjXDrLEtrSqpucmSYpeKBPcPWdF8bUwPT-;alias Max-Forwards: 70 From: "1003316" ;tag=v7Aa7aW3HZRlMC0aAe5-JReiJmlKgbyt To: "1003316" Call-ID: EQsHCbsokZ8abw5waEHve4NYfQmjyCsp CSeq: 33699 REGISTER User-Agent: sip client Supported: outbound, path Contact: ;reg-id=1;+sip.instance="" Expires: 0 Content-Length: 0 [Sep 7 15:51:58] DEBUG[27519] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=33699 (rdata0x7f745404ab18) [Sep 7 15:51:58] DEBUG[27519] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000031 to use for Request msg REGISTER/cseq=33699 (rdata0x7f745404ab18) [Sep 7 15:51:58] DEBUG[27550] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:51:58] DEBUG[27550] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:51:58] DEBUG[27550] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 7 15:51:58] DEBUG[27550] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Reusing ODBC handle 0x1e53310 from class 'asterisk' [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Parameter 1 ('id') = '1003316@sip0.srv.local' [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Releasing ODBC handle 0x1e53310 into pool [Sep 7 15:51:58] DEBUG[27550] res_pjsip_endpoint_identifier_user.c: Identified by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:51:58] DEBUG[27550] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1003316'. [Sep 7 15:51:58] DEBUG[27550] netsock2.c: Splitting '172.31.26.174' into... [Sep 7 15:51:58] DEBUG[27550] netsock2.c: ...host '172.31.26.174' and port ''. [Sep 7 15:51:58] DEBUG[27550] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:51:58] DEBUG[27550] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:51:58] DEBUG[27548] manager.c: Examining AMI event: Event: ChallengeSent Privilege: security,all EventTV: 2020-09-07T15:51:58.314+0900 Severity: Informational Service: PJSIP EventVersion: 1 AccountID: 1003316 SessionID: EQsHCbsokZ8abw5waEHve4NYfQmjyCsp LocalAddress: IPV4/TLS/172.31.26.174/5061 RemoteAddress: IPV4/TLS/10.0.0.132/1180 Challenge: [Sep 7 15:51:58] VERBOSE[27550] res_pjsip_logger.c: <--- Transmitting SIP response (584 bytes) to TLS:10.0.0.132:1180 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/TLS 10.0.0.132:1180;rport=1180;received=10.0.0.132;branch=z9hG4bKPjXDrLEtrSqpucmSYpeKBPcPWdF8bUwPT-;alias Call-ID: EQsHCbsokZ8abw5waEHve4NYfQmjyCsp From: "1003316" ;tag=v7Aa7aW3HZRlMC0aAe5-JReiJmlKgbyt To: "1003316" ;tag=z9hG4bKPjXDrLEtrSqpucmSYpeKBPcPWdF8bUwPT- CSeq: 33699 REGISTER WWW-Authenticate: Digest realm="asterisk",nonce="1599461518/bbfe9b56c88a568f8b292b067bb2f4c5",opaque="586ccb083aabab1a",algorithm=md5,qop="auth" Server: Asterisk PBX Content-Length: 0 [Sep 7 15:51:58] VERBOSE[27519] res_pjsip_logger.c: <--- Received SIP request (909 bytes) from TLS:10.0.0.132:1180 ---> REGISTER sip:sip0.srv.local:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 10.0.0.132:1180;rport;branch=z9hG4bKPjg33IFO.bSn2dF6UQIwufbYxzH3WJ.LW0;alias Max-Forwards: 70 From: "1003316" ;tag=v7Aa7aW3HZRlMC0aAe5-JReiJmlKgbyt To: "1003316" Call-ID: EQsHCbsokZ8abw5waEHve4NYfQmjyCsp CSeq: 33700 REGISTER User-Agent: sip client Supported: outbound, path Contact: ;reg-id=1;+sip.instance="" Expires: 0 Authorization: Digest username="1003316", realm="asterisk", nonce="1599461518/bbfe9b56c88a568f8b292b067bb2f4c5", uri="sip:sip0.srv.local:5061;transport=tls", response="baa7b8ae3163f081632c3684ca0972d0", algorithm=md5, cnonce="uXBxJT6rvITN1HDclFS9wgnIIbpQVW6t", opaque="586ccb083aabab1a", qop=auth, nc=00000001 Content-Length: 0 [Sep 7 15:51:58] DEBUG[27519] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=33700 (rdata0x7f745404ab18) [Sep 7 15:51:58] DEBUG[27519] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000031 to use for Request msg REGISTER/cseq=33700 (rdata0x7f745404ab18) [Sep 7 15:51:58] DEBUG[27550] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:51:58] DEBUG[27550] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:51:58] DEBUG[27550] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 7 15:51:58] DEBUG[27550] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Reusing ODBC handle 0x1e53310 from class 'asterisk' [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Parameter 1 ('id') = '1003316@sip0.srv.local' [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Releasing ODBC handle 0x1e53310 into pool [Sep 7 15:51:58] DEBUG[27550] res_pjsip_endpoint_identifier_user.c: Identified by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:51:58] DEBUG[27550] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1003316'. [Sep 7 15:51:58] DEBUG[27550] res_pjsip_authenticator_digest.c: Calculated nonce 1599461518/bbfe9b56c88a568f8b292b067bb2f4c5. Actual nonce is 1599461518/bbfe9b56c88a568f8b292b067bb2f4c5 [Sep 7 15:51:58] DEBUG[27550] netsock2.c: Splitting '172.31.26.174' into... [Sep 7 15:51:58] DEBUG[27550] netsock2.c: ...host '172.31.26.174' and port ''. [Sep 7 15:51:58] DEBUG[27550] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:51:58] DEBUG[27550] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:51:58] DEBUG[27548] manager.c: Examining AMI event: Event: SuccessfulAuth Privilege: security,all EventTV: 2020-09-07T15:51:58.334+0900 Severity: Informational Service: PJSIP EventVersion: 1 AccountID: 1003316 SessionID: EQsHCbsokZ8abw5waEHve4NYfQmjyCsp LocalAddress: IPV4/TLS/172.31.26.174/5061 RemoteAddress: IPV4/TLS/10.0.0.132/1180 UsingPassword: 1 [Sep 7 15:51:58] DEBUG[27550] res_pjsip_registrar.c: Matched id '1003316' to aor '1003316' [Sep 7 15:51:58] DEBUG[27550] res_pjsip_registrar.c: Matched aor '1003316' by To username [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Reusing ODBC handle 0x1e53310 from class 'asterisk' [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Parameter 1 ('id LIKE') = '1003316;@%' [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Releasing ODBC handle 0x1e53310 into pool [Sep 7 15:51:58] DEBUG[27550] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 7 15:51:58] DEBUG[27550] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:51:58] DEBUG[27550] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 7 15:51:58] DEBUG[27550] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 7 15:51:58] DEBUG[27550] config.c: extract uint from [1180] in [0, 4294967295] gives [1180](0) [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Reusing ODBC handle 0x1e53310 from class 'asterisk' [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Skip: 0; SQL: DELETE FROM ps_contacts WHERE id=? [Sep 7 15:51:58] DEBUG[27550] res_config_odbc.c: Parameter 1 = '1003316;@d46d1ff6c4d83c2c654e05df07e3aff2' [Sep 7 15:51:58] DEBUG[27550] res_odbc.c: Releasing ODBC handle 0x1e53310 into pool [Sep 7 15:51:58] VERBOSE[27550] res_pjsip_logger.c: <--- Transmitting SIP response (477 bytes) to TLS:10.0.0.132:1180 ---> SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.132:1180;rport=1180;received=10.0.0.132;branch=z9hG4bKPjg33IFO.bSn2dF6UQIwufbYxzH3WJ.LW0;alias Call-ID: EQsHCbsokZ8abw5waEHve4NYfQmjyCsp From: "1003316" ;tag=v7Aa7aW3HZRlMC0aAe5-JReiJmlKgbyt To: "1003316" ;tag=z9hG4bKPjg33IFO.bSn2dF6UQIwufbYxzH3WJ.LW0 CSeq: 33700 REGISTER Date: Mon, 07 Sep 2020 06:51:58 GMT Expires: 0 Server: Asterisk PBX Content-Length: 0