[Apr 20 10:55:06] VERBOSE[6081] res_pjsip_logger.c: <--- Received SIP request (533 bytes) from UDP:192.168.50.110:5060 --->
[Apr 20 10:55:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=10 (rdata0x7f5428003808)
[Apr 20 10:55:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-0000002a to use for Request msg REGISTER/cseq=10 (rdata0x7f5428003808)
[Apr 20 10:55:06] DEBUG[6079] threadpool.c: Increasing threadpool pjsip/pool's size by 5
[Apr 20 10:55:06] DEBUG[30233] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Apr 20 10:55:06] DEBUG[30233] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '795866765F51' domain '49874.telecomvirtual.com.br'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip_endpoint_identifier_user.c: Identified by From username '795866765F51' domain '49874.telecomvirtual.com.br'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip_registrar.c: Matched id '795866765F51' to aor '795866765F51'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip_registrar.c: Matched aor '795866765F51' by To username
[Apr 20 10:55:06] VERBOSE[30233] res_pjsip_registrar.c: Added contact 'sip:795866765F51@49874.telecomvirtual.com.br:5060' to AOR '795866765F51' with expiration of 120 seconds
[Apr 20 10:55:06] DEBUG[30235] res_pjsip/pjsip_options.c: Starting scheduled callback on AOR '795866765F51' for qualifying as there is now a contact on it
[Apr 20 10:55:06] VERBOSE[30233] res_pjsip_logger.c: <--- Transmitting SIP response (621 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '795866765F51'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying contact '795866765F51;@741fd5461a0c57c0846cdb5beeb53802' on AOR '795866765F51'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: Wrapper created
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: Set timer to 3000 msec
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '192.168.50.110'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Transport type for target '192.168.50.110' is 'UDP transport'
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Target '192.168.50.110' is an IP address, skipping resolution
[Apr 20 10:55:06] VERBOSE[30233] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:55:06] VERBOSE[6081] res_pjsip_logger.c: <--- Received SIP response (819 bytes) from UDP:192.168.50.110:5060 --->
[Apr 20 10:55:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=11631 (rdata0x7f5428003808).  Using request transaction as basis.
[Apr 20 10:55:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f53b40037a8 for Response msg 200/OPTIONS/cseq=11631 (rdata0x7f5428003808).
[Apr 20 10:55:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/795866765F51-00000042 on transaction tsx0x7f53b40037a8
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: PJSIP tsx response received
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: Cancelling timer
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: Timer cancelled
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: Callbacks executed
[Apr 20 10:55:06] DEBUG[30233] res_pjsip.c: 0x7f542c002570: wrapper destroyed
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_options.c: Endpoint state compositor '795866765F51' is online as AOR '795866765F51' is available
[Apr 20 10:55:06] VERBOSE[30233] res_pjsip/pjsip_configuration.c: Endpoint 795866765F51 is now Reachable
[Apr 20 10:55:06] VERBOSE[30233] res_pjsip/pjsip_options.c: Contact 795866765F51/sip:795866765F51@49874.telecomvirtual.com.br:5060 is now Reachable.  RTT: 19.312 msec
[Apr 20 10:55:06] DEBUG[30233] res_pjsip/pjsip_options.c: AOR '795866765F51' now has 1 available contacts
[Apr 20 10:55:20] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:55:50] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '795866765F51'
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying contact '795866765F51;@741fd5461a0c57c0846cdb5beeb53802' on AOR '795866765F51'
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Wrapper created
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Set timer to 3000 msec
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '192.168.50.110'
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Transport type for target '192.168.50.110' is 'UDP transport'
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Target '192.168.50.110' is an IP address, skipping resolution
[Apr 20 10:56:06] VERBOSE[30233] res_pjsip_logger.c: <--- Transmitting SIP request (537 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:56:06] VERBOSE[6081] res_pjsip_logger.c: <--- Received SIP response (818 bytes) from UDP:192.168.50.110:5060 --->
[Apr 20 10:56:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=4099 (rdata0x7f5428003808).  Using request transaction as basis.
[Apr 20 10:56:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f542c02a7a8 for Response msg 200/OPTIONS/cseq=4099 (rdata0x7f5428003808).
[Apr 20 10:56:06] DEBUG[6081] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/795866765F51-00000042 on transaction tsx0x7f542c02a7a8
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: PJSIP tsx response received
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Cancelling timer
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Timer cancelled
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Callbacks executed
[Apr 20 10:56:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: wrapper destroyed
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_options.c: Contact 795866765F51/sip:795866765F51@49874.telecomvirtual.com.br:5060 status didn't change: Reachable, RTT: 20.186 msec
[Apr 20 10:56:06] DEBUG[30233] res_pjsip/pjsip_options.c: AOR '795866765F51' now has 1 available contacts
[Apr 20 10:56:20] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:56:50] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:57:04] VERBOSE[6081] res_pjsip_logger.c: <--- Received SIP request (531 bytes) from UDP:192.168.50.96:5060 --->
[Apr 20 10:57:04] DEBUG[6081] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=10 (rdata0x7f5428003808)
[Apr 20 10:57:04] DEBUG[6081] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000038 to use for Request msg REGISTER/cseq=10 (rdata0x7f5428003808)
[Apr 20 10:57:04] DEBUG[30233] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against
[Apr 20 10:57:04] DEBUG[30233] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '795866765F51' domain '49874.telecomvirtual.com.br'
[Apr 20 10:57:04] DEBUG[30233] res_pjsip_endpoint_identifier_user.c: Identified by From username '795866765F51' domain '49874.telecomvirtual.com.br'
[Apr 20 10:57:04] DEBUG[30233] res_pjsip_registrar.c: Matched id '795866765F51' to aor '795866765F51'
[Apr 20 10:57:04] DEBUG[30233] res_pjsip_registrar.c: Matched aor '795866765F51' by To username
[Apr 20 10:57:04] DEBUG[30233] res_pjsip_registrar.c: Refreshed contact 'sip:795866765F51@49874.telecomvirtual.com.br:5060' on AOR '795866765F51' with new expiration of 120 seconds
[Apr 20 10:57:04] VERBOSE[30233] res_pjsip_logger.c: <--- Transmitting SIP response (618 bytes) to UDP:192.168.50.96:5060 --->
[Apr 20 10:57:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '795866765F51'
[Apr 20 10:57:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying contact '795866765F51;@741fd5461a0c57c0846cdb5beeb53802' on AOR '795866765F51'
[Apr 20 10:57:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Wrapper created
[Apr 20 10:57:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Set timer to 3000 msec
[Apr 20 10:57:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '192.168.50.110'
[Apr 20 10:57:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Transport type for target '192.168.50.110' is 'UDP transport'
[Apr 20 10:57:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Target '192.168.50.110' is an IP address, skipping resolution
[Apr 20 10:57:06] VERBOSE[30233] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:06] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:07] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:09] DEBUG[6081] res_pjsip.c: 0x7f542c001ec0: Internal tsx timer expired after 3000 msec
[Apr 20 10:57:09] DEBUG[6081] res_pjsip.c: 0x7f542c001ec0: Timer handled here
[Apr 20 10:57:09] DEBUG[6081] res_pjsip.c: 0x7f542c001ec0: Callbacks executed
[Apr 20 10:57:09] VERBOSE[30233] res_pjsip/pjsip_configuration.c: Endpoint 795866765F51 is now Unreachable
[Apr 20 10:57:09] VERBOSE[30233] res_pjsip/pjsip_options.c: Contact 795866765F51/sip:795866765F51@49874.telecomvirtual.com.br:5060 is now Unreachable.  RTT: 0.000 msec
[Apr 20 10:57:09] DEBUG[30233] res_pjsip/pjsip_options.c: AOR '795866765F51' now has 0 available contacts
[Apr 20 10:57:09] DEBUG[6079] threadpool.c: Increasing threadpool pjsip/pool's size by 5
[Apr 20 10:57:09] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:13] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:17] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:20] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:57:21] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:25] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:29] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:33] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:57:36] VERBOSE[6081] res_pjsip_logger.c: <--- Received SIP response (424 bytes) from UDP:192.168.50.110:5060 --->
[Apr 20 10:57:36] DEBUG[6081] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 408/OPTIONS/cseq=40724 (rdata0x7f5428003808).  Using request transaction as basis.
[Apr 20 10:57:36] DEBUG[6081] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f53b40037a8 for Response msg 408/OPTIONS/cseq=40724 (rdata0x7f5428003808).
[Apr 20 10:57:36] DEBUG[6081] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/795866765F51-00000042 on transaction tsx0x7f53b40037a8
[Apr 20 10:57:36] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: PJSIP tsx response received
[Apr 20 10:57:36] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: wrapper destroyed
[Apr 20 10:57:50] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:58:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '795866765F51'
[Apr 20 10:58:06] DEBUG[30233] res_pjsip/pjsip_options.c: Qualifying contact '795866765F51;@741fd5461a0c57c0846cdb5beeb53802' on AOR '795866765F51'
[Apr 20 10:58:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Wrapper created
[Apr 20 10:58:06] DEBUG[30233] res_pjsip.c: 0x7f542c001ec0: Set timer to 3000 msec
[Apr 20 10:58:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '192.168.50.110'
[Apr 20 10:58:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Transport type for target '192.168.50.110' is 'UDP transport'
[Apr 20 10:58:06] DEBUG[30233] res_pjsip/pjsip_resolver.c: Target '192.168.50.110' is an IP address, skipping resolution
[Apr 20 10:58:06] VERBOSE[30233] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:06] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:07] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:09] DEBUG[6081] res_pjsip.c: 0x7f542c001ec0: Internal tsx timer expired after 3000 msec
[Apr 20 10:58:09] DEBUG[6081] res_pjsip.c: 0x7f542c001ec0: Timer handled here
[Apr 20 10:58:09] DEBUG[6081] res_pjsip.c: 0x7f542c001ec0: Callbacks executed
[Apr 20 10:58:09] DEBUG[30233] res_pjsip/pjsip_options.c: Contact 795866765F51/sip:795866765F51@49874.telecomvirtual.com.br:5060 status didn't change: Unreachable, RTT: 0.000 msec
[Apr 20 10:58:09] DEBUG[30233] res_pjsip/pjsip_options.c: AOR '795866765F51' now has 0 available contacts
[Apr 20 10:58:09] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:13] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:17] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:20] DEBUG[6096] res_pjsip_registrar.c: Expiring 0 contacts
[Apr 20 10:58:21] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:25] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->
[Apr 20 10:58:29] VERBOSE[6081] res_pjsip_logger.c: <--- Transmitting SIP request (538 bytes) to UDP:192.168.50.110:5060 --->