[Sep 9 14:22:36] Asterisk 13.11.1 built by root @ bkk on a i686 running Linux on 2016-09-09 04:12:13 UTC [Sep 9 14:22:36] VERBOSE[19146] config.c: Parsing '/etc/asterisk/logger.conf': Found [Sep 9 14:22:38] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:38] VERBOSE[20771] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:22:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:41] VERBOSE[20783] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:22:42] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:42] VERBOSE[20787] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:22:46] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:46] VERBOSE[20794] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:22:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416566 Interval: 30 [Sep 9 14:22:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:22:50] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:50] VERBOSE[20801] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:22:54] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:54] VERBOSE[20810] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:22:58] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:22:58] VERBOSE[20817] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:02] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:02] VERBOSE[20824] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:06] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:06] VERBOSE[20833] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:10] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:10] VERBOSE[20840] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:11] VERBOSE[20850] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:14] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:14] VERBOSE[20872] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416596 Interval: 30 [Sep 9 14:23:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:23:18] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:18] VERBOSE[20879] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:22] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:22] VERBOSE[20886] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:26] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:26] VERBOSE[20893] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:30] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:30] VERBOSE[20900] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:34] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:34] VERBOSE[20907] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:38] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:38] VERBOSE[20914] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:41] VERBOSE[20924] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:42] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:42] VERBOSE[20928] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416626 Interval: 30 [Sep 9 14:23:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:23:46] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:46] VERBOSE[20935] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:50] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:50] VERBOSE[20942] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:54] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:54] VERBOSE[20949] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:23:58] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:23:58] VERBOSE[20956] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:02] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:02] VERBOSE[20963] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:07] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:07] VERBOSE[20970] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:11] VERBOSE[20977] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:11] VERBOSE[20987] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:15] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:15] VERBOSE[20993] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416656 Interval: 30 [Sep 9 14:24:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:24:19] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:19] VERBOSE[21000] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:23] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:23] VERBOSE[21007] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:27] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:27] VERBOSE[21016] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:31] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:31] VERBOSE[21023] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:35] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:35] VERBOSE[21030] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:39] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:39] VERBOSE[21037] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:41] VERBOSE[21047] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:43] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:43] VERBOSE[21051] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416686 Interval: 30 [Sep 9 14:24:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:24:47] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:47] VERBOSE[21058] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:51] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:51] VERBOSE[21065] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:55] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:55] VERBOSE[21072] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:24:59] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:24:59] VERBOSE[21079] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:03] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:03] VERBOSE[21086] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:07] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:07] VERBOSE[21093] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:11] VERBOSE[21100] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:11] VERBOSE[21110] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:15] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:15] VERBOSE[21114] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416716 Interval: 30 [Sep 9 14:25:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:25:19] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:19] VERBOSE[21121] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:23] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:23] VERBOSE[21128] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:27] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:27] VERBOSE[21135] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:31] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:31] VERBOSE[21142] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:35] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:35] VERBOSE[21149] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:39] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:39] VERBOSE[21156] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:41] VERBOSE[21166] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:43] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:43] VERBOSE[21170] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416746 Interval: 30 [Sep 9 14:25:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:25:47] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:47] VERBOSE[21177] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:51] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:51] VERBOSE[21184] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:55] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:55] VERBOSE[21191] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:25:59] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:25:59] VERBOSE[21198] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:03] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:04] VERBOSE[21205] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:08] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:08] VERBOSE[21212] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:11] VERBOSE[21222] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:12] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:12] VERBOSE[21226] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:16] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:16] VERBOSE[21233] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416776 Interval: 30 [Sep 9 14:26:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:26:20] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:20] VERBOSE[21240] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:24] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:24] VERBOSE[21247] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:28] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:28] VERBOSE[21254] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:32] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:32] VERBOSE[21261] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:36] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:36] VERBOSE[21268] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:40] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:40] VERBOSE[21275] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:41] VERBOSE[21285] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:44] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:44] VERBOSE[21294] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416806 Interval: 30 [Sep 9 14:26:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:26:48] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:48] VERBOSE[21301] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:52] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:52] VERBOSE[21308] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:26:56] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:26:56] VERBOSE[21315] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:00] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:00] VERBOSE[21322] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:04] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:04] VERBOSE[21329] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:08] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:08] VERBOSE[21336] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:11] VERBOSE[21346] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:12] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:12] VERBOSE[21350] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:16] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:16] VERBOSE[21357] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416836 Interval: 30 [Sep 9 14:27:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:27:18] DEBUG[9232] pjproject: sip_endpoint.c Processing incoming message: Request msg REGISTER/cseq=1391 (rdata0xb6e0128c) [Sep 9 14:27:18] VERBOSE[9232] res_pjsip_logger.c: <--- Received SIP request (841 bytes) from UDP:192.168.32.101:5060 ---> REGISTER sip:192.168.32.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.32.101:5060;branch=z9hG4bK452598042 From: "3577" ;tag=760838200 To: "3577" Call-ID: 0_1184989583@192.168.32.101 CSeq: 1391 REGISTER Contact: Authorization: Digest username="3577", realm="asterisk", nonce="1473413838/840d3933b25c9de6f7f080be0fc6894b", uri="sip:192.168.32.254:5060", response="fcd986341b5a390e0b9bd344c6b2054b", algorithm=MD5, cnonce="0a4f113b", opaque="48854dcc60abc998", qop=auth, nc=00000002 Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T27P 45.80.14.2 Expires: 3600 Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 0 [Sep 9 14:27:18] DEBUG[9232] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=1391 (rdata0xb6e0128c) [Sep 9 14:27:18] DEBUG[9232] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000042 to use for Request msg REGISTER/cseq=1391 (rdata0xb6e0128c) [Sep 9 14:27:18] DEBUG[9230] threadpool.c: Increasing threadpool SIP's size by 5 [Sep 9 14:27:18] DEBUG[21363] pjproject: sip_endpoint.c Distributing rdata to modules: Request msg REGISTER/cseq=1391 (rdata0xb6e0ae74) [Sep 9 14:27:18] DEBUG[21363] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 9 14:27:18] DEBUG[21363] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '3577' domain '192.168.32.254' [Sep 9 14:27:18] DEBUG[21363] res_pjsip_endpoint_identifier_user.c: Identified by From username '3577' domain '192.168.32.254' [Sep 9 14:27:18] NOTICE[21363] res_pjsip/pjsip_distributor.c: Request 'REGISTER' from '"3577" ' failed for '192.168.32.101:5060' (callid: 0_1184989583@192.168.32.101) - No matching endpoint found [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.101' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.101' and port ''. [Sep 9 14:27:18] DEBUG[21363] pjproject: endpoint .Response msg 401/REGISTER/cseq=1391 (tdta0xb6e0e890) created [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.101' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.101' and port ''. [Sep 9 14:27:18] VERBOSE[21363] res_pjsip_logger.c: <--- Transmitting SIP response (505 bytes) to UDP:192.168.32.101:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.32.101:5060;rport=5060;received=192.168.32.101;branch=z9hG4bK452598042 Call-ID: 0_1184989583@192.168.32.101 From: "3577" ;tag=760838200 To: "3577" ;tag=z9hG4bK452598042 CSeq: 1391 REGISTER WWW-Authenticate: Digest realm="asterisk",nonce="1473416838/ba3bf0fe749c5a3ffd0d81d7a2532e10",opaque="6ca744e851339c86",stale=true,algorithm=md5,qop="auth" Server: Asterisk PBX 13.11.1 Content-Length: 0 [Sep 9 14:27:18] DEBUG[21363] pjproject: tdta0xb6e0e890 .Destroying txdata Response msg 401/REGISTER/cseq=1391 (tdta0xb6e0e890) [Sep 9 14:27:18] DEBUG[9232] pjproject: sip_endpoint.c Processing incoming message: Request msg REGISTER/cseq=1392 (rdata0xb6e0128c) [Sep 9 14:27:18] VERBOSE[9232] res_pjsip_logger.c: <--- Received SIP request (842 bytes) from UDP:192.168.32.101:5060 ---> REGISTER sip:192.168.32.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.32.101:5060;branch=z9hG4bK1466499939 From: "3577" ;tag=760838200 To: "3577" Call-ID: 0_1184989583@192.168.32.101 CSeq: 1392 REGISTER Contact: Authorization: Digest username="3577", realm="asterisk", nonce="1473416838/ba3bf0fe749c5a3ffd0d81d7a2532e10", uri="sip:192.168.32.254:5060", response="9d82f05ff883fbfe4101ed63dd945a2c", algorithm=MD5, cnonce="0a4f113b", opaque="6ca744e851339c86", qop=auth, nc=00000001 Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T27P 45.80.14.2 Expires: 3600 Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 0 [Sep 9 14:27:18] DEBUG[9232] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=1392 (rdata0xb6e0128c) [Sep 9 14:27:18] DEBUG[9232] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000042 to use for Request msg REGISTER/cseq=1392 (rdata0xb6e0128c) [Sep 9 14:27:18] DEBUG[21363] pjproject: sip_endpoint.c Distributing rdata to modules: Request msg REGISTER/cseq=1392 (rdata0xb6e0ae74) [Sep 9 14:27:18] DEBUG[21363] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 9 14:27:18] DEBUG[21363] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '3577' domain '192.168.32.254' [Sep 9 14:27:18] DEBUG[21363] res_pjsip_endpoint_identifier_user.c: Identified by From username '3577' domain '192.168.32.254' [Sep 9 14:27:18] NOTICE[21363] res_pjsip/pjsip_distributor.c: Request 'REGISTER' from '"3577" ' failed for '192.168.32.101:5060' (callid: 0_1184989583@192.168.32.101) - No matching endpoint found [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.101' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.101' and port ''. [Sep 9 14:27:18] DEBUG[21363] pjproject: endpoint .Response msg 401/REGISTER/cseq=1392 (tdta0xb6e0e890) created [Sep 9 14:27:18] DEBUG[21363] res_pjsip_authenticator_digest.c: Calculated nonce 1473416838/ba3bf0fe749c5a3ffd0d81d7a2532e10. Actual nonce is 1473416838/ba3bf0fe749c5a3ffd0d81d7a2532e10 [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:27:18] DEBUG[21363] netsock2.c: Splitting '192.168.32.101' into... [Sep 9 14:27:18] DEBUG[21363] netsock2.c: ...host '192.168.32.101' and port ''. [Sep 9 14:27:18] DEBUG[21363] pjproject: tdta0xb6e0e890 .Destroying txdata Response msg 401/REGISTER/cseq=1392 (tdta0xb6e0e890) [Sep 9 14:27:18] DEBUG[21363] res_pjsip_registrar.c: Matched id '3577' to aor '3577' [Sep 9 14:27:18] DEBUG[21363] res_pjsip_registrar.c: Matched aor '3577' by To username [Sep 9 14:27:18] DEBUG[21363] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Sep 9 14:27:18] DEBUG[21363] res_pjsip_registrar.c: Refreshed contact 'sip:3577@192.168.32.101:5060' on AOR '3577' with new expiration of 3600 seconds [Sep 9 14:27:18] DEBUG[9169] threadpool.c: Increasing threadpool Sorcery's size by 1 [Sep 9 14:27:18] DEBUG[21366] config.c: extract uint from [3] in [0, 4294967295] gives [3](0) [Sep 9 14:27:18] DEBUG[21366] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:27:18] DEBUG[21366] config.c: extract uint from [2] in [0, 4294967295] gives [2](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 9 14:27:18] DEBUG[21363] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Sep 9 14:27:18] DEBUG[9170] threadpool.c: Increasing threadpool stasis-core's size by 1 [Sep 9 14:27:18] DEBUG[9169] threadpool.c: Increasing threadpool Sorcery's size by 1 [Sep 9 14:27:18] DEBUG[21363] pjproject: endpoint .Response msg 200/REGISTER/cseq=1392 (tdta0xb6e0e890) created [Sep 9 14:27:18] DEBUG[21363] pjproject: tsx0xb7607224 ..Transaction created for Request msg REGISTER/cseq=1392 (rdata0xb6e0ae74) [Sep 9 14:27:18] DEBUG[21363] pjproject: tsx0xb7607224 .Incoming Request msg REGISTER/cseq=1392 (rdata0xb6e0ae74) in state Null [Sep 9 14:27:18] DEBUG[21363] pjproject: tsx0xb7607224 ..State changed from Null to Trying, event=RX_MSG [Sep 9 14:27:18] DEBUG[21363] pjproject: tsx0xb7607224 .Sending Response msg 200/REGISTER/cseq=1392 (tdta0xb6e0e890) in state Trying [Sep 9 14:27:18] VERBOSE[21363] res_pjsip_logger.c: <--- Transmitting SIP response (445 bytes) to UDP:192.168.32.101:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.32.101:5060;rport=5060;received=192.168.32.101;branch=z9hG4bK1466499939 Call-ID: 0_1184989583@192.168.32.101 From: "3577" ;tag=760838200 To: "3577" ;tag=z9hG4bK1466499939 CSeq: 1392 REGISTER Date: Fri, 09 Sep 2016 10:27:18 GMT Contact: ;expires=3599 Expires: 3600 Server: Asterisk PBX 13.11.1 Content-Length: 0 [Sep 9 14:27:18] DEBUG[21363] pjproject: tsx0xb7607224 ..State changed from Trying to Completed, event=TX_MSG [Sep 9 14:27:20] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:20] VERBOSE[21372] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:24] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:24] VERBOSE[21379] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:28] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:28] VERBOSE[21386] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:32] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:32] VERBOSE[21393] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:36] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:36] VERBOSE[21400] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:38] DEBUG[21368] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:27:38] DEBUG[9170] threadpool.c: Destroying worker thread 627 [Sep 9 14:27:40] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:40] VERBOSE[21407] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:41] VERBOSE[21417] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:44] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:44] VERBOSE[21421] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416866 Interval: 30 [Sep 9 14:27:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:27:48] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:48] VERBOSE[21428] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:50] DEBUG[9232] pjproject: tsx0xb7607224 Timeout timer event [Sep 9 14:27:50] DEBUG[9232] pjproject: tsx0xb7607224 .State changed from Completed to Terminated, event=TIMER [Sep 9 14:27:50] DEBUG[9232] pjproject: tsx0xb7607224 Timeout timer event [Sep 9 14:27:50] DEBUG[9232] pjproject: tsx0xb7607224 .State changed from Terminated to Destroyed, event=TIMER [Sep 9 14:27:50] DEBUG[9232] pjproject: tdta0xb6e0e890 ..Destroying txdata Response msg 200/REGISTER/cseq=1392 (tdta0xb6e0e890) [Sep 9 14:27:50] DEBUG[9232] pjproject: tsx0xb7607224 Transaction destroyed! [Sep 9 14:27:52] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:52] VERBOSE[21435] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:27:56] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:27:56] VERBOSE[21442] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:00] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:00] VERBOSE[21449] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:05] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:05] VERBOSE[21456] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:09] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:09] VERBOSE[21463] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:11] VERBOSE[21473] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:13] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:13] VERBOSE[21477] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416896 Interval: 30 [Sep 9 14:28:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:28:17] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:17] VERBOSE[21484] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:18] DEBUG[21362] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[21361] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[21364] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[9230] threadpool.c: Destroying worker thread 622 [Sep 9 14:28:18] DEBUG[9230] threadpool.c: Destroying worker thread 621 [Sep 9 14:28:18] DEBUG[21365] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[9230] threadpool.c: Destroying worker thread 624 [Sep 9 14:28:18] DEBUG[9230] threadpool.c: Destroying worker thread 625 [Sep 9 14:28:18] DEBUG[21366] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[9169] threadpool.c: Destroying worker thread 626 [Sep 9 14:28:18] DEBUG[21367] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[9169] threadpool.c: Destroying worker thread 628 [Sep 9 14:28:18] DEBUG[21363] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:28:18] DEBUG[9230] threadpool.c: Destroying worker thread 623 [Sep 9 14:28:21] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:21] VERBOSE[21491] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:25] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:25] VERBOSE[21498] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:29] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:29] VERBOSE[21505] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:33] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:33] VERBOSE[21512] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:37] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:37] VERBOSE[21519] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:41] VERBOSE[21526] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:41] VERBOSE[21536] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:45] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:45] VERBOSE[21540] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:46] DEBUG[9232] pjproject: sip_endpoint.c Processing incoming message: Request msg REGISTER/cseq=633 (rdata0xb6e0128c) [Sep 9 14:28:46] VERBOSE[9232] res_pjsip_logger.c: <--- Received SIP request (845 bytes) from UDP:192.168.32.113:5060 ---> REGISTER sip:192.168.32.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.32.113:5060;branch=z9hG4bK3354938992 From: "3565" ;tag=2290989805 To: "3565" Call-ID: 0_3581077712@192.168.32.113 CSeq: 633 REGISTER Contact: Authorization: Digest username="3565", realm="asterisk", nonce="1473413926/e26c48da3a271ee4adec6d067c253f8c", uri="sip:192.168.32.254:5060", response="b62fac270c77e2cc43af3eeddcbdc724", algorithm=MD5, cnonce="0a4f113b", opaque="724791d5188eed5e", qop=auth, nc=00000002 Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T21P_E2 52.80.15.1 Expires: 3600 Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 0 [Sep 9 14:28:46] DEBUG[9232] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=633 (rdata0xb6e0128c) [Sep 9 14:28:46] DEBUG[9232] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000003d to use for Request msg REGISTER/cseq=633 (rdata0xb6e0128c) [Sep 9 14:28:46] DEBUG[9230] threadpool.c: Increasing threadpool SIP's size by 5 [Sep 9 14:28:46] DEBUG[21544] pjproject: sip_endpoint.c Distributing rdata to modules: Request msg REGISTER/cseq=633 (rdata0xb6e0ae74) [Sep 9 14:28:46] DEBUG[21544] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 9 14:28:46] DEBUG[21544] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '3565' domain '192.168.32.254' [Sep 9 14:28:46] DEBUG[21544] res_pjsip_endpoint_identifier_user.c: Identified by From username '3565' domain '192.168.32.254' [Sep 9 14:28:46] NOTICE[21544] res_pjsip/pjsip_distributor.c: Request 'REGISTER' from '"3565" ' failed for '192.168.32.113:5060' (callid: 0_3581077712@192.168.32.113) - No matching endpoint found [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.113' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.113' and port ''. [Sep 9 14:28:46] DEBUG[21544] pjproject: endpoint .Response msg 401/REGISTER/cseq=633 (tdta0xb6e17e08) created [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.113' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.113' and port ''. [Sep 9 14:28:46] VERBOSE[21544] res_pjsip_logger.c: <--- Transmitting SIP response (507 bytes) to UDP:192.168.32.113:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.32.113:5060;rport=5060;received=192.168.32.113;branch=z9hG4bK3354938992 Call-ID: 0_3581077712@192.168.32.113 From: "3565" ;tag=2290989805 To: "3565" ;tag=z9hG4bK3354938992 CSeq: 633 REGISTER WWW-Authenticate: Digest realm="asterisk",nonce="1473416926/b97d97330cb21794b7c4b06542ce3f23",opaque="3ed2c97563769579",stale=true,algorithm=md5,qop="auth" Server: Asterisk PBX 13.11.1 Content-Length: 0 [Sep 9 14:28:46] DEBUG[21544] pjproject: tdta0xb6e17e08 .Destroying txdata Response msg 401/REGISTER/cseq=633 (tdta0xb6e17e08) [Sep 9 14:28:46] DEBUG[9232] pjproject: sip_endpoint.c Processing incoming message: Request msg REGISTER/cseq=634 (rdata0xb6e0128c) [Sep 9 14:28:46] VERBOSE[9232] res_pjsip_logger.c: <--- Received SIP request (844 bytes) from UDP:192.168.32.113:5060 ---> REGISTER sip:192.168.32.254:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.32.113:5060;branch=z9hG4bK973278962 From: "3565" ;tag=2290989805 To: "3565" Call-ID: 0_3581077712@192.168.32.113 CSeq: 634 REGISTER Contact: Authorization: Digest username="3565", realm="asterisk", nonce="1473416926/b97d97330cb21794b7c4b06542ce3f23", uri="sip:192.168.32.254:5060", response="fc716bc38d153f6d6201ab82f720dac5", algorithm=MD5, cnonce="0a4f113b", opaque="3ed2c97563769579", qop=auth, nc=00000001 Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T21P_E2 52.80.15.1 Expires: 3600 Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 0 [Sep 9 14:28:46] DEBUG[9232] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=634 (rdata0xb6e0128c) [Sep 9 14:28:46] DEBUG[9232] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000003d to use for Request msg REGISTER/cseq=634 (rdata0xb6e0128c) [Sep 9 14:28:46] DEBUG[21544] pjproject: sip_endpoint.c Distributing rdata to modules: Request msg REGISTER/cseq=634 (rdata0xb6e0ae74) [Sep 9 14:28:46] DEBUG[21544] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 9 14:28:46] DEBUG[21544] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '3565' domain '192.168.32.254' [Sep 9 14:28:46] DEBUG[21544] res_pjsip_endpoint_identifier_user.c: Identified by From username '3565' domain '192.168.32.254' [Sep 9 14:28:46] NOTICE[21544] res_pjsip/pjsip_distributor.c: Request 'REGISTER' from '"3565" ' failed for '192.168.32.113:5060' (callid: 0_3581077712@192.168.32.113) - No matching endpoint found [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.113' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.113' and port ''. [Sep 9 14:28:46] DEBUG[21544] pjproject: endpoint .Response msg 401/REGISTER/cseq=634 (tdta0xb6e17e08) created [Sep 9 14:28:46] DEBUG[21544] res_pjsip_authenticator_digest.c: Calculated nonce 1473416926/b97d97330cb21794b7c4b06542ce3f23. Actual nonce is 1473416926/b97d97330cb21794b7c4b06542ce3f23 [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.254' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.254' and port ''. [Sep 9 14:28:46] DEBUG[21544] netsock2.c: Splitting '192.168.32.113' into... [Sep 9 14:28:46] DEBUG[21544] netsock2.c: ...host '192.168.32.113' and port ''. [Sep 9 14:28:46] DEBUG[21544] pjproject: tdta0xb6e17e08 .Destroying txdata Response msg 401/REGISTER/cseq=634 (tdta0xb6e17e08) [Sep 9 14:28:46] DEBUG[21544] res_pjsip_registrar.c: Matched id '3565' to aor '3565' [Sep 9 14:28:46] DEBUG[21544] res_pjsip_registrar.c: Matched aor '3565' by To username [Sep 9 14:28:46] DEBUG[21544] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Sep 9 14:28:46] DEBUG[21544] res_pjsip_registrar.c: Refreshed contact 'sip:3565@192.168.32.113:5060' on AOR '3565' with new expiration of 3600 seconds [Sep 9 14:28:46] DEBUG[9169] threadpool.c: Increasing threadpool Sorcery's size by 1 [Sep 9 14:28:46] DEBUG[21549] config.c: extract uint from [3] in [0, 4294967295] gives [3](0) [Sep 9 14:28:46] DEBUG[21549] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:28:46] DEBUG[21549] config.c: extract uint from [2] in [0, 4294967295] gives [2](0) [Sep 9 14:28:46] DEBUG[9169] threadpool.c: Increasing threadpool Sorcery's size by 1 [Sep 9 14:28:46] DEBUG[9170] threadpool.c: Increasing threadpool stasis-core's size by 1 [Sep 9 14:28:46] DEBUG[21544] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract double from [3.000000] in [-inf, inf] gives [3.000000](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [0] in [0, 86400] gives [0](0) [Sep 9 14:28:46] DEBUG[21544] config.c: extract uint from [5060] in [0, 4294967295] gives [5060](0) [Sep 9 14:28:46] DEBUG[21544] pjproject: endpoint .Response msg 200/REGISTER/cseq=634 (tdta0xb6e17e08) created [Sep 9 14:28:46] DEBUG[21544] pjproject: tsx0xb7607224 ..Transaction created for Request msg REGISTER/cseq=634 (rdata0xb6e0ae74) [Sep 9 14:28:46] DEBUG[21544] pjproject: tsx0xb7607224 .Incoming Request msg REGISTER/cseq=634 (rdata0xb6e0ae74) in state Null [Sep 9 14:28:46] DEBUG[21544] pjproject: tsx0xb7607224 ..State changed from Null to Trying, event=RX_MSG [Sep 9 14:28:46] DEBUG[21544] pjproject: tsx0xb7607224 .Sending Response msg 200/REGISTER/cseq=634 (tdta0xb6e17e08) in state Trying [Sep 9 14:28:46] VERBOSE[21544] res_pjsip_logger.c: <--- Transmitting SIP response (443 bytes) to UDP:192.168.32.113:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.32.113:5060;rport=5060;received=192.168.32.113;branch=z9hG4bK973278962 Call-ID: 0_3581077712@192.168.32.113 From: "3565" ;tag=2290989805 To: "3565" ;tag=z9hG4bK973278962 CSeq: 634 REGISTER Date: Fri, 09 Sep 2016 10:28:46 GMT Contact: ;expires=3599 Expires: 3600 Server: Asterisk PBX 13.11.1 Content-Length: 0 [Sep 9 14:28:46] DEBUG[21544] pjproject: tsx0xb7607224 ..State changed from Trying to Completed, event=TX_MSG [Sep 9 14:28:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416926 Interval: 30 [Sep 9 14:28:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:28:49] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:49] VERBOSE[21555] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:53] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:53] VERBOSE[21562] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:28:57] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:28:57] VERBOSE[21569] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:01] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:01] VERBOSE[21576] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:05] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:05] VERBOSE[21583] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:06] DEBUG[21551] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:06] DEBUG[9170] threadpool.c: Destroying worker thread 636 [Sep 9 14:29:09] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:09] VERBOSE[21590] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:11] VERBOSE[21600] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:13] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:13] VERBOSE[21604] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416956 Interval: 30 [Sep 9 14:29:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:29:17] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:17] VERBOSE[21611] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:18] DEBUG[9232] pjproject: tsx0xb7607224 Timeout timer event [Sep 9 14:29:18] DEBUG[9232] pjproject: tsx0xb7607224 .State changed from Completed to Terminated, event=TIMER [Sep 9 14:29:18] DEBUG[9232] pjproject: tsx0xb7607224 Timeout timer event [Sep 9 14:29:18] DEBUG[9232] pjproject: tsx0xb7607224 .State changed from Terminated to Destroyed, event=TIMER [Sep 9 14:29:18] DEBUG[9232] pjproject: tdta0xb6e17e08 ..Destroying txdata Response msg 200/REGISTER/cseq=634 (tdta0xb6e17e08) [Sep 9 14:29:18] DEBUG[9232] pjproject: tsx0xb7607224 Transaction destroyed! [Sep 9 14:29:21] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:21] VERBOSE[21618] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:25] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:25] VERBOSE[21625] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:29] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:29] VERBOSE[21632] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:33] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:33] VERBOSE[21639] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:37] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:37] VERBOSE[21646] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:41] VERBOSE[21656] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:41] VERBOSE[21660] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:45] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:45] VERBOSE[21667] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:46] DEBUG[21545] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[21547] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[21546] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[9230] threadpool.c: Destroying worker thread 630 [Sep 9 14:29:46] DEBUG[9230] threadpool.c: Destroying worker thread 632 [Sep 9 14:29:46] DEBUG[21548] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[9230] threadpool.c: Destroying worker thread 631 [Sep 9 14:29:46] DEBUG[9230] threadpool.c: Destroying worker thread 633 [Sep 9 14:29:46] DEBUG[21550] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[21549] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[9169] threadpool.c: Destroying worker thread 635 [Sep 9 14:29:46] DEBUG[9169] threadpool.c: Destroying worker thread 634 [Sep 9 14:29:46] DEBUG[21544] threadpool.c: Worker thread idle timeout reached. Dying. [Sep 9 14:29:46] DEBUG[9230] threadpool.c: Destroying worker thread 629 [Sep 9 14:29:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473416986 Interval: 30 [Sep 9 14:29:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:29:49] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:49] VERBOSE[21674] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:53] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:53] VERBOSE[21681] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:29:57] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:29:57] VERBOSE[21688] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:01] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:01] VERBOSE[21695] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:06] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:06] VERBOSE[21702] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:10] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:10] VERBOSE[21709] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:11] VERBOSE[21719] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:14] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:14] VERBOSE[21723] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473417016 Interval: 30 [Sep 9 14:30:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:30:18] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:18] VERBOSE[21730] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:22] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:22] VERBOSE[21737] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:26] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:26] VERBOSE[21744] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:30] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:30] VERBOSE[21751] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:34] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:34] VERBOSE[21758] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:38] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:38] VERBOSE[21765] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:41] VERBOSE[21775] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:42] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:42] VERBOSE[21779] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:46] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:46] VERBOSE[21786] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473417046 Interval: 30 [Sep 9 14:30:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:30:50] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:50] VERBOSE[21793] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:54] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:54] VERBOSE[21800] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:30:58] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:30:58] VERBOSE[21807] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:02] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:02] VERBOSE[21814] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:06] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:06] VERBOSE[21821] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:10] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:10] VERBOSE[21828] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:11] VERBOSE[21838] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:14] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:14] VERBOSE[21842] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473417076 Interval: 30 [Sep 9 14:31:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:31:18] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:18] VERBOSE[21849] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:22] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:22] VERBOSE[21856] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:26] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:26] VERBOSE[21863] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:30] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:30] VERBOSE[21870] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:34] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:34] VERBOSE[21877] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:38] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:38] VERBOSE[21884] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:41] VERBOSE[21894] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:42] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:42] VERBOSE[21898] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473417106 Interval: 30 [Sep 9 14:31:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:31:46] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:46] VERBOSE[21905] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:50] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:50] VERBOSE[21912] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:54] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:54] VERBOSE[21919] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:31:58] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:31:58] VERBOSE[21926] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:02] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:02] VERBOSE[21933] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:07] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:07] VERBOSE[21940] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:11] VERBOSE[21947] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:11] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:11] VERBOSE[21957] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:15] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:15] VERBOSE[21961] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:16] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473417136 Interval: 30 [Sep 9 14:32:16] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:32:19] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:19] VERBOSE[21968] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:23] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:23] VERBOSE[21975] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:27] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:27] VERBOSE[21982] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:31] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:31] VERBOSE[21989] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:35] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:35] VERBOSE[21996] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:39] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:39] VERBOSE[22003] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:41] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:41] VERBOSE[22013] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:43] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:43] VERBOSE[22017] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:46] DEBUG[9273] res_pjsip_registrar_expire.c: Woke up at 1473417166 Interval: 30 [Sep 9 14:32:46] DEBUG[9273] res_pjsip_registrar_expire.c: Expiring 0 contacts [Sep 9 14:32:47] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:47] VERBOSE[22024] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:51] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:51] VERBOSE[22033] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:55] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:55] VERBOSE[22040] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:32:59] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:32:59] VERBOSE[22047] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:33:03] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:33:03] VERBOSE[22054] asterisk.c: Remote UNIX connection disconnected [Sep 9 14:33:07] VERBOSE[9171] asterisk.c: Remote UNIX connection [Sep 9 14:33:07] VERBOSE[22061] asterisk.c: Remote UNIX connection disconnected