[Oct 30 11:14:56] DEBUG[5473] chan_sip.c: = Looking for  Call ID: 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de (Checking From) --From tag 895608486 --To-tag   
[Oct 30 11:14:56] DEBUG[5473] acl.c: For destination 'XXX.XXX.XXX.XXX', our source address is 'XXX.XXX.XXX.XXX'.
[Oct 30 11:14:56] DEBUG[5473] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5072
[Oct 30 11:14:56] DEBUG[5473] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:14:56] DEBUG[5473] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473] chan_sip.c: Allocating new SIP dialog for 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de - INVITE (No RTP)
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[Oct 30 11:14:56] DEBUG[5473][C-00000001] sip/reqresp_parser.c: Begin: parsing SIP "Supported: timer"
[Oct 30 11:14:56] DEBUG[5473][C-00000001] sip/reqresp_parser.c: Found SIP option: -timer-
[Oct 30 11:14:56] DEBUG[5473][C-00000001] sip/reqresp_parser.c: Matched SIP option: timer
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting '1und1-3.sip.mgc.voip.telefonica.de:5060' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host '1und1-3.sip.mgc.voip.telefonica.de' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x22751f8'
[Oct 30 11:14:56] DEBUG[5473][C-00000001] res_rtp_asterisk.c: Allocated port 16624 for RTP instance '0x22751f8'
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: RTP instance '0x22751f8' is setup and ready to go
[Oct 30 11:14:56] DEBUG[5473][C-00000001] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x22751f8'
[Oct 30 11:14:56] VERBOSE[5473][C-00000001] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Setting NAT on RTP to Off
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing session-level SDP o=- 1435954818 0 IN IP4 XXX.XXX.XXX.XXX... OK.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing session-level SDP s=Cisco SDP 0... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing session-level SDP c=IN IP4 XXX.XXX.XXX.XXX... OK.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 8 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 0 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 18 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 101 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 102 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 103 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 104 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 105 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 4 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 106 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 3 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 107 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 108 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 109 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 125 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 99 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Setting payload 100 based on m type on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 G729a/8000... OK.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 102 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:102 G726-16/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 103 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:103 G726-24/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:104 G726-32/8000... OK.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 105 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:105 G7231-H/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 106 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:106 G7231-L/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 107 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:107 G729b/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 108 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:108 G7231a-H/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 109 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:109 G7231a-L/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 125 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:125 CLEARMODE/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:99 telephone-event/8000... OK.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=fmtp:99 0-15... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Unsetting payload 100 on 0x7f37081d5d50
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:100 X-NSE/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=fmtp:100 192-194,200-202... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=X-sqn:0... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=X-cap: 1 audio RTP/AVP 100... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=X-cpar: a=rtpmap:100 X-NSE/8000... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=X-cpar: a=fmtp:100 192-194,200-202... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Processing media-level (audio) SDP a=X-cap: 2 image udptl t38... UNSUPPORTED OR FAILED.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x22751f8'
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 0 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 3 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 4 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 8 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 18 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 99 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 101 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] rtp_engine.c: Copying payload 104 from 0x7f37081d5d50 to 0x22753c0
[Oct 30 11:14:56] DEBUG[5473][C-00000001] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x22751f8'
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: We're settling with these formats: (gsm|ulaw|alaw|g726aal2)
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Checking SIP call limits for device 
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Updating call counter for incoming call
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX:5072' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: Splitting '1und1-3.sip.mgc.voip.telefonica.de:5060' into...
[Oct 30 11:14:56] DEBUG[5473][C-00000001] netsock2.c: ...host '1und1-3.sip.mgc.voip.telefonica.de' and port ''.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: *** Our native formats are (alaw) 
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: *** Joint capabilities are (gsm|ulaw|alaw|g726aal2) 
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: *** Our capabilities are (gsm|ulaw|alaw|g726|g726aal2) 
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: *** AST_CODEC_CHOOSE formats are alaw 
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: This channel will not be able to handle video.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: build_route: Record-Route hop: <sip:XXX.XXX.XXX.XXX;lr=on>
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: build_route: Record-Route hop: <sip:XXX.XXX.XXX.XXX;lr=on;did=4b8.861a0db7>
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: build_route: Record-Route hop: <sip:XXX.XXX.XXX.XXX;lr=on>
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Got redirecting from number +497213357498
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Got redirecting to number +497213357498
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Got redirecting to name +497213357498
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Incoming INVITE with 'timer' option supported
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: INVITE also has "Session-Expires" header.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Session-Expires: 1800
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: INVITE also has "Min-SE" header.
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Received Min-SE: 1800
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Session timer started: 9 - 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de 900000ms
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001: New call is still down.... Trying... 
[Oct 30 11:14:56] DEBUG[5473][C-00000001] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for XXX.XXX.XXX.XXX:5060
[Oct 30 11:14:56] DEBUG[5464] devicestate.c: No provider found, checking channel drivers for SIP - 1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:14:56] DEBUG[5464] chan_sip.c: Checking device state for peer 1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:14:56] DEBUG[5464] devicestate.c: Changing state for SIP/1und1-3.sip.mgc.voip.telefonica.de - state 4 (Invalid)
[Oct 30 11:14:56] DEBUG[5464] devicestate.c: device 'SIP/1und1-3.sip.mgc.voip.telefonica.de' state '4'
[Oct 30 11:14:56] DEBUG[6243][C-00000001] pbx.c: Launching 'Goto'
[Oct 30 11:14:56] VERBOSE[6243][C-00000001] pbx.c:     -- Executing [dslinfo@default:1] Goto("SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001", "dslinfo,dslinfo,1") in new stack
[Oct 30 11:14:56] VERBOSE[6243][C-00000001] pbx.c:     -- Goto (dslinfo,dslinfo,1)
[Oct 30 11:14:56] DEBUG[6243][C-00000001] pbx.c: Function result is '+497218198286'
[Oct 30 11:14:56] DEBUG[6243][C-00000001] pbx.c: Launching 'Verbose'
[Oct 30 11:14:56] VERBOSE[6243][C-00000001] pbx.c:     -- Executing [dslinfo@dslinfo:1] Verbose("SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001", "DSL-Info for +497218198286") in new stack
[Oct 30 11:14:56] VERBOSE[6243][C-00000001] app_verbose.c: DSL-Info for +497218198286
[Oct 30 11:14:56] DEBUG[6243][C-00000001] pbx.c: Launching 'AGI'
[Oct 30 11:14:56] VERBOSE[6243][C-00000001] pbx.c:     -- Executing [dslinfo@dslinfo:2] AGI("SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001", "dslinfo") in new stack
[Oct 30 11:14:56] VERBOSE[6243][C-00000001] res_agi.c:     -- Launched AGI Script /usr/share/asterisk/agi-bin/dslinfo
[Oct 30 11:14:57] DEBUG[6243][C-00000001] pbx.c: Evaluating 'SIP_HEADER(Call-ID)' (from 'SIP_HEADER(Call-ID)}' len 19)
[Oct 30 11:14:57] DEBUG[6243][C-00000001] pbx.c: Function result is '64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de'
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: SIP answering channel: SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001
[Oct 30 11:14:57] DEBUG[6243][C-00000001] res_rtp_asterisk.c: Setting the marker bit due to a source update
[Oct 30 11:14:57] DEBUG[5464] devicestate.c: No provider found, checking channel drivers for SIP - 1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:14:57] DEBUG[5464] chan_sip.c: Checking device state for peer 1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:14:57] DEBUG[5464] devicestate.c: Changing state for SIP/1und1-3.sip.mgc.voip.telefonica.de - state 4 (Invalid)
[Oct 30 11:14:57] DEBUG[5464] devicestate.c: device 'SIP/1und1-3.sip.mgc.voip.telefonica.de' state '4'
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: Setting framing from config on incoming call
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: ** Our capability: (gsm|ulaw|alaw|g726aal2) Video flag: True Text flag: True
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: ** Our prefcodec: (nothing) 
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: -- Done with adding codecs to SDP
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: Done building SDP. Settling with this capability: (gsm|ulaw|alaw|g726aal2)
[Oct 30 11:14:57] DEBUG[6243][C-00000001] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for XXX.XXX.XXX.XXX:5060
[Oct 30 11:14:57] DEBUG[5473] chan_sip.c: = Looking for  Call ID: 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de (Checking From) --From tag 895608486 --To-tag as1fea1879  
[Oct 30 11:14:57] DEBUG[5473][C-00000001] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[Oct 30 11:14:57] DEBUG[5473][C-00000001] chan_sip.c: Stopping retransmission on '64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de' of Response 1: Match Found
[Oct 30 11:14:57] DEBUG[6243][C-00000001] res_rtp_asterisk.c: 0x2279740 -- Probation learning mode pass with source address XXX.XXX.XXX.XXX:39944
[Oct 30 11:14:57] DEBUG[6243][C-00000001] res_rtp_asterisk.c: Ooh, format changed from unknown to alaw
[Oct 30 11:14:57] DEBUG[6243][C-00000001] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:14:57] DEBUG[6243][C-00000001] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Oct 30 11:14:57] VERBOSE[6243][C-00000001] file.c:     -- <SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001> Playing 'Greeting.alaw' (language 'dslinfo')
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:58] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:14:59] DEBUG[6243][C-00000001] chan_sip.c: Bogus frame of format 'slin16' received from 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'!
[Oct 30 11:15:00] DEBUG[6243][C-00000001] res_rtp_asterisk.c: Got RTCP report of 72 bytes
[Oct 30 11:15:00] DEBUG[5473] chan_sip.c: = Looking for  Call ID: 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de (Checking From) --From tag 895608486 --To-tag as1fea1879  
[Oct 30 11:15:00] DEBUG[5473][C-00000001] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
[Oct 30 11:15:00] DEBUG[5473][C-00000001] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:15:00] DEBUG[5473][C-00000001] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:15:00] DEBUG[5473][C-00000001] chan_sip.c: Setting SIP_ALREADYGONE on dialog 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:15:00] DEBUG[5473][C-00000001] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x22751f8'
[Oct 30 11:15:00] DEBUG[5473][C-00000001] chan_sip.c: Session timer stopped: 9 - 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:15:00] DEBUG[5473][C-00000001] chan_sip.c: Received bye, issuing owner hangup
[Oct 30 11:15:00] DEBUG[5473][C-00000001] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for XXX.XXX.XXX.XXX:5060
[Oct 30 11:15:00] DEBUG[6243][C-00000001] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Oct 30 11:15:00] DEBUG[6243][C-00000001] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Oct 30 11:15:00] DEBUG[6243][C-00000001] res_agi.c: SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001 hungup
[Oct 30 11:15:00] VERBOSE[6243][C-00000001] res_agi.c:     -- <SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001>AGI Script dslinfo completed, returning 4
[Oct 30 11:15:00] DEBUG[6243][C-00000001] pbx.c: Spawn extension (dslinfo,dslinfo,2) exited non-zero on 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'
[Oct 30 11:15:00] VERBOSE[6243][C-00000001] pbx.c:   == Spawn extension (dslinfo, dslinfo, 2) exited non-zero on 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'
[Oct 30 11:15:00] DEBUG[6243][C-00000001] channel.c: Soft-Hanging up channel 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'
[Oct 30 11:15:00] DEBUG[6243][C-00000001] channel.c: Hanging up channel 'SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001'
[Oct 30 11:15:00] DEBUG[6243][C-00000001] chan_sip.c: Hangup call SIP/1und1-3.sip.mgc.voip.telefonica.de-00000001, SIP callid 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:15:00] DEBUG[6243][C-00000001] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x22751f8'
[Oct 30 11:15:00] DEBUG[6243][C-00000001] cdr.c: Dropping CDR !
[Oct 30 11:15:00] DEBUG[5464] devicestate.c: No provider found, checking channel drivers for SIP - 1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:15:00] DEBUG[5464] chan_sip.c: Checking device state for peer 1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:15:00] DEBUG[5464] devicestate.c: Changing state for SIP/1und1-3.sip.mgc.voip.telefonica.de - state 4 (Invalid)
[Oct 30 11:15:00] DEBUG[5464] devicestate.c: device 'SIP/1und1-3.sip.mgc.voip.telefonica.de' state '4'
[Oct 30 11:15:32] DEBUG[5473] chan_sip.c: Auto destroying SIP dialog '64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de'
[Oct 30 11:15:32] DEBUG[5473] chan_sip.c: Destroying SIP dialog 64826531-48894aa2-738b279-1143@1und1-3.sip.mgc.voip.telefonica.de
[Oct 30 11:15:32] DEBUG[5473] rtp_engine.c: Destroyed RTP instance '0x22751f8'