[Nov 6 13:43:00] VERBOSE[51608] dns_core.c: Registered DNS resolver 'system' with priority '2147483647' [Nov 6 13:43:08] DEBUG[51634] pjsip: endpoint Request msg REGISTER/cseq=21612 (tdta0x7f66cc005230) created. [Nov 6 13:43:08] DEBUG[51634] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 1 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:43:08] DEBUG[51634] pjsip: tsx0x7f66cc006 .Transaction created for Request msg REGISTER/cseq=21613 (tdta0x7f66cc005230) [Nov 6 13:43:08] DEBUG[51634] pjsip: tsx0x7f66cc006 Sending Request msg REGISTER/cseq=21613 (tdta0x7f66cc005230) in state Null [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:43:08] DEBUG[51634] pjsip: tsx0x7f66cc006 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:43:08] DEBUG[51624] dns_core.c: Query '0x7f66cc00a3c8': Could not add record, invalid TTL '-822083584' [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] All parallel queries completed [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] NAPTR record received on target 'sip.itco.nl' [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] SRV record received on target '_sip._udp.sip.itco.nl' [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Added target 'sip1.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] A record being skipped on target 'sip.itco.nl' because NAPTR or SRV record exists [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] New queries added, performing parallel resolution again [Nov 6 13:43:08] DEBUG[51624] dns_core.c: Query '0x7f66d0002eb8': Could not add record, invalid TTL '-822083584' [Nov 6 13:43:08] DEBUG[51624] dns_core.c: Query '0x7f66d00036f8': Could not add record, invalid TTL '-2145386240' [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] All parallel queries completed [Nov 6 13:43:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Resolution completed - 0 viable targets [Nov 6 13:43:08] DEBUG[51634] res_pjsip/pjsip_resolver.c: [0x7f66cc009588] Invoking user callback with '0' addresses [Nov 6 13:43:08] WARNING[51634] pjsip: tsx0x7f66cc006 Failed to send Request msg REGISTER/cseq=21613 (tdta0x7f66cc005230)! err=320047 (No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:43:08] DEBUG[51634] pjsip: tsx0x7f66cc006 State changed from Calling to Terminated, event=TRANSPORT_ERROR [Nov 6 13:43:08] DEBUG[51634] res_pjsip_outbound_registration.c: Received REGISTER response 503(No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:43:08] DEBUG[51634] res_pjsip_outbound_registration.c: Processing REGISTER response 503 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:43:08] DEBUG[51634] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 60 seconds [Nov 6 13:43:08] WARNING[51634] res_pjsip_outbound_registration.c: No response received from 'sip:sip.itco.nl' on registration attempt to 'sip:tryba@sip.itco.nl', retrying in '60' [Nov 6 13:43:08] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:43:08] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:43:08] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21613 (tdta0x7f66cc005230) [Nov 6 13:43:08] DEBUG[51633] pjsip: tsx0x7f66cc006 Transaction destroyed! [Nov 6 13:43:20] DEBUG[51618] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:43:20] DEBUG[51617] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:43:20] DEBUG[51616] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:43:20] DEBUG[51610] threadpool.c: Destroying worker thread 4 [Nov 6 13:43:20] DEBUG[51610] threadpool.c: Destroying worker thread 3 [Nov 6 13:43:20] DEBUG[51610] threadpool.c: Destroying worker thread 2 [Nov 6 13:43:24] DEBUG[51614] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:43:24] DEBUG[51615] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:43:24] DEBUG[51610] threadpool.c: Destroying worker thread 0 [Nov 6 13:43:24] DEBUG[51610] threadpool.c: Destroying worker thread 1 [Nov 6 13:44:01] DEBUG[51638] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:01] DEBUG[51637] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:01] DEBUG[51636] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:01] DEBUG[51635] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:01] DEBUG[51632] threadpool.c: Destroying worker thread 9 [Nov 6 13:44:01] DEBUG[51632] threadpool.c: Destroying worker thread 8 [Nov 6 13:44:01] DEBUG[51632] threadpool.c: Destroying worker thread 7 [Nov 6 13:44:01] DEBUG[51632] threadpool.c: Destroying worker thread 6 [Nov 6 13:44:02] DEBUG[51640] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:02] DEBUG[51609] threadpool.c: Destroying worker thread 11 [Nov 6 13:44:03] DEBUG[51639] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:03] DEBUG[51609] threadpool.c: Destroying worker thread 10 [Nov 6 13:44:08] DEBUG[51634] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:44:08] DEBUG[51632] threadpool.c: Destroying worker thread 5 [Nov 6 13:44:08] DEBUG[51632] threadpool.c: Increasing threadpool SIP's size by 5 [Nov 6 13:44:08] DEBUG[51687] pjsip: endpoint Request msg REGISTER/cseq=21613 (tdta0x7f66cc005230) created. [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 2 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x7f66cc006 .Transaction created for Request msg REGISTER/cseq=21614 (tdta0x7f66cc005230) [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x7f66cc006 Sending Request msg REGISTER/cseq=21614 (tdta0x7f66cc005230) in state Null [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x7f66cc006 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:44:08] DEBUG[51624] dns_core.c: Query '0x279cef8': Could not add record, invalid TTL '-268435456' [Nov 6 13:44:08] DEBUG[51624] dns_core.c: Query '0x22a1008': Could not add record, invalid TTL '-1828716544' [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] All parallel queries completed [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] SRV record received on target '_sip._udp.sip.itco.nl' [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Added target 'sip1.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] A record being skipped on target 'sip.itco.nl' because NAPTR or SRV record exists [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] New queries added, performing parallel resolution again [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] All parallel queries completed [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] A record received on target 'sip1.itco.nl' [Nov 6 13:44:08] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Resolution completed - 1 viable targets [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Address '0' is 109.235.32.56:5060 with transport 'UDP' [Nov 6 13:44:08] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9ca8] Invoking user callback with '1' addresses [Nov 6 13:44:08] DEBUG[51687] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 10.0.3.176:5060 [Nov 6 13:44:08] DEBUG[51687] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:44:08] DEBUG[51687] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:44:08] DEBUG[51687] netsock2.c: Splitting '109.235.32.56:5060' into... [Nov 6 13:44:08] DEBUG[51687] netsock2.c: ...host '109.235.32.56' and port '5060'. [Nov 6 13:44:08] DEBUG[51633] pjsip: sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=21614 (rdata0x2440dc8) [Nov 6 13:44:08] DEBUG[51633] netsock2.c: Splitting '109.235.32.56:5060' into... [Nov 6 13:44:08] DEBUG[51633] netsock2.c: ...host '109.235.32.56' and port '5060'. [Nov 6 13:44:08] DEBUG[51633] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:44:08] DEBUG[51633] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:44:08] DEBUG[51687] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 401/REGISTER/cseq=21614 (rdata0x7f66dc005638) [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x7f66cc006 .Incoming Response msg 401/REGISTER/cseq=21614 (rdata0x7f66dc005638) in state Calling [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x7f66cc006 ..State changed from Calling to Completed, event=RX_MSG [Nov 6 13:44:08] DEBUG[51687] pjsip: sip_auth_clien ...Unable to set auth for tdta0x7f66cc005230: can not find credential for sip.itco.nl/Digest [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Received REGISTER response 401(Unauthorized) [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Processing REGISTER response 401 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Sending authenticated REGISTER to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x2441e58 .Transaction created for Request msg REGISTER/cseq=21615 (tdta0x7f66cc005230) [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x2441e58 Sending Request msg REGISTER/cseq=21615 (tdta0x7f66cc005230) in state Null [Nov 6 13:44:08] DEBUG[51687] pjsip: endpoint .Request msg REGISTER/cseq=21615 (tdta0x7f66cc005230): skipping target resolution because address is already set [Nov 6 13:44:08] DEBUG[51687] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 10.0.3.176:5060 [Nov 6 13:44:08] DEBUG[51687] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:44:08] DEBUG[51687] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:44:08] DEBUG[51687] netsock2.c: Splitting '109.235.32.56:5060' into... [Nov 6 13:44:08] DEBUG[51687] netsock2.c: ...host '109.235.32.56' and port '5060'. [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x2441e58 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:44:08] DEBUG[51633] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=21615 (rdata0x7f66dc007588) [Nov 6 13:44:08] DEBUG[51633] netsock2.c: Splitting '109.235.32.56:5060' into... [Nov 6 13:44:08] DEBUG[51633] netsock2.c: ...host '109.235.32.56' and port '5060'. [Nov 6 13:44:08] DEBUG[51633] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:44:08] DEBUG[51633] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:44:08] DEBUG[51687] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/REGISTER/cseq=21615 (rdata0x7f66dc0094d8) [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x2441e58 .Incoming Response msg 200/REGISTER/cseq=21615 (rdata0x7f66dc0094d8) in state Calling [Nov 6 13:44:08] DEBUG[51687] pjsip: tsx0x2441e58 ..State changed from Calling to Completed, event=RX_MSG [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Received REGISTER response 200(OK) [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Processing REGISTER response 200 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Outbound registration to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' successful [Nov 6 13:44:08] DEBUG[51687] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 50 seconds [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Completed to Terminated, event=TIMER [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x7f66cc006 Transaction destroyed! [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Completed to Terminated, event=TIMER [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:44:13] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21615 (tdta0x7f66cc005230) [Nov 6 13:44:13] DEBUG[51633] pjsip: tsx0x2441e58 Transaction destroyed! [Nov 6 13:44:58] DEBUG[51687] pjsip: endpoint Request msg REGISTER/cseq=21615 (tdta0x7f66cc005230) created. [Nov 6 13:44:58] DEBUG[51687] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 1 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:44:58] DEBUG[51687] pjsip: tsx0x2441e58 .Transaction created for Request msg REGISTER/cseq=21616 (tdta0x7f66cc005230) [Nov 6 13:44:58] DEBUG[51687] pjsip: tsx0x2441e58 Sending Request msg REGISTER/cseq=21616 (tdta0x7f66cc005230) in state Null [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:44:58] DEBUG[51687] pjsip: tsx0x2441e58 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:44:58] DEBUG[51624] dns_core.c: Query '0x279d668': Could not add record, invalid TTL '-1107296256' [Nov 6 13:44:58] DEBUG[51624] dns_core.c: Query '0x279cef8': Could not add record, invalid TTL '-654311424' [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] All parallel queries completed [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] SRV record received on target '_sip._tcp.sip.itco.nl' [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target 'sip0.itco.nl' with record type '1', transport 'TCP', and port '5060' [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] A record being skipped on target 'sip.itco.nl' because NAPTR or SRV record exists [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] New queries added, performing parallel resolution again [Nov 6 13:44:58] DEBUG[51624] dns_core.c: Query '0x7f66d0000b98': Could not add record, invalid TTL '-317325312' [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] All parallel queries completed [Nov 6 13:44:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Resolution completed - 0 viable targets [Nov 6 13:44:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Invoking user callback with '0' addresses [Nov 6 13:44:58] WARNING[51687] pjsip: tsx0x2441e58 Failed to send Request msg REGISTER/cseq=21616 (tdta0x7f66cc005230)! err=320047 (No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:44:58] DEBUG[51687] pjsip: tsx0x2441e58 State changed from Calling to Terminated, event=TRANSPORT_ERROR [Nov 6 13:44:58] DEBUG[51687] res_pjsip_outbound_registration.c: Received REGISTER response 503(No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:44:58] DEBUG[51687] res_pjsip_outbound_registration.c: Processing REGISTER response 503 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:44:58] DEBUG[51687] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 60 seconds [Nov 6 13:44:58] WARNING[51687] res_pjsip_outbound_registration.c: No response received from 'sip:sip.itco.nl' on registration attempt to 'sip:tryba@sip.itco.nl', retrying in '60' [Nov 6 13:44:58] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:44:58] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:44:58] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21616 (tdta0x7f66cc005230) [Nov 6 13:44:58] DEBUG[51633] pjsip: tsx0x2441e58 Transaction destroyed! [Nov 6 13:45:08] DEBUG[51691] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:45:08] DEBUG[51690] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:45:08] DEBUG[51689] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:45:08] DEBUG[51688] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:45:08] DEBUG[51632] threadpool.c: Destroying worker thread 16 [Nov 6 13:45:08] DEBUG[51632] threadpool.c: Destroying worker thread 15 [Nov 6 13:45:08] DEBUG[51632] threadpool.c: Destroying worker thread 14 [Nov 6 13:45:08] DEBUG[51632] threadpool.c: Destroying worker thread 13 [Nov 6 13:45:58] DEBUG[51687] pjsip: endpoint Request msg REGISTER/cseq=21616 (tdta0x7f66cc005230) created. [Nov 6 13:45:58] DEBUG[51687] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 2 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:45:58] DEBUG[51687] pjsip: tsx0x2441e58 .Transaction created for Request msg REGISTER/cseq=21617 (tdta0x7f66cc005230) [Nov 6 13:45:58] DEBUG[51687] pjsip: tsx0x2441e58 Sending Request msg REGISTER/cseq=21617 (tdta0x7f66cc005230) in state Null [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:45:58] DEBUG[51687] pjsip: tsx0x2441e58 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:45:58] DEBUG[51624] dns_core.c: Query '0x27b1e88': Could not add record, invalid TTL '-2113929216' [Nov 6 13:45:58] DEBUG[51624] dns_core.c: Query '0x2584468': Could not add record, invalid TTL '-1660944384' [Nov 6 13:45:58] DEBUG[51624] dns_core.c: Query '0x2795e98': Could not add record, invalid TTL '-268435456' [Nov 6 13:45:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] All parallel queries completed [Nov 6 13:45:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] SRV record received on target '_sip._tcp.sip.itco.nl' [Nov 6 13:45:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Added target 'sip1.itco.nl' with record type '1', transport 'TCP', and port '5060' [Nov 6 13:45:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] New queries added, performing parallel resolution again [Nov 6 13:45:58] DEBUG[51624] dns_core.c: Query '0x7f66d0000b98': Could not add record, invalid TTL '-702611200' [Nov 6 13:45:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] All parallel queries completed [Nov 6 13:45:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Resolution completed - 0 viable targets [Nov 6 13:45:58] DEBUG[51687] res_pjsip/pjsip_resolver.c: [0x27a9eb8] Invoking user callback with '0' addresses [Nov 6 13:45:58] WARNING[51687] pjsip: tsx0x2441e58 Failed to send Request msg REGISTER/cseq=21617 (tdta0x7f66cc005230)! err=320047 (No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:45:58] DEBUG[51687] pjsip: tsx0x2441e58 State changed from Calling to Terminated, event=TRANSPORT_ERROR [Nov 6 13:45:58] DEBUG[51687] res_pjsip_outbound_registration.c: Received REGISTER response 503(No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:45:58] DEBUG[51687] res_pjsip_outbound_registration.c: Processing REGISTER response 503 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:45:58] DEBUG[51687] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 60 seconds [Nov 6 13:45:58] WARNING[51687] res_pjsip_outbound_registration.c: No response received from 'sip:sip.itco.nl' on registration attempt to 'sip:tryba@sip.itco.nl', retrying in '60' [Nov 6 13:45:58] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:45:58] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:45:58] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21617 (tdta0x7f66cc005230) [Nov 6 13:45:58] DEBUG[51633] pjsip: tsx0x2441e58 Transaction destroyed! [Nov 6 13:46:58] DEBUG[51687] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:46:58] DEBUG[51632] threadpool.c: Destroying worker thread 12 [Nov 6 13:46:58] DEBUG[51632] threadpool.c: Increasing threadpool SIP's size by 5 [Nov 6 13:46:58] DEBUG[51693] pjsip: endpoint Request msg REGISTER/cseq=21617 (tdta0x7f66cc005230) created. [Nov 6 13:46:58] DEBUG[51693] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 3 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:46:58] DEBUG[51693] pjsip: tsx0x2441e58 .Transaction created for Request msg REGISTER/cseq=21618 (tdta0x7f66cc005230) [Nov 6 13:46:58] DEBUG[51693] pjsip: tsx0x2441e58 Sending Request msg REGISTER/cseq=21618 (tdta0x7f66cc005230) in state Null [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:46:58] DEBUG[51693] pjsip: tsx0x2441e58 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:46:58] DEBUG[51624] dns_core.c: Query '0x7f66cc006438': Could not add record, invalid TTL '-1275068416' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] All parallel queries completed [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] NAPTR record received on target 'sip.itco.nl' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] SRV record received on target '_sip._tcp.sip.itco.nl' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target 'sip0.itco.nl' with record type '1', transport 'TCP', and port '5060' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] SRV record received on target '_sip._udp.sip.itco.nl' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target 'sip0.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] New queries added, performing parallel resolution again [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] All parallel queries completed [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] SRV record received on target '_sip._tcp.sip.itco.nl' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Added target 'sip1.itco.nl' with record type '1', transport 'TCP', and port '5060' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] A record being skipped on target 'sip0.itco.nl' because NAPTR or SRV record exists [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] A record being skipped on target 'sip0.itco.nl' because NAPTR or SRV record exists [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] New queries added, performing parallel resolution again [Nov 6 13:46:58] DEBUG[51624] dns_core.c: Query '0x7f66d00011b8': Could not add record, invalid TTL '-1709244160' [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] All parallel queries completed [Nov 6 13:46:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Resolution completed - 0 viable targets [Nov 6 13:46:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc006268] Invoking user callback with '0' addresses [Nov 6 13:46:58] WARNING[51693] pjsip: tsx0x2441e58 Failed to send Request msg REGISTER/cseq=21618 (tdta0x7f66cc005230)! err=320047 (No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:46:58] DEBUG[51693] pjsip: tsx0x2441e58 State changed from Calling to Terminated, event=TRANSPORT_ERROR [Nov 6 13:46:58] DEBUG[51693] res_pjsip_outbound_registration.c: Received REGISTER response 503(No answer record in the DNS response (PJLIB_UTIL_EDNSNOANSWERREC)) [Nov 6 13:46:58] DEBUG[51693] res_pjsip_outbound_registration.c: Processing REGISTER response 503 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:46:58] DEBUG[51693] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 60 seconds [Nov 6 13:46:58] WARNING[51693] res_pjsip_outbound_registration.c: No response received from 'sip:sip.itco.nl' on registration attempt to 'sip:tryba@sip.itco.nl', retrying in '60' [Nov 6 13:46:58] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:46:58] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:46:58] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21618 (tdta0x7f66cc005230) [Nov 6 13:46:58] DEBUG[51633] pjsip: tsx0x2441e58 Transaction destroyed! [Nov 6 13:47:58] DEBUG[51697] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:47:58] DEBUG[51696] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:47:58] DEBUG[51695] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:47:58] DEBUG[51694] threadpool.c: Worker thread idle timeout reached. Dying. [Nov 6 13:47:58] DEBUG[51632] threadpool.c: Destroying worker thread 21 [Nov 6 13:47:58] DEBUG[51632] threadpool.c: Destroying worker thread 20 [Nov 6 13:47:58] DEBUG[51632] threadpool.c: Destroying worker thread 19 [Nov 6 13:47:58] DEBUG[51632] threadpool.c: Destroying worker thread 18 [Nov 6 13:47:58] DEBUG[51693] pjsip: endpoint Request msg REGISTER/cseq=21618 (tdta0x7f66cc005230) created. [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 4 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x2441e58 .Transaction created for Request msg REGISTER/cseq=21619 (tdta0x7f66cc005230) [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x2441e58 Sending Request msg REGISTER/cseq=21619 (tdta0x7f66cc005230) in state Null [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x2441e58 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:47:58] DEBUG[51624] dns_core.c: Query '0x7f66cc000c48': Could not add record, invalid TTL '-268435456' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] All parallel queries completed [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] NAPTR record received on target 'sip.itco.nl' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] SRV record received on target '_sip._udp.sip.itco.nl' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip1.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] A record being skipped on target 'sip.itco.nl' because NAPTR or SRV record exists [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] New queries added, performing parallel resolution again [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] All parallel queries completed [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] SRV record received on target '_sip._udp.sip.itco.nl' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip0.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] A record being skipped on target 'sip1.itco.nl' because NAPTR or SRV record exists [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] New queries added, performing parallel resolution again [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] All parallel queries completed [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] A record received on target 'sip0.itco.nl' [Nov 6 13:47:58] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Resolution completed - 1 viable targets [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Address '0' is 109.235.32.55:5060 with transport 'UDP' [Nov 6 13:47:58] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Invoking user callback with '1' addresses [Nov 6 13:47:58] DEBUG[51693] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 10.0.3.176:5060 [Nov 6 13:47:58] DEBUG[51693] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:47:58] DEBUG[51693] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:47:58] DEBUG[51693] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:47:58] DEBUG[51693] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:47:58] DEBUG[51633] pjsip: sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=21619 (rdata0x7f66dc005df8) [Nov 6 13:47:58] DEBUG[51633] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:47:58] DEBUG[51633] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:47:58] DEBUG[51633] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:47:58] DEBUG[51633] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:47:58] DEBUG[51693] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 401/REGISTER/cseq=21619 (rdata0x7f66dc007d48) [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x2441e58 .Incoming Response msg 401/REGISTER/cseq=21619 (rdata0x7f66dc007d48) in state Calling [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x2441e58 ..State changed from Calling to Completed, event=RX_MSG [Nov 6 13:47:58] DEBUG[51693] pjsip: sip_auth_clien ...Unable to set auth for tdta0x7f66cc005230: can not find credential for sip.itco.nl/Digest [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Received REGISTER response 401(Unauthorized) [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Processing REGISTER response 401 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Sending authenticated REGISTER to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x7f66cc006 .Transaction created for Request msg REGISTER/cseq=21620 (tdta0x7f66cc005230) [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x7f66cc006 Sending Request msg REGISTER/cseq=21620 (tdta0x7f66cc005230) in state Null [Nov 6 13:47:58] DEBUG[51693] pjsip: endpoint .Request msg REGISTER/cseq=21620 (tdta0x7f66cc005230): skipping target resolution because address is already set [Nov 6 13:47:58] DEBUG[51693] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 10.0.3.176:5060 [Nov 6 13:47:58] DEBUG[51693] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:47:58] DEBUG[51693] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:47:58] DEBUG[51693] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:47:58] DEBUG[51693] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x7f66cc006 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:47:58] DEBUG[51633] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=21620 (rdata0x7f66dc005638) [Nov 6 13:47:58] DEBUG[51633] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:47:58] DEBUG[51633] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:47:58] DEBUG[51633] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:47:58] DEBUG[51633] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:47:58] DEBUG[51693] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/REGISTER/cseq=21620 (rdata0x7f66dc007588) [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x7f66cc006 .Incoming Response msg 200/REGISTER/cseq=21620 (rdata0x7f66dc007588) in state Calling [Nov 6 13:47:58] DEBUG[51693] pjsip: tsx0x7f66cc006 ..State changed from Calling to Completed, event=RX_MSG [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Received REGISTER response 200(OK) [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Processing REGISTER response 200 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Outbound registration to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' successful [Nov 6 13:47:58] DEBUG[51693] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 50 seconds [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Completed to Terminated, event=TIMER [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x2441e58 Transaction destroyed! [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Completed to Terminated, event=TIMER [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:48:03] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21620 (tdta0x7f66cc005230) [Nov 6 13:48:03] DEBUG[51633] pjsip: tsx0x7f66cc006 Transaction destroyed! [Nov 6 13:48:15] DEBUG[51669] res_timing_timerfd.c: Expected to acknowledge 1 ticks but got 3 instead [Nov 6 13:48:48] DEBUG[51693] pjsip: endpoint Request msg REGISTER/cseq=21620 (tdta0x7f66cc005230) created. [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Outbound REGISTER attempt 1 to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x7f66cc006 .Transaction created for Request msg REGISTER/cseq=21621 (tdta0x7f66cc005230) [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x7f66cc006 Sending Request msg REGISTER/cseq=21621 (tdta0x7f66cc005230) in state Null [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target 'sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: Transport type for target 'sip.itco.nl' is 'Unspecified' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Created resolution tracking for target 'sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip.itco.nl' with record type '35', transport 'Unspecified', and port '0' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target '_sip._udp.sip.itco.nl' with record type '33', transport 'UDP', and port '5060' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Starting initial resolution using parallel queries for target 'sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x7f66cc006 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:48:48] DEBUG[51624] dns_core.c: Query '0x7f66cc000c48': Could not add record, invalid TTL '-1107296256' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] All parallel queries completed [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] NAPTR record received on target 'sip.itco.nl' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target '_sip._tcp.sip.itco.nl' with record type '33', transport 'TCP', and port '5060' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] SRV record received on target '_sip._udp.sip.itco.nl' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Added target 'sip0.itco.nl' with record type '1', transport 'UDP', and port '5060' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] A record being skipped on target 'sip.itco.nl' because NAPTR or SRV record exists [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] New queries added, performing parallel resolution again [Nov 6 13:48:48] DEBUG[51624] dns_core.c: Query '0x7f66d00014b8': Could not add record, invalid TTL '-1107296256' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] All parallel queries completed [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] A record received on target 'sip0.itco.nl' [Nov 6 13:48:48] DEBUG[51624] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Resolution completed - 1 viable targets [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Address '0' is 109.235.32.55:5060 with transport 'UDP' [Nov 6 13:48:48] DEBUG[51693] res_pjsip/pjsip_resolver.c: [0x7f66cc009758] Invoking user callback with '1' addresses [Nov 6 13:48:48] DEBUG[51693] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 10.0.3.176:5060 [Nov 6 13:48:48] DEBUG[51693] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:48:48] DEBUG[51693] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:48:48] DEBUG[51693] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:48:48] DEBUG[51693] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:48:48] DEBUG[51633] pjsip: sip_endpoint.c Processing incoming message: Response msg 401/REGISTER/cseq=21621 (rdata0x7f66dc005638) [Nov 6 13:48:48] DEBUG[51633] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:48:48] DEBUG[51633] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:48:48] DEBUG[51633] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:48:48] DEBUG[51633] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:48:48] DEBUG[51693] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 401/REGISTER/cseq=21621 (rdata0x7f66dc007588) [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x7f66cc006 .Incoming Response msg 401/REGISTER/cseq=21621 (rdata0x7f66dc007588) in state Calling [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x7f66cc006 ..State changed from Calling to Completed, event=RX_MSG [Nov 6 13:48:48] DEBUG[51693] pjsip: sip_auth_clien ...Unable to set auth for tdta0x7f66cc005230: can not find credential for sip.itco.nl/Digest [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Received REGISTER response 401(Unauthorized) [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Processing REGISTER response 401 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Sending authenticated REGISTER to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x2441e58 .Transaction created for Request msg REGISTER/cseq=21622 (tdta0x7f66cc005230) [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x2441e58 Sending Request msg REGISTER/cseq=21622 (tdta0x7f66cc005230) in state Null [Nov 6 13:48:48] DEBUG[51693] pjsip: endpoint .Request msg REGISTER/cseq=21622 (tdta0x7f66cc005230): skipping target resolution because address is already set [Nov 6 13:48:48] DEBUG[51693] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 10.0.3.176:5060 [Nov 6 13:48:48] DEBUG[51693] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:48:48] DEBUG[51693] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:48:48] DEBUG[51693] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:48:48] DEBUG[51693] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x2441e58 .State changed from Null to Calling, event=TX_MSG [Nov 6 13:48:48] DEBUG[51633] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/REGISTER/cseq=21622 (rdata0x7f66dc005638) [Nov 6 13:48:48] DEBUG[51633] netsock2.c: Splitting '109.235.32.55:5060' into... [Nov 6 13:48:48] DEBUG[51633] netsock2.c: ...host '109.235.32.55' and port '5060'. [Nov 6 13:48:48] DEBUG[51633] netsock2.c: Splitting '0.0.0.0:5060' into... [Nov 6 13:48:48] DEBUG[51633] netsock2.c: ...host '0.0.0.0' and port '5060'. [Nov 6 13:48:48] DEBUG[51693] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/REGISTER/cseq=21622 (rdata0x7f66dc007588) [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x2441e58 .Incoming Response msg 200/REGISTER/cseq=21622 (rdata0x7f66dc007588) in state Calling [Nov 6 13:48:48] DEBUG[51693] pjsip: tsx0x2441e58 ..State changed from Calling to Completed, event=RX_MSG [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Received REGISTER response 200(OK) [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Processing REGISTER response 200 from server 'sip:sip.itco.nl' for client 'sip:tryba@sip.itco.nl' [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Outbound registration to 'sip:sip.itco.nl' with client 'sip:tryba@sip.itco.nl' successful [Nov 6 13:48:48] DEBUG[51693] res_pjsip_outbound_registration.c: Scheduling outbound registration to server 'sip:sip.itco.nl' from client 'sip:tryba@sip.itco.nl' in 50 seconds [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Completed to Terminated, event=TIMER [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x7f66cc006 Timeout timer event [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x7f66cc006 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x7f66cc006 Transaction destroyed! [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Completed to Terminated, event=TIMER [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x2441e58 Timeout timer event [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x2441e58 .State changed from Terminated to Destroyed, event=TIMER [Nov 6 13:48:53] DEBUG[51633] pjsip: tdta0x7f66cc00 ..Destroying txdata Request msg REGISTER/cseq=21622 (tdta0x7f66cc005230) [Nov 6 13:48:53] DEBUG[51633] pjsip: tsx0x2441e58 Transaction destroyed! [Nov 6 13:49:33] DEBUG[51608] cdr.c: CDR Engine termination request received; waiting on messages... [Nov 6 13:49:33] DEBUG[51608] taskprocessor.c: destroying taskprocessor 'ast_msg_queue' [Nov 6 13:49:33] VERBOSE[51608] asterisk.c: Asterisk cleanly ending (0). [Nov 6 13:49:33] VERBOSE[51608] asterisk.c: Executing last minute cleanups [Nov 6 13:49:33] VERBOSE[51608] res_musiconhold.c: Destroying musiconhold processes [Nov 6 13:49:33] DEBUG[51608] res_musiconhold.c: Destroying MOH class 'default' [Nov 6 13:49:33] DEBUG[51608] taskprocessor.c: destroying taskprocessor 'c448de60-af61-4193-bda7-5c2bbdec8b26' [Nov 6 13:49:33] VERBOSE[51608] manager.c: Manager unregistered action DBGet [Nov 6 13:49:33] VERBOSE[51608] manager.c: Manager unregistered action DBPut [Nov 6 13:49:33] VERBOSE[51608] manager.c: Manager unregistered action DBDel [Nov 6 13:49:33] VERBOSE[51608] manager.c: Manager unregistered action DBDelTree [Nov 6 13:49:33] DEBUG[51608] asterisk.c: Asterisk ending (0).