[2021-06-30 14:37:34] Asterisk 16.17.0 built by mockbuild @ jenkins7 on a x86_64 running Linux on 2021-04-22 07:22:22 UTC [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying all contacts on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@39f74f498a570e103133eb762779d1ce' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790114d20: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790114d20: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:14113 ---> OPTIONS sip:103@8.8.8.8:14113 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjb907430a-3454-43d9-bcc8-8ad2c77f2a48 From: ;tag=adc972cf-ff10-42fa-8c92-714897205f28 To: Contact: Call-ID: 58b45470-d799-4e45-9363-db24c231f730 CSeq: 8152 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@c736cfcaf15badbc96bf98418b3a3ef2' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790057dc0: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790057dc0: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:21086 ---> OPTIONS sip:103@8.8.8.8:21086 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj51c43ef5-feea-4c47-aa7a-6dc2db59cf56 From: ;tag=2051ea99-a1a7-42a4-9c80-a3d8223c80be To: Contact: Call-ID: c319e7d2-8451-43a7-98bc-37806f0bc886 CSeq: 36687 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@24d16efcdec3bffc8e76fb3eb2903087' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779021cbf0: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779021cbf0: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:56477 ---> OPTIONS sip:103@8.8.8.8:56477 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj94d7070e-3a39-47b7-a698-51b2e6b2ba61 From: ;tag=481b7d79-96f5-4be9-a608-4cecc19521e8 To: Contact: Call-ID: cdc8147f-cfad-40ae-b8de-8160cc2d8e49 CSeq: 1017 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@52d3eb8dbadc73c6ffd8f56908d80be5' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000cde0: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000cde0: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:49377 ---> OPTIONS sip:103@8.8.8.8:49377 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj87c85b88-fc7a-44f4-9dfe-5b70902b357d From: ;tag=51831176-12e3-4263-9956-9caea77cf6da To: Contact: Call-ID: e9e0e56e-6f19-488c-a517-a2a65c4f19f6 CSeq: 44087 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@d681f2d50c073d7273390a5a1421e28e' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f77901206f0: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f77901206f0: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:15072 ---> OPTIONS sip:103@8.8.8.8:15072 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc4bbf961-a553-4ed0-a39a-136fd5a448cf From: ;tag=14ab60c1-d08c-4063-8ef9-1b93304fd7f0 To: Contact: Call-ID: 11ed5a54-c833-4895-abbf-255e3df9c06b CSeq: 34937 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@939a36457f35e42f7152046a3f52ff59' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790118300: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790118300: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:41561 ---> OPTIONS sip:103@8.8.8.8:41561 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPje5b0190d-c598-492c-9637-3776d9689093 From: ;tag=20c04828-e04f-46c3-adbd-711abf46646c To: Contact: Call-ID: 58692c0b-bd02-4533-8ca4-f3f987e3a510 CSeq: 52840 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@137bca63e13a4570597ad7048f3cd1cb' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f77901207b0: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f77901207b0: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '4.4.4.4' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '4.4.4.4' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '4.4.4.4' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '4.4.4.4' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '4.4.4.4' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (427 bytes) to UDP:4.4.4.4:5060 ---> OPTIONS sip:103@4.4.4.4:5060 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj45002c86-fd2f-4c8f-ac1b-3a2d79b6927c From: ;tag=a9ec25b3-7d80-4977-a253-f3145f65bf44 To: Contact: Call-ID: 5eb97e15-1760-45dd-a0bb-c9dbc31c6087 CSeq: 60492 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@84699bf1c3722bc095923267aa6b1c42' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f77900f4520: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f77900f4520: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:56633 ---> OPTIONS sip:103@8.8.8.8:56633 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc72ad7bb-6347-4017-b649-466ee7c1ad28 From: ;tag=2366125f-52dd-41ea-adf5-1e8a8f7b39a8 To: Contact: Call-ID: 0a43ee83-17cb-4b8a-9020-54b2455a5b5d CSeq: 31768 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@59bf3fa4f33a33cd473e214f5848010d' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790108520: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f7790108520: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '4.4.4.4' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '4.4.4.4' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '4.4.4.4' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '4.4.4.4' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '4.4.4.4' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (427 bytes) to UDP:4.4.4.4:1025 ---> OPTIONS sip:103@4.4.4.4:1025 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj50e95b31-2e18-4389-91bc-8436bcea48a3 From: ;tag=aad5c7c2-9bf9-4238-be8c-71d71a501220 To: Contact: Call-ID: a2a6b703-e348-41de-8102-a46ee12c26d2 CSeq: 13313 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@0f2a1ae6268b4565d3b4512363cbd492' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '4.4.4.4' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '4.4.4.4' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '4.4.4.4' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '4.4.4.4' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '4.4.4.4' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (427 bytes) to UDP:4.4.4.4:1026 ---> OPTIONS sip:103@4.4.4.4:1026 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjf95387d8-ec2b-4e9d-8070-9a33490acb11 From: ;tag=0278c4cd-e449-4af7-877f-0b8ea9f420d7 To: Contact: Call-ID: 04c8d03d-e975-4e1f-98d3-2989c5d75cf0 CSeq: 46668 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Qualifying contact '103;@182861172349f7398f991c0159edd6a9' on AOR '103' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b9c0: Wrapper created [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b9c0: Set timer to 3000 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Performing SIP DNS resolution of target '8.8.8.8' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Transport type for target '8.8.8.8' is 'UDP transport' [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_resolver.c: Target '8.8.8.8' is an IP address, skipping resolution [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[20581] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[20581] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:17946 ---> OPTIONS sip:103@8.8.8.8:17946 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj3318b99b-ce66-4cee-85ce-c15708c49faf From: ;tag=53a05a4e-34c6-49ce-999f-5946fe201405 To: Contact: Call-ID: eb959868-8251-458d-8e8a-6fbf7d23539f CSeq: 62870 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Received SIP response (695 bytes) from UDP:4.4.4.4:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj45002c86-fd2f-4c8f-ac1b-3a2d79b6927c From: ;tag=a9ec25b3-7d80-4977-a253-f3145f65bf44 To: "103" ;tag=8D07E0D7-AE7263FA CSeq: 60492 OPTIONS Call-ID: 5eb97e15-1760-45dd-a0bb-c9dbc31c6087 Contact: Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER Supported: replaces,100rel,100rel,timer,replaces,norefersub,sdp-anat User-Agent: PolycomVVX-VVX_411-UA/5.7.4.1087 Accept-Language: en Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml Accept-Encoding: identity Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=60492 (rdata0x7f77a0001168). Using request transaction as basis. [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f77980639a8 for Response msg 200/OPTIONS/cseq=60492 (rdata0x7f77a0001168). [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/103-00000049 on transaction tsx0x7f77980639a8 [2021-06-30 14:37:36] DEBUG[15389] res_pjsip.c: 0x7f77901207b0: PJSIP tsx response received [2021-06-30 14:37:36] DEBUG[15389] res_pjsip.c: 0x7f77901207b0: Cancelling timer [2021-06-30 14:37:36] DEBUG[15389] res_pjsip.c: 0x7f77901207b0: Timer cancelled [2021-06-30 14:37:36] DEBUG[15389] res_pjsip.c: 0x7f77901207b0: Callbacks executed [2021-06-30 14:37:36] DEBUG[15389] res_pjsip.c: 0x7f77901207b0: wrapper destroyed [2021-06-30 14:37:36] DEBUG[15389] res_pjsip/pjsip_options.c: Contact 103/sip:103@4.4.4.4:5060;x-ast-orig-host=192.168.1.205:0 status didn't change: Reachable, RTT: 25.814 msec [2021-06-30 14:37:36] DEBUG[15389] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Received SIP response (695 bytes) from UDP:4.4.4.4:1025 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj50e95b31-2e18-4389-91bc-8436bcea48a3 From: ;tag=aad5c7c2-9bf9-4238-be8c-71d71a501220 To: "103" ;tag=EE2E99F2-492B79DD CSeq: 13313 OPTIONS Call-ID: a2a6b703-e348-41de-8102-a46ee12c26d2 Contact: Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER Supported: replaces,100rel,100rel,timer,replaces,norefersub,sdp-anat User-Agent: PolycomVVX-VVX_411-UA/5.7.4.1087 Accept-Language: en Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml Accept-Encoding: identity Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=13313 (rdata0x7f77a0001168). Using request transaction as basis. [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f77980295c8 for Response msg 200/OPTIONS/cseq=13313 (rdata0x7f77a0001168). [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/103-00000049 on transaction tsx0x7f77980295c8 [2021-06-30 14:37:36] DEBUG[16541] res_pjsip.c: 0x7f7790108520: PJSIP tsx response received [2021-06-30 14:37:36] DEBUG[16541] res_pjsip.c: 0x7f7790108520: Cancelling timer [2021-06-30 14:37:36] DEBUG[16541] res_pjsip.c: 0x7f7790108520: Timer cancelled [2021-06-30 14:37:36] DEBUG[16541] res_pjsip.c: 0x7f7790108520: Callbacks executed [2021-06-30 14:37:36] DEBUG[16541] res_pjsip.c: 0x7f7790108520: wrapper destroyed [2021-06-30 14:37:36] DEBUG[16541] res_pjsip/pjsip_options.c: Contact 103/sip:103@4.4.4.4:1025;x-ast-orig-host=192.168.1.188:0 status didn't change: Reachable, RTT: 29.976 msec [2021-06-30 14:37:36] DEBUG[16541] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Received SIP response (695 bytes) from UDP:4.4.4.4:1026 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjf95387d8-ec2b-4e9d-8070-9a33490acb11 From: ;tag=0278c4cd-e449-4af7-877f-0b8ea9f420d7 To: "103" ;tag=3ADD8CEC-2AF5D631 CSeq: 46668 OPTIONS Call-ID: 04c8d03d-e975-4e1f-98d3-2989c5d75cf0 Contact: Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER Supported: replaces,100rel,100rel,timer,replaces,norefersub,sdp-anat User-Agent: PolycomVVX-VVX_411-UA/5.7.4.1087 Accept-Language: en Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml Accept-Encoding: identity Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: No dialog serializer for Response msg 200/OPTIONS/cseq=46668 (rdata0x7f77a0001168). Using request transaction as basis. [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: Found transaction tsx0x7f77983f6558 for Response msg 200/OPTIONS/cseq=46668 (rdata0x7f77a0001168). [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_distributor.c: Found serializer pjsip/options/103-00000049 on transaction tsx0x7f77983f6558 [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: PJSIP tsx response received [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: Cancelling timer [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: Timer cancelled [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: Callbacks executed [2021-06-30 14:37:36] DEBUG[20581] res_pjsip.c: 0x7f779000b940: wrapper destroyed [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: Contact 103/sip:103@4.4.4.4:1026;x-ast-orig-host=192.168.1.240:0 status didn't change: Reachable, RTT: 30.744 msec [2021-06-30 14:37:36] DEBUG[20581] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:14113 ---> OPTIONS sip:103@8.8.8.8:14113 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjb907430a-3454-43d9-bcc8-8ad2c77f2a48 From: ;tag=adc972cf-ff10-42fa-8c92-714897205f28 To: Contact: Call-ID: 58b45470-d799-4e45-9363-db24c231f730 CSeq: 8152 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:21086 ---> OPTIONS sip:103@8.8.8.8:21086 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj51c43ef5-feea-4c47-aa7a-6dc2db59cf56 From: ;tag=2051ea99-a1a7-42a4-9c80-a3d8223c80be To: Contact: Call-ID: c319e7d2-8451-43a7-98bc-37806f0bc886 CSeq: 36687 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:56477 ---> OPTIONS sip:103@8.8.8.8:56477 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj94d7070e-3a39-47b7-a698-51b2e6b2ba61 From: ;tag=481b7d79-96f5-4be9-a608-4cecc19521e8 To: Contact: Call-ID: cdc8147f-cfad-40ae-b8de-8160cc2d8e49 CSeq: 1017 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:49377 ---> OPTIONS sip:103@8.8.8.8:49377 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj87c85b88-fc7a-44f4-9dfe-5b70902b357d From: ;tag=51831176-12e3-4263-9956-9caea77cf6da To: Contact: Call-ID: e9e0e56e-6f19-488c-a517-a2a65c4f19f6 CSeq: 44087 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:15072 ---> OPTIONS sip:103@8.8.8.8:15072 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc4bbf961-a553-4ed0-a39a-136fd5a448cf From: ;tag=14ab60c1-d08c-4063-8ef9-1b93304fd7f0 To: Contact: Call-ID: 11ed5a54-c833-4895-abbf-255e3df9c06b CSeq: 34937 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:41561 ---> OPTIONS sip:103@8.8.8.8:41561 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPje5b0190d-c598-492c-9637-3776d9689093 From: ;tag=20c04828-e04f-46c3-adbd-711abf46646c To: Contact: Call-ID: 58692c0b-bd02-4533-8ca4-f3f987e3a510 CSeq: 52840 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:56633 ---> OPTIONS sip:103@8.8.8.8:56633 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc72ad7bb-6347-4017-b649-466ee7c1ad28 From: ;tag=2366125f-52dd-41ea-adf5-1e8a8f7b39a8 To: Contact: Call-ID: 0a43ee83-17cb-4b8a-9020-54b2455a5b5d CSeq: 31768 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:36] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:36] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:36] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:17946 ---> OPTIONS sip:103@8.8.8.8:17946 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj3318b99b-ce66-4cee-85ce-c15708c49faf From: ;tag=53a05a4e-34c6-49ce-999f-5946fe201405 To: Contact: Call-ID: eb959868-8251-458d-8e8a-6fbf7d23539f CSeq: 62870 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:14113 ---> OPTIONS sip:103@8.8.8.8:14113 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjb907430a-3454-43d9-bcc8-8ad2c77f2a48 From: ;tag=adc972cf-ff10-42fa-8c92-714897205f28 To: Contact: Call-ID: 58b45470-d799-4e45-9363-db24c231f730 CSeq: 8152 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:21086 ---> OPTIONS sip:103@8.8.8.8:21086 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj51c43ef5-feea-4c47-aa7a-6dc2db59cf56 From: ;tag=2051ea99-a1a7-42a4-9c80-a3d8223c80be To: Contact: Call-ID: c319e7d2-8451-43a7-98bc-37806f0bc886 CSeq: 36687 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:41561 ---> OPTIONS sip:103@8.8.8.8:41561 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPje5b0190d-c598-492c-9637-3776d9689093 From: ;tag=20c04828-e04f-46c3-adbd-711abf46646c To: Contact: Call-ID: 58692c0b-bd02-4533-8ca4-f3f987e3a510 CSeq: 52840 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:56477 ---> OPTIONS sip:103@8.8.8.8:56477 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj94d7070e-3a39-47b7-a698-51b2e6b2ba61 From: ;tag=481b7d79-96f5-4be9-a608-4cecc19521e8 To: Contact: Call-ID: cdc8147f-cfad-40ae-b8de-8160cc2d8e49 CSeq: 1017 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:56633 ---> OPTIONS sip:103@8.8.8.8:56633 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc72ad7bb-6347-4017-b649-466ee7c1ad28 From: ;tag=2366125f-52dd-41ea-adf5-1e8a8f7b39a8 To: Contact: Call-ID: 0a43ee83-17cb-4b8a-9020-54b2455a5b5d CSeq: 31768 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:15072 ---> OPTIONS sip:103@8.8.8.8:15072 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc4bbf961-a553-4ed0-a39a-136fd5a448cf From: ;tag=14ab60c1-d08c-4063-8ef9-1b93304fd7f0 To: Contact: Call-ID: 11ed5a54-c833-4895-abbf-255e3df9c06b CSeq: 34937 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:49377 ---> OPTIONS sip:103@8.8.8.8:49377 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj87c85b88-fc7a-44f4-9dfe-5b70902b357d From: ;tag=51831176-12e3-4263-9956-9caea77cf6da To: Contact: Call-ID: e9e0e56e-6f19-488c-a517-a2a65c4f19f6 CSeq: 44087 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:37] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:37] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:37] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:17946 ---> OPTIONS sip:103@8.8.8.8:17946 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj3318b99b-ce66-4cee-85ce-c15708c49faf From: ;tag=53a05a4e-34c6-49ce-999f-5946fe201405 To: Contact: Call-ID: eb959868-8251-458d-8e8a-6fbf7d23539f CSeq: 62870 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[15389] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:14113;x-ast-orig-host=192.168.1.183:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[15389] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f7790057dc0: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f7790057dc0: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f7790057dc0: Callbacks executed [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779021cbf0: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779021cbf0: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779021cbf0: Callbacks executed [2021-06-30 14:37:39] DEBUG[16541] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:21086;x-ast-orig-host=192.168.1.183:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[16541] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[16541] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:56477;x-ast-orig-host=192.168.1.198:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[16541] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779000cde0: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779000cde0: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779000cde0: Callbacks executed [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f77901206f0: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f77901206f0: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f77901206f0: Callbacks executed [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f7790118300: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f7790118300: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f7790118300: Callbacks executed [2021-06-30 14:37:39] DEBUG[20581] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:49377;x-ast-orig-host=192.168.1.198:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[20581] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[20581] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:15072;x-ast-orig-host=192.168.1.154:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[20581] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[20581] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:41561;x-ast-orig-host=192.168.1.183:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[20581] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f77900f4520: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f77900f4520: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f77900f4520: Callbacks executed [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779000b9c0: Internal tsx timer expired after 3000 msec [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779000b9c0: Timer handled here [2021-06-30 14:37:39] DEBUG[1948] res_pjsip.c: 0x7f779000b9c0: Callbacks executed [2021-06-30 14:37:39] DEBUG[15389] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:56633;x-ast-orig-host=192.168.1.154:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[15389] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[15389] res_pjsip/pjsip_options.c: Contact 103/sip:103@8.8.8.8:17946;x-ast-orig-host=192.168.1.183:0 status didn't change: Unreachable, RTT: 0.000 msec [2021-06-30 14:37:39] DEBUG[15389] res_pjsip/pjsip_options.c: AOR '103' now has 3 available contacts [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:54367 ---> OPTIONS sip:102@8.8.8.8:54367 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPje9119676-97d2-4188-a20f-74bc322d4aec From: ;tag=c06ea40b-2d39-4c89-83ea-d94af5ec918a To: Contact: Call-ID: 75038929-9d47-40b7-9df1-4153e23dfdd1 CSeq: 42926 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:56477 ---> OPTIONS sip:102@8.8.8.8:56477 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj76749299-6cb3-4534-9f4d-e3f2dfffff31 From: ;tag=57f31b7f-f6ca-453c-a9f4-05b805682502 To: Contact: Call-ID: 53e19ceb-2e60-46f6-af54-929b2554c512 CSeq: 40087 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:34257 ---> OPTIONS sip:102@8.8.8.8:34257 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj6a58b75d-b167-41d2-9a43-fec3819be0c1 From: ;tag=dcd23303-14a8-45f2-83e8-95cfb5fc7ded To: Contact: Call-ID: 27de0610-2d08-4e29-9d8a-c2344dc0432a CSeq: 46026 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:15072 ---> OPTIONS sip:102@8.8.8.8:15072 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj1777176c-9c87-4556-9ba3-a29745461129 From: ;tag=c54ba11d-7ee1-4623-ad0a-afc211b669e6 To: Contact: Call-ID: cf3b7f28-2163-43de-9d5a-9c54b6ad64d8 CSeq: 52391 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:41561 ---> OPTIONS sip:102@8.8.8.8:41561 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjb1b4b4c5-3d08-4f96-8ce9-2ef17789e439 From: ;tag=6ba3c4f7-3e19-4da1-8b8a-b4422ea5f70f To: Contact: Call-ID: eec69fa4-9e84-45af-9871-45b15e47c0d9 CSeq: 57582 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:49377 ---> OPTIONS sip:102@8.8.8.8:49377 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj6dc3d16b-9f8a-4c5b-a983-b92bebcc9409 From: ;tag=2fc33938-751f-48db-8300-08531a87a04a To: Contact: Call-ID: 66004604-2b0e-4a6b-bde8-efa125c6e0b1 CSeq: 63648 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:56633 ---> OPTIONS sip:102@8.8.8.8:56633 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc422434e-3bb8-4d87-b166-4b43693071c0 From: ;tag=2a192b05-9cf3-4315-8fe8-78a08e561977 To: Contact: Call-ID: 2945f74b-a486-49ac-bce2-15784ecb1ff5 CSeq: 49844 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:17946 ---> OPTIONS sip:102@8.8.8.8:17946 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjd0741ccb-5c7a-4e0f-a6f4-d9e4c3d7019e From: ;tag=1060d62f-5b14-4219-a4f5-718a4d93ab5e To: Contact: Call-ID: c7798bd3-22db-467f-a9be-f9570f886a26 CSeq: 34665 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:14113 ---> OPTIONS sip:103@8.8.8.8:14113 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjb907430a-3454-43d9-bcc8-8ad2c77f2a48 From: ;tag=adc972cf-ff10-42fa-8c92-714897205f28 To: Contact: Call-ID: 58b45470-d799-4e45-9363-db24c231f730 CSeq: 8152 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:21086 ---> OPTIONS sip:103@8.8.8.8:21086 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj51c43ef5-feea-4c47-aa7a-6dc2db59cf56 From: ;tag=2051ea99-a1a7-42a4-9c80-a3d8223c80be To: Contact: Call-ID: c319e7d2-8451-43a7-98bc-37806f0bc886 CSeq: 36687 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:49377 ---> OPTIONS sip:103@8.8.8.8:49377 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj87c85b88-fc7a-44f4-9dfe-5b70902b357d From: ;tag=51831176-12e3-4263-9956-9caea77cf6da To: Contact: Call-ID: e9e0e56e-6f19-488c-a517-a2a65c4f19f6 CSeq: 44087 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:15072 ---> OPTIONS sip:103@8.8.8.8:15072 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc4bbf961-a553-4ed0-a39a-136fd5a448cf From: ;tag=14ab60c1-d08c-4063-8ef9-1b93304fd7f0 To: Contact: Call-ID: 11ed5a54-c833-4895-abbf-255e3df9c06b CSeq: 34937 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:56633 ---> OPTIONS sip:103@8.8.8.8:56633 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPjc72ad7bb-6347-4017-b649-466ee7c1ad28 From: ;tag=2366125f-52dd-41ea-adf5-1e8a8f7b39a8 To: Contact: Call-ID: 0a43ee83-17cb-4b8a-9020-54b2455a5b5d CSeq: 31768 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (425 bytes) to UDP:8.8.8.8:56477 ---> OPTIONS sip:103@8.8.8.8:56477 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPj94d7070e-3a39-47b7-a698-51b2e6b2ba61 From: ;tag=481b7d79-96f5-4be9-a608-4cecc19521e8 To: Contact: Call-ID: cdc8147f-cfad-40ae-b8de-8160cc2d8e49 CSeq: 1017 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0 [2021-06-30 14:37:39] DEBUG[1948] res_pjsip/pjsip_message_filter.c: Re-wrote Contact URI host/port to 1.1.1.1:5060 (this may be re-written again later) [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: Splitting '8.8.8.8' into... [2021-06-30 14:37:39] DEBUG[1948] netsock2.c: ...host '8.8.8.8' and port ''. [2021-06-30 14:37:39] VERBOSE[1948] res_pjsip_logger.c: <--- Transmitting SIP request (426 bytes) to UDP:8.8.8.8:41561 ---> OPTIONS sip:103@8.8.8.8:41561 SIP/2.0 Via: SIP/2.0/UDP 1.1.1.1:5060;rport;branch=z9hG4bKPje5b0190d-c598-492c-9637-3776d9689093 From: ;tag=20c04828-e04f-46c3-adbd-711abf46646c To: Contact: Call-ID: 58692c0b-bd02-4533-8ca4-f3f987e3a510 CSeq: 52840 OPTIONS Max-Forwards: 70 User-Agent: FPBX-15.0.17.37(16.17.0) Content-Length: 0