[Sep 29 18:34:32] DEBUG[23724] manager.c: Manager received command 'Login'
[Sep 29 18:34:32] DEBUG[23724] manager.c: Manager received command 'Command'
[Sep 29 18:34:32] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:32] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:32] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 579314d81c38eed353a1fd85002a67ef@217.69.26.155 - OPTIONS (No RTP)
[Sep 29 18:34:32] DEBUG[23655] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Sep 29 18:34:32] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 217.69.26.155:5060
[Sep 29 18:34:32] DEBUG[23655] chan_sip.c: SIP message could not be handled, bad request: 579314d81c38eed353a1fd85002a67ef@217.69.26.155
[Sep 29 18:34:42] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:42] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:42] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 61fbd086601567ff7f9a5b887b4b97c4@217.69.26.153 - OPTIONS (No RTP)
[Sep 29 18:34:42] DEBUG[23655] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Sep 29 18:34:42] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 217.69.26.153:5060
[Sep 29 18:34:42] DEBUG[23655] chan_sip.c: SIP message could not be handled, bad request: 61fbd086601567ff7f9a5b887b4b97c4@217.69.26.153
[Sep 29 18:34:43] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:43] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:43] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 78cd07c051f6c64d162fdb6d4ef8ba53@217.69.26.154 - OPTIONS (No RTP)
[Sep 29 18:34:43] DEBUG[23655] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Sep 29 18:34:43] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 217.69.26.154:5060
[Sep 29 18:34:43] DEBUG[23655] chan_sip.c: SIP message could not be handled, bad request: 78cd07c051f6c64d162fdb6d4ef8ba53@217.69.26.154
[Sep 29 18:34:44] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Setting NAT on RTP to Off
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 3a689c2b1150636278bdc02a75860877@217.69.26.155 - INVITE (With RTP)
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Setting NAT on RTP to Off
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing session-level SDP o=florent 430153782 430153782 IN IP4 217.69.26.155... UNSUPPORTED.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing session-level SDP s=THECALLR PBX... UNSUPPORTED.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing session-level SDP c=IN IP4 217.69.26.155... OK.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:10 L16/8000... OK.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: We're settling with these formats: 0x40 (slin)
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Checking SIP call limits for device
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Updating call counter for incoming call
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: *** Our native formats are 0x40 (slin)
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: *** Joint capabilities are 0x40 (slin)
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: *** Our capabilities are 0x40 (slin)
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x40 (slin)
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: This channel will not be able to handle video.
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: build_route: Record-Route hop: <sip:217.69.26.165;lr=on>
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: SIP/proxy-00000001: New call is still down.... Trying...
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'Set'
[Sep 29 18:34:44] DEBUG[23645] devicestate.c: No provider found, checking channel drivers for SIP - proxy
[Sep 29 18:34:44] DEBUG[23645] chan_sip.c: Checking device state for peer proxy
[Sep 29 18:34:44] DEBUG[23645] devicestate.c: Changing state for SIP/proxy - state 1 (Not in use)
[Sep 29 18:34:44] DEBUG[23645] devicestate.c: device 'SIP/proxy' state '1'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'Goto'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'AGI'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Function result is '10'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Expression result is '1'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Function result is '1'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Expression result is '1'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'GotoIf'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'NoOp'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'NoOp'
[Sep 29 18:34:44] DEBUG[23732] pbx.c: Launching 'MeetMe'
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: SIP answering channel: SIP/proxy-00000001
[Sep 29 18:34:44] DEBUG[23732] rtp.c: Setting the marker bit due to a source update
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: Setting framing from config on incoming call
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: ** Our capability: 0x40 (slin) Video flag: True Text flag: True
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: ** Our prefcodec: 0x0 (nothing)
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: -- Done with adding codecs to SDP
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: Done building SDP. Settling with this capability: 0x40 (slin)
[Sep 29 18:34:44] DEBUG[23732] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:34:44] DEBUG[23645] devicestate.c: No provider found, checking channel drivers for SIP - proxy
[Sep 29 18:34:44] DEBUG[23645] chan_sip.c: Checking device state for peer proxy
[Sep 29 18:34:44] DEBUG[23645] devicestate.c: Changing state for SIP/proxy - state 1 (Not in use)
[Sep 29 18:34:44] DEBUG[23645] devicestate.c: device 'SIP/proxy' state '1'
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[Sep 29 18:34:44] DEBUG[23655] chan_sip.c: Stopping retransmission on '3a689c2b1150636278bdc02a75860877@217.69.26.155' of Response 102: Match Found
[Sep 29 18:34:44] DEBUG[23732] app_meetme.c: The requested confno is '2'?
[Sep 29 18:34:44] DEBUG[23732] app_meetme.c: Building dynamic conference '2'
[Sep 29 18:34:44] DEBUG[23732] devicestate.c: device 'meetme:2' state '2'
[Sep 29 18:34:44] DEBUG[23732] app_meetme.c: Placed channel SIP/proxy-00000001 in DAHDI conf 1023
[Sep 29 18:34:44] DEBUG[23732] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Sep 29 18:34:44] DEBUG[23732] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Sep 29 18:34:44] DEBUG[23732] rtp.c: Ooh, format changed from unknown to slin
[Sep 29 18:34:44] DEBUG[23732] rtp.c: Created smoother: format: 64 ms: 20 len: 320
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 6229dd775934d1177bb66f6257c7a8bf@172.16.1.36 - OPTIONS (No RTP)
[Sep 29 18:34:47] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Initializing initreq for method OPTIONS - callid 00848a000d1e529d794c84527a390093@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Stopping retransmission on '00848a000d1e529d794c84527a390093@172.16.1.36' of Request 102: Match Found
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Destroying SIP dialog 00848a000d1e529d794c84527a390093@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 718f2f091af0ed5e507e33094a4edb69@172.16.1.36 - OPTIONS (No RTP)
[Sep 29 18:34:47] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Initializing initreq for method OPTIONS - callid 610b1fd743340ac23f7a74f569cfc6d6@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.69.26.155:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 3ccb6f135f7a36ee7b375d1c7240a10e@172.16.1.36 - OPTIONS (No RTP)
[Sep 29 18:34:47] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Initializing initreq for method OPTIONS - callid 08bbb5fd6db8b3d70bcf42bf735d38b5@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.69.26.153:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Stopping retransmission on '610b1fd743340ac23f7a74f569cfc6d6@172.16.1.36' of Request 102: Match Found
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Destroying SIP dialog 610b1fd743340ac23f7a74f569cfc6d6@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Stopping retransmission on '08bbb5fd6db8b3d70bcf42bf735d38b5@172.16.1.36' of Request 102: Match Found
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Destroying SIP dialog 08bbb5fd6db8b3d70bcf42bf735d38b5@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 4f3200dc5ff148492a34cecc67b00f30@172.16.1.36 - OPTIONS (No RTP)
[Sep 29 18:34:47] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Initializing initreq for method OPTIONS - callid 5f9934ee59bfb8e2038de3e6398dcdc2@172.16.1.36
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 217.69.26.154:5060
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Stopping retransmission on '5f9934ee59bfb8e2038de3e6398dcdc2@172.16.1.36' of Request 102: Match Found
[Sep 29 18:34:47] DEBUG[23655] chan_sip.c: Destroying SIP dialog 5f9934ee59bfb8e2038de3e6398dcdc2@172.16.1.36
[Sep 29 18:34:49] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:34:54] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:34:59] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:03] DEBUG[23850] manager.c: Manager received command 'Login'
[Sep 29 18:35:03] DEBUG[23850] manager.c: Manager received command 'Command'
[Sep 29 18:35:04] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:04] DEBUG[23655] chan_sip.c: Auto destroying SIP dialog '579314d81c38eed353a1fd85002a67ef@217.69.26.155'
[Sep 29 18:35:04] DEBUG[23655] chan_sip.c: Destroying SIP dialog 579314d81c38eed353a1fd85002a67ef@217.69.26.155
[Sep 29 18:35:09] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:12] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Setting NAT on RTP to Off
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 24f6201d2d1b91f364a6a34f167f6551@217.69.26.153 - INVITE (With RTP)
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Setting NAT on RTP to Off
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing session-level SDP o=florent 263577282 263577282 IN IP4 217.69.26.153... UNSUPPORTED.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing session-level SDP s=THECALLR PBX... UNSUPPORTED.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing session-level SDP c=IN IP4 217.69.26.153... OK.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:10 L16/8000... OK.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: We're settling with these formats: 0x40 (slin)
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Checking SIP call limits for device
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Updating call counter for incoming call
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: *** Our native formats are 0x40 (slin)
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: *** Joint capabilities are 0x40 (slin)
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: *** Our capabilities are 0x40 (slin)
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x40 (slin)
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: This channel will not be able to handle video.
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: build_route: Record-Route hop: <sip:217.69.26.165;lr=on>
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: SIP/proxy-00000002: New call is still down.... Trying...
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:35:12] DEBUG[23645] devicestate.c: No provider found, checking channel drivers for SIP - proxy
[Sep 29 18:35:12] DEBUG[23645] chan_sip.c: Checking device state for peer proxy
[Sep 29 18:35:12] DEBUG[23645] devicestate.c: Changing state for SIP/proxy - state 1 (Not in use)
[Sep 29 18:35:12] DEBUG[23645] devicestate.c: device 'SIP/proxy' state '1'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'Set'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'Goto'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'AGI'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Function result is '10'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Expression result is '1'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Function result is '1'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Expression result is '1'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'GotoIf'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'NoOp'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'NoOp'
[Sep 29 18:35:12] DEBUG[23969] pbx.c: Launching 'MeetMe'
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: SIP answering channel: SIP/proxy-00000002
[Sep 29 18:35:12] DEBUG[23969] rtp.c: Setting the marker bit due to a source update
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: Setting framing from config on incoming call
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: ** Our capability: 0x40 (slin) Video flag: True Text flag: True
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: ** Our prefcodec: 0x0 (nothing)
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: -- Done with adding codecs to SDP
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: Done building SDP. Settling with this capability: 0x40 (slin)
[Sep 29 18:35:12] DEBUG[23969] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:35:12] DEBUG[23645] devicestate.c: No provider found, checking channel drivers for SIP - proxy
[Sep 29 18:35:12] DEBUG[23645] chan_sip.c: Checking device state for peer proxy
[Sep 29 18:35:12] DEBUG[23645] devicestate.c: Changing state for SIP/proxy - state 1 (Not in use)
[Sep 29 18:35:12] DEBUG[23645] devicestate.c: device 'SIP/proxy' state '1'
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[Sep 29 18:35:12] DEBUG[23655] chan_sip.c: Stopping retransmission on '24f6201d2d1b91f364a6a34f167f6551@217.69.26.153' of Response 102: Match Found
[Sep 29 18:35:12] DEBUG[23969] app_meetme.c: The requested confno is '2'?
[Sep 29 18:35:12] DEBUG[23969] app_meetme.c: Does conf 2 match 2?
[Sep 29 18:35:12] DEBUG[23969] rtp.c: Ooh, format changed from unknown to slin
[Sep 29 18:35:12] DEBUG[23969] rtp.c: Created smoother: format: 64 ms: 20 len: 320
[Sep 29 18:35:12] DEBUG[23969] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second
[Sep 29 18:35:14] DEBUG[23655] chan_sip.c: Auto destroying SIP dialog '61fbd086601567ff7f9a5b887b4b97c4@217.69.26.153'
[Sep 29 18:35:14] DEBUG[23655] chan_sip.c: Destroying SIP dialog 61fbd086601567ff7f9a5b887b4b97c4@217.69.26.153
[Sep 29 18:35:14] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:14] DEBUG[23969] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Sep 29 18:35:14] DEBUG[23969] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Sep 29 18:35:14] DEBUG[23969] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Sep 29 18:35:15] DEBUG[23655] chan_sip.c: Auto destroying SIP dialog '78cd07c051f6c64d162fdb6d4ef8ba53@217.69.26.154'
[Sep 29 18:35:15] DEBUG[23655] chan_sip.c: Destroying SIP dialog 78cd07c051f6c64d162fdb6d4ef8ba53@217.69.26.154
[Sep 29 18:35:17] DEBUG[23969] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 52 (4), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 52 (4), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000004 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 50 (2), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 50 (2), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000002 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 48 (0), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 48 (0), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 48 (0), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: Sending dtmf: 48 (0), at 217.69.26.153
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:18] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000000 (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: Sending dtmf: 55 (7), at 217.69.26.153
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: Sending dtmf: 55 (7), at 217.69.26.153
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 00000007 (len = 4)
[Sep 29 18:35:19] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: Sending dtmf: 35 (#), at 217.69.26.153
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[Sep 29 18:35:19] DEBUG[23969] rtp.c: Sending dtmf: 35 (#), at 217.69.26.153
[Sep 29 18:35:19] DEBUG[23969] app_meetme.c: Placed channel SIP/proxy-00000002 in DAHDI conf 1023
[Sep 29 18:35:19] DEBUG[23669] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[Sep 29 18:35:19] DEBUG[23669] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[Sep 29 18:35:19] DEBUG[23969] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Sep 29 18:35:19] DEBUG[23969] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Sep 29 18:35:20] DEBUG[23969] rtp.c: Difference is 40984, ms is 5143
[Sep 29 18:35:22] DEBUG[23969] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:24] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:27] DEBUG[23969] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:29] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:32] DEBUG[23969] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:32] DEBUG[23655] acl.c: Found IP address for this socket
[Sep 29 18:35:32] DEBUG[23655] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 172.16.1.36:5060
[Sep 29 18:35:32] DEBUG[23655] chan_sip.c: Allocating new SIP dialog for 7bd4952f42d5de63384c075e109a61ed@217.69.26.155 - OPTIONS (No RTP)
[Sep 29 18:35:32] DEBUG[23655] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Sep 29 18:35:32] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 217.69.26.155:5060
[Sep 29 18:35:32] DEBUG[23655] chan_sip.c: SIP message could not be handled, bad request: 7bd4952f42d5de63384c075e109a61ed@217.69.26.155
[Sep 29 18:35:33] DEBUG[23973] manager.c: Manager received command 'Login'
[Sep 29 18:35:33] DEBUG[23973] manager.c: Manager received command 'Command'
[Sep 29 18:35:34] DEBUG[23732] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:37] DEBUG[23655] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
[Sep 29 18:35:37] DEBUG[23655] chan_sip.c: Setting SIP_ALREADYGONE on dialog 3a689c2b1150636278bdc02a75860877@217.69.26.155
[Sep 29 18:35:37] DEBUG[23655] chan_sip.c: Received bye, issuing owner hangup
[Sep 29 18:35:37] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:35:37] DEBUG[23732] pbx.c: Spawn extension (CONFERENCE,s,3) exited non-zero on 'SIP/proxy-00000001'
[Sep 29 18:35:37] DEBUG[23732] channel.c: Soft-Hanging up channel 'SIP/proxy-00000001'
[Sep 29 18:35:37] DEBUG[23732] channel.c: Hanging up channel 'SIP/proxy-00000001'
[Sep 29 18:35:37] DEBUG[23732] chan_sip.c: Hangup call SIP/proxy-00000001, SIP callid 3a689c2b1150636278bdc02a75860877@217.69.26.155
[Sep 29 18:35:37] DEBUG[23645] devicestate.c: No provider found, checking channel drivers for SIP - proxy
[Sep 29 18:35:37] DEBUG[23645] chan_sip.c: Checking device state for peer proxy
[Sep 29 18:35:37] DEBUG[23645] devicestate.c: Changing state for SIP/proxy - state 1 (Not in use)
[Sep 29 18:35:37] DEBUG[23645] devicestate.c: device 'SIP/proxy' state '1'
[Sep 29 18:35:37] DEBUG[23969] rtp.c: Got RTCP report of 64 bytes
[Sep 29 18:35:37] DEBUG[23655] chan_sip.c: Destroying SIP dialog 3a689c2b1150636278bdc02a75860877@217.69.26.155
[Sep 29 18:35:39] DEBUG[23655] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
[Sep 29 18:35:39] DEBUG[23655] chan_sip.c: Setting SIP_ALREADYGONE on dialog 24f6201d2d1b91f364a6a34f167f6551@217.69.26.153
[Sep 29 18:35:39] DEBUG[23655] chan_sip.c: Received bye, issuing owner hangup
[Sep 29 18:35:39] DEBUG[23655] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 217.69.26.165:5060
[Sep 29 18:35:39] DEBUG[23969] devicestate.c: device 'meetme:2' state '1'
[Sep 29 18:35:39] DEBUG[23969] pbx.c: Spawn extension (CONFERENCE,s,3) exited non-zero on 'SIP/proxy-00000002'
[Sep 29 18:35:39] DEBUG[23969] channel.c: Soft-Hanging up channel 'SIP/proxy-00000002'
[Sep 29 18:35:39] DEBUG[23969] channel.c: Hanging up channel 'SIP/proxy-00000002'
[Sep 29 18:35:39] DEBUG[23969] chan_sip.c: Hangup call SIP/proxy-00000002, SIP callid 24f6201d2d1b91f364a6a34f167f6551@217.69.26.153
[Sep 29 18:35:39] DEBUG[23645] devicestate.c: No provider found, checking channel drivers for SIP - proxy
[Sep 29 18:35:39] DEBUG[23645] chan_sip.c: Checking device state for peer proxy
[Sep 29 18:35:39] DEBUG[23645] devicestate.c: Changing state for SIP/proxy - state 1 (Not in use)
[Sep 29 18:35:39] DEBUG[23645] devicestate.c: device 'SIP/proxy' state '1'