Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (8 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (8 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 103 Cmd SIP/2.0 Feb 17 09:04:59 VERBOSE[3171] logger.c: Destroying call '0b1ecd8702b12f240cca740b1407da21@192.168.4.2' Feb 17 09:04:59 DEBUG[7107] manager.c: Manager received command 'Logoff' Feb 17 09:04:59 VERBOSE[7107] logger.c: == Manager 'admin' logged off from 127.0.0.1 Feb 17 09:04:59 VERBOSE[7076] logger.c: dialparties.agi: Extension 119 is available...skipping checks Feb 17 09:04:59 VERBOSE[7076] logger.c: -- dialparties.agi: DbSet CALLTRACE/119 to 0625110000 Feb 17 09:04:59 VERBOSE[7076] logger.c: -- AGI Script dialparties.agi completed, returning 0 Feb 17 09:04:59 VERBOSE[7076] logger.c: -- Executing Dial("Local/164@from-internal-e1cb,2", "SIP/119|45|wrt") in new stack Feb 17 09:04:59 DEBUG[7076] chan_sip.c: Setting NAT on RTP to 0 Feb 17 09:04:59 DEBUG[7076] chan_sip.c: Outgoing Call for 119 Feb 17 09:04:59 VERBOSE[7076] logger.c: We're at 192.168.4.2 port 12412 Feb 17 09:04:59 VERBOSE[7076] logger.c: Adding codec 0x8 (alaw) to SDP Feb 17 09:04:59 VERBOSE[7076] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Feb 17 09:04:59 VERBOSE[7076] logger.c: 13 headers, 10 lines Feb 17 09:04:59 VERBOSE[7076] logger.c: Reliably Transmitting (no NAT) to 192.168.4.119:5060: INVITE sip:119@192.168.4.119:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7699d008 From: "T:0625110000" ;tag=as4b3cc02a To: Contact: Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:04:59 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 212 v=0 o=root 3115 3115 IN IP4 192.168.4.2 s=session c=IN IP4 192.168.4.2 t=0 0 m=audio 12412 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Feb 17 09:04:59 VERBOSE[7076] logger.c: -- Called 119 Feb 17 09:04:59 DEBUG[7025] channel.c: Got a FRAME_CONTROL (3) frame on channel Local/164@from-internal-e1cb,1 Feb 17 09:04:59 DEBUG[7025] channel.c: Bridge stops bridging channels mISDN/8-1 and Local/164@from-internal-e1cb,1 Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5060: SIP/2.0 100 Trying To: From: "T:0625110000" ;tag=as4b3cc02a Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7699d008 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (8 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (8 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '39c79f0567172676596d05e91848884d@192.168.4.2' Request 102: Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0e1797db From: "T:0625110000" ;tag=as5fe83790 To: ;tag=hgpagdkdfs Call-ID: 0b1ecd8702b12f240cca740b1407da21@192.168.4.2 CSeq: 104 BYE Contact: ;flow-id=1 User-Agent: snom360/5.3 Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (9 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (9 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 104 Cmd SIP/2.0 Feb 17 09:04:59 VERBOSE[3171] logger.c: Destroying call '0b1ecd8702b12f240cca740b1407da21@192.168.4.2' Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0e1797db From: "T:0625110000" ;tag=as5fe83790 To: ;tag=hgpagdkdfs Call-ID: 0b1ecd8702b12f240cca740b1407da21@192.168.4.2 CSeq: 104 BYE Contact: ;flow-id=1 User-Agent: snom360/5.3 Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (9 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (9 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 104 Cmd SIP/2.0 Feb 17 09:04:59 VERBOSE[3171] logger.c: Destroying call '0b1ecd8702b12f240cca740b1407da21@192.168.4.2' Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5060: SIP/2.0 180 Ringing To: ;tag=4aba29b95b3f577fi0 From: "T:0625110000" ;tag=as4b3cc02a Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7699d008 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (8 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (8 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '39c79f0567172676596d05e91848884d@192.168.4.2' Request 102: Found Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.165:2078: NOTIFY sip:165@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK24a8d778 From: ;tag=as7f27b1c1 To: ;tag=gbqrps3tvz Contact: Call-ID: 3c267009c832-mpe9rsnvjgaf@snom360-000413231F0E CSeq: 164 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 220 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.160:2060: NOTIFY sip:160@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK37329e89 From: ;tag=as33e98a6c To: ;tag=nw323x4db2 Contact: Call-ID: 3c267009dbba-xm8frwr2wo6n@snom360-0004132328CA CSeq: 219 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.161:2060: NOTIFY sip:161@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK427a4696 From: ;tag=as4cc6a867 To: ;tag=j58v0vleq9 Contact: Call-ID: 3c26700a1d4c-e8wlriu7nizt@snom360-0004132328B1 CSeq: 219 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.169:2063: NOTIFY sip:169@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5b482afa From: ;tag=as096edcdf To: ;tag=yoxyugy148 Contact: Call-ID: 3c267009c832-ek4dwbswi2xn@snom360-000413232742 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.168:2060: NOTIFY sip:168@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6e2ac95d From: ;tag=as798ae201 To: ;tag=qcyv94r5dr Contact: Call-ID: 3c267009c350-95gzsegzdles@snom360-0004132324D2 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.163:2063: NOTIFY sip:163@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK2ba09010 From: ;tag=as1d1f6788 To: ;tag=6kfevg1cd5 Contact: Call-ID: 3c267009b98c-q0sj9l9cfsny@snom360-0004132328D5 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.166:2057: NOTIFY sip:166@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK2ad0323e From: ;tag=as2d73266d To: ;tag=h56mu3bfqt Contact: Call-ID: 3c267009de2b-y389eo0v2nf4@snom360-0004132324D7 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.167:2063: NOTIFY sip:167@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK73ec1a50 From: ;tag=as6c9ad12e To: ;tag=71f9j3grny Contact: Call-ID: 3c267009ea60-jt01a2qbf0jl@snom360-000413231F12 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.164:2063: NOTIFY sip:164@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3419c5f5 From: ;tag=as1852ebb2 To: ;tag=b0j3umo164 Contact: Call-ID: 3c267009b98c-muikz16vcltg@snom360-00041323230D CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.162:2057: NOTIFY sip:162@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK053a106e From: ;tag=as7985f89b To: ;tag=y7o72q79a7 Contact: Call-ID: 3c267009b4aa-tgo333op0muz@snom360-000413232740 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 221 early --- Feb 17 09:04:59 VERBOSE[7076] logger.c: -- SIP/119-6897 is ringing Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.165:2078: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK24a8d778 From: ;tag=as7f27b1c1 To: ;tag=gbqrps3tvz Call-ID: 3c267009c832-mpe9rsnvjgaf@snom360-000413231F0E CSeq: 164 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c832-mpe9rsnvjgaf@snom360-000413231F0E' of Request 164: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK053a106e From: ;tag=as7985f89b To: ;tag=y7o72q79a7 Call-ID: 3c267009b4aa-tgo333op0muz@snom360-000413232740 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b4aa-tgo333op0muz@snom360-000413232740' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.160:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK37329e89 From: ;tag=as33e98a6c To: ;tag=nw323x4db2 Call-ID: 3c267009dbba-xm8frwr2wo6n@snom360-0004132328CA CSeq: 219 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009dbba-xm8frwr2wo6n@snom360-0004132328CA' of Request 219: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK2ad0323e From: ;tag=as2d73266d To: ;tag=h56mu3bfqt Call-ID: 3c267009de2b-y389eo0v2nf4@snom360-0004132324D7 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009de2b-y389eo0v2nf4@snom360-0004132324D7' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK73ec1a50 From: ;tag=as6c9ad12e To: ;tag=71f9j3grny Call-ID: 3c267009ea60-jt01a2qbf0jl@snom360-000413231F12 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009ea60-jt01a2qbf0jl@snom360-000413231F12' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.169:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5b482afa From: ;tag=as096edcdf To: ;tag=yoxyugy148 Call-ID: 3c267009c832-ek4dwbswi2xn@snom360-000413232742 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c832-ek4dwbswi2xn@snom360-000413232742' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK2ba09010 From: ;tag=as1d1f6788 To: ;tag=6kfevg1cd5 Call-ID: 3c267009b98c-q0sj9l9cfsny@snom360-0004132328D5 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b98c-q0sj9l9cfsny@snom360-0004132328D5' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.161:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK427a4696 From: ;tag=as4cc6a867 To: ;tag=j58v0vleq9 Call-ID: 3c26700a1d4c-e8wlriu7nizt@snom360-0004132328B1 CSeq: 219 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c26700a1d4c-e8wlriu7nizt@snom360-0004132328B1' of Request 219: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.168:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6e2ac95d From: ;tag=as798ae201 To: ;tag=qcyv94r5dr Call-ID: 3c267009c350-95gzsegzdles@snom360-0004132324D2 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c350-95gzsegzdles@snom360-0004132324D2' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3419c5f5 From: ;tag=as1852ebb2 To: ;tag=b0j3umo164 Call-ID: 3c267009b98c-muikz16vcltg@snom360-00041323230D CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:04:59 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b98c-muikz16vcltg@snom360-00041323230D' of Request 220: Match Found Feb 17 09:04:59 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.165:2078: SUBSCRIBE sip:*97@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.165:2078;branch=z9hG4bK-ebltrhdrc17k;rport From: ;tag=6ofyuw46w9 To: Call-ID: 3c26700a0c35-suvk6qhu2h8u@snom360-000413231F0E CSeq: 210 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: message-summary Accept: application/simple-message-summary Expires: 3600 Content-Length: 0 Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:04:59 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:04:59 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:04:59 VERBOSE[3171] logger.c: Sending to 192.168.4.165 : 2078 (NAT) Feb 17 09:04:59 VERBOSE[3171] logger.c: Found peer '165' Feb 17 09:04:59 VERBOSE[3171] logger.c: Looking for *97 in from-internal (domain 192.168.4.2) Feb 17 09:04:59 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.165:2078: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.4.165:2078;branch=z9hG4bK-ebltrhdrc17k;rport;received=192.168.4.165 From: ;tag=6ofyuw46w9 To: ;tag=as3ec312c5 Call-ID: 3c26700a0c35-suvk6qhu2h8u@snom360-000413231F0E CSeq: 210 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:04:59 VERBOSE[3171] logger.c: Destroying call '3c26700a0c35-suvk6qhu2h8u@snom360-000413231F0E' Feb 17 09:05:01 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:01 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.164:2063: OPTIONS sip:164@192.168.4.164:2063;line=ftnnhb74 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK1a74f616;rport From: "Unknown" ;tag=as3a6545df To: Contact: Call-ID: 48984bc22b2f41b11dfb12081a616139@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:01 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:01 NOTICE[3171] chan_sip.c: -- Registration for 'frn6/martin.hetzner@freenet.de' timed out, trying again (Attempt #4202) Feb 17 09:05:01 DEBUG[3171] chan_sip.c: Stopping retransmission on '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' of Request 4300: Match Found Feb 17 09:05:01 DEBUG[3171] chan_sip.c: Scheduled a registration timeout for freenet.de id #183535 Feb 17 09:05:01 VERBOSE[3171] logger.c: REGISTER 12 headers, 0 lines Feb 17 09:05:01 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:01 VERBOSE[3171] logger.c: Destroying call '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' Feb 17 09:05:01 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK1a74f616;rport=5060 From: "Unknown" ;tag=as3a6545df To: Call-ID: 48984bc22b2f41b11dfb12081a616139@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:01 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:01 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:01 DEBUG[3171] chan_sip.c: Stopping retransmission on '48984bc22b2f41b11dfb12081a616139@192.168.4.2' of Request 102: Match Found Feb 17 09:05:01 VERBOSE[3171] logger.c: Destroying call '48984bc22b2f41b11dfb12081a616139@192.168.4.2' Feb 17 09:05:02 VERBOSE[3171] logger.c: Retransmitting #1 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:03 VERBOSE[3171] logger.c: Retransmitting #2 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:05 VERBOSE[3171] logger.c: Retransmitting #3 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:08 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:08 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.168:2060: OPTIONS sip:168@192.168.4.168:2060;line=elvkhfuk SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4455db44;rport From: "Unknown" ;tag=as144a0c77 To: Contact: Call-ID: 55093aaa3def64143aa4a0455d61e902@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:08 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:08 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.168:2060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4455db44;rport=5060 From: "Unknown" ;tag=as144a0c77 To: Call-ID: 55093aaa3def64143aa4a0455d61e902@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:08 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:08 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:08 DEBUG[3171] chan_sip.c: Stopping retransmission on '55093aaa3def64143aa4a0455d61e902@192.168.4.2' of Request 102: Match Found Feb 17 09:05:08 VERBOSE[3171] logger.c: Destroying call '55093aaa3def64143aa4a0455d61e902@192.168.4.2' Feb 17 09:05:09 VERBOSE[3171] logger.c: Retransmitting #4 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:10 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SUBSCRIBE sip:091132391810@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-5ybqytm90fio;rport From: ;tag=hg7vb2yp8i To: Call-ID: 3c267009a393-dmwvz7lxtmn4@snom360-000413232740 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:10 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:10 VERBOSE[3171] logger.c: Sending to 192.168.4.162 : 2057 (NAT) Feb 17 09:05:10 VERBOSE[3171] logger.c: Found user '162' Feb 17 09:05:10 VERBOSE[3171] logger.c: Looking for 091132391810 in from-internal (domain 192.168.4.2) Feb 17 09:05:10 DEBUG[3171] chan_sip.c: Adding subscription for extension 091132391810 context from-internal for peer 162 Feb 17 09:05:10 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a393-dmwvz7lxtmn4@snom360-000413232740' in 3610000 ms Feb 17 09:05:10 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091132391810 in context from-internal Feb 17 09:05:10 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.162:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-5ybqytm90fio;rport;received=192.168.4.162 From: ;tag=hg7vb2yp8i To: ;tag=as0b47d62d Call-ID: 3c267009a393-dmwvz7lxtmn4@snom360-000413232740 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:10 VERBOSE[3171] logger.c: Destroying call '3c267009a393-dmwvz7lxtmn4@snom360-000413232740' Feb 17 09:05:10 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SUBSCRIBE sip:09112342260@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-xmz6x0ybz8jv;rport From: ;tag=ezrt5fbw11 To: Call-ID: 3c267009a393-6r0b9dxghn4d@snom360-000413232740 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:10 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:10 VERBOSE[3171] logger.c: Sending to 192.168.4.162 : 2057 (NAT) Feb 17 09:05:10 VERBOSE[3171] logger.c: Found user '162' Feb 17 09:05:10 VERBOSE[3171] logger.c: Looking for 09112342260 in from-internal (domain 192.168.4.2) Feb 17 09:05:10 DEBUG[3171] chan_sip.c: Adding subscription for extension 09112342260 context from-internal for peer 162 Feb 17 09:05:10 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a393-6r0b9dxghn4d@snom360-000413232740' in 3610000 ms Feb 17 09:05:10 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 09112342260 in context from-internal Feb 17 09:05:10 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.162:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-xmz6x0ybz8jv;rport;received=192.168.4.162 From: ;tag=ezrt5fbw11 To: ;tag=as54d862e1 Call-ID: 3c267009a393-6r0b9dxghn4d@snom360-000413232740 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:10 VERBOSE[3171] logger.c: Destroying call '3c267009a393-6r0b9dxghn4d@snom360-000413232740' Feb 17 09:05:10 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SUBSCRIBE sip:091123422621@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-bm0c45vto6jm;rport From: ;tag=pth06iiy4y To: Call-ID: 3c267009a604-xgjfw1mke4cx@snom360-000413232740 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:10 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:10 VERBOSE[3171] logger.c: Sending to 192.168.4.162 : 2057 (NAT) Feb 17 09:05:10 VERBOSE[3171] logger.c: Found user '162' Feb 17 09:05:10 VERBOSE[3171] logger.c: Looking for 091123422621 in from-internal (domain 192.168.4.2) Feb 17 09:05:10 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422621 context from-internal for peer 162 Feb 17 09:05:10 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a604-xgjfw1mke4cx@snom360-000413232740' in 3610000 ms Feb 17 09:05:10 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422621 in context from-internal Feb 17 09:05:10 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.162:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-bm0c45vto6jm;rport;received=192.168.4.162 From: ;tag=pth06iiy4y To: ;tag=as13785369 Call-ID: 3c267009a604-xgjfw1mke4cx@snom360-000413232740 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:10 VERBOSE[3171] logger.c: Destroying call '3c267009a604-xgjfw1mke4cx@snom360-000413232740' Feb 17 09:05:10 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SUBSCRIBE sip:091123422627@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-06wk5y82krs1;rport From: ;tag=7yrpcv4zjj To: Call-ID: 3c267009a604-vy8h36lfnvww@snom360-000413232740 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:10 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:10 VERBOSE[3171] logger.c: Sending to 192.168.4.162 : 2057 (NAT) Feb 17 09:05:10 VERBOSE[3171] logger.c: Found user '162' Feb 17 09:05:10 VERBOSE[3171] logger.c: Looking for 091123422627 in from-internal (domain 192.168.4.2) Feb 17 09:05:10 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422627 context from-internal for peer 162 Feb 17 09:05:10 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a604-vy8h36lfnvww@snom360-000413232740' in 3610000 ms Feb 17 09:05:10 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422627 in context from-internal Feb 17 09:05:10 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.162:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-06wk5y82krs1;rport;received=192.168.4.162 From: ;tag=7yrpcv4zjj To: ;tag=as1fc9d04e Call-ID: 3c267009a604-vy8h36lfnvww@snom360-000413232740 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:10 VERBOSE[3171] logger.c: Destroying call '3c267009a604-vy8h36lfnvww@snom360-000413232740' Feb 17 09:05:10 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SUBSCRIBE sip:*97@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-raxbi06dm6pa;rport From: ;tag=wdvmlkk2hi To: Call-ID: 3c267009ef42-ibhl0fj11s3r@snom360-000413232740 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: message-summary Accept: application/simple-message-summary Expires: 3600 Content-Length: 0 Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:10 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:10 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:10 VERBOSE[3171] logger.c: Sending to 192.168.4.162 : 2057 (NAT) Feb 17 09:05:10 VERBOSE[3171] logger.c: Found peer '162' Feb 17 09:05:10 VERBOSE[3171] logger.c: Looking for *97 in from-internal (domain 192.168.4.2) Feb 17 09:05:10 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.162:2057: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.4.162:2057;branch=z9hG4bK-raxbi06dm6pa;rport;received=192.168.4.162 From: ;tag=wdvmlkk2hi To: ;tag=as31c2c967 Call-ID: 3c267009ef42-ibhl0fj11s3r@snom360-000413232740 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:10 VERBOSE[3171] logger.c: Destroying call '3c267009ef42-ibhl0fj11s3r@snom360-000413232740' Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.161:2060: BYE sip:091125508811@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.161:2060;branch=z9hG4bK-s9rmtqvy8hkg;rport From: "Waltraud Pfahler" ;tag=qy3o7dq46h To: ;tag=as66932ba7 Call-ID: 3c27b76509c4-ryd8xmgu2cfg@snom360-0004132328B1 CSeq: 2 BYE Max-Forwards: 70 Contact: ;flow-id=1 User-Agent: snom360/5.3 RTP-RxStat: Total_Rx_Pkts=3296,Rx_Pkts=3296,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=3291,Tx_Pkts=3291,Remote_Tx_Pkts=0 Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:11 VERBOSE[3171] logger.c: Sending to 192.168.4.161 : 2060 (NAT) Feb 17 09:05:11 VERBOSE[3171] logger.c: Transmitting (NAT) to 192.168.4.161:2060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.161:2060;branch=z9hG4bK-s9rmtqvy8hkg;rport;received=192.168.4.161 From: "Waltraud Pfahler" ;tag=qy3o7dq46h To: ;tag=as66932ba7 Call-ID: 3c27b76509c4-ryd8xmgu2cfg@snom360-0004132328B1 CSeq: 2 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing --- Feb 17 09:05:11 DEBUG[6989] channel.c: Didn't get a frame from channel: SIP/161-8693 Feb 17 09:05:11 DEBUG[6989] channel.c: Bridge stops bridging channels SIP/161-8693 and mISDN/4-u128 Feb 17 09:05:11 DEBUG[6989] chan_misdn.c: misdn_hangup(mISDN/4-u128) Feb 17 09:05:11 DEBUG[6989] app_dial.c: Exiting with DIALSTATUS=ANSWER. Feb 17 09:05:11 VERBOSE[6989] logger.c: == Spawn extension (macro-lcr-dial, s, 7) exited non-zero on 'SIP/161-8693' in macro 'lcr-dial' Feb 17 09:05:11 VERBOSE[6989] logger.c: == Spawn extension (macro-dialout-trunk, s, 21) exited non-zero on 'SIP/161-8693' in macro 'dialout-trunk' Feb 17 09:05:11 VERBOSE[6989] logger.c: == Spawn extension (from-internal, 091125508811, 1) exited non-zero on 'SIP/161-8693' Feb 17 09:05:11 VERBOSE[6989] logger.c: -- Executing Macro("SIP/161-8693", "hangupcall") in new stack Feb 17 09:05:11 VERBOSE[6989] logger.c: -- Executing ResetCDR("SIP/161-8693", "w") in new stack Feb 17 09:05:11 DEBUG[6989] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 17 09:05:11 DEBUG[6989] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-02-17 09:04:03','09831610061','09831610061','091125508811','from-internal', 'SIP/161-8693','mISDN/4-u128','ResetCDR','w',68,59,'ANSWERED',3,'','1140163443.4019') Feb 17 09:05:11 VERBOSE[6989] logger.c: -- Executing NoCDR("SIP/161-8693", "") in new stack Feb 17 09:05:11 WARNING[6989] cdr.c: CDR on channel 'SIP/161-8693' not posted Feb 17 09:05:11 WARNING[6989] cdr.c: CDR on channel 'SIP/161-8693' lacks end Feb 17 09:05:11 VERBOSE[6989] logger.c: -- Executing DeadAGI("SIP/161-8693", "callback_internal.php.agi") in new stack Feb 17 09:05:11 VERBOSE[6989] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/callback_internal.php.agi Feb 17 09:05:11 DEBUG[7153] manager.c: Manager received command 'login' Feb 17 09:05:11 VERBOSE[7153] logger.c: == Parsing '/etc/asterisk/manager.conf': Feb 17 09:05:11 VERBOSE[7153] logger.c: == Parsing '/etc/asterisk/manager.conf': Found Feb 17 09:05:11 VERBOSE[7153] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Feb 17 09:05:11 VERBOSE[7153] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found Feb 17 09:05:11 WARNING[7153] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Feb 17 09:05:11 DEBUG[7153] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Feb 17 09:05:11 DEBUG[7153] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Feb 17 09:05:11 DEBUG[7153] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Feb 17 09:05:11 DEBUG[7153] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Feb 17 09:05:11 VERBOSE[7153] logger.c: == Manager 'admin' logged on from 127.0.0.1 Feb 17 09:05:11 DEBUG[7153] manager.c: Manager received command 'Logoff' Feb 17 09:05:11 VERBOSE[6989] logger.c: -- AGI Script callback_internal.php.agi completed, returning 0 Feb 17 09:05:11 VERBOSE[6989] logger.c: -- Executing Wait("SIP/161-8693", "5") in new stack Feb 17 09:05:11 VERBOSE[6989] logger.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/161-8693' in macro 'hangupcall' Feb 17 09:05:11 VERBOSE[6989] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/161-8693' Feb 17 09:05:11 DEBUG[6989] chan_sip.c: update_call_counter(161) - decrement call limit counter Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.165:2078: NOTIFY sip:165@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK08c18e2c From: ;tag=as2257567c To: ;tag=wrcz9yby2c Contact: Call-ID: 3c267009c5c1-jcryt9n2ttkr@snom360-000413231F0E CSeq: 142 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.160:2060: NOTIFY sip:160@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3788bf39 From: ;tag=as0fa533ef To: ;tag=l7jqzsz5c2 Contact: Call-ID: 3c267009d949-qwu6ehy5ocrf@snom360-0004132328CA CSeq: 182 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.161:2060: NOTIFY sip:161@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK601b2462 From: ;tag=as14ef3f0a To: ;tag=xrmoyfxfnq Contact: Call-ID: 3c26700a1d4c-hsgzjf7jmowl@snom360-0004132328B1 CSeq: 182 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.169:2063: NOTIFY sip:169@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5e651564 From: ;tag=as3a803576 To: ;tag=14dondkq30 Contact: Call-ID: 3c267009c5c1-3luww5zb7v07@snom360-000413232742 CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.168:2060: NOTIFY sip:168@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK23eab503 From: ;tag=as34fcdd8c To: ;tag=dzn16u5x6f Contact: Call-ID: 3c267009c350-ecn3vk63yg7e@snom360-0004132324D2 CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.163:2063: NOTIFY sip:163@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK44cba55d From: ;tag=as457c5e8a To: ;tag=vbiqt5sbk7 Contact: Call-ID: 3c267009b71b-lscg8vtpt62a@snom360-0004132328D5 CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.166:2057: NOTIFY sip:166@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK63bc0114 From: ;tag=as2b2291a7 To: ;tag=bsumwi5pw9 Contact: Call-ID: 3c267009de2b-tzvfcerbgqxt@snom360-0004132324D7 CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.167:2063: NOTIFY sip:167@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK2e4a50ae From: ;tag=as3b06d5f8 To: ;tag=acujtlm2or Contact: Call-ID: 3c267009e7ef-trwspjyh25ht@snom360-000413231F12 CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.164:2063: NOTIFY sip:164@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0a5e15a6 From: ;tag=as01edc778 To: ;tag=2ecevecd3l Contact: Call-ID: 3c267009b71b-liq52ky5y2cb@snom360-00041323230D CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.162:2057: NOTIFY sip:162@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK1f90fe68 From: ;tag=as7d88d5e0 To: ;tag=wpv0l99f20 Contact: Call-ID: 3c267009b4aa-m5aeza92flmd@snom360-000413232740 CSeq: 183 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 terminated --- Feb 17 09:05:11 DEBUG[7025] channel.c: Nobody there, continuing... Feb 17 09:05:11 VERBOSE[7153] logger.c: == Manager 'admin' logged off from 127.0.0.1 Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.169:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5e651564 From: ;tag=as3a803576 To: ;tag=14dondkq30 Call-ID: 3c267009c5c1-3luww5zb7v07@snom360-000413232742 CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c5c1-3luww5zb7v07@snom360-000413232742' of Request 183: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: Destroying call '3c27b76509c4-ryd8xmgu2cfg@snom360-0004132328B1' Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK44cba55d From: ;tag=as457c5e8a To: ;tag=vbiqt5sbk7 Call-ID: 3c267009b71b-lscg8vtpt62a@snom360-0004132328D5 CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b71b-lscg8vtpt62a@snom360-0004132328D5' of Request 183: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK2e4a50ae From: ;tag=as3b06d5f8 To: ;tag=acujtlm2or Call-ID: 3c267009e7ef-trwspjyh25ht@snom360-000413231F12 CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009e7ef-trwspjyh25ht@snom360-000413231F12' of Request 183: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.165:2078: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK08c18e2c From: ;tag=as2257567c To: ;tag=wrcz9yby2c Call-ID: 3c267009c5c1-jcryt9n2ttkr@snom360-000413231F0E CSeq: 142 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c5c1-jcryt9n2ttkr@snom360-000413231F0E' of Request 142: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.160:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3788bf39 From: ;tag=as0fa533ef To: ;tag=l7jqzsz5c2 Call-ID: 3c267009d949-qwu6ehy5ocrf@snom360-0004132328CA CSeq: 182 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009d949-qwu6ehy5ocrf@snom360-0004132328CA' of Request 182: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.168:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK23eab503 From: ;tag=as34fcdd8c To: ;tag=dzn16u5x6f Call-ID: 3c267009c350-ecn3vk63yg7e@snom360-0004132324D2 CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c350-ecn3vk63yg7e@snom360-0004132324D2' of Request 183: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.161:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK601b2462 From: ;tag=as14ef3f0a To: ;tag=xrmoyfxfnq Call-ID: 3c26700a1d4c-hsgzjf7jmowl@snom360-0004132328B1 CSeq: 182 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c26700a1d4c-hsgzjf7jmowl@snom360-0004132328B1' of Request 182: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK1f90fe68 From: ;tag=as7d88d5e0 To: ;tag=wpv0l99f20 Call-ID: 3c267009b4aa-m5aeza92flmd@snom360-000413232740 CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b4aa-m5aeza92flmd@snom360-000413232740' of Request 183: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0a5e15a6 From: ;tag=as01edc778 To: ;tag=2ecevecd3l Call-ID: 3c267009b71b-liq52ky5y2cb@snom360-00041323230D CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b71b-liq52ky5y2cb@snom360-00041323230D' of Request 183: Match Found Feb 17 09:05:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK63bc0114 From: ;tag=as2b2291a7 To: ;tag=bsumwi5pw9 Call-ID: 3c267009de2b-tzvfcerbgqxt@snom360-0004132324D7 CSeq: 183 NOTIFY Content-Length: 0 Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:11 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:11 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009de2b-tzvfcerbgqxt@snom360-0004132324D7' of Request 183: Match Found Feb 17 09:05:12 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:12 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.169:2063: OPTIONS sip:169@192.168.4.169:2063;line=9je1hkks SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0f6e5bc7;rport From: "Unknown" ;tag=as4711c9b3 To: Contact: Call-ID: 367e969516c34f7d626ce4192679145c@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:12 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:12 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.169:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0f6e5bc7;rport=5060 From: "Unknown" ;tag=as4711c9b3 To: Call-ID: 367e969516c34f7d626ce4192679145c@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:12 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:12 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:12 DEBUG[3171] chan_sip.c: Stopping retransmission on '367e969516c34f7d626ce4192679145c@192.168.4.2' of Request 102: Match Found Feb 17 09:05:12 VERBOSE[3171] logger.c: Destroying call '367e969516c34f7d626ce4192679145c@192.168.4.2' Feb 17 09:05:13 VERBOSE[3171] logger.c: Retransmitting #5 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:17 VERBOSE[3171] logger.c: Retransmitting #6 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK569f5aba;rport From: ;tag=as6ce221cc To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4301 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:21 NOTICE[3171] chan_sip.c: -- Registration for 'frn6/martin.hetzner@freenet.de' timed out, trying again (Attempt #4203) Feb 17 09:05:21 DEBUG[3171] chan_sip.c: Stopping retransmission on '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' of Request 4301: Match Found Feb 17 09:05:21 DEBUG[3171] chan_sip.c: Scheduled a registration timeout for freenet.de id #183558 Feb 17 09:05:21 VERBOSE[3171] logger.c: REGISTER 12 headers, 0 lines Feb 17 09:05:21 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:21 VERBOSE[3171] logger.c: Destroying call '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' Feb 17 09:05:21 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:21 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.165:2078: OPTIONS sip:165@192.168.4.165:2078;line=7aeindbh SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK25f00b9f;rport From: "Unknown" ;tag=as7c07a1ca To: Contact: Call-ID: 6d1d6c4d6d9cd39c4f84674d79dfa27e@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:21 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:21 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.165:2078: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK25f00b9f;rport=5060 From: "Unknown" ;tag=as7c07a1ca To: Call-ID: 6d1d6c4d6d9cd39c4f84674d79dfa27e@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:21 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:21 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:21 DEBUG[3171] chan_sip.c: Stopping retransmission on '6d1d6c4d6d9cd39c4f84674d79dfa27e@192.168.4.2' of Request 102: Match Found Feb 17 09:05:21 VERBOSE[3171] logger.c: Destroying call '6d1d6c4d6d9cd39c4f84674d79dfa27e@192.168.4.2' Feb 17 09:05:22 VERBOSE[3171] logger.c: Retransmitting #1 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:22 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:22 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.167:2063: OPTIONS sip:167@192.168.4.167:2063;line=6wj76hcw SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK54505f6c;rport From: "Unknown" ;tag=as0ba241f9 To: Contact: Call-ID: 726f20ca4890a2d65cdaaf4d46b0fc58@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:22 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:23 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK54505f6c;rport=5060 From: "Unknown" ;tag=as0ba241f9 To: Call-ID: 726f20ca4890a2d65cdaaf4d46b0fc58@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:23 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:23 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:23 DEBUG[3171] chan_sip.c: Stopping retransmission on '726f20ca4890a2d65cdaaf4d46b0fc58@192.168.4.2' of Request 102: Match Found Feb 17 09:05:23 VERBOSE[3171] logger.c: Destroying call '726f20ca4890a2d65cdaaf4d46b0fc58@192.168.4.2' Feb 17 09:05:23 VERBOSE[3171] logger.c: Retransmitting #2 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:25 VERBOSE[3171] logger.c: Retransmitting #3 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.115:5061: INVITE sip:05211439811@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-63e2bc8 From: First Call 2 ;tag=fcb186a56800ec0o1 To: Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 101 INVITE Max-Forwards: 70 Contact: First Call 2 Expires: 240 User-Agent: Sipura/SPA2002-3.1.5 Content-Length: 377 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 14006946 14006946 IN IP4 192.168.4.115 s=- c=IN IP4 192.168.4.115 t=0 0 m=audio 16450 RTP/AVP 8 0 2 96 97 98 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/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=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (14 headers 17 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (14 headers 17 lines)--- Feb 17 09:05:28 VERBOSE[3171] logger.c: Using INVITE request as basis request - ba5fec49-c18a456c@192.168.4.115 Feb 17 09:05:28 VERBOSE[3171] logger.c: Sending to 192.168.4.115 : 5061 (non-NAT) Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Setting NAT on RTP to 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.115:5061: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-63e2bc8;received=192.168.4.115 From: First Call 2 ;tag=fcb186a56800ec0o1 To: ;tag=as47e13d63 Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="72e06569" Content-Length: 0 --- Feb 17 09:05:28 VERBOSE[3171] logger.c: Scheduling destruction of call 'ba5fec49-c18a456c@192.168.4.115' in 15000 ms Feb 17 09:05:28 VERBOSE[3171] logger.c: Found user '116' Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.115:5061: ACK sip:05211439811@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-63e2bc8 From: First Call 2 ;tag=fcb186a56800ec0o1 To: ;tag=as47e13d63 Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 101 ACK Max-Forwards: 70 Contact: First Call 2 User-Agent: Sipura/SPA2002-3.1.5 Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on 'ba5fec49-c18a456c@192.168.4.115' of Response 101: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.115:5061: INVITE sip:05211439811@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165 From: First Call 2 ;tag=fcb186a56800ec0o1 To: Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="116",realm="asterisk",nonce="72e06569",uri="sip:05211439811@192.168.4.2",algorithm=MD5,response="e17111cd5f86e7732378736def24fd51" Contact: First Call 2 Expires: 240 User-Agent: Sipura/SPA2002-3.1.5 Content-Length: 377 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 14006946 14006946 IN IP4 192.168.4.115 s=- c=IN IP4 192.168.4.115 t=0 0 m=audio 16450 RTP/AVP 8 0 2 96 97 98 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/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=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (15 headers 17 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (15 headers 17 lines)--- Feb 17 09:05:28 VERBOSE[3171] logger.c: Using INVITE request as basis request - ba5fec49-c18a456c@192.168.4.115 Feb 17 09:05:28 VERBOSE[3171] logger.c: Sending to 192.168.4.115 : 5061 (non-NAT) Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Setting NAT on RTP to 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found user '116' Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 8 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 2 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 96 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 97 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 98 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 100 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found RTP audio format 101 Feb 17 09:05:28 VERBOSE[3171] logger.c: Peer audio RTP is at port 192.168.4.115:16450 Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Peer audio RTP is at port 192.168.4.115:16450 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format PCMA Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format PCMU Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format G726-32 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format G726-40 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format G726-24 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format G726-16 Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format NSE Feb 17 09:05:28 VERBOSE[3171] logger.c: Found description format telephone-event Feb 17 09:05:28 VERBOSE[3171] logger.c: Capabilities: us - 0x8 (alaw), peer - audio=0x41c (ulaw|alaw|g726|ilbc)/video=0x0 (nothing), combined - 0x8 (alaw) Feb 17 09:05:28 VERBOSE[3171] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Checking SIP call limits for device 116 Feb 17 09:05:28 VERBOSE[3171] logger.c: Looking for 05211439811 in from-internal (domain 192.168.4.2) Feb 17 09:05:28 DEBUG[3171] chan_sip.c: build_route: Contact hop: First Call 2 Feb 17 09:05:28 VERBOSE[3171] logger.c: list_route: hop: Feb 17 09:05:28 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.115:5061: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165;received=192.168.4.115 From: First Call 2 ;tag=fcb186a56800ec0o1 To: Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.165:2078: NOTIFY sip:165@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5d65d02e From: ;tag=as10a8a479 To: ;tag=i51xvh226v Contact: Call-ID: 3c267009c5c1-pm968bfxh49k@snom360-000413231F0E CSeq: 157 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.160:2060: NOTIFY sip:160@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK19f7108c From: ;tag=as3b0d316a To: ;tag=eyrx8v5st9 Contact: Call-ID: 3c267009d949-vnzf21gin77n@snom360-0004132328CA CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.161:2060: NOTIFY sip:161@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4953db7e From: ;tag=as7790c0c4 To: ;tag=5kicbws6tr Contact: Call-ID: 3c26700a1adb-ibwc6piqzng7@snom360-0004132328B1 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.169:2063: NOTIFY sip:169@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3dd6eab7 From: ;tag=as0f70f8a9 To: ;tag=k58e81mikg Contact: Call-ID: 3c267009c5c1-locpuytqrifi@snom360-000413232742 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.168:2060: NOTIFY sip:168@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK47a2df0b From: ;tag=as4c480732 To: ;tag=i3ct4x77r4 Contact: Call-ID: 3c267009c0df-1d6jh25idioe@snom360-0004132324D2 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.163:2063: NOTIFY sip:163@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6c0e950d From: ;tag=as121efef5 To: ;tag=p1194xfin2 Contact: Call-ID: 3c267009b71b-7gfpzjbgjcwy@snom360-0004132328D5 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.166:2057: NOTIFY sip:166@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK031ebf86 From: ;tag=as7c9fcc7e To: ;tag=3q1d6xupo3 Contact: Call-ID: 3c267009dbba-5z107i2tjceh@snom360-0004132324D7 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.167:2063: NOTIFY sip:167@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK489805c4 From: ;tag=as61dba6e0 To: ;tag=3j25n58hrn Contact: Call-ID: 3c267009e7ef-duzaqvfyn5sj@snom360-000413231F12 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.164:2063: NOTIFY sip:164@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK06ff8896 From: ;tag=as5d83c4dc To: ;tag=oroc65v1my Contact: Call-ID: 3c267009b71b-qlcdngk0a8ie@snom360-00041323230D CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.162:2057: NOTIFY sip:162@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5a225860 From: ;tag=as1c2a1228 To: ;tag=8j6snimxy9 Contact: Call-ID: 3c267009b239-9vgl2g8u0b34@snom360-000413232740 CSeq: 169 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "dialout-trunk|2|05211439811|") in new stack Feb 17 09:05:28 DEBUG[7155] pbx.c: Expression result is '1' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "1?3:2)") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Goto (macro-dialout-trunk,s,3) Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "user-callerid") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing DBget("SIP/116-d48a", "AMPUSER=DEVICE/116/user") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- DBget: varname=AMPUSER, family=DEVICE, key=116/user Feb 17 09:05:28 VERBOSE[7155] logger.c: -- DBget: set variable AMPUSER to 116 Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing DBget("SIP/116-d48a", "AMPUSERCIDNAME=AMPUSER/116/cidname") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=116/cidname Feb 17 09:05:28 VERBOSE[7155] logger.c: -- DBget: set variable AMPUSERCIDNAME to Susanne Albrecht Feb 17 09:05:28 DEBUG[7155] pbx.c: Expression result is '0' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "0?5") in new stack Feb 17 09:05:28 DEBUG[7155] pbx.c: Not taking any branch Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing SetCallerID("SIP/116-d48a", ""Susanne Albrecht" <116>") in new stack Feb 17 09:05:28 DEBUG[7155] pbx.c: Function result is 'Susanne Albrecht' Feb 17 09:05:28 DEBUG[7155] pbx.c: Expression result is '1' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "1?7") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Goto (macro-user-callerid,s,7) Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing NoOp("SIP/116-d48a", "Using CallerID "Susanne Albrecht" <116>") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "dnd-announce") in new stack Feb 17 09:05:28 DEBUG[7155] pbx.c: Expression result is '0' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "0?6") in new stack Feb 17 09:05:28 DEBUG[7155] pbx.c: Not taking any branch Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing Set("SIP/116-d48a", "DND_ANNOUNCE=YES") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing DBget("SIP/116-d48a", "DND_STATUS=DND/116") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- DBget: varname=DND_STATUS, family=DND, key=116 Feb 17 09:05:28 DEBUG[7155] db.c: Unable to find key '116' in family 'DND' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- DBget: Value not found in database. Feb 17 09:05:28 DEBUG[7155] pbx.c: Expression result is '0' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "0?5:6") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Goto (macro-dnd-announce,s,6) Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing NoOp("SIP/116-d48a", "s") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "record-enable|116|OUT") in new stack Feb 17 09:05:28 DEBUG[7155] pbx.c: Function result is '0' Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "0 > 0?2:4") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Goto (macro-record-enable,s,4) Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing NoOp("SIP/116-d48a", "Caller Num 116 Dialed 05211439811") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Executing AGI("SIP/116-d48a", "recordingcheck|20060217-090528|1140163528.4033|116|05211439811") in new stack Feb 17 09:05:28 VERBOSE[7155] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.165:2078: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5d65d02e From: ;tag=as10a8a479 To: ;tag=i51xvh226v Call-ID: 3c267009c5c1-pm968bfxh49k@snom360-000413231F0E CSeq: 157 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c5c1-pm968bfxh49k@snom360-000413231F0E' of Request 157: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.160:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK19f7108c From: ;tag=as3b0d316a To: ;tag=eyrx8v5st9 Call-ID: 3c267009d949-vnzf21gin77n@snom360-0004132328CA CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009d949-vnzf21gin77n@snom360-0004132328CA' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.161:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4953db7e From: ;tag=as7790c0c4 To: ;tag=5kicbws6tr Call-ID: 3c26700a1adb-ibwc6piqzng7@snom360-0004132328B1 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c26700a1adb-ibwc6piqzng7@snom360-0004132328B1' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6c0e950d From: ;tag=as121efef5 To: ;tag=p1194xfin2 Call-ID: 3c267009b71b-7gfpzjbgjcwy@snom360-0004132328D5 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b71b-7gfpzjbgjcwy@snom360-0004132328D5' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK06ff8896 From: ;tag=as5d83c4dc To: ;tag=oroc65v1my Call-ID: 3c267009b71b-qlcdngk0a8ie@snom360-00041323230D CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b71b-qlcdngk0a8ie@snom360-00041323230D' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.169:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3dd6eab7 From: ;tag=as0f70f8a9 To: ;tag=k58e81mikg Call-ID: 3c267009c5c1-locpuytqrifi@snom360-000413232742 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c5c1-locpuytqrifi@snom360-000413232742' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK489805c4 From: ;tag=as61dba6e0 To: ;tag=3j25n58hrn Call-ID: 3c267009e7ef-duzaqvfyn5sj@snom360-000413231F12 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009e7ef-duzaqvfyn5sj@snom360-000413231F12' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.168:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK47a2df0b From: ;tag=as4c480732 To: ;tag=i3ct4x77r4 Call-ID: 3c267009c0df-1d6jh25idioe@snom360-0004132324D2 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c0df-1d6jh25idioe@snom360-0004132324D2' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5a225860 From: ;tag=as1c2a1228 To: ;tag=8j6snimxy9 Call-ID: 3c267009b239-9vgl2g8u0b34@snom360-000413232740 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b239-9vgl2g8u0b34@snom360-000413232740' of Request 169: Match Found Feb 17 09:05:28 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK031ebf86 From: ;tag=as7c9fcc7e To: ;tag=3q1d6xupo3 Call-ID: 3c267009dbba-5z107i2tjceh@snom360-0004132324D7 CSeq: 169 NOTIFY Content-Length: 0 Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:28 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:28 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009dbba-5z107i2tjceh@snom360-0004132324D7' of Request 169: Match Found Feb 17 09:05:29 VERBOSE[7155] logger.c: recordingcheck|20060217-090528|1140163528.4033|116|05211439811: Outbound recording not enabled Feb 17 09:05:29 VERBOSE[7155] logger.c: -- AGI Script recordingcheck completed, returning 0 Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing NoOp("SIP/116-d48a", "No recording needed "" is call filename ") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "outbound-callerid|2") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing DBget("SIP/116-d48a", "USEROUTCID=AMPUSER/116/outboundcid") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- DBget: varname=USEROUTCID, family=AMPUSER, key=116/outboundcid Feb 17 09:05:29 VERBOSE[7155] logger.c: -- DBget: set variable USEROUTCID to Feb 17 09:05:29 DEBUG[7155] pbx.c: Expression result is '0' Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "0?4") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Not taking any branch Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing SetCallerID("SIP/116-d48a", "09831610061") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Expression result is '1' Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "1?6") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-outbound-callerid,s,6) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing NoOp("SIP/116-d48a", "CallerID set to 09831610061") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing SetGroup("SIP/116-d48a", "OUT_2") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing CheckGroup("SIP/116-d48a", "999") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing SetVar("SIP/116-d48a", "DIAL_NUMBER=05211439811") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing SetVar("SIP/116-d48a", "DIAL_TRUNK=2") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing AGI("SIP/116-d48a", "fixlocalprefix") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix Feb 17 09:05:29 VERBOSE[7155] logger.c: -- AGI Script fixlocalprefix completed, returning 0 Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing SetVar("SIP/116-d48a", "OUTNUM=05211439811") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Cut("SIP/116-d48a", "custom=OUT_2|:|1") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Expression result is '1' Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "1?16") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-dialout-trunk,s,16) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Cut("SIP/116-d48a", "pre_num=OUT_2|$|1") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Cut("SIP/116-d48a", "the_num=OUT_2|$|2") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Cut("SIP/116-d48a", "post_num=OUT_2|$|3") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Expression result is '1' Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "1?20:21") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-dialout-trunk,s,20) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing SetVar("SIP/116-d48a", "the_num=05211439811") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "lcr-dial|misdn/g:TEports/|05211439811/s") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Set("SIP/116-d48a", "LCR_DEVICE=misdn/g:TEports/") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Set("SIP/116-d48a", "LCR_NUMBER=05211439811/s") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Expression result is '0' Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "0?20") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Not taking any branch Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Macro("SIP/116-d48a", "lcr-target|05211439811/s") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Goto("SIP/116-d48a", "05211439811/s|1") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-lcr-target,05211439811/s,1) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Goto("SIP/116-d48a", "lcr-target-NATIONAL|1") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-lcr-target,lcr-target-NATIONAL,1) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIfTime("SIP/116-d48a", "0:00-9:00|mon-fri|*|*?lcr-target-NATIONAL-mon-fri-0:00-9:00|1") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIfTime("SIP/116-d48a", "9:00-19:00|mon-fri|*|*?lcr-target-NATIONAL-mon-fri-9:00-19:00|1") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-lcr-target,lcr-target-NATIONAL-mon-fri-9:00-19:00,1) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Set("SIP/116-d48a", "SERVICE_PROVIDER=01078") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Set("SIP/116-d48a", "FALLBACK_PROVIDER=01079") in new stack Feb 17 09:05:29 DEBUG[7155] pbx.c: Expression result is '1' Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing GotoIf("SIP/116-d48a", "1?7") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Goto (macro-lcr-dial,s,7) Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Executing Dial("SIP/116-d48a", "misdn/g:TEports/0107805211439811/s") in new stack Feb 17 09:05:29 VERBOSE[7155] logger.c: -- Called g:TEports/0107805211439811/s Feb 17 09:05:29 VERBOSE[3171] logger.c: Retransmitting #4 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:30 VERBOSE[7155] logger.c: We're at 192.168.4.2 port 10918 Feb 17 09:05:30 VERBOSE[7155] logger.c: Adding codec 0x8 (alaw) to SDP Feb 17 09:05:30 VERBOSE[7155] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Feb 17 09:05:30 VERBOSE[7155] logger.c: Transmitting (no NAT) to 192.168.4.115:5061: SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165;received=192.168.4.115 From: First Call 2 ;tag=fcb186a56800ec0o1 To: ;tag=as0554beb1 Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 3115 3115 IN IP4 192.168.4.2 s=session c=IN IP4 192.168.4.2 t=0 0 m=audio 10918 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Feb 17 09:05:31 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SUBSCRIBE sip:091132391810@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-jihbhnznz2zw;rport From: ;tag=4p4ushprv5 To: Call-ID: 3c267009a875-s2qsk7j13j56@snom360-00041323230D CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:31 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:31 VERBOSE[3171] logger.c: Sending to 192.168.4.164 : 2063 (NAT) Feb 17 09:05:31 VERBOSE[3171] logger.c: Found user '164' Feb 17 09:05:31 VERBOSE[3171] logger.c: Looking for 091132391810 in from-internal (domain 192.168.4.2) Feb 17 09:05:31 DEBUG[3171] chan_sip.c: Adding subscription for extension 091132391810 context from-internal for peer 164 Feb 17 09:05:31 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a875-s2qsk7j13j56@snom360-00041323230D' in 3610000 ms Feb 17 09:05:31 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091132391810 in context from-internal Feb 17 09:05:31 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.164:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-jihbhnznz2zw;rport;received=192.168.4.164 From: ;tag=4p4ushprv5 To: ;tag=as302f6c6e Call-ID: 3c267009a875-s2qsk7j13j56@snom360-00041323230D CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:31 VERBOSE[3171] logger.c: Destroying call '3c267009a875-s2qsk7j13j56@snom360-00041323230D' Feb 17 09:05:31 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SUBSCRIBE sip:09112342260@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-a55w40pwhz8f;rport From: ;tag=ylensp9r58 To: Call-ID: 3c267009a875-51mo2qrsgbx1@snom360-00041323230D CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:31 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:31 VERBOSE[3171] logger.c: Sending to 192.168.4.164 : 2063 (NAT) Feb 17 09:05:31 VERBOSE[3171] logger.c: Found user '164' Feb 17 09:05:31 VERBOSE[3171] logger.c: Looking for 09112342260 in from-internal (domain 192.168.4.2) Feb 17 09:05:31 DEBUG[3171] chan_sip.c: Adding subscription for extension 09112342260 context from-internal for peer 164 Feb 17 09:05:31 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a875-51mo2qrsgbx1@snom360-00041323230D' in 3610000 ms Feb 17 09:05:31 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 09112342260 in context from-internal Feb 17 09:05:31 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.164:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-a55w40pwhz8f;rport;received=192.168.4.164 From: ;tag=ylensp9r58 To: ;tag=as7f78f094 Call-ID: 3c267009a875-51mo2qrsgbx1@snom360-00041323230D CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:31 VERBOSE[3171] logger.c: Destroying call '3c267009a875-51mo2qrsgbx1@snom360-00041323230D' Feb 17 09:05:31 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SUBSCRIBE sip:091123422621@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-7tkt92rdk88l;rport From: ;tag=an1jen7b6c To: Call-ID: 3c267009aae6-mp0xhh9e2vr4@snom360-00041323230D CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:31 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:31 VERBOSE[3171] logger.c: Sending to 192.168.4.164 : 2063 (NAT) Feb 17 09:05:31 VERBOSE[3171] logger.c: Found user '164' Feb 17 09:05:31 VERBOSE[3171] logger.c: Looking for 091123422621 in from-internal (domain 192.168.4.2) Feb 17 09:05:31 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422621 context from-internal for peer 164 Feb 17 09:05:31 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009aae6-mp0xhh9e2vr4@snom360-00041323230D' in 3610000 ms Feb 17 09:05:31 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422621 in context from-internal Feb 17 09:05:31 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.164:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-7tkt92rdk88l;rport;received=192.168.4.164 From: ;tag=an1jen7b6c To: ;tag=as0dbc3e2f Call-ID: 3c267009aae6-mp0xhh9e2vr4@snom360-00041323230D CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:31 VERBOSE[3171] logger.c: Destroying call '3c267009aae6-mp0xhh9e2vr4@snom360-00041323230D' Feb 17 09:05:31 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SUBSCRIBE sip:091123422627@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-v7tetsu4h0kl;rport From: ;tag=yrv6jhyn1r To: Call-ID: 3c267009aae6-wbh5aj0l7n5g@snom360-00041323230D CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:31 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:31 VERBOSE[3171] logger.c: Sending to 192.168.4.164 : 2063 (NAT) Feb 17 09:05:31 VERBOSE[3171] logger.c: Found user '164' Feb 17 09:05:31 VERBOSE[3171] logger.c: Looking for 091123422627 in from-internal (domain 192.168.4.2) Feb 17 09:05:31 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422627 context from-internal for peer 164 Feb 17 09:05:31 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009aae6-wbh5aj0l7n5g@snom360-00041323230D' in 3610000 ms Feb 17 09:05:31 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422627 in context from-internal Feb 17 09:05:31 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.164:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-v7tetsu4h0kl;rport;received=192.168.4.164 From: ;tag=yrv6jhyn1r To: ;tag=as7d627da5 Call-ID: 3c267009aae6-wbh5aj0l7n5g@snom360-00041323230D CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:31 VERBOSE[3171] logger.c: Destroying call '3c267009aae6-wbh5aj0l7n5g@snom360-00041323230D' Feb 17 09:05:31 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SUBSCRIBE sip:*97@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-09219ag0okth;rport From: ;tag=socnzbpq2e To: Call-ID: 3c26700a0000-lsnwodkfn0ak@snom360-00041323230D CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: message-summary Accept: application/simple-message-summary Expires: 3600 Content-Length: 0 Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:31 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:31 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:31 VERBOSE[3171] logger.c: Sending to 192.168.4.164 : 2063 (NAT) Feb 17 09:05:31 VERBOSE[3171] logger.c: Found peer '164' Feb 17 09:05:31 VERBOSE[3171] logger.c: Looking for *97 in from-internal (domain 192.168.4.2) Feb 17 09:05:31 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.164:2063: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.4.164:2063;branch=z9hG4bK-09219ag0okth;rport;received=192.168.4.164 From: ;tag=socnzbpq2e To: ;tag=as76973c36 Call-ID: 3c26700a0000-lsnwodkfn0ak@snom360-00041323230D CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:31 VERBOSE[3171] logger.c: Destroying call '3c26700a0000-lsnwodkfn0ak@snom360-00041323230D' Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5060: SIP/2.0 200 OK To: ;tag=4aba29b95b3f577fi0 From: "T:0625110000" ;tag=as4b3cc02a Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7699d008 Contact: Andreas Raab Server: Sipura/SPA2002-3.1.5 Content-Length: 239 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 13986661 13986661 IN IP4 192.168.4.119 s=- c=IN IP4 192.168.4.119 t=0 0 m=audio 16452 RTP/AVP 8 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:100 NSE/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (12 headers 12 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (12 headers 12 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Acked pending invite 102 Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '39c79f0567172676596d05e91848884d@192.168.4.2' of Request 102: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: Found RTP audio format 8 Feb 17 09:05:33 VERBOSE[3171] logger.c: Found RTP audio format 100 Feb 17 09:05:33 VERBOSE[3171] logger.c: Found RTP audio format 101 Feb 17 09:05:33 VERBOSE[3171] logger.c: Peer audio RTP is at port 192.168.4.119:16452 Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Peer audio RTP is at port 192.168.4.119:16452 Feb 17 09:05:33 VERBOSE[3171] logger.c: Found description format PCMA Feb 17 09:05:33 VERBOSE[3171] logger.c: Found description format NSE Feb 17 09:05:33 VERBOSE[3171] logger.c: Found description format telephone-event Feb 17 09:05:33 VERBOSE[3171] logger.c: Capabilities: us - 0x8 (alaw), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Feb 17 09:05:33 VERBOSE[3171] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Feb 17 09:05:33 DEBUG[3171] chan_sip.c: build_route: Contact hop: Andreas Raab Feb 17 09:05:33 VERBOSE[3171] logger.c: list_route: hop: Feb 17 09:05:33 VERBOSE[3171] logger.c: set_destination: Parsing for address/port to send to Feb 17 09:05:33 VERBOSE[3171] logger.c: set_destination: set destination to 192.168.4.119, port 5060 Feb 17 09:05:33 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.119:5060: ACK sip:119@192.168.4.119:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4b30d5e1 From: "T:0625110000" ;tag=as4b3cc02a To: ;tag=4aba29b95b3f577fi0 Contact: Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- Feb 17 09:05:33 VERBOSE[7076] logger.c: -- SIP/119-6897 answered Local/164@from-internal-e1cb,2 Feb 17 09:05:33 DEBUG[7025] channel.c: Got a FRAME_CONTROL (-1) frame on channel Local/164@from-internal-e1cb,1 Feb 17 09:05:33 DEBUG[7025] channel.c: Bridge stops bridging channels mISDN/8-1 and Local/164@from-internal-e1cb,1 Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.165:2078: NOTIFY sip:165@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK36b3e3a7 From: ;tag=as7f27b1c1 To: ;tag=gbqrps3tvz Contact: Call-ID: 3c267009c832-mpe9rsnvjgaf@snom360-000413231F0E CSeq: 165 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 202 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.160:2060: NOTIFY sip:160@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK378aa3aa From: ;tag=as33e98a6c To: ;tag=nw323x4db2 Contact: Call-ID: 3c267009dbba-xm8frwr2wo6n@snom360-0004132328CA CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.161:2060: NOTIFY sip:161@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5ffdbe40 From: ;tag=as4cc6a867 To: ;tag=j58v0vleq9 Contact: Call-ID: 3c26700a1d4c-e8wlriu7nizt@snom360-0004132328B1 CSeq: 220 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.169:2063: NOTIFY sip:169@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70d1fb60 From: ;tag=as096edcdf To: ;tag=yoxyugy148 Contact: Call-ID: 3c267009c832-ek4dwbswi2xn@snom360-000413232742 CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.168:2060: NOTIFY sip:168@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0c32a172 From: ;tag=as798ae201 To: ;tag=qcyv94r5dr Contact: Call-ID: 3c267009c350-95gzsegzdles@snom360-0004132324D2 CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.163:2063: NOTIFY sip:163@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK537b27bc From: ;tag=as1d1f6788 To: ;tag=6kfevg1cd5 Contact: Call-ID: 3c267009b98c-q0sj9l9cfsny@snom360-0004132328D5 CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.166:2057: NOTIFY sip:166@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7198a9fb From: ;tag=as2d73266d To: ;tag=h56mu3bfqt Contact: Call-ID: 3c267009de2b-y389eo0v2nf4@snom360-0004132324D7 CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.167:2063: NOTIFY sip:167@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK722daa72 From: ;tag=as6c9ad12e To: ;tag=71f9j3grny Contact: Call-ID: 3c267009ea60-jt01a2qbf0jl@snom360-000413231F12 CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.164:2063: NOTIFY sip:164@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK00d7bd80 From: ;tag=as1852ebb2 To: ;tag=b0j3umo164 Contact: Call-ID: 3c267009b98c-muikz16vcltg@snom360-00041323230D CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3135] logger.c: Reliably Transmitting (no NAT) to 192.168.4.162:2057: NOTIFY sip:162@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK16caa2f2 From: ;tag=as7985f89b To: ;tag=y7o72q79a7 Contact: Call-ID: 3c267009b4aa-tgo333op0muz@snom360-000413232740 CSeq: 221 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 203 confirmed --- Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.165:2078: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK36b3e3a7 From: ;tag=as7f27b1c1 To: ;tag=gbqrps3tvz Call-ID: 3c267009c832-mpe9rsnvjgaf@snom360-000413231F0E CSeq: 165 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c832-mpe9rsnvjgaf@snom360-000413231F0E' of Request 165: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.161:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5ffdbe40 From: ;tag=as4cc6a867 To: ;tag=j58v0vleq9 Call-ID: 3c26700a1d4c-e8wlriu7nizt@snom360-0004132328B1 CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c26700a1d4c-e8wlriu7nizt@snom360-0004132328B1' of Request 220: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.169:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70d1fb60 From: ;tag=as096edcdf To: ;tag=yoxyugy148 Call-ID: 3c267009c832-ek4dwbswi2xn@snom360-000413232742 CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c832-ek4dwbswi2xn@snom360-000413232742' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK722daa72 From: ;tag=as6c9ad12e To: ;tag=71f9j3grny Call-ID: 3c267009ea60-jt01a2qbf0jl@snom360-000413231F12 CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009ea60-jt01a2qbf0jl@snom360-000413231F12' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.160:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK378aa3aa From: ;tag=as33e98a6c To: ;tag=nw323x4db2 Call-ID: 3c267009dbba-xm8frwr2wo6n@snom360-0004132328CA CSeq: 220 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009dbba-xm8frwr2wo6n@snom360-0004132328CA' of Request 220: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7198a9fb From: ;tag=as2d73266d To: ;tag=h56mu3bfqt Call-ID: 3c267009de2b-y389eo0v2nf4@snom360-0004132324D7 CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009de2b-y389eo0v2nf4@snom360-0004132324D7' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.168:2060: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0c32a172 From: ;tag=as798ae201 To: ;tag=qcyv94r5dr Call-ID: 3c267009c350-95gzsegzdles@snom360-0004132324D2 CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009c350-95gzsegzdles@snom360-0004132324D2' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK16caa2f2 From: ;tag=as7985f89b To: ;tag=y7o72q79a7 Call-ID: 3c267009b4aa-tgo333op0muz@snom360-000413232740 CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b4aa-tgo333op0muz@snom360-000413232740' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK00d7bd80 From: ;tag=as1852ebb2 To: ;tag=b0j3umo164 Call-ID: 3c267009b98c-muikz16vcltg@snom360-00041323230D CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b98c-muikz16vcltg@snom360-00041323230D' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK537b27bc From: ;tag=as1d1f6788 To: ;tag=6kfevg1cd5 Call-ID: 3c267009b98c-q0sj9l9cfsny@snom360-0004132328D5 CSeq: 221 NOTIFY Content-Length: 0 Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)Feb 17 09:05:33 VERBOSE[3171] logger.c: --- (7 headers 0 lines)--- Feb 17 09:05:33 DEBUG[3171] chan_sip.c: Stopping retransmission on '3c267009b98c-q0sj9l9cfsny@snom360-0004132328D5' of Request 221: Match Found Feb 17 09:05:33 VERBOSE[3171] logger.c: Retransmitting #5 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:34 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5060: INVITE sip:0625110000@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.119:5060;branch=z9hG4bK-a40f1cd0 From: ;tag=4aba29b95b3f577fi0 To: "T:0625110000" ;tag=as4b3cc02a Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 101 INVITE Max-Forwards: 70 Contact: Andreas Raab Expires: 30 User-Agent: Sipura/SPA2002-3.1.5 Content-Length: 233 Content-Type: application/sdp v=0 o=- 13990182 13990182 IN IP4 192.168.4.119 s=- c=IN IP4 0.0.0.0 t=0 0 m=audio 16452 RTP/AVP 8 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:100 NSE/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendonly Feb 17 09:05:34 VERBOSE[3171] logger.c: --- (12 headers 12 lines)Feb 17 09:05:34 VERBOSE[3171] logger.c: --- (12 headers 12 lines)--- Feb 17 09:05:34 VERBOSE[3171] logger.c: Using INVITE request as basis request - 39c79f0567172676596d05e91848884d@192.168.4.2 Feb 17 09:05:34 VERBOSE[3171] logger.c: Sending to 192.168.4.119 : 5060 (non-NAT) Feb 17 09:05:34 VERBOSE[3171] logger.c: Found RTP audio format 8 Feb 17 09:05:34 VERBOSE[3171] logger.c: Found RTP audio format 100 Feb 17 09:05:34 VERBOSE[3171] logger.c: Found RTP audio format 101 Feb 17 09:05:34 VERBOSE[3171] logger.c: Peer audio RTP is at port 0.0.0.0:16452 Feb 17 09:05:34 DEBUG[3171] chan_sip.c: Peer audio RTP is at port 0.0.0.0:16452 Feb 17 09:05:34 VERBOSE[3171] logger.c: Found description format PCMA Feb 17 09:05:34 VERBOSE[3171] logger.c: Found description format NSE Feb 17 09:05:34 VERBOSE[3171] logger.c: Found description format telephone-event Feb 17 09:05:34 VERBOSE[3171] logger.c: Capabilities: us - 0x8 (alaw), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Feb 17 09:05:34 VERBOSE[3171] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Feb 17 09:05:34 VERBOSE[3171] logger.c: -- Started music on hold, class 'default', on channel 'Local/164@from-internal-e1cb,2' Feb 17 09:05:34 VERBOSE[3171] logger.c: We're at 192.168.4.2 port 12412 Feb 17 09:05:34 VERBOSE[3171] logger.c: Adding codec 0x8 (alaw) to SDP Feb 17 09:05:34 VERBOSE[3171] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Feb 17 09:05:34 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.119:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.119:5060;branch=z9hG4bK-a40f1cd0;received=192.168.4.119 From: ;tag=4aba29b95b3f577fi0 To: "T:0625110000" ;tag=as4b3cc02a Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 212 v=0 o=root 3115 3116 IN IP4 192.168.4.2 s=session c=IN IP4 192.168.4.2 t=0 0 m=audio 12412 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Feb 17 09:05:34 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5060: ACK sip:0625110000@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.119:5060;branch=z9hG4bK-67a1b516 From: ;tag=4aba29b95b3f577fi0 To: "T:0625110000" ;tag=as4b3cc02a Call-ID: 39c79f0567172676596d05e91848884d@192.168.4.2 CSeq: 101 ACK Max-Forwards: 70 Contact: Andreas Raab User-Agent: Sipura/SPA2002-3.1.5 Content-Length: 0 Feb 17 09:05:34 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:34 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:34 DEBUG[3171] chan_sip.c: Stopping retransmission on '39c79f0567172676596d05e91848884d@192.168.4.2' of Response 101: Match Found Feb 17 09:05:34 VERBOSE[7155] logger.c: -- mISDN/8-u129 is proceeding passing it to SIP/116-d48a Feb 17 09:05:34 VERBOSE[7155] logger.c: -- mISDN/8-u129 is ringing Feb 17 09:05:34 VERBOSE[7155] logger.c: Transmitting (no NAT) to 192.168.4.115:5061: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165;received=192.168.4.115 From: First Call 2 ;tag=fcb186a56800ec0o1 To: ;tag=as0554beb1 Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:37 VERBOSE[3171] logger.c: Retransmitting #6 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK211284b1;rport From: ;tag=as0ae50062 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4302 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:38 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:38 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.162:2057: OPTIONS sip:162@192.168.4.162:2057;line=wb74hch3 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK65ea6520;rport From: "Unknown" ;tag=as1d067194 To: Contact: Call-ID: 0b1b822c510130a73e1e9cf42bbb7091@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:38 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:38 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.162:2057: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK65ea6520;rport=5060 From: "Unknown" ;tag=as1d067194 To: Call-ID: 0b1b822c510130a73e1e9cf42bbb7091@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:38 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:38 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:38 DEBUG[3171] chan_sip.c: Stopping retransmission on '0b1b822c510130a73e1e9cf42bbb7091@192.168.4.2' of Request 102: Match Found Feb 17 09:05:38 VERBOSE[3171] logger.c: Destroying call '0b1b822c510130a73e1e9cf42bbb7091@192.168.4.2' Feb 17 09:05:40 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:40 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.160:2060: OPTIONS sip:160@192.168.4.160:2060;line=q7g7zh22 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK106a1504;rport From: "Unknown" ;tag=as3797689c To: Contact: Call-ID: 4a44da494d9afc6b400a1d35089ac00b@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:40 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.160:2060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK106a1504;rport=5060 From: "Unknown" ;tag=as3797689c To: Call-ID: 4a44da494d9afc6b400a1d35089ac00b@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:40 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:40 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:40 DEBUG[3171] chan_sip.c: Stopping retransmission on '4a44da494d9afc6b400a1d35089ac00b@192.168.4.2' of Request 102: Match Found Feb 17 09:05:40 VERBOSE[3171] logger.c: Destroying call '4a44da494d9afc6b400a1d35089ac00b@192.168.4.2' Feb 17 09:05:40 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:40 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.161:2060: OPTIONS sip:161@192.168.4.161:2060;line=4mj9cbc1 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK23a47caa;rport From: "Unknown" ;tag=as661ddc4e To: Contact: Call-ID: 640573bf56e5d9285c3a97da703a6c64@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:40 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.161:2060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK23a47caa;rport=5060 From: "Unknown" ;tag=as661ddc4e To: Call-ID: 640573bf56e5d9285c3a97da703a6c64@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:40 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:40 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:40 DEBUG[3171] chan_sip.c: Stopping retransmission on '640573bf56e5d9285c3a97da703a6c64@192.168.4.2' of Request 102: Match Found Feb 17 09:05:40 VERBOSE[3171] logger.c: Destroying call '640573bf56e5d9285c3a97da703a6c64@192.168.4.2' Feb 17 09:05:40 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:40 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.163:2063: OPTIONS sip:163@192.168.4.163:2063;line=5fb5r5qr SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5a6fc38d;rport From: "Unknown" ;tag=as0f61fe83 To: Contact: Call-ID: 687e06e8407dfdb878c148dd0f3aae64@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:40 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5a6fc38d;rport=5060 From: "Unknown" ;tag=as0f61fe83 To: Call-ID: 687e06e8407dfdb878c148dd0f3aae64@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:40 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:40 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:40 DEBUG[3171] chan_sip.c: Stopping retransmission on '687e06e8407dfdb878c148dd0f3aae64@192.168.4.2' of Request 102: Match Found Feb 17 09:05:40 VERBOSE[3171] logger.c: Destroying call '687e06e8407dfdb878c148dd0f3aae64@192.168.4.2' Feb 17 09:05:41 NOTICE[3171] chan_sip.c: -- Registration for 'frn6/martin.hetzner@freenet.de' timed out, trying again (Attempt #4204) Feb 17 09:05:41 DEBUG[3171] chan_sip.c: Stopping retransmission on '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' of Request 4302: Match Found Feb 17 09:05:41 DEBUG[3171] chan_sip.c: Scheduled a registration timeout for freenet.de id #183605 Feb 17 09:05:41 VERBOSE[3171] logger.c: REGISTER 12 headers, 0 lines Feb 17 09:05:41 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:41 VERBOSE[3171] logger.c: Destroying call '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' Feb 17 09:05:42 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:42 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.117:5061: OPTIONS sip:118@192.168.4.117:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK05c23802;rport From: "Unknown" ;tag=as347c83d5 To: Contact: Call-ID: 326f211a33275e3d50af57137c4928ae@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:42 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:42 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.117:5061: SIP/2.0 200 OK To: ;tag=a51cee1013169c24i1 From: "Unknown" ;tag=as347c83d5 Call-ID: 326f211a33275e3d50af57137c4928ae@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK05c23802 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:42 DEBUG[3171] chan_sip.c: Stopping retransmission on '326f211a33275e3d50af57137c4928ae@192.168.4.2' of Request 102: Match Found Feb 17 09:05:42 VERBOSE[3171] logger.c: Destroying call '326f211a33275e3d50af57137c4928ae@192.168.4.2' Feb 17 09:05:42 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:42 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.109:5060: OPTIONS sip:109@192.168.4.109:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK096406d0;rport From: "Unknown" ;tag=as3285e44f To: Contact: Call-ID: 38e2e4143d713b883e0734c55ebe34ca@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:42 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:42 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.109:5060: SIP/2.0 200 OK To: ;tag=6d13f5145361a0f2i0 From: "Unknown" ;tag=as3285e44f Call-ID: 38e2e4143d713b883e0734c55ebe34ca@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK096406d0 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:42 DEBUG[3171] chan_sip.c: Stopping retransmission on '38e2e4143d713b883e0734c55ebe34ca@192.168.4.2' of Request 102: Match Found Feb 17 09:05:42 VERBOSE[3171] logger.c: Destroying call '38e2e4143d713b883e0734c55ebe34ca@192.168.4.2' Feb 17 09:05:42 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:42 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.109:5061: OPTIONS sip:111@192.168.4.109:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK446f44ca;rport From: "Unknown" ;tag=as3a76012e To: Contact: Call-ID: 4c46c98b7185621922663ca0794aba5a@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:42 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:42 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.109:5061: SIP/2.0 200 OK To: ;tag=78b404048f6f5ec2i1 From: "Unknown" ;tag=as3a76012e Call-ID: 4c46c98b7185621922663ca0794aba5a@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK446f44ca Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:42 DEBUG[3171] chan_sip.c: Stopping retransmission on '4c46c98b7185621922663ca0794aba5a@192.168.4.2' of Request 102: Match Found Feb 17 09:05:42 VERBOSE[3171] logger.c: Destroying call '4c46c98b7185621922663ca0794aba5a@192.168.4.2' Feb 17 09:05:42 VERBOSE[3171] logger.c: Retransmitting #1 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:42 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:42 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.123:5060: OPTIONS sip:123@192.168.4.123:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5a527581;rport From: "Unknown" ;tag=as44fb6b01 To: Contact: Call-ID: 0559ff9a143b655f6afe08885eab23bf@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:42 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:42 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.123:5060: SIP/2.0 200 OK To: ;tag=c598e5c92b73df8di0 From: "Unknown" ;tag=as44fb6b01 Call-ID: 0559ff9a143b655f6afe08885eab23bf@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK5a527581 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:42 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:42 DEBUG[3171] chan_sip.c: Stopping retransmission on '0559ff9a143b655f6afe08885eab23bf@192.168.4.2' of Request 102: Match Found Feb 17 09:05:42 VERBOSE[3171] logger.c: Destroying call '0559ff9a143b655f6afe08885eab23bf@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.121:5060: OPTIONS sip:121@192.168.4.121:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK701fe069;rport From: "Unknown" ;tag=as681d67ff To: Contact: Call-ID: 387c5b3b2c3fe8c017dd58ae1ee7953e@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.121:5060: SIP/2.0 200 OK To: ;tag=c598e5c92b73df8di0 From: "Unknown" ;tag=as681d67ff Call-ID: 387c5b3b2c3fe8c017dd58ae1ee7953e@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK701fe069 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '387c5b3b2c3fe8c017dd58ae1ee7953e@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '387c5b3b2c3fe8c017dd58ae1ee7953e@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.121:5061: OPTIONS sip:122@192.168.4.121:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK63ff06fb;rport From: "Unknown" ;tag=as0a24735f To: Contact: Call-ID: 1a72da7e685a452e43fdee0d787a71e2@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.121:5061: SIP/2.0 200 OK To: ;tag=5cc753e9fedb47edi1 From: "Unknown" ;tag=as0a24735f Call-ID: 1a72da7e685a452e43fdee0d787a71e2@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK63ff06fb Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '1a72da7e685a452e43fdee0d787a71e2@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '1a72da7e685a452e43fdee0d787a71e2@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.105:5060: OPTIONS sip:105@192.168.4.105:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7e0a5c30;rport From: "Unknown" ;tag=as791803cb To: Contact: Call-ID: 4bfaef202a5c7ffe0199c888341facd7@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.105:5061: OPTIONS sip:124@192.168.4.105:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK292f89be;rport From: "Unknown" ;tag=as4e840db2 To: Contact: Call-ID: 7d7a095e5bd357b9454828407fbe296e@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.105:5060: SIP/2.0 200 OK To: ;tag=e9e4584d29c96ef5i0 From: "Unknown" ;tag=as791803cb Call-ID: 4bfaef202a5c7ffe0199c888341facd7@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7e0a5c30 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '4bfaef202a5c7ffe0199c888341facd7@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '4bfaef202a5c7ffe0199c888341facd7@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.105:5061: SIP/2.0 200 OK To: ;tag=c5b74d8dd370fab5i1 From: "Unknown" ;tag=as4e840db2 Call-ID: 7d7a095e5bd357b9454828407fbe296e@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK292f89be Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '7d7a095e5bd357b9454828407fbe296e@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '7d7a095e5bd357b9454828407fbe296e@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.166:2057: OPTIONS sip:166@192.168.4.166:2057;line=tpixzxdu SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK047ee0c4;rport From: "Unknown" ;tag=as3c32bcd8 To: Contact: Call-ID: 2a5f4065577ad4b24edb434813d6ae4a@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK047ee0c4;rport=5060 From: "Unknown" ;tag=as3c32bcd8 To: Call-ID: 2a5f4065577ad4b24edb434813d6ae4a@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '2a5f4065577ad4b24edb434813d6ae4a@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '2a5f4065577ad4b24edb434813d6ae4a@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.113:5060: OPTIONS sip:113@192.168.4.113:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK20978270;rport From: "Unknown" ;tag=as1992365e To: Contact: Call-ID: 1c0ceaeb493f06035a7694da4293fcd9@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.113:5061: OPTIONS sip:114@192.168.4.113:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4779e61a;rport From: "Unknown" ;tag=as7f92ec49 To: Contact: Call-ID: 442e7aea2556e9a86efd5f4f7921d2ae@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.113:5060: SIP/2.0 200 OK To: ;tag=af85ffe044443492i0 From: "Unknown" ;tag=as1992365e Call-ID: 1c0ceaeb493f06035a7694da4293fcd9@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK20978270 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '1c0ceaeb493f06035a7694da4293fcd9@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '1c0ceaeb493f06035a7694da4293fcd9@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.113:5061: SIP/2.0 200 OK To: ;tag=da9b757092abc42i1 From: "Unknown" ;tag=as7f92ec49 Call-ID: 442e7aea2556e9a86efd5f4f7921d2ae@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4779e61a Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '442e7aea2556e9a86efd5f4f7921d2ae@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '442e7aea2556e9a86efd5f4f7921d2ae@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: Retransmitting #2 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.119:5060: OPTIONS sip:119@192.168.4.119:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK66a3437b;rport From: "Unknown" ;tag=as5fb27efe To: Contact: Call-ID: 153a36483f93a9a45600c71d4f18cae9@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5060: SIP/2.0 200 OK To: ;tag=4391cc0713eefe9bi0 From: "Unknown" ;tag=as5fb27efe Call-ID: 153a36483f93a9a45600c71d4f18cae9@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK66a3437b Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '153a36483f93a9a45600c71d4f18cae9@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '153a36483f93a9a45600c71d4f18cae9@192.168.4.2' Feb 17 09:05:43 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:43 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.119:5061: OPTIONS sip:120@192.168.4.119:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK15b1cfaf;rport From: "Unknown" ;tag=as33623b70 To: Contact: Call-ID: 2f24e661178c27aa690975d9378d838b@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:43 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.119:5061: SIP/2.0 200 OK To: ;tag=cff280a7e524d27bi1 From: "Unknown" ;tag=as33623b70 Call-ID: 2f24e661178c27aa690975d9378d838b@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK15b1cfaf Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:43 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:43 DEBUG[3171] chan_sip.c: Stopping retransmission on '2f24e661178c27aa690975d9378d838b@192.168.4.2' of Request 102: Match Found Feb 17 09:05:43 VERBOSE[3171] logger.c: Destroying call '2f24e661178c27aa690975d9378d838b@192.168.4.2' Feb 17 09:05:44 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:44 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.107:5060: OPTIONS sip:107@192.168.4.107:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK40316fa1;rport From: "Unknown" ;tag=as25e607d4 To: Contact: Call-ID: 62f58f2e5b4856c077eb2d05341de9dd@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:44 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:44 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.107:5061: OPTIONS sip:108@192.168.4.107:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7a19e491;rport From: "Unknown" ;tag=as30d73862 To: Contact: Call-ID: 4677c5185f53fb9905d07a5d1f62b0bb@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:44 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.107:5060: SIP/2.0 200 OK To: ;tag=be63d4151766e5f6i0 From: "Unknown" ;tag=as25e607d4 Call-ID: 62f58f2e5b4856c077eb2d05341de9dd@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK40316fa1 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:44 DEBUG[3171] chan_sip.c: Stopping retransmission on '62f58f2e5b4856c077eb2d05341de9dd@192.168.4.2' of Request 102: Match Found Feb 17 09:05:44 VERBOSE[3171] logger.c: Destroying call '62f58f2e5b4856c077eb2d05341de9dd@192.168.4.2' Feb 17 09:05:44 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.107:5061: SIP/2.0 200 OK To: ;tag=1a5b8b1ddad75a8ei1 From: "Unknown" ;tag=as30d73862 Call-ID: 4677c5185f53fb9905d07a5d1f62b0bb@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK7a19e491 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:44 DEBUG[3171] chan_sip.c: Stopping retransmission on '4677c5185f53fb9905d07a5d1f62b0bb@192.168.4.2' of Request 102: Match Found Feb 17 09:05:44 VERBOSE[3171] logger.c: Destroying call '4677c5185f53fb9905d07a5d1f62b0bb@192.168.4.2' Feb 17 09:05:44 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:44 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.125:5061: OPTIONS sip:106@192.168.4.125:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK057964a1;rport From: "Unknown" ;tag=as68bd224e To: Contact: Call-ID: 28756c1d2f6168d06adfd1c06a72be0a@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:44 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.125:5061: SIP/2.0 200 OK To: ;tag=605f863a3d1bd21bi1 From: "Unknown" ;tag=as68bd224e Call-ID: 28756c1d2f6168d06adfd1c06a72be0a@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK057964a1 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:44 DEBUG[3171] chan_sip.c: Stopping retransmission on '28756c1d2f6168d06adfd1c06a72be0a@192.168.4.2' of Request 102: Match Found Feb 17 09:05:44 VERBOSE[3171] logger.c: Destroying call '28756c1d2f6168d06adfd1c06a72be0a@192.168.4.2' Feb 17 09:05:44 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:44 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.115:5060: OPTIONS sip:115@192.168.4.115:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4c3cd9e9;rport From: "Unknown" ;tag=as01b33845 To: Contact: Call-ID: 789eb48174a6436c02ce3cf76280bbf5@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:44 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.115:5060: SIP/2.0 200 OK To: ;tag=cc79d56cdb317c45i0 From: "Unknown" ;tag=as01b33845 Call-ID: 789eb48174a6436c02ce3cf76280bbf5@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4c3cd9e9 Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:44 DEBUG[3171] chan_sip.c: Stopping retransmission on '789eb48174a6436c02ce3cf76280bbf5@192.168.4.2' of Request 102: Match Found Feb 17 09:05:44 VERBOSE[3171] logger.c: Destroying call '789eb48174a6436c02ce3cf76280bbf5@192.168.4.2' Feb 17 09:05:44 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:05:44 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.115:5061: OPTIONS sip:116@192.168.4.115:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK437dd71f;rport From: "Unknown" ;tag=as019eda25 To: Contact: Call-ID: 1846107e00cacb6e352a9780688cd2f0@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:05:44 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:05:44 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.115:5061: SIP/2.0 200 OK To: ;tag=14bcf434f1a7ac1di1 From: "Unknown" ;tag=as019eda25 Call-ID: 1846107e00cacb6e352a9780688cd2f0@192.168.4.2 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK437dd71f Server: Sipura/SPA2002-3.1.5 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:05:44 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:05:44 DEBUG[3171] chan_sip.c: Stopping retransmission on '1846107e00cacb6e352a9780688cd2f0@192.168.4.2' of Request 102: Match Found Feb 17 09:05:44 VERBOSE[3171] logger.c: Destroying call '1846107e00cacb6e352a9780688cd2f0@192.168.4.2' Feb 17 09:05:45 VERBOSE[3171] logger.c: Retransmitting #3 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:49 VERBOSE[3171] logger.c: Retransmitting #4 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:53 VERBOSE[3171] logger.c: Retransmitting #5 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:05:54 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SUBSCRIBE sip:091132391810@192.168.4.2;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-u31w6qvn62ic;rport From: ;tag=hx26rb7bjw To: Call-ID: 3c267009d949-spwz4gycnsnk@snom360-000413231F12 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:54 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:54 VERBOSE[3171] logger.c: Sending to 192.168.4.167 : 2063 (NAT) Feb 17 09:05:54 VERBOSE[3171] logger.c: Found user '167' Feb 17 09:05:54 VERBOSE[3171] logger.c: Looking for 091132391810 in from-internal (domain 192.168.4.2) Feb 17 09:05:54 DEBUG[3171] chan_sip.c: Adding subscription for extension 091132391810 context from-internal for peer 167 Feb 17 09:05:54 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009d949-spwz4gycnsnk@snom360-000413231F12' in 3610000 ms Feb 17 09:05:54 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091132391810 in context from-internal Feb 17 09:05:54 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.167:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-u31w6qvn62ic;rport;received=192.168.4.167 From: ;tag=hx26rb7bjw To: ;tag=as341b36d8 Call-ID: 3c267009d949-spwz4gycnsnk@snom360-000413231F12 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:54 VERBOSE[3171] logger.c: Destroying call '3c267009d949-spwz4gycnsnk@snom360-000413231F12' Feb 17 09:05:54 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SUBSCRIBE sip:09112342260@192.168.4.2;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-2k2qnbdgfqrl;rport From: ;tag=fg9n780evt To: Call-ID: 3c267009d949-69b4lig8nt8h@snom360-000413231F12 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:54 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:54 VERBOSE[3171] logger.c: Sending to 192.168.4.167 : 2063 (NAT) Feb 17 09:05:54 VERBOSE[3171] logger.c: Found user '167' Feb 17 09:05:54 VERBOSE[3171] logger.c: Looking for 09112342260 in from-internal (domain 192.168.4.2) Feb 17 09:05:54 DEBUG[3171] chan_sip.c: Adding subscription for extension 09112342260 context from-internal for peer 167 Feb 17 09:05:54 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009d949-69b4lig8nt8h@snom360-000413231F12' in 3610000 ms Feb 17 09:05:54 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 09112342260 in context from-internal Feb 17 09:05:54 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.167:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-2k2qnbdgfqrl;rport;received=192.168.4.167 From: ;tag=fg9n780evt To: ;tag=as5e4556c3 Call-ID: 3c267009d949-69b4lig8nt8h@snom360-000413231F12 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:54 VERBOSE[3171] logger.c: Destroying call '3c267009d949-69b4lig8nt8h@snom360-000413231F12' Feb 17 09:05:54 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SUBSCRIBE sip:091123422621@192.168.4.2;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-vnlshwfvjgsp;rport From: ;tag=yylysg8jvv To: Call-ID: 3c267009d949-puj6m6jvobb1@snom360-000413231F12 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:54 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:54 VERBOSE[3171] logger.c: Sending to 192.168.4.167 : 2063 (NAT) Feb 17 09:05:54 VERBOSE[3171] logger.c: Found user '167' Feb 17 09:05:54 VERBOSE[3171] logger.c: Looking for 091123422621 in from-internal (domain 192.168.4.2) Feb 17 09:05:54 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422621 context from-internal for peer 167 Feb 17 09:05:54 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009d949-puj6m6jvobb1@snom360-000413231F12' in 3610000 ms Feb 17 09:05:54 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422621 in context from-internal Feb 17 09:05:54 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.167:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-vnlshwfvjgsp;rport;received=192.168.4.167 From: ;tag=yylysg8jvv To: ;tag=as5edaf446 Call-ID: 3c267009d949-puj6m6jvobb1@snom360-000413231F12 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:54 VERBOSE[3171] logger.c: Destroying call '3c267009d949-puj6m6jvobb1@snom360-000413231F12' Feb 17 09:05:54 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SUBSCRIBE sip:091123422627@192.168.4.2;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-q7ww9y9c3vmq;rport From: ;tag=kk58403of1 To: Call-ID: 3c267009dbba-twmwru7mvhoo@snom360-000413231F12 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:54 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:54 VERBOSE[3171] logger.c: Sending to 192.168.4.167 : 2063 (NAT) Feb 17 09:05:54 VERBOSE[3171] logger.c: Found user '167' Feb 17 09:05:54 VERBOSE[3171] logger.c: Looking for 091123422627 in from-internal (domain 192.168.4.2) Feb 17 09:05:54 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422627 context from-internal for peer 167 Feb 17 09:05:54 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009dbba-twmwru7mvhoo@snom360-000413231F12' in 3610000 ms Feb 17 09:05:54 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422627 in context from-internal Feb 17 09:05:54 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.167:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-q7ww9y9c3vmq;rport;received=192.168.4.167 From: ;tag=kk58403of1 To: ;tag=as38567b90 Call-ID: 3c267009dbba-twmwru7mvhoo@snom360-000413231F12 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:54 VERBOSE[3171] logger.c: Destroying call '3c267009dbba-twmwru7mvhoo@snom360-000413231F12' Feb 17 09:05:54 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.167:2063: SUBSCRIBE sip:*97@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-qz659hr44wg5;rport From: ;tag=ervwqbksv4 To: Call-ID: 3c26700a2e63-ap6kbxhwa83i@snom360-000413231F12 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: message-summary Accept: application/simple-message-summary Expires: 3600 Content-Length: 0 Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:05:54 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:05:54 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:05:54 VERBOSE[3171] logger.c: Sending to 192.168.4.167 : 2063 (NAT) Feb 17 09:05:54 VERBOSE[3171] logger.c: Found peer '167' Feb 17 09:05:54 VERBOSE[3171] logger.c: Looking for *97 in from-internal (domain 192.168.4.2) Feb 17 09:05:54 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.167:2063: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.4.167:2063;branch=z9hG4bK-qz659hr44wg5;rport;received=192.168.4.167 From: ;tag=ervwqbksv4 To: ;tag=as236deb69 Call-ID: 3c26700a2e63-ap6kbxhwa83i@snom360-000413231F12 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:05:54 VERBOSE[3171] logger.c: Destroying call '3c26700a2e63-ap6kbxhwa83i@snom360-000413231F12' Feb 17 09:05:57 VERBOSE[3171] logger.c: Retransmitting #6 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK6950d894;rport From: ;tag=as2430a2d0 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4303 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:01 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:06:01 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.164:2063: OPTIONS sip:164@192.168.4.164:2063;line=ftnnhb74 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0596bb79;rport From: "Unknown" ;tag=as2a2bad98 To: Contact: Call-ID: 617b63cc15994daa1a99554e3cc24445@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:06:01 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:06:01 NOTICE[3171] chan_sip.c: -- Registration for 'frn6/martin.hetzner@freenet.de' timed out, trying again (Attempt #4205) Feb 17 09:06:01 DEBUG[3171] chan_sip.c: Stopping retransmission on '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' of Request 4303: Match Found Feb 17 09:06:01 DEBUG[3171] chan_sip.c: Scheduled a registration timeout for freenet.de id #183667 Feb 17 09:06:01 VERBOSE[3171] logger.c: REGISTER 12 headers, 0 lines Feb 17 09:06:01 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:01 VERBOSE[3171] logger.c: Destroying call '1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2' Feb 17 09:06:01 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.164:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0596bb79;rport=5060 From: "Unknown" ;tag=as2a2bad98 To: Call-ID: 617b63cc15994daa1a99554e3cc24445@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:06:01 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:06:01 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:06:01 DEBUG[3171] chan_sip.c: Stopping retransmission on '617b63cc15994daa1a99554e3cc24445@192.168.4.2' of Request 102: Match Found Feb 17 09:06:01 VERBOSE[3171] logger.c: Destroying call '617b63cc15994daa1a99554e3cc24445@192.168.4.2' Feb 17 09:06:02 VERBOSE[3171] logger.c: Retransmitting #1 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:03 VERBOSE[3171] logger.c: Retransmitting #2 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:05 VERBOSE[3171] logger.c: Retransmitting #3 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:06 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SUBSCRIBE sip:091132391810@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-b2b3wuem0jog;rport From: ;tag=9u1ldaeyng To: Call-ID: 3c267009cd14-h0fn485vub8x@snom360-0004132324D7 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:06 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:06 VERBOSE[3171] logger.c: Sending to 192.168.4.166 : 2057 (NAT) Feb 17 09:06:06 VERBOSE[3171] logger.c: Found user '166' Feb 17 09:06:06 VERBOSE[3171] logger.c: Looking for 091132391810 in from-internal (domain 192.168.4.2) Feb 17 09:06:06 DEBUG[3171] chan_sip.c: Adding subscription for extension 091132391810 context from-internal for peer 166 Feb 17 09:06:06 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009cd14-h0fn485vub8x@snom360-0004132324D7' in 3610000 ms Feb 17 09:06:06 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091132391810 in context from-internal Feb 17 09:06:06 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.166:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-b2b3wuem0jog;rport;received=192.168.4.166 From: ;tag=9u1ldaeyng To: ;tag=as38aee956 Call-ID: 3c267009cd14-h0fn485vub8x@snom360-0004132324D7 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:06 VERBOSE[3171] logger.c: Destroying call '3c267009cd14-h0fn485vub8x@snom360-0004132324D7' Feb 17 09:06:06 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SUBSCRIBE sip:09112342260@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-dravmkt04e87;rport From: ;tag=qz3kw71jeg To: Call-ID: 3c267009cd14-43x9jr4b8pu5@snom360-0004132324D7 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:06 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:06 VERBOSE[3171] logger.c: Sending to 192.168.4.166 : 2057 (NAT) Feb 17 09:06:06 VERBOSE[3171] logger.c: Found user '166' Feb 17 09:06:06 VERBOSE[3171] logger.c: Looking for 09112342260 in from-internal (domain 192.168.4.2) Feb 17 09:06:06 DEBUG[3171] chan_sip.c: Adding subscription for extension 09112342260 context from-internal for peer 166 Feb 17 09:06:06 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009cd14-43x9jr4b8pu5@snom360-0004132324D7' in 3610000 ms Feb 17 09:06:06 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 09112342260 in context from-internal Feb 17 09:06:06 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.166:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-dravmkt04e87;rport;received=192.168.4.166 From: ;tag=qz3kw71jeg To: ;tag=as1dd35f4d Call-ID: 3c267009cd14-43x9jr4b8pu5@snom360-0004132324D7 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:06 VERBOSE[3171] logger.c: Destroying call '3c267009cd14-43x9jr4b8pu5@snom360-0004132324D7' Feb 17 09:06:06 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SUBSCRIBE sip:091123422621@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-7dstu8gpbbcr;rport From: ;tag=55v6op3c4g To: Call-ID: 3c267009cf85-ozcta424t1ws@snom360-0004132324D7 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:06 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:06 VERBOSE[3171] logger.c: Sending to 192.168.4.166 : 2057 (NAT) Feb 17 09:06:06 VERBOSE[3171] logger.c: Found user '166' Feb 17 09:06:06 VERBOSE[3171] logger.c: Looking for 091123422621 in from-internal (domain 192.168.4.2) Feb 17 09:06:06 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422621 context from-internal for peer 166 Feb 17 09:06:06 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009cf85-ozcta424t1ws@snom360-0004132324D7' in 3610000 ms Feb 17 09:06:06 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422621 in context from-internal Feb 17 09:06:06 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.166:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-7dstu8gpbbcr;rport;received=192.168.4.166 From: ;tag=55v6op3c4g To: ;tag=as15671a6e Call-ID: 3c267009cf85-ozcta424t1ws@snom360-0004132324D7 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:06 VERBOSE[3171] logger.c: Destroying call '3c267009cf85-ozcta424t1ws@snom360-0004132324D7' Feb 17 09:06:06 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SUBSCRIBE sip:091123422627@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-5qd5um5rdfnz;rport From: ;tag=3an63r3ady To: Call-ID: 3c267009cf85-0c020tng6xu5@snom360-0004132324D7 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:06 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:06 VERBOSE[3171] logger.c: Sending to 192.168.4.166 : 2057 (NAT) Feb 17 09:06:06 VERBOSE[3171] logger.c: Found user '166' Feb 17 09:06:06 VERBOSE[3171] logger.c: Looking for 091123422627 in from-internal (domain 192.168.4.2) Feb 17 09:06:06 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422627 context from-internal for peer 166 Feb 17 09:06:06 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009cf85-0c020tng6xu5@snom360-0004132324D7' in 3610000 ms Feb 17 09:06:06 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422627 in context from-internal Feb 17 09:06:06 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.166:2057: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-5qd5um5rdfnz;rport;received=192.168.4.166 From: ;tag=3an63r3ady To: ;tag=as147da067 Call-ID: 3c267009cf85-0c020tng6xu5@snom360-0004132324D7 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:06 VERBOSE[3171] logger.c: Destroying call '3c267009cf85-0c020tng6xu5@snom360-0004132324D7' Feb 17 09:06:06 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.166:2057: SUBSCRIBE sip:*97@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-jgz7urzm4rzj;rport From: ;tag=ejqzutykpo To: Call-ID: 3c26700a2710-q7syhl0wiu9s@snom360-0004132324D7 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: message-summary Accept: application/simple-message-summary Expires: 3600 Content-Length: 0 Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:06 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:06 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:06 VERBOSE[3171] logger.c: Sending to 192.168.4.166 : 2057 (NAT) Feb 17 09:06:06 VERBOSE[3171] logger.c: Found peer '166' Feb 17 09:06:06 VERBOSE[3171] logger.c: Looking for *97 in from-internal (domain 192.168.4.2) Feb 17 09:06:06 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.166:2057: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.4.166:2057;branch=z9hG4bK-jgz7urzm4rzj;rport;received=192.168.4.166 From: ;tag=ejqzutykpo To: ;tag=as7cc85e49 Call-ID: 3c26700a2710-q7syhl0wiu9s@snom360-0004132324D7 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:06 VERBOSE[3171] logger.c: Destroying call '3c26700a2710-q7syhl0wiu9s@snom360-0004132324D7' Feb 17 09:06:08 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:06:08 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.168:2060: OPTIONS sip:168@192.168.4.168:2060;line=elvkhfuk SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3e4e96a1;rport From: "Unknown" ;tag=as6e14a7f6 To: Contact: Call-ID: 2e5e13b922a7bf01245af7474b551538@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:06:08 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:06:08 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.168:2060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK3e4e96a1;rport=5060 From: "Unknown" ;tag=as6e14a7f6 To: Call-ID: 2e5e13b922a7bf01245af7474b551538@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:06:08 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:06:08 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:06:08 DEBUG[3171] chan_sip.c: Stopping retransmission on '2e5e13b922a7bf01245af7474b551538@192.168.4.2' of Request 102: Match Found Feb 17 09:06:08 VERBOSE[3171] logger.c: Destroying call '2e5e13b922a7bf01245af7474b551538@192.168.4.2' Feb 17 09:06:09 VERBOSE[3171] logger.c: Retransmitting #4 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SUBSCRIBE sip:091132391810@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-u4429wqb1lbq;rport From: ;tag=f5ckxsb6as To: Call-ID: 3c267009a604-akj86ml3vccs@snom360-0004132328D5 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:11 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:11 VERBOSE[3171] logger.c: Sending to 192.168.4.163 : 2063 (NAT) Feb 17 09:06:11 VERBOSE[3171] logger.c: Found user '163' Feb 17 09:06:11 VERBOSE[3171] logger.c: Looking for 091132391810 in from-internal (domain 192.168.4.2) Feb 17 09:06:11 DEBUG[3171] chan_sip.c: Adding subscription for extension 091132391810 context from-internal for peer 163 Feb 17 09:06:11 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a604-akj86ml3vccs@snom360-0004132328D5' in 3610000 ms Feb 17 09:06:11 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091132391810 in context from-internal Feb 17 09:06:11 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.163:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-u4429wqb1lbq;rport;received=192.168.4.163 From: ;tag=f5ckxsb6as To: ;tag=as467a1269 Call-ID: 3c267009a604-akj86ml3vccs@snom360-0004132328D5 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:11 VERBOSE[3171] logger.c: Destroying call '3c267009a604-akj86ml3vccs@snom360-0004132328D5' Feb 17 09:06:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SUBSCRIBE sip:09112342260@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-wetiupmgais7;rport From: ;tag=aiploztw7k To: Call-ID: 3c267009a875-5w1qkgmw8lp9@snom360-0004132328D5 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:11 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:11 VERBOSE[3171] logger.c: Sending to 192.168.4.163 : 2063 (NAT) Feb 17 09:06:11 VERBOSE[3171] logger.c: Found user '163' Feb 17 09:06:11 VERBOSE[3171] logger.c: Looking for 09112342260 in from-internal (domain 192.168.4.2) Feb 17 09:06:11 DEBUG[3171] chan_sip.c: Adding subscription for extension 09112342260 context from-internal for peer 163 Feb 17 09:06:11 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a875-5w1qkgmw8lp9@snom360-0004132328D5' in 3610000 ms Feb 17 09:06:11 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 09112342260 in context from-internal Feb 17 09:06:11 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.163:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-wetiupmgais7;rport;received=192.168.4.163 From: ;tag=aiploztw7k To: ;tag=as33bf0a57 Call-ID: 3c267009a875-5w1qkgmw8lp9@snom360-0004132328D5 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:11 VERBOSE[3171] logger.c: Destroying call '3c267009a875-5w1qkgmw8lp9@snom360-0004132328D5' Feb 17 09:06:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SUBSCRIBE sip:091123422621@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-j501nqh1e53n;rport From: ;tag=0oea11aspr To: Call-ID: 3c267009a875-lkz70sdqlbkm@snom360-0004132328D5 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:11 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:11 VERBOSE[3171] logger.c: Sending to 192.168.4.163 : 2063 (NAT) Feb 17 09:06:11 VERBOSE[3171] logger.c: Found user '163' Feb 17 09:06:11 VERBOSE[3171] logger.c: Looking for 091123422621 in from-internal (domain 192.168.4.2) Feb 17 09:06:11 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422621 context from-internal for peer 163 Feb 17 09:06:11 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009a875-lkz70sdqlbkm@snom360-0004132328D5' in 3610000 ms Feb 17 09:06:11 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422621 in context from-internal Feb 17 09:06:11 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.163:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-j501nqh1e53n;rport;received=192.168.4.163 From: ;tag=0oea11aspr To: ;tag=as190e0343 Call-ID: 3c267009a875-lkz70sdqlbkm@snom360-0004132328D5 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:11 VERBOSE[3171] logger.c: Destroying call '3c267009a875-lkz70sdqlbkm@snom360-0004132328D5' Feb 17 09:06:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SUBSCRIBE sip:091123422627@192.168.4.2;user=phone;user:phone SIP/2.0 Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-1dz3yutv96xn;rport From: ;tag=i83sgsk6nf To: Call-ID: 3c267009aae6-r4doeyzi4wa6@snom360-0004132328D5 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: dialog Accept: application/dialog-info+xml Expires: 3600 Content-Length: 0 Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:11 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:11 VERBOSE[3171] logger.c: Sending to 192.168.4.163 : 2063 (NAT) Feb 17 09:06:11 VERBOSE[3171] logger.c: Found user '163' Feb 17 09:06:11 VERBOSE[3171] logger.c: Looking for 091123422627 in from-internal (domain 192.168.4.2) Feb 17 09:06:11 DEBUG[3171] chan_sip.c: Adding subscription for extension 091123422627 context from-internal for peer 163 Feb 17 09:06:11 VERBOSE[3171] logger.c: Scheduling destruction of call '3c267009aae6-r4doeyzi4wa6@snom360-0004132328D5' in 3610000 ms Feb 17 09:06:11 ERROR[3171] chan_sip.c: Got SUBSCRIBE for extensions without hint. Please add hint to 091123422627 in context from-internal Feb 17 09:06:11 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.163:2063: SIP/2.0 404 Not found Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-1dz3yutv96xn;rport;received=192.168.4.163 From: ;tag=i83sgsk6nf To: ;tag=as7a6079b1 Call-ID: 3c267009aae6-r4doeyzi4wa6@snom360-0004132328D5 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:11 VERBOSE[3171] logger.c: Destroying call '3c267009aae6-r4doeyzi4wa6@snom360-0004132328D5' Feb 17 09:06:11 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.163:2063: SUBSCRIBE sip:*97@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-vj3q1fxkkx5r;rport From: ;tag=1ambl4tzjc To: Call-ID: 3c267009f1b3-lwy6ghlf3l4u@snom360-0004132328D5 CSeq: 281 SUBSCRIBE Max-Forwards: 70 Contact: ;flow-id=1 Event: message-summary Accept: application/simple-message-summary Expires: 3600 Content-Length: 0 Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)Feb 17 09:06:11 VERBOSE[3171] logger.c: --- (12 headers 0 lines)--- Feb 17 09:06:11 VERBOSE[3171] logger.c: Using latest SUBSCRIBE request as basis request Feb 17 09:06:11 VERBOSE[3171] logger.c: Sending to 192.168.4.163 : 2063 (NAT) Feb 17 09:06:11 VERBOSE[3171] logger.c: Found peer '163' Feb 17 09:06:11 VERBOSE[3171] logger.c: Looking for *97 in from-internal (domain 192.168.4.2) Feb 17 09:06:11 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.163:2063: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.4.163:2063;branch=z9hG4bK-vj3q1fxkkx5r;rport;received=192.168.4.163 From: ;tag=1ambl4tzjc To: ;tag=as1a5df262 Call-ID: 3c267009f1b3-lwy6ghlf3l4u@snom360-0004132328D5 CSeq: 281 SUBSCRIBE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:11 VERBOSE[3171] logger.c: Destroying call '3c267009f1b3-lwy6ghlf3l4u@snom360-0004132328D5' Feb 17 09:06:12 VERBOSE[3171] logger.c: 12 headers, 0 lines Feb 17 09:06:12 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.169:2063: OPTIONS sip:169@192.168.4.169:2063;line=9je1hkks SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0753d4b0;rport From: "Unknown" ;tag=as412345ad To: Contact: Call-ID: 4118d1a02cb9a67467696c4b1b84a323@192.168.4.2 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 17 Feb 2006 08:06:12 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Feb 17 09:06:12 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.169:2063: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK0753d4b0;rport=5060 From: "Unknown" ;tag=as412345ad To: Call-ID: 4118d1a02cb9a67467696c4b1b84a323@192.168.4.2 CSeq: 102 OPTIONS Contact: ;flow-id=1 User-Agent: snom360/5.3 Accept-Language: en Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer Supported: timer, 100rel, replaces, callerid Content-Length: 0 Feb 17 09:06:12 VERBOSE[3171] logger.c: --- (14 headers 0 lines)Feb 17 09:06:12 VERBOSE[3171] logger.c: --- (14 headers 0 lines)--- Feb 17 09:06:12 DEBUG[3171] chan_sip.c: Stopping retransmission on '4118d1a02cb9a67467696c4b1b84a323@192.168.4.2' of Request 102: Match Found Feb 17 09:06:12 VERBOSE[3171] logger.c: Destroying call '4118d1a02cb9a67467696c4b1b84a323@192.168.4.2' Feb 17 09:06:13 VERBOSE[3171] logger.c: Retransmitting #5 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:17 VERBOSE[3171] logger.c: Retransmitting #6 (no NAT) to 62.104.23.42:5060: REGISTER sip:freenet.de SIP/2.0 Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK70133679;rport From: ;tag=as5bd039e9 To: Call-ID: 1e7d63a5458bd7084f98114c1ab0d21e@192.168.4.2 CSeq: 4304 REGISTER User-Agent: Asterisk PBX Max-Forwards: 70 Expires: 120 Contact: Event: registration Content-Length: 0 --- Feb 17 09:06:18 VERBOSE[3171] logger.c: <-- SIP read from 192.168.4.115:5061: CANCEL sip:05211439811@192.168.4.2 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165 From: First Call 2 ;tag=fcb186a56800ec0o1 To: Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 CANCEL Max-Forwards: 70 Proxy-Authorization: Digest username="116",realm="asterisk",nonce="72e06569",uri="sip:05211439811@192.168.4.2",algorithm=MD5,response="2b79f13a65286b2e1f92a293e4b46dd8" User-Agent: Sipura/SPA2002-3.1.5 Content-Length: 0 Feb 17 09:06:18 VERBOSE[3171] logger.c: --- (10 headers 0 lines)Feb 17 09:06:18 VERBOSE[3171] logger.c: --- (10 headers 0 lines)--- Feb 17 09:06:18 VERBOSE[3171] logger.c: Sending to 192.168.4.115 : 5061 (non-NAT) Feb 17 09:06:18 VERBOSE[3171] logger.c: Reliably Transmitting (no NAT) to 192.168.4.115:5061: SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165;received=192.168.4.115 From: First Call 2 ;tag=fcb186a56800ec0o1 To: ;tag=as0554beb1 Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06:18 VERBOSE[3171] logger.c: Transmitting (no NAT) to 192.168.4.115:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.4.115:5061;branch=z9hG4bK-29a61165;received=192.168.4.115 From: First Call 2 ;tag=fcb186a56800ec0o1 To: ;tag=as0554beb1 Call-ID: ba5fec49-c18a456c@192.168.4.115 CSeq: 102 CANCEL User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Feb 17 09:06