pbx:~ # tail -f /var/log/asterisk/full
[Nov 25 16:03:34] DEBUG[17318] chan_mobile.c: Checking device state for device SGH-F200
[Nov 25 16:03:34] DEBUG[17318] chan_mobile.c: Checking device state for device SGH-F200
[Nov 25 16:03:37] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,2]
[Nov 25 16:03:37] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,2]
[Nov 25 16:03:51] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1]
[Nov 25 16:03:51] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1]
[Nov 25 16:03:52] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3]
[Nov 25 16:03:52] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3]
[Nov 25 16:03:58] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1]
[Nov 25 16:03:58] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1]
[Nov 25 16:04:02] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3]
[Nov 25 16:04:02] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3]
[Nov 25 16:04:04] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1]
[Nov 25 16:04:04] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1]
[Nov 25 16:04:06] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3]
[Nov 25 16:04:06] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3]
[Nov 25 16:04:16] VERBOSE[17318] logger.c:   == Using TOS bits 0
[Nov 25 16:04:16] VERBOSE[17318] logger.c:   == Using CoS mark 5
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: Setting NAT on RTP to Off
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: Allocating new SIP dialog for 72e517f4538bb6cc4ecef81810eb791a@sipgate.de - INVITE (With RTP)
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: Setting NAT on RTP to Off
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: Checking SIP call limits for device 1837993
[Nov 25 16:04:16] DEBUG[17318] pbx.c: Launching 'Answer'
[Nov 25 16:04:16] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:1] Answer("SIP/1837993-08216e98", "") in new stack
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: SIP answering channel: SIP/1837993-08216e98
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: Setting framing from config on incoming call
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: ** Our capability: 0x10e (gsm|ulaw|alaw|g729) Video flag: True Text flag: True
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: ** Our prefcodec: 0x0 (nothing)
[Nov 25 16:04:16] DEBUG[17318] pbx.c: Launching 'Wait'
[Nov 25 16:04:16] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:2] Wait("SIP/1837993-08216e98", "1") in new stack
[Nov 25 16:04:16] DEBUG[17318] chan_sip.c: Stopping retransmission on '72e517f4538bb6cc4ecef81810eb791a@sipgate.de' of Response 102: Match Found
[Nov 25 16:04:16] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1]
[Nov 25 16:04:16] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1]
[Nov 25 16:04:17] DEBUG[17318] pbx.c: Launching 'Verbose'
[Nov 25 16:04:17] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:3] Verbose("SIP/1837993-08216e98", "7001") in new stack
[Nov 25 16:04:17] VERBOSE[17318] logger.c: 7001
[Nov 25 16:04:17] DEBUG[17318] pbx.c: Launching 'Verbose'
[Nov 25 16:04:17] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:4] Verbose("SIP/1837993-08216e98", "") in new stack
[Nov 25 16:04:17] DEBUG[17318] pbx.c: Launching 'Authenticate'
[Nov 25 16:04:17] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:5] Authenticate("SIP/1837993-08216e98", "1234") in new stack
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from unknown to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] VERBOSE[17318] logger.c:     -- <SIP/1837993-08216e98> Playing 'agent-pass.gsm' (language 'en')
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:17] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:17] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:17] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:18] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:18] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:18] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from ulaw to alaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 8 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Ooh, format changed from alaw to ulaw
[Nov 25 16:04:19] DEBUG[17318] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Nov 25 16:04:19] DEBUG[17318] chan_sip.c: Oooh, format changed to 8
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:19] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format ulaw
[Nov 25 16:04:20] DEBUG[17318] chan_sip.c: Oooh, format changed to 4
[Nov 25 16:04:20] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to read format ulaw
[Nov 25 16:04:20] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format ulaw
[Nov 25 16:04:21] DEBUG[17318] rtp.c: Sending dtmf: 49 (1), at 217.10.68.74
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] DEBUG[17318] rtp.c: Sending dtmf: 49 (1), at 217.10.68.74
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: 1 (49) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] DEBUG[17318] rtp.c: Sending dtmf: 50 (2), at 217.10.68.74
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] DEBUG[17318] rtp.c: Sending dtmf: 50 (2), at 217.10.68.74
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: 2 (50) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:21] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3]
[Nov 25 16:04:22] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3]
[Nov 25 16:04:22] DEBUG[17318] rtp.c: Sending dtmf: 51 (3), at 217.10.68.74
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] DEBUG[17318] rtp.c: Sending dtmf: 51 (3), at 217.10.68.74
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: 3 (51) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] DEBUG[17318] rtp.c: Sending dtmf: 52 (4), at 217.10.68.74
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] DEBUG[17318] rtp.c: Sending dtmf: 52 (4), at 217.10.68.74
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: 4 (52) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:22] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:25] DEBUG[17318] rtp.c: Sending dtmf: 35 (#), at 217.10.68.74
[Nov 25 16:04:25] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:25] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:25] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:25] DEBUG[17318] rtp.c: Sending dtmf: 35 (#), at 217.10.68.74
[Nov 25 16:04:25] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: # (35) ] [SIP/1837993-08216e98]
[Nov 25 16:04:25] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:25] VERBOSE[17318] logger.c:     -- <SIP/1837993-08216e98> Playing 'auth-thankyou.gsm' (language 'en')
[Nov 25 16:04:25] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:25] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:26] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format ulaw
[Nov 25 16:04:26] DEBUG[17318] pbx.c: Launching 'BackGround'
[Nov 25 16:04:26] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:6] BackGround("SIP/1837993-08216e98", "vm-enter-num-to-call") in new stack
[Nov 25 16:04:26] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:26] VERBOSE[17318] logger.c:     -- <SIP/1837993-08216e98> Playing 'vm-enter-num-to-call.gsm' (language 'en')
[Nov 25 16:04:28] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format ulaw
[Nov 25 16:04:28] DEBUG[17318] pbx.c: Launching 'DISA'
[Nov 25 16:04:28] VERBOSE[17318] logger.c:     -- Executing [7001@incoming_sipgate:7] DISA("SIP/1837993-08216e98", "no-password,phones,"sipgate" <7001>") in new stack
[Nov 25 16:04:28] DEBUG[17318] app_disa.c: Digittimeout: 5000
[Nov 25 16:04:28] DEBUG[17318] app_disa.c: Responsetimeout: 10000
[Nov 25 16:04:28] DEBUG[17318] app_disa.c: Mailbox:
[Nov 25 16:04:28] DEBUG[17318] app_disa.c: Context: phones
[Nov 25 16:04:28] DEBUG[17318] app_disa.c: DISA no-password login success
[Nov 25 16:04:28] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format slin
[Nov 25 16:04:30] DEBUG[17318] rtp.c: Sending dtmf: 53 (5), at 217.10.68.74
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] DEBUG[17318] rtp.c: Sending dtmf: 53 (5), at 217.10.68.74
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: 5 (53) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format ulaw
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] DEBUG[17318] rtp.c: Sending dtmf: 53 (5), at 217.10.68.74
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] DEBUG[17318] rtp.c: Sending dtmf: 53 (5), at 217.10.68.74
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: DTMF End (1) SUBCLASS: 5 (53) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] DEBUG[17318] pbx.c: Launching 'Answer'
[Nov 25 16:04:30] VERBOSE[17318] logger.c:     -- Executing [55@phones:1] Answer("SIP/1837993-08216e98", "") in new stack
[Nov 25 16:04:30] DEBUG[17318] pbx.c: Launching 'Playback'
[Nov 25 16:04:30] VERBOSE[17318] logger.c:     -- Executing [55@phones:2] Playback("SIP/1837993-08216e98", "demo-congrats") in new stack
[Nov 25 16:04:30] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format gsm
[Nov 25 16:04:30] VERBOSE[17318] logger.c:     -- <SIP/1837993-08216e98> Playing 'demo-congrats.gsm' (language 'en')
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:30] VERBOSE[17318] logger.c: << [ TYPE: Null Frame (5) SUBCLASS: N/A (0) ] [SIP/1837993-08216e98]
[Nov 25 16:04:32] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1]
[Nov 25 16:04:32] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1]
[Nov 25 16:04:33] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,0]
[Nov 25 16:04:33] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,0]
[Nov 25 16:04:34] DEBUG[17318] channel.c: Set channel SIP/1837993-08216e98 to write format ulaw
[Nov 25 16:04:34] DEBUG[17318] pbx.c: Spawn extension (phones,55,2) exited non-zero on 'SIP/1837993-08216e98'
[Nov 25 16:04:34] VERBOSE[17318] logger.c:   == Spawn extension (phones, 55, 2) exited non-zero on 'SIP/1837993-08216e98'
[Nov 25 16:04:34] DEBUG[17318] channel.c: Soft-Hanging up channel 'SIP/1837993-08216e98'
[Nov 25 16:04:34] DEBUG[17318] channel.c: Hanging up channel 'SIP/1837993-08216e98'
[Nov 25 16:04:34] DEBUG[17318] chan_sip.c: Hangup call SIP/1837993-08216e98, SIP callid 72e517f4538bb6cc4ecef81810eb791a@sipgate.de
[Nov 25 16:04:34] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,2]
[Nov 25 16:04:34] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,2]