anarki software # anarki software # anarki software # asterisk -r Asterisk SVN-trunk-r50603, Copyright (C) 1999 - 2006 Digium, Inc. and others. Created by Mark Spencer Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type 'core show license' for details. ========================================================================= NOTE: This is a development version of Asterisk, and should not be used in production installations. Connected to Asterisk SVN-trunk-r50603 currently running on anarki (pid = 31178) -- Remote UNIX connection Verbosity is at least 5 anarki*CLI> core <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-9baa51ee From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34996 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-9baa51ee [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34996 NOTIFY [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- anarki*CLI> core <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-9baa51ee;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as7dab7dd6 Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34996 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:57:32] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> core set debug 5 <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-d42f2c0f From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34995 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-d42f2c0f [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34995 NOTIFY [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-d42f2c0f;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as0c13c8ab Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34995 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:57:36] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> core set debug 5 Core debug is at least 5 anarki*CLI> core set verbose 5 Verbosity is at least 5 anarki*CLI> sip set <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-6dfeb4df From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34997 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-6dfeb4df [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34997 NOTIFY [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-6dfeb4df;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as269d5509 Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34997 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:57:47] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> sip set debug ip SIP Debugging re-enabled anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-aaa043b0 From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34996 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-aaa043b0 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34996 NOTIFY [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-aaa043b0;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as0521a9ca Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34996 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:57:51] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-dbd31f92 From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34998 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-dbd31f92 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34998 NOTIFY [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-dbd31f92;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as706ea6fd Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34998 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:02] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-14c22677 From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34997 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-14c22677 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34997 NOTIFY [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-14c22677;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as2c54ee9c Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34997 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:06] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e6adc956 From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34999 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e6adc956 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34999 NOTIFY [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e6adc956;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as05dff859 Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 34999 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:17] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-e5fed61 From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34998 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 56]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-e5fed61 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34998 NOTIFY [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-e5fed61;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as5736d271 Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34998 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:21] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 327 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-8413a552 From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35000 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-8413a552 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35000 NOTIFY [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-8413a552;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as231531ef Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35000 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:32] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> INVITE sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e36ed7f2 From: alx ;tag=e864fb04f6cbbc22o0 To: Remote-Party-ID: alx ;screen=yes;party=calling Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 101 INVITE Max-Forwards: 70 Contact: alx Expires: 240 User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 446 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 61925591 61925591 IN IP4 192.168.20.12 s=- c=IN IP4 10.10.10.23 t=0 0 m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729a/8000 a=rtpmap:96 G726-40/8000 a=rtpmap:97 G726-24/8000 a=rtpmap:98 G726-16/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv <-------------> [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 40]: INVITE sip:0123123123@172.16.16.111 SIP/2.0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e36ed7f2 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 31]: To: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 72]: Remote-Party-ID: alx ;screen=yes;party=calling [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: CSeq: 101 INVITE [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 45]: Contact: alx [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 12]: Expires: 240 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 19]: Content-Length: 446 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 12 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 13 [ 19]: Supported: x-sipura [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 14 [ 29]: Content-Type: application/sdp [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 15 [ 0]: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 42]: o=- 61925591 61925591 IN IP4 192.168.20.12 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 3]: s=- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 20]: c=IN IP4 10.10.10.23 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 49]: m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 100 101 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 23]: a=rtpmap:2 G726-32/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 20]: a=rtpmap:4 G723/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 22]: a=rtpmap:18 G729a/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 24]: a=rtpmap:96 G726-40/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 24]: a=rtpmap:97 G726-24/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 24]: a=rtpmap:98 G726-16/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 21]: a=rtpmap:100 NSE/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 15 [ 18]: a=fmtp:100 192-193 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 16 [ 33]: a=rtpmap:101 telephone-event/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 17 [ 15]: a=fmtp:101 0-15 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 18 [ 10]: a=ptime:30 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 19 [ 10]: a=sendrecv --- (15 headers 20 lines) --- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2670 do_setnat: Setting NAT on RTP to Off [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2675 do_setnat: Setting NAT on VRTP to Off [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2680 do_setnat: Setting NAT on UDPTL to Off [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4369 sip_alloc: Allocating new SIP dialog for 7025662c-5d5e0b1a@192.168.20.12 - INVITE (With RTP) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1706 parse_sip_options: Begin: parsing SIP "Supported: x-sipura" [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1714 parse_sip_options: Found SIP option: -x-sipura- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1726 parse_sip_options: Found private SIP option, not supported: x-sipura Sending to 10.10.10.23 : 8634 (no NAT) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:13420 handle_request_invite: Initializing initreq for method INVITE - callid 7025662c-5d5e0b1a@192.168.20.12 Using INVITE request as basis request - 7025662c-5d5e0b1a@192.168.20.12 Found user '123456789' for '123456789' [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2670 do_setnat: Setting NAT on RTP to On [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2675 do_setnat: Setting NAT on VRTP to On [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2680 do_setnat: Setting NAT on UDPTL to On <--- Reliably Transmitting (NAT) to 10.10.10.23:8632 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e36ed7f2;received=10.10.10.23 From: alx ;tag=e864fb04f6cbbc22o0 To: ;tag=as6745287c Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 101 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces WWW-Authenticate: Digest algorithm=MD5, realm="blabla.ch", nonce="4650447f" Content-Length: 0 <------------> [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1996 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #25 Scheduling destruction of SIP dialog '7025662c-5d5e0b1a@192.168.20.12' in 32000 ms (Method: INVITE) anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> ACK sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e36ed7f2 From: alx ;tag=e864fb04f6cbbc22o0 To: ;tag=as6745287c Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 101 ACK Max-Forwards: 70 Contact: alx User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 37]: ACK sip:0123123123@172.16.16.111 SIP/2.0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-e36ed7f2 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 46]: To: ;tag=as6745287c [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 13]: CSeq: 101 ACK [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 45]: Contact: alx [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as6745287c [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2095 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #25 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '7025662c-5d5e0b1a@192.168.20.12' of Response 101: Match Found anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> INVITE sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ef4f3e96 From: alx ;tag=e864fb04f6cbbc22o0 To: Remote-Party-ID: alx ;screen=yes;party=calling Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 INVITE Max-Forwards: 70 Authorization: Digest username="123456789",realm="blabla.ch",nonce="4650447f",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="4fa2868a30fee7adb6af3552a665e104" Contact: alx Expires: 240 User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 446 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 61925591 61925591 IN IP4 192.168.20.12 s=- c=IN IP4 10.10.10.23 t=0 0 m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:4 G723/8000 a=rtpmap:18 G729a/8000 a=rtpmap:96 G726-40/8000 a=rtpmap:97 G726-24/8000 a=rtpmap:98 G726-16/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv <-------------> [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 40]: INVITE sip:0123123123@172.16.16.111 SIP/2.0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ef4f3e96 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 31]: To: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 72]: Remote-Party-ID: alx ;screen=yes;party=calling [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [165]: Authorization: Digest username="123456789",realm="blabla.ch",nonce="4650447f",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="4fa2868a30fee7adb6af3552a665e104" [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 45]: Contact: alx [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 12]: Expires: 240 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 12 [ 19]: Content-Length: 446 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 13 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 14 [ 19]: Supported: x-sipura [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 15 [ 29]: Content-Type: application/sdp [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 16 [ 0]: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 42]: o=- 61925591 61925591 IN IP4 192.168.20.12 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 3]: s=- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 20]: c=IN IP4 10.10.10.23 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 49]: m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 100 101 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 23]: a=rtpmap:2 G726-32/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 20]: a=rtpmap:4 G723/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 22]: a=rtpmap:18 G729a/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 24]: a=rtpmap:96 G726-40/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 24]: a=rtpmap:97 G726-24/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 24]: a=rtpmap:98 G726-16/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 21]: a=rtpmap:100 NSE/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 15 [ 18]: a=fmtp:100 192-193 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 16 [ 33]: a=rtpmap:101 telephone-event/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 17 [ 15]: a=fmtp:101 0-15 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 18 [ 10]: a=ptime:30 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 19 [ 10]: a=sendrecv --- (16 headers 20 lines) --- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as6745287c [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1706 parse_sip_options: Begin: parsing SIP "Supported: x-sipura" [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1714 parse_sip_options: Found SIP option: -x-sipura- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1726 parse_sip_options: Found private SIP option, not supported: x-sipura Sending to 10.10.10.23 : 8632 (NAT) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:13420 handle_request_invite: Initializing initreq for method INVITE - callid 7025662c-5d5e0b1a@192.168.20.12 Using INVITE request as basis request - 7025662c-5d5e0b1a@192.168.20.12 Found user '123456789' for '123456789' [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2670 do_setnat: Setting NAT on RTP to On [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2675 do_setnat: Setting NAT on VRTP to On [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2680 do_setnat: Setting NAT on UDPTL to On Found RTP audio format 8 Found RTP audio format 0 Found RTP audio format 2 Found RTP audio format 4 Found RTP audio format 18 Found RTP audio format 96 Found RTP audio format 97 Found RTP audio format 98 Found RTP audio format 100 Found RTP audio format 101 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4936 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 10.10.10.23:16406 Found description format PCMA for ID 8 Found description format PCMU for ID 0 Found description format G726-32 for ID 2 Found description format G723 for ID 4 Found description format G729a for ID 18 Found description format G726-40 for ID 96 Found description format G726-24 for ID 97 Found description format G726-16 for ID 98 Found description format NSE for ID 100 Got unsupported a:fmtp in SDP offer Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:5166 process_sdp: T38 state changed to 0 on channel Capabilities: us - 0xa (gsm|alaw), peer - audio=0xd0d (g723|ulaw|alaw|g726|g729|ilbc)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 10.10.10.23:16406 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:5243 process_sdp: We're settling with these formats: 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:13501 handle_request_invite: Checking SIP call limits for device 123456789 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3093 update_call_counter: Updating call counter for incoming call Looking for 0123123123 in privileged (domain 172.16.16.111) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3873 sip_new: *** Our native formats are 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3874 sip_new: *** Joint capabilities are 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3875 sip_new: *** Our capabilities are 0xa (gsm|alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3876 sip_new: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3899 sip_new: This channel will not be able to handle video. [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:7975 build_route: build_route: Contact hop: alx list_route: hop: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:13576 handle_request_invite: SIP/123456789-081b6b98: New call is still down.... Trying... <--- Transmitting (NAT) to 10.10.10.23:8632 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ef4f3e96;received=10.10.10.23 From: alx ;tag=e864fb04f6cbbc22o0 To: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Jan 12 23:58:33] DEBUG[31182]: devicestate.c:387 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/123456789-081b6b98 [Jan 12 23:58:33] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 123456789 [Jan 12 23:58:33] DEBUG[31182]: devicestate.c:371 do_state_change: Changing state for SIP/123456789 - state 1 (Not in use) [Jan 12 23:58:33] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 123456789 [Jan 12 23:58:33] DEBUG[31182]: pbx.c:1693 pbx_extension_helper: Launching 'Dial' -- Executing [0123123123@privileged:1] Dial("SIP/123456789-081b6b98", "SIP/0123123123@172.16.16.155") in new stack [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:15383 sip_request_call: Asked to create a SIP channel with formats: 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4369 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Jan 12 23:58:33] DEBUG[31182]: app_queue.c:568 changethread: Device 'SIP/123456789' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3873 sip_new: *** Our native formats are 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3874 sip_new: *** Joint capabilities are 0x0 (nothing) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3875 sip_new: *** Our capabilities are 0x40a (gsm|alaw|ilbc) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3876 sip_new: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3878 sip_new: *** Our preferred formats from the incoming channel are 0x8 (alaw) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3899 sip_new: This channel will not be able to handle video. [Jan 12 23:58:33] DEBUG[31182]: channel.c:3187 ast_channel_inherit_variables: Not copying variable STACK-privileged-0123123123-1. [Jan 12 23:58:33] DEBUG[31182]: channel.c:3187 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Jan 12 23:58:33] DEBUG[31182]: channel.c:3187 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Jan 12 23:58:33] DEBUG[31182]: channel.c:3187 ast_channel_inherit_variables: Not copying variable SIPURI. [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2919 sip_call: Outgoing Call for 0123123123 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:3093 update_call_counter: Updating call counter for outgoing call [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2936 sip_call: Our T38 capability (3856), joint T38 capability (3856) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6246 add_sdp: ** Our capability: 0x40a (gsm|alaw|ilbc) Video flag: False [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6247 add_sdp: ** Our prefcodec: 0x8 (alaw) Audio is at 172.16.16.111 port 14614 Adding codec 0x8 (alaw) to SDP Adding codec 0x2 (gsm) to SDP Adding codec 0x400 (ilbc) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6372 add_sdp: -- Done with adding codecs to SDP [Jan 12 23:58:33] DEBUG[31182]: channel.c:2277 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6416 add_sdp: Done building SDP. Settling with this capability: 0x40a (gsm|alaw|ilbc) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1646 initialize_initreq: Initializing initreq for method INVITE - callid 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 40]: INVITE sip:0123123123@172.16.16.155 SIP/2.0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 61]: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK19a5291d;rport [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 60]: From: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 31]: To: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 36]: Contact: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 26]: User-Agent: blabla.ch 1.4PBX [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 35]: Date: Fri, 12 Jan 2007 22:58:33 GMT [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 19]: Supported: replaces [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 12 [ 29]: Content-Type: application/sdp [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 13 [ 19]: Content-Length: 304 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 14 [ 0]: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 36]: o=root 31219 31219 IN IP4 172.16.16.111 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 9]: s=session [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.111 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 32]: m=audio 14614 RTP/AVP 8 3 97 101 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 19]: a=rtpmap:3 GSM/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 21]: a=rtpmap:97 iLBC/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 17]: a=fmtp:97 mode=30 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 15]: a=fmtp:101 0-16 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 25]: a=silenceSupp:off - - - - [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 10]: a=ptime:20 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 10]: a=sendrecv Reliably Transmitting (no NAT) to 172.16.16.155:5060: INVITE sip:0123123123@172.16.16.155 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK19a5291d;rport Max-Forwards: 70 From: "Alex blabla" ;tag=as07b3e2bf To: Contact: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Date: Fri, 12 Jan 2007 22:58:33 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 304 v=0 o=root 31219 31219 IN IP4 172.16.16.111 s=session c=IN IP4 172.16.16.111 t=0 0 m=audio 14614 RTP/AVP 8 3 97 101 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:1996 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #27 -- Called 0123123123@172.16.16.155 <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 From: "Alex blabla" ;tag=as07b3e2bf To: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 102 INVITE <-------------> [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 18]: SIP/2.0 100 Trying [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 86]: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 60]: From: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 31]: To: [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 0]: --- (6 headers 0 lines) --- [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag Our tag: as07b3e2bf [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2140 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #27 - INVITE (got response) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:2149 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' Request 102: Found [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:11737 handle_response_invite: SIP response 100 to standard invite [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6471 transmit_response_with_sdp: Setting framing from config on incoming call [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6246 add_sdp: ** Our capability: 0x8 (alaw) Video flag: True [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6247 add_sdp: ** Our prefcodec: 0x0 (nothing) Audio is at 172.16.16.111 port 15296 Adding codec 0x8 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6372 add_sdp: -- Done with adding codecs to SDP [Jan 12 23:58:33] DEBUG[31182]: channel.c:2277 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) [Jan 12 23:58:33] DEBUG[31182]: chan_sip.c:6416 add_sdp: Done building SDP. Settling with this capability: 0x8 (alaw) <--- Transmitting (NAT) to 10.10.10.23:8632 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ef4f3e96;received=10.10.10.23 From: alx ;tag=e864fb04f6cbbc22o0 To: ;tag=as2ccf40c0 Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 236 v=0 o=root 31219 31219 IN IP4 172.16.16.111 s=session c=IN IP4 172.16.16.111 t=0 0 m=audio 15296 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Jan 12 23:58:33] DEBUG[31182]: rtp.c:2734 ast_rtp_write: Ooh, format changed from unknown to alaw [Jan 12 23:58:33] DEBUG[31182]: rtp.c:2751 ast_rtp_write: Created smoother: format: 8 ms: 20 len: 160 [Jan 12 23:58:34] NOTICE[31182]: rtp.c:1312 ast_rtp_read: Unknown RTP codec 100 received from '10.10.10.23' anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 102 INVITE Contact: Content-Type: application/sdp Content-Length: 340 v=0 o=- 19 0 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=audio 16000 RTP/AVP 8 101 100 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 200-202 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 28]: SIP/2.0 183 Session Progress [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 86]: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 60]: From: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 45]: To: ;tag=134037335 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 55]: Contact: [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 29]: Content-Type: application/sdp [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 19]: Content-Length: 340 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 0]: [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 26]: o=- 19 0 IN IP4 172.16.16.155 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 13]: s=Cisco SDP 0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.155 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 31]: m=audio 16000 RTP/AVP 8 101 100 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 33]: a=rtpmap:101 telephone-event/8000 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 15]: a=fmtp:101 0-15 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 23]: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 18]: a=fmtp:100 200-202 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 9]: a=X-sqn:0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 28]: a=X-cap: 1 audio RTP/AVP 100 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 33]: a=X-cpar: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 28]: a=X-cpar: a=fmtp:100 200-202 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 26]: a=X-cap: 2 image udptl t38 --- (9 headers 15 lines) --- [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag Our tag: as07b3e2bf [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:2149 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' Request 102: Found [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:11737 handle_response_invite: SIP response 183 to standard invite Found RTP audio format 8 Found RTP audio format 101 Found RTP audio format 100 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4936 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 172.16.16.155:16000 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer Found description format X-NSE for ID 100 Got unsupported a:fmtp in SDP offer [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:5166 process_sdp: T38 state changed to 0 on channel SIP/172.16.16.155-081bb160 Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.16.16.155:16000 Peer video RTP is at port 172.16.16.155:61110 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:5243 process_sdp: We're settling with these formats: 0x8 (alaw) [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:5250 process_sdp: We have an owner, now see if we need to change this call -- SIP/172.16.16.155-081bb160 is making progress passing it to SIP/123456789-081b6b98 [Jan 12 23:58:34] DEBUG[31182]: rtp.c:2734 ast_rtp_write: Ooh, format changed from unknown to alaw [Jan 12 23:58:34] DEBUG[31182]: rtp.c:2751 ast_rtp_write: Created smoother: format: 8 ms: 20 len: 160 anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 102 INVITE Contact: Content-Type: application/sdp Content-Length: 340 v=0 o=- 19 0 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=audio 16000 RTP/AVP 8 101 100 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 200-202 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 28]: SIP/2.0 183 Session Progress [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 86]: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 60]: From: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 45]: To: ;tag=134037335 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 55]: Contact: [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 29]: Content-Type: application/sdp [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 19]: Content-Length: 340 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 0]: [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 26]: o=- 19 0 IN IP4 172.16.16.155 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 13]: s=Cisco SDP 0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.155 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 31]: m=audio 16000 RTP/AVP 8 101 100 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 33]: a=rtpmap:101 telephone-event/8000 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 15]: a=fmtp:101 0-15 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 23]: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 18]: a=fmtp:100 200-202 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 9]: a=X-sqn:0 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 28]: a=X-cap: 1 audio RTP/AVP 100 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 33]: a=X-cpar: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 28]: a=X-cpar: a=fmtp:100 200-202 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 26]: a=X-cap: 2 image udptl t38 --- (9 headers 15 lines) --- [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:2149 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' Request 102: Found [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:11737 handle_response_invite: SIP response 183 to standard invite Found RTP audio format 8 Found RTP audio format 101 Found RTP audio format 100 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:4936 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 172.16.16.155:16000 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer Found description format X-NSE for ID 100 Got unsupported a:fmtp in SDP offer [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:5166 process_sdp: T38 state changed to 0 on channel SIP/172.16.16.155-081bb160 Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.16.16.155:16000 Peer video RTP is at port 172.16.16.155:61110 [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:5243 process_sdp: We're settling with these formats: 0x8 (alaw) [Jan 12 23:58:34] DEBUG[31182]: chan_sip.c:5250 process_sdp: We have an owner, now see if we need to change this call -- SIP/172.16.16.155-081bb160 is making progress passing it to SIP/123456789-081b6b98 [Jan 12 23:58:34] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 132 bytes anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-7764a54b From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34999 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-7764a54b [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 34999 NOTIFY [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 anarki*CLI> <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-7764a54b;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as4705d0e6 Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 34999 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 [Jan 12 23:58:36] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 116 bytes anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 102 INVITE Contact: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE Supported: timer Content-Type: application/sdp Content-Length: 340 v=0 o=- 19 0 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=audio 16000 RTP/AVP 8 101 100 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 200-202 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 14]: SIP/2.0 200 Ok [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 86]: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK19a5291d;rport=5060 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 60]: From: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 45]: To: ;tag=134037335 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 55]: Contact: [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 69]: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 16]: Supported: timer [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 29]: Content-Type: application/sdp [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 19]: Content-Length: 340 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 0]: [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 26]: o=- 19 0 IN IP4 172.16.16.155 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 13]: s=Cisco SDP 0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.155 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 31]: m=audio 16000 RTP/AVP 8 101 100 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 33]: a=rtpmap:101 telephone-event/8000 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 15]: a=fmtp:101 0-15 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 23]: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 18]: a=fmtp:100 200-202 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 9]: a=X-sqn:0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 28]: a=X-cap: 1 audio RTP/AVP 100 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 33]: a=X-cpar: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 28]: a=X-cpar: a=fmtp:100 200-202 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 26]: a=X-cap: 2 image udptl t38 --- (11 headers 15 lines) --- [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:2090 __sip_ack: Acked pending invite 102 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' of Request 102: Match Found [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:11737 handle_response_invite: SIP response 200 to standard invite Found RTP audio format 8 Found RTP audio format 101 Found RTP audio format 100 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4936 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 172.16.16.155:16000 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer Found description format X-NSE for ID 100 Got unsupported a:fmtp in SDP offer [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:5166 process_sdp: T38 state changed to 0 on channel SIP/172.16.16.155-081bb160 Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.16.16.155:16000 Peer video RTP is at port 172.16.16.155:61110 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:5243 process_sdp: We're settling with these formats: 0x8 (alaw) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:5250 process_sdp: We have an owner, now see if we need to change this call [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:3093 update_call_counter: Updating call counter for outgoing call --- set_address_from_contact host '172.16.16.155' [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:7975 build_route: build_route: Contact hop: list_route: hop: [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:5681 reqprep: Strict routing enforced for session 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 set_destination: Parsing for address/port to send to set_destination: set destination to 172.16.16.155, port 5060 Transmitting (no NAT) to 172.16.16.155:5060: ACK sip:0123123123@172.16.16.155:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK4f84172c;rport Max-Forwards: 70 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Contact: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 102 ACK User-Agent: blabla.ch 1.4PBX Content-Length: 0 --- [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:387 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/172.16.16.155-081bb160 -- SIP/172.16.16.155-081bb160 answered SIP/123456789-081b6b98 [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:387 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/123456789-081b6b98 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:3539 sip_answer: SIP answering channel: SIP/123456789-081b6b98 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:6471 transmit_response_with_sdp: Setting framing from config on incoming call [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:6246 add_sdp: ** Our capability: 0x8 (alaw) Video flag: True [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:6247 add_sdp: ** Our prefcodec: 0x0 (nothing) Audio is at 172.16.16.111 port 15296 Adding codec 0x8 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:6372 add_sdp: -- Done with adding codecs to SDP [Jan 12 23:58:36] DEBUG[31182]: channel.c:2277 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:6416 add_sdp: Done building SDP. Settling with this capability: 0x8 (alaw) <--- Reliably Transmitting (NAT) to 10.10.10.23:8632 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ef4f3e96;received=10.10.10.23 From: alx ;tag=e864fb04f6cbbc22o0 To: ;tag=as2ccf40c0 Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 236 v=0 o=root 31219 31220 IN IP4 172.16.16.111 s=session c=IN IP4 172.16.16.111 t=0 0 m=audio 15296 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:1996 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #31 [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 172.16.16.155 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 172.16.16.155 [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:371 do_state_change: Changing state for SIP/172.16.16.155 - state 2 (In use) [Jan 12 23:58:36] DEBUG[31182]: app_queue.c:568 changethread: Device 'SIP/172.16.16.155' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 123456789 [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:371 do_state_change: Changing state for SIP/123456789 - state 1 (Not in use) [Jan 12 23:58:36] DEBUG[31182]: app_queue.c:568 changethread: Device 'SIP/123456789' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 12 23:58:36] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 123456789 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> ACK sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-93573fe6 From: alx ;tag=e864fb04f6cbbc22o0 To: ;tag=as2ccf40c0 Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 ACK Max-Forwards: 70 Authorization: Digest username="123456789",realm="blabla.ch",nonce="4650447f",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="970dcc6d773e8f6b2922b8a336e893f0" Contact: alx User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 37]: ACK sip:0123123123@172.16.16.111 SIP/2.0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-93573fe6 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 46]: To: ;tag=as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 13]: CSeq: 102 ACK [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [165]: Authorization: Digest username="123456789",realm="blabla.ch",nonce="4650447f",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="970dcc6d773e8f6b2922b8a336e893f0" [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 45]: Contact: alx [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 17]: Content-Length: 0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 0]: --- (11 headers 0 lines) --- [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:36] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14897 sipsock_read: Failed to grab owner channel lock, trying again. (SIP call 7025662c-5d5e0b1a@192.168.20.12) -- Packet2Packet bridging SIP/123456789-081b6b98 and SIP/172.16.16.155-081bb160 [Jan 12 23:58:37] DEBUG[31182]: rtp.c:2751 ast_rtp_write: Created smoother: format: 8 ms: 20 len: 160 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:2095 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #31 [Jan 12 23:58:37] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '7025662c-5d5e0b1a@192.168.20.12' of Response 102: Match Found [Jan 12 23:58:37] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 132 bytes [Jan 12 23:58:39] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 116 bytes [Jan 12 23:58:42] NOTICE[31182]: rtp.c:1312 ast_rtp_read: Unknown RTP codec 100 received from '172.16.16.155' [Jan 12 23:58:42] NOTICE[31182]: rtp.c:1312 ast_rtp_read: Unknown RTP codec 100 received from '172.16.16.155' [Jan 12 23:58:42] NOTICE[31182]: rtp.c:1312 ast_rtp_read: Unknown RTP codec 100 received from '172.16.16.155' [Jan 12 23:58:42] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 116 bytes [Jan 12 23:58:44] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 132 bytes [Jan 12 23:58:44] DEBUG[31182]: rtp.c:920 ast_rtcp_read: Got RTCP report of 48 bytes anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> INVITE sip:0123456789@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1 From: ;tag=134037335 To: "Alex blabla" ;tag=as07b3e2bf Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 1 INVITE Supported: timer Contact: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE Max-Forwards: 69 ontent-Type: application/sdp Content-Length: 235 v=0 o=- 19 1 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=image 16000 udptl t38 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 40]: INVITE sip:0123456789@172.16.16.111 SIP/2.0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 78]: Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 47]: From: ;tag=134037335 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 58]: To: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 14]: CSeq: 1 INVITE [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Supported: timer [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 59]: Contact: [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 69]: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 16]: Max-Forwards: 69 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 29]: Content-Type: application/sdp [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 19]: Content-Length: 235 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 12 [ 0]: [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 26]: o=- 19 1 IN IP4 172.16.16.155 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 13]: s=Cisco SDP 0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.155 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 23]: m=image 16000 udptl t38 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 9]: a=X-sqn:0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 28]: a=X-cap: 1 audio RTP/AVP 100 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 33]: a=X-cpar: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 28]: a=X-cpar: a=fmtp:100 200-202 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 26]: a=X-cap: 2 image udptl t38 --- (12 headers 11 lines) --- [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:1706 parse_sip_options: Begin: parsing SIP "Supported: timer" [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:1714 parse_sip_options: Found SIP option: -timer- [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:1720 parse_sip_options: Matched SIP option: timer Sending to 172.16.16.155 : 5060 (no NAT) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:13420 handle_request_invite: Initializing initreq for method INVITE - callid 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Got T.38 offer in SDP in dialog 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4882 process_sdp: T38 state changed to 4 on channel SIP/172.16.16.155-081bb160 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4932 process_sdp: Peer T.38 UDPTL is at port 172.16.16.155:16000 Got T.38 Re-invite without audio. Keeping RTP active during T.38 session. Callid 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:5159 process_sdp: Our T38 capability = (3856), peer T38 capability (0), joint T38 capability (3856) Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x0 (nothing)/video=0x0 (nothing), combined - 0x0 (nothing) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:5201 process_sdp: Have T.38 but no audio codecs, accepting offer anyway [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:13554 handle_request_invite: Got a SIP re-invite for call 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:13649 handle_request_invite: SIP/172.16.16.155-081bb160: This call is UP.... [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:16829 sip_handle_t38_reinvite: Sending reinvite on SIP '7025662c-5d5e0b1a@192.168.20.12' - It's UDPTL soon redirected to us (IP 172.16.16.111) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:5681 reqprep: Strict routing enforced for session 7025662c-5d5e0b1a@192.168.20.12 set_destination: Parsing for address/port to send to set_destination: set destination to 10.10.10.23, port 8634 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:6081 add_t38_sdp: T.38 UDPTL is at 172.16.16.111 port 4245 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:6087 add_t38_sdp: Our T38 capability (3856), peer T38 capability (3856), joint capability (3856) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:6014 t38_get_rate: T38MaxFaxRate 9600 found [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:1644 initialize_initreq: Initializing already initialized SIP dialog 7025662c-5d5e0b1a@192.168.20.12 (presumably reinvite) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 45]: INVITE sip:123456789@10.10.10.23:8634 SIP/2.0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 61]: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK045fc8f4;rport [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 48]: From: ;tag=as2ccf40c0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 57]: To: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 36]: Contact: [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 26]: User-Agent: blabla.ch 1.4PBX [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 19]: Supported: replaces [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 12 [ 29]: Content-Type: application/sdp [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 13 [ 19]: Content-Length: 338 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 14 [ 0]: [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 36]: o=root 31219 31221 IN IP4 172.16.16.111 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 9]: s=session [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.111 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 22]: m=image 4245 udptl t38 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 17]: a=T38FaxVersion:0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 20]: a=T38MaxBitRate:9600 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 24]: a=T38FaxFillBitRemoval:0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 24]: a=T38FaxTranscodingMMR:0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 25]: a=T38FaxTranscodingJBIG:0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 11 [ 37]: a=T38FaxRateManagement:transferredTCF [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 12 [ 21]: a=T38FaxMaxBuffer:400 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 13 [ 23]: a=T38FaxMaxDatagram:400 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 14 [ 23]: a=T38FaxUdpEC:t38UDPFEC Reliably Transmitting (NAT) to 10.10.10.23:8632: INVITE sip:123456789@10.10.10.23:8634 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK045fc8f4;rport Max-Forwards: 70 From: ;tag=as2ccf40c0 To: alx ;tag=e864fb04f6cbbc22o0 Contact: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces X-asterisk-Info: SIP re-invite (External RTP bridge) Content-Type: application/sdp Content-Length: 338 v=0 o=root 31219 31221 IN IP4 172.16.16.111 s=session c=IN IP4 172.16.16.111 t=0 0 m=image 4245 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval:0 a=T38FaxTranscodingMMR:0 a=T38FaxTranscodingJBIG:0 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:400 a=T38FaxMaxDatagram:400 a=T38FaxUdpEC:t38UDPFEC --- [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:1996 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #32 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> SIP/2.0 488 Not Acceptable Here To: alx ;tag=e864fb04f6cbbc22o0 From: ;tag=as2ccf40c0 Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 INVITE Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK045fc8f4 Contact: alx Warning: 304 spa "Media type not available" Server: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 31]: SIP/2.0 488 Not Acceptable Here [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: To: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 48]: From: ;tag=as2ccf40c0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 55]: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK045fc8f4 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 45]: Contact: alx [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 43]: Warning: 304 spa "Media type not available" [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 29]: Server: Linksys/SPA2102-3.3.6 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:2090 __sip_ack: Acked pending invite 102 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:2095 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #32 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '7025662c-5d5e0b1a@192.168.20.12' of Request 102: Match Found [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:11735 handle_response_invite: SIP response 488 to RE-invite on outgoing call 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:5681 reqprep: Strict routing enforced for session 7025662c-5d5e0b1a@192.168.20.12 set_destination: Parsing for address/port to send to set_destination: set destination to 10.10.10.23, port 8634 Transmitting (NAT) to 10.10.10.23:8632: ACK sip:123456789@10.10.10.23:8634 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK045fc8f4;rport Max-Forwards: 70 From: ;tag=as2ccf40c0 To: alx ;tag=e864fb04f6cbbc22o0 Contact: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 102 ACK User-Agent: blabla.ch 1.4PBX Content-Length: 0 --- [Jan 12 23:58:44] ERROR[31182]: chan_sip.c:11981 handle_response_invite: Got error on T.38 re-invite. Bad configuration. Peer needs to have T.38 disabled. [Jan 12 23:58:44] DEBUG[31182]: rtp.c:3151 bridge_p2p_loop: p2p-rtp-bridge: Got a FRAME_CONTROL (8) frame on channel SIP/123456789-081b6b98 [Jan 12 23:58:44] DEBUG[31182]: channel.c:3942 ast_channel_bridge: Returning from native bridge, channels: SIP/123456789-081b6b98, SIP/172.16.16.155-081bb160 [Jan 12 23:58:44] DEBUG[31182]: channel.c:1592 ast_hangup: Hanging up channel 'SIP/172.16.16.155-081bb160' [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:3391 sip_hangup: Hangup call SIP/172.16.16.155-081bb160, SIP callid 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:3399 sip_hangup: update_call_counter(0123123123) - decrement call limit counter on hangup [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:3093 update_call_counter: Updating call counter for incoming call Scheduling destruction of SIP dialog '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' in 32000 ms (Method: INVITE) [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:387 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/172.16.16.155-081bb160 [Jan 12 23:58:44] DEBUG[31182]: rtp.c:1537 ast_rtp_early_bridge: Channel '' has no RTP, not doing anything [Jan 12 23:58:44] DEBUG[31182]: app_dial.c:1682 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Jan 12 23:58:44] DEBUG[31182]: pbx.c:2289 __ast_pbx_run: Spawn extension (privileged,0123123123,1) exited non-zero on 'SIP/123456789-081b6b98' == Spawn extension (privileged, 0123123123, 1) exited non-zero on 'SIP/123456789-081b6b98' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '"Alex blabla" <0123456789>' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '0123456789' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '0123123123' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'privileged' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'SIP/123456789-081b6b98' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'SIP/172.16.16.155-081bb160' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'Dial' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'SIP/0123123123@172.16.16.155' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-01-12 23:58:33' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-01-12 23:58:36' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-01-12 23:58:44' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '11' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '8' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'ANSWERED' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '1168642713.2' [Jan 12 23:58:44] DEBUG[31182]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '' [Jan 12 23:58:44] DEBUG[31182]: channel.c:1592 ast_hangup: Hanging up channel 'SIP/123456789-081b6b98' [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:3391 sip_hangup: Hangup call SIP/123456789-081b6b98, SIP callid 7025662c-5d5e0b1a@192.168.20.12) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:3399 sip_hangup: update_call_counter(123456789) - decrement call limit counter on hangup [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:3093 update_call_counter: Updating call counter for outgoing call Scheduling destruction of SIP dialog '7025662c-5d5e0b1a@192.168.20.12' in 32000 ms (Method: ACK) [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:5681 reqprep: Strict routing enforced for session 7025662c-5d5e0b1a@192.168.20.12 set_destination: Parsing for address/port to send to set_destination: set destination to 10.10.10.23, port 8634 Reliably Transmitting (NAT) to 10.10.10.23:8632: BYE sip:123456789@10.10.10.23:8634 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK06b23f29;rport Max-Forwards: 70 From: ;tag=as2ccf40c0 To: alx ;tag=e864fb04f6cbbc22o0 Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 103 BYE User-Agent: blabla.ch 1.4PBX Content-Length: 0 --- [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:1996 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #35 [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:387 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/123456789-081b6b98 [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 172.16.16.155 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 172.16.16.155 [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:371 do_state_change: Changing state for SIP/172.16.16.155 - state 1 (Not in use) [Jan 12 23:58:44] DEBUG[31182]: app_queue.c:568 changethread: Device 'SIP/172.16.16.155' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 123456789 [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:371 do_state_change: Changing state for SIP/123456789 - state 1 (Not in use) [Jan 12 23:58:44] DEBUG[31182]: app_queue.c:568 changethread: Device 'SIP/123456789' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 12 23:58:44] DEBUG[31182]: devicestate.c:245 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Jan 12 23:58:44] DEBUG[31182]: chan_sip.c:15325 sip_devicestate: Checking device state for peer 123456789 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> SIP/2.0 200 OK To: alx ;tag=e864fb04f6cbbc22o0 From: ;tag=as2ccf40c0 Call-ID: 7025662c-5d5e0b1a@192.168.20.12 CSeq: 103 BYE Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK06b23f29 Server: Linksys/SPA2102-3.3.6 P-RTP-Stat: PS=572,OS=91364,PR=499,OR=79528,PL=58542,JI=1,LA=536,DU=8,EN=G711a,DE=G711a Content-Length: 0 <-------------> [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: To: alx ;tag=e864fb04f6cbbc22o0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 48]: From: ;tag=as2ccf40c0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 40]: Call-ID: 7025662c-5d5e0b1a@192.168.20.12 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 13]: CSeq: 103 BYE [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 55]: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK06b23f29 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 29]: Server: Linksys/SPA2102-3.3.6 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 87]: P-RTP-Stat: PS=572,OS=91364,PR=499,OR=79528,PL=58542,JI=1,LA=536,DU=8,EN=G711a,DE=G711a [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 17]: Content-Length: 0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 0]: --- (9 headers 0 lines) --- [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4433 find_call: = No match Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 7025662c-5d5e0b1a@192.168.20.12 Their Tag e864fb04f6cbbc22o0 Our tag: as2ccf40c0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:2095 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #35 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '7025662c-5d5e0b1a@192.168.20.12' of Request 103: Match Found Really destroying SIP dialog '7025662c-5d5e0b1a@192.168.20.12' Method: ACK anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> INVITE sip:0123456789@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1 From: ;tag=134037335 To: "Alex blabla" ;tag=as07b3e2bf Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 1 INVITE Supported: timer Contact: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE Max-Forwards: 69 ontent-Type: application/sdp Content-Length: 235 v=0 o=- 19 1 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=image 16000 udptl t38 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 40]: INVITE sip:0123456789@172.16.16.111 SIP/2.0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 78]: Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 47]: From: ;tag=134037335 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 58]: To: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 14]: CSeq: 1 INVITE [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Supported: timer [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 59]: Contact: [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 69]: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 16]: Max-Forwards: 69 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 29]: Content-Type: application/sdp [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 11 [ 19]: Content-Length: 235 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 12 [ 0]: [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 0 [ 3]: v=0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 1 [ 26]: o=- 19 1 IN IP4 172.16.16.155 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 2 [ 13]: s=Cisco SDP 0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 3 [ 19]: c=IN IP4 172.16.16.155 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 4 [ 5]: t=0 0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 5 [ 23]: m=image 16000 udptl t38 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 6 [ 9]: a=X-sqn:0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 7 [ 28]: a=X-cap: 1 audio RTP/AVP 100 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 8 [ 33]: a=X-cpar: a=rtpmap:100 X-NSE/8000 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 9 [ 28]: a=X-cpar: a=fmtp:100 200-202 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Body 10 [ 26]: a=X-cap: 2 image udptl t38 --- (12 headers 11 lines) --- [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:14726 handle_request: Ignoring SIP message because of retransmit (INVITE Seqno 1, ours 1) Ignoring this INVITE request [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:13556 handle_request_invite: Got a SIP re-transmit of INVITE for call 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:45] NOTICE[31182]: chan_sip.c:13746 handle_request_invite: Unable to create/find SIP channel for this INVITE <--- Transmitting (no NAT) to 172.16.16.155:5060 ---> SIP/2.0 503 Unavailable Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1;received=172.16.16.155 From: ;tag=134037335 To: "Alex blabla" ;tag=as07b3e2bf Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 1 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> Scheduling destruction of SIP dialog '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' in 32000 ms (Method: INVITE) anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> ACK sip:0123456789@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1 CSeq: 1 ACK From: ;tag=134037335 To: "Alex blabla" ;tag=as07b3e2bf Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Max-Forwards: 69 Content-Length: 0 <-------------> [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 37]: ACK sip:0123456789@172.16.16.111 SIP/2.0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 78]: Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bKhfom6g004o30hb8ie4k1sb0000g00.1 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 11]: CSeq: 1 ACK [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 47]: From: ;tag=134037335 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 58]: To: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 69 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Content-Length: 0 [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 0]: --- (8 headers 0 lines) --- [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:14709 handle_request: **** Received ACK (6) - Command in SIP ACK [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' of Response 1: Match Not Found [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:5681 reqprep: Strict routing enforced for session 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 set_destination: Parsing for address/port to send to set_destination: set destination to 172.16.16.155, port 5060 Reliably Transmitting (no NAT) to 172.16.16.155:5060: BYE sip:0123123123@172.16.16.155:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK60c6f156;rport Max-Forwards: 70 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 103 BYE User-Agent: blabla.ch 1.4PBX Content-Length: 0 --- [Jan 12 23:58:45] DEBUG[31182]: chan_sip.c:1996 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #37 Scheduling destruction of SIP dialog '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' in 32000 ms (Method: ACK) [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:1892 retrans_pkt: SIP TIMER: Rescheduling retransmission #37 (1) BYE - 8 [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:1906 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #37)) Retransmitting #1 (no NAT) to 172.16.16.155:5060: BYE sip:0123123123@172.16.16.155:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK60c6f156;rport Max-Forwards: 70 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 103 BYE User-Agent: blabla.ch 1.4PBX Content-Length: 0 --- anarki*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK60c6f156;rport=5060 From: "Alex blabla" ;tag=as07b3e2bf To: ;tag=134037335 Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 CSeq: 103 BYE Content-Length: 0 <-------------> [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 14]: SIP/2.0 200 Ok [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 86]: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK60c6f156;rport=5060 [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 60]: From: "Alex blabla" ;tag=as07b3e2bf [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 45]: To: ;tag=134037335 [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 52]: Call-ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 13]: CSeq: 103 BYE [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 17]: Content-Length: 0 [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 0]: --- (7 headers 0 lines) --- [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:4433 find_call: = Found Their Call ID: 0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111 Their Tag 134037335 Our tag: as07b3e2bf [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:2095 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #37 [Jan 12 23:58:46] DEBUG[31182]: chan_sip.c:2106 __sip_ack: Stopping retransmission on '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' of Request 103: Match Found SIP Response message for INCOMING dialog BYE arrived Really destroying SIP dialog '0ee5c5f5216dbe9d222104a04e55de75@172.16.16.111' Method: ACK anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ca5a726f From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35001 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ca5a726f [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35001 NOTIFY [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-ca5a726f;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as20ea2698 Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35001 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:47] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-c1b53ad9 From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 35000 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-c1b53ad9 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35000 NOTIFY [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- anarki*CLI> <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-c1b53ad9;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as1ba61055 Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 35000 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:58:51] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-35e601f6 From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35002 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-35e601f6 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35002 NOTIFY [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- anarki*CLI> <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-35e601f6;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as1f794698 Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35002 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:59:02] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-1d041edc From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 35001 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-1d041edc [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35001 NOTIFY [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- anarki*CLI> <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-1d041edc;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as3543727d Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 35001 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:59:06] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-41473bad From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35003 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-41473bad [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35003 NOTIFY [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-41473bad;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as51e7d472 Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35003 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:59:17] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8638 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-7595de6f From: fax ;tag=b23acf09d7790d15o1 To: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 35002 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-7595de6f [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: fax ;tag=b23acf09d7790d15o1 [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 73a58db2-3c24ba3f@192.168.20.12 [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35002 NOTIFY [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8638 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8639;branch=z9hG4bK-7595de6f;received=10.10.10.23 From: fax ;tag=b23acf09d7790d15o1 To: ;tag=as01253b9b Call-ID: 73a58db2-3c24ba3f@192.168.20.12 CSeq: 35002 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:59:21] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI> <--- SIP read from 10.10.10.23:8632 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-7bd0cf15 From: alx ;tag=3de574a99405a3f5o0 To: Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35004 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 0 [ 29]: NOTIFY sip:172.16.16.111 SIP/2.0 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-7bd0cf15 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 2 [ 59]: From: alx ;tag=3de574a99405a3f5o0 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 3 [ 20]: To: [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 4 [ 40]: Call-ID: 7192acee-b2b9c433@192.168.20.12 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 5 [ 18]: CSeq: 35004 NOTIFY [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 7 [ 17]: Event: keep-alive [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 8 [ 33]: User-Agent: Linksys/SPA2102-3.3.6 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 9 [ 17]: Content-Length: 0 [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:4647 parse_request: Header 10 [ 0]: --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:8632 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:8634;branch=z9hG4bK-7bd0cf15;received=10.10.10.23 From: alx ;tag=3de574a99405a3f5o0 To: ;tag=as7729966d Call-ID: 7192acee-b2b9c433@192.168.20.12 CSeq: 35004 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Jan 12 23:59:32] DEBUG[31182]: chan_sip.c:14888 sipsock_read: Invalid SIP message - rejected , no callid, len 328 anarki*CLI>