[Oct 30 11:11:41] DEBUG[5473] chan_sip.c: = Looking for  Call ID: SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3 (Checking From) --From tag SDlgk4501-002262c4 --To-tag   
[Oct 30 11:11:41] DEBUG[5473] acl.c: For destination 'XXX.XXX.XXX.XXX', our source address is 'XXX.XXX.XXX.XXX'.
[Oct 30 11:11:41] DEBUG[5473] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5072
[Oct 30 11:11:41] DEBUG[5473] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:41] DEBUG[5473] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473] chan_sip.c: Allocating new SIP dialog for SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3 - INVITE (No RTP)
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[Oct 30 11:11:41] DEBUG[5473][C-00000000] sip/reqresp_parser.c: Begin: parsing SIP "Supported: resource-priority,100rel"
[Oct 30 11:11:41] DEBUG[5473][C-00000000] sip/reqresp_parser.c: Found SIP option: -resource-priority-
[Oct 30 11:11:41] DEBUG[5473][C-00000000] sip/reqresp_parser.c: Matched SIP option: resource-priority
[Oct 30 11:11:41] DEBUG[5473][C-00000000] sip/reqresp_parser.c: Found SIP option: -100rel-
[Oct 30 11:11:41] DEBUG[5473][C-00000000] sip/reqresp_parser.c: Matched SIP option: 100rel
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'arcor.de' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'arcor.de' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x227fe28'
[Oct 30 11:11:41] DEBUG[5473][C-00000000] res_rtp_asterisk.c: Allocated port 17748 for RTP instance '0x227fe28'
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: RTP instance '0x227fe28' is setup and ready to go
[Oct 30 11:11:41] DEBUG[5473][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x227fe28'
[Oct 30 11:11:41] VERBOSE[5473][C-00000000] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Setting NAT on RTP to Off
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing session-level SDP o=- 0 279515826 IN IP4 XXX.XXX.XXX.XXX... OK.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing session-level SDP s=IMSS... UNSUPPORTED OR FAILED.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing session-level SDP c=IN IP4 XXX.XXX.XXX.XXX... OK.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Setting payload 8 based on m type on 0x7f37081d5d50
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Setting payload 101 based on m type on 0x7f37081d5d50
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Setting payload 18 based on m type on 0x7f37081d5d50
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Setting payload 106 based on m type on 0x7f37081d5d50
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 G726-32/8000... OK.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:106 telephone-event/8000... OK.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x227fe28'
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Copying payload 8 from 0x7f37081d5d50 to 0x227fff0
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Copying payload 18 from 0x7f37081d5d50 to 0x227fff0
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Copying payload 101 from 0x7f37081d5d50 to 0x227fff0
[Oct 30 11:11:41] DEBUG[5473][C-00000000] rtp_engine.c: Copying payload 106 from 0x7f37081d5d50 to 0x227fff0
[Oct 30 11:11:41] DEBUG[5473][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x227fe28'
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: We're settling with these formats: (alaw|g726aal2)
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Checking SIP call limits for device 
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Updating call counter for incoming call
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX:5072' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: Splitting 'arcor.de' into...
[Oct 30 11:11:41] DEBUG[5473][C-00000000] netsock2.c: ...host 'arcor.de' and port ''.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: *** Our native formats are (alaw) 
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: *** Joint capabilities are (alaw|g726aal2) 
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: *** Our capabilities are (gsm|ulaw|alaw|g726|g726aal2) 
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: *** AST_CODEC_CHOOSE formats are alaw 
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: This channel will not be able to handle video.
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: build_route: Record-Route hop: <sip:XXX.XXX.XXX.XXX;lr=on>
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: build_route: Record-Route hop: <sip:XXX.XXX.XXX.XXX;lr=on;did=58b.a7b57752>
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: build_route: Record-Route hop: <sip:XXX.XXX.XXX.XXX;lr=on>
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Got redirecting from number +4972191581098
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Got redirecting to number +4972191581098
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: SIP/arcor.de-00000000: New call is still down.... Trying... 
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for XXX.XXX.XXX.XXX:5060
[Oct 30 11:11:41] DEBUG[5464] devicestate.c: No provider found, checking channel drivers for SIP - arcor.de
[Oct 30 11:11:41] DEBUG[5464] chan_sip.c: Checking device state for peer arcor.de
[Oct 30 11:11:41] DEBUG[5464] devicestate.c: Changing state for SIP/arcor.de - state 4 (Invalid)
[Oct 30 11:11:41] DEBUG[5464] devicestate.c: device 'SIP/arcor.de' state '4'
[Oct 30 11:11:41] DEBUG[6193][C-00000000] pbx.c: Launching 'Goto'
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] pbx.c:     -- Executing [dslinfo@default:1] Goto("SIP/arcor.de-00000000", "dslinfo,dslinfo,1") in new stack
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] pbx.c:     -- Goto (dslinfo,dslinfo,1)
[Oct 30 11:11:41] DEBUG[6193][C-00000000] pbx.c: Function result is '+497218198286'
[Oct 30 11:11:41] DEBUG[6193][C-00000000] pbx.c: Launching 'Verbose'
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] pbx.c:     -- Executing [dslinfo@dslinfo:1] Verbose("SIP/arcor.de-00000000", "DSL-Info for +497218198286") in new stack
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] app_verbose.c: DSL-Info for +497218198286
[Oct 30 11:11:41] DEBUG[6193][C-00000000] pbx.c: Launching 'AGI'
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] pbx.c:     -- Executing [dslinfo@dslinfo:2] AGI("SIP/arcor.de-00000000", "dslinfo") in new stack
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] res_agi.c:     -- Launched AGI Script /usr/share/asterisk/agi-bin/dslinfo
[Oct 30 11:11:41] DEBUG[6193][C-00000000] pbx.c: Evaluating 'SIP_HEADER(Call-ID)' (from 'SIP_HEADER(Call-ID)}' len 19)
[Oct 30 11:11:41] DEBUG[6193][C-00000000] pbx.c: Function result is 'SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3'
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: SIP answering channel: SIP/arcor.de-00000000
[Oct 30 11:11:41] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update
[Oct 30 11:11:41] DEBUG[5464] devicestate.c: No provider found, checking channel drivers for SIP - arcor.de
[Oct 30 11:11:41] DEBUG[5464] chan_sip.c: Checking device state for peer arcor.de
[Oct 30 11:11:41] DEBUG[5464] devicestate.c: Changing state for SIP/arcor.de - state 4 (Invalid)
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: Setting framing from config on incoming call
[Oct 30 11:11:41] DEBUG[5464] devicestate.c: device 'SIP/arcor.de' state '4'
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: ** Our capability: (alaw|g726aal2) Video flag: True Text flag: True
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) 
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: -- Done with adding codecs to SDP
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (alaw|g726aal2)
[Oct 30 11:11:41] DEBUG[6193][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for XXX.XXX.XXX.XXX:5060
[Oct 30 11:11:41] DEBUG[5473] chan_sip.c: = Looking for  Call ID: SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3 (Checking From) --From tag SDlgk4501-002262c4 --To-tag as4d67d4b5  
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[Oct 30 11:11:41] DEBUG[5473][C-00000000] chan_sip.c: Stopping retransmission on 'SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3' of Response 1: Match Found
[Oct 30 11:11:41] DEBUG[6193][C-00000000] res_rtp_asterisk.c: 0x2285030 -- Probation learning mode pass with source address XXX.XXX.XXX.XXX:21350
[Oct 30 11:11:41] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from unknown to alaw
[Oct 30 11:11:41] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:11:41] DEBUG[6193][C-00000000] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Oct 30 11:11:41] VERBOSE[6193][C-00000000] file.c:     -- <SIP/arcor.de-00000000> Playing 'Greeting.alaw' (language 'dslinfo')
[Oct 30 11:11:43] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to g726aal2
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from alaw to g726aal2
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: g726aal2 ms: 20 len: 80
[Oct 30 11:11:43] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from g726aal2 to alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:11:43] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to g726aal2
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from alaw to g726aal2
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: g726aal2 ms: 20 len: 80
[Oct 30 11:11:43] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from g726aal2 to alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:11:43] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to g726aal2
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from alaw to g726aal2
[Oct 30 11:11:43] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: g726aal2 ms: 20 len: 80
[Oct 30 11:11:44] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from g726aal2 to alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:11:44] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to g726aal2
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from alaw to g726aal2
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: g726aal2 ms: 20 len: 80
[Oct 30 11:11:44] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from g726aal2 to alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:11:44] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to g726aal2
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from alaw to g726aal2
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: g726aal2 ms: 20 len: 80
[Oct 30 11:11:44] DEBUG[6193][C-00000000] chan_sip.c: Oooh, format changed to alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to read format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] channel.c: Set channel SIP/arcor.de-00000000 to write format alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Ooh, format changed from g726aal2 to alaw
[Oct 30 11:11:44] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Created smoother: format: alaw ms: 20 len: 160
[Oct 30 11:11:46] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Got RTCP report of 108 bytes
[Oct 30 11:11:46] DEBUG[5473] chan_sip.c: = Looking for  Call ID: SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3 (Checking From) --From tag SDlgk4501-002262c4 --To-tag as4d67d4b5  
[Oct 30 11:11:46] DEBUG[5473][C-00000000] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
[Oct 30 11:11:46] DEBUG[5473][C-00000000] netsock2.c: Splitting 'XXX.XXX.XXX.XXX' into...
[Oct 30 11:11:46] DEBUG[5473][C-00000000] netsock2.c: ...host 'XXX.XXX.XXX.XXX' and port ''.
[Oct 30 11:11:46] DEBUG[5473][C-00000000] chan_sip.c: Setting SIP_ALREADYGONE on dialog SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3
[Oct 30 11:11:46] DEBUG[5473][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x227fe28'
[Oct 30 11:11:46] DEBUG[5473][C-00000000] chan_sip.c: Received bye, issuing owner hangup
[Oct 30 11:11:46] DEBUG[5473][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for XXX.XXX.XXX.XXX:5060
[Oct 30 11:11:46] DEBUG[6193][C-00000000] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Oct 30 11:11:46] DEBUG[6193][C-00000000] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Oct 30 11:11:46] DEBUG[6193][C-00000000] res_agi.c: SIP/arcor.de-00000000 hungup
[Oct 30 11:11:46] VERBOSE[6193][C-00000000] res_agi.c:     -- <SIP/arcor.de-00000000>AGI Script dslinfo completed, returning 4
[Oct 30 11:11:46] DEBUG[6193][C-00000000] pbx.c: Spawn extension (dslinfo,dslinfo,2) exited non-zero on 'SIP/arcor.de-00000000'
[Oct 30 11:11:46] VERBOSE[6193][C-00000000] pbx.c:   == Spawn extension (dslinfo, dslinfo, 2) exited non-zero on 'SIP/arcor.de-00000000'
[Oct 30 11:11:46] DEBUG[6193][C-00000000] channel.c: Soft-Hanging up channel 'SIP/arcor.de-00000000'
[Oct 30 11:11:46] DEBUG[6193][C-00000000] channel.c: Hanging up channel 'SIP/arcor.de-00000000'
[Oct 30 11:11:46] DEBUG[6193][C-00000000] chan_sip.c: Hangup call SIP/arcor.de-00000000, SIP callid SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3
[Oct 30 11:11:46] DEBUG[6193][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x227fe28'
[Oct 30 11:11:46] DEBUG[6193][C-00000000] cdr.c: Dropping CDR !
[Oct 30 11:11:46] DEBUG[5464] devicestate.c: No provider found, checking channel drivers for SIP - arcor.de
[Oct 30 11:11:46] DEBUG[5464] chan_sip.c: Checking device state for peer arcor.de
[Oct 30 11:11:46] DEBUG[5464] devicestate.c: Changing state for SIP/arcor.de - state 4 (Invalid)
[Oct 30 11:11:46] DEBUG[5464] devicestate.c: device 'SIP/arcor.de' state '4'
[Oct 30 11:12:18] DEBUG[5473] chan_sip.c: Auto destroying SIP dialog 'SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3'
[Oct 30 11:12:18] DEBUG[5473] chan_sip.c: Destroying SIP dialog SDlgk4501-6b9daefcfdef07424f86494b48645d50-iikcha3
[Oct 30 11:12:18] DEBUG[5473] rtp_engine.c: Destroyed RTP instance '0x227fe28'