; to test chanspy/record bug simulation:
; Use the following sip, iax and extensions.conf files
; 1. Call 600 from the sip phone registered as 200 (I used xlite)
; 2. Call 420 from the iax phone registered as ongs ( I used Mozphone)
; 3. debug messages show frames being dropped during Record().
;================================================
;This is the extensions.conf file
[general]
clearglobalvars=yes
[test]
exten => s,1,Answer
exten => s,n,Wait(5)
exten => s,n,Set(spyonChannel=${CHANNEL},g)
exten => s,n,NoOp(${spyonChannel})
exten => s,n,Playback(/var/lib/asterisk/sounds/demo-thanks)
exten => s,n,Record(/tmp/1.gsm,3.5,30)
exten => s,n,Playback(/tmp/1)
exten => s,n,Set(spyonChannel="",g)
exten => s,n,Hangup
[InternalPhones]
;600
exten => 600,1,Goto(test,s,1)
;ChanSpy
exten => 420,1,Answer
exten => 420,n,NoOp(${spyonChannel})
exten => 420,n,ChanSpy(${spyonChannel},qv(4))
exten => 420,n,HangUp
;================================================
;This is the sip.conf
[general]
port = 5060
bindaddr = 0.0.0.0
disallow=all
allow=ulaw
allow=alaw
allow=g729
allow=g723
allow=gsm
[200]
type=friend
username=200
secret=123456
host=dynamic
context=InternalPhones
nat=yes
;================================================
;This is the iax.conf
[general]
port=4569
bandwidth=low
disallow=all
allow=gsm
jitterbuffer=yes
[ongs]
type=friend
context=InternalPhones
secret=1234
host=dynamic
allow=gsm
auth=plaintext,md5,rsa
callerid=ongs
;================================================
;This is part of the debug log
[Mar 19 07:14:44] DEBUG[23625] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Setting NAT on RTP to Off
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Allocating new SIP dialog for MGI4NjIyODk0Zjg0Y2U1NzRjOTEyNzJhODUwMmI1YTA. - INVITE (With RTP)
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Setting NAT on RTP to On
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Stopping retransmission on 'MGI4NjIyODk0Zjg0Y2U1NzRjOTEyNzJhODUwMmI1YTA.' of Response 1: Match Found
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Setting NAT on RTP to On
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: We're settling with these formats: 0xe (gsm|ulaw|alaw)
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Checking SIP call limits for device 200
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: build_route: Contact hop: <sip:200@192.168.1.34:28034>
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: SIP/200-08ee4020: New call is still down.... Trying... 
[Mar 19 07:17:36] DEBUG[23900] pbx.c: Launching 'Goto'
[Mar 19 07:17:36] DEBUG[23900] pbx.c: Launching 'Answer'
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: SIP answering channel: SIP/200-08ee4020
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Setting framing from config on incoming call
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: ** Our capability: 0xe (gsm|ulaw|alaw) Video flag: True Text flag: True
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: ** Our prefcodec: 0x0 (nothing) 
[Mar 19 07:17:36] DEBUG[23900] pbx.c: Launching 'Wait'
[Mar 19 07:17:36] DEBUG[23900] app_queue.c: Device 'SIP/200' changed to state '1' (Not in use)
[Mar 19 07:17:36] DEBUG[23900] app_queue.c: Device 'SIP/200' changed to state '1' (Not in use)
[Mar 19 07:17:36] DEBUG[23900] rtp.c: Got RTCP report of 132 bytes
[Mar 19 07:17:36] DEBUG[23900] chan_sip.c: Stopping retransmission on 'MGI4NjIyODk0Zjg0Y2U1NzRjOTEyNzJhODUwMmI1YTA.' of Response 2: Match Found
[Mar 19 07:17:40] DEBUG[23900] rtp.c: Got RTCP report of 152 bytes
[Mar 19 07:17:41] DEBUG[23900] pbx.c: Launching 'Set'
[Mar 19 07:17:41] DEBUG[23900] pbx.c: Launching 'NoOp'
[Mar 19 07:17:41] DEBUG[23900] pbx.c: Launching 'Playback'
[Mar 19 07:17:41] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to write format gsm
[Mar 19 07:17:41] DEBUG[23900] rtp.c: Ooh, format changed from unknown to ulaw
[Mar 19 07:17:41] DEBUG[23900] rtp.c: Created smoother: format: 4 ms: 20 len: 160
[Mar 19 07:17:43] DEBUG[23900] pbx.c: Launching 'Answer'
[Mar 19 07:17:43] DEBUG[23900] chan_iax2.c: Answering IAX2 call
[Mar 19 07:17:43] DEBUG[23900] channel.c: Avoiding initial deadlock for channel '0x8ef8510'
[Mar 19 07:17:43] DEBUG[23900] pbx.c: Launching 'NoOp'
[Mar 19 07:17:43] DEBUG[23900] pbx.c: Launching 'ChanSpy'
[Mar 19 07:17:43] DEBUG[23900] channel.c: Set channel IAX2/dilip-1 to write format slin
[Mar 19 07:17:43] DEBUG[23900] chan_iax2.c: Ooh, voice format changed to 2
[Mar 19 07:17:43] DEBUG[23900] channel.c: Set channel IAX2/dilip-1 to read format gsm
[Mar 19 07:17:43] DEBUG[23900] channel.c: Spy ChanSpy added to channel SIP/200-08ee4020
[Mar 19 07:17:43] DEBUG[23900] channel.c: Building translator from ulaw to SLINEAR for spies on channel SIP/200-08ee4020
[Mar 19 07:17:43] DEBUG[23900] channel.c: Building translator from ulaw to SLINEAR for spies on channel SIP/200-08ee4020
[Mar 19 07:17:43] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:17:46] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to write format ulaw
[Mar 19 07:17:46] DEBUG[23900] pbx.c: Launching 'Record'
[Mar 19 07:17:46] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to write format gsm
[Mar 19 07:17:47] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:17:47] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to write format ulaw
[Mar 19 07:17:47] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to read format slin
[Mar 19 07:17:47] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:17:47] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:07] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:07] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:07] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
;LOTS MORE OF THE SAME MESSAGE HERE...........................
[Mar 19 07:18:17] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Spy 'ChanSpy' on channel 'SIP/200-08ee4020' read queue too long, dropping frames
[Mar 19 07:18:18] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to read format ulaw
[Mar 19 07:18:18] DEBUG[23900] pbx.c: Launching 'Playback'
[Mar 19 07:18:18] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to write format gsm
[Mar 19 07:18:21] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:24] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:28] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:31] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:32] DEBUG[23900] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots
[Mar 19 07:18:34] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:38] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:41] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:43] DEBUG[23900] chan_iax2.c: Packet arrived out of order (expecting 17, got 16) (frametype = 6, subclass = 3)
[Mar 19 07:18:43] DEBUG[23900] chan_iax2.c: Acking anyway
[Mar 19 07:18:44] DEBUG[23900] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots
[Mar 19 07:18:45] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:48] DEBUG[23900] rtp.c: Got RTCP report of 176 bytes
[Mar 19 07:18:49] DEBUG[23900] channel.c: Set channel SIP/200-08ee4020 to write format ulaw
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Launching 'Set'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Launching 'Hangup'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Spawn extension (test,s,9) exited non-zero on 'SIP/200-08ee4020'
[Mar 19 07:18:49] DEBUG[23900] channel.c: Spy ChanSpy removed from channel SIP/200-08ee4020
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '"200" <200>'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '200'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is 's'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is 'test'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is 'SIP/200-08ee4020'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is ''
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is 'Hangup'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is ''
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '2007-03-19 07:17:36'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '2007-03-19 07:17:36'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '2007-03-19 07:18:49'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '73'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '73'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is 'ANSWERED'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is 'DOCUMENTATION'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is ''
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is '1174268856.0'
[Mar 19 07:18:49] DEBUG[23900] pbx.c: Function result is ''
[Mar 19 07:18:49] DEBUG[23900] channel.c: Hanging up channel 'SIP/200-08ee4020'
[Mar 19 07:18:49] DEBUG[23900] chan_sip.c: Hangup call SIP/200-08ee4020, SIP callid MGI4NjIyODk0Zjg0Y2U1NzRjOTEyNzJhODUwMmI1YTA.)