[2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4961 sip_alloc: Allocating new SIP dialog for 60aae82a-47d2fa21-848f4abc@192.168.30.194 - INVITE (With RTP) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3044 do_setnat: Setting NAT on RTP to Off [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3049 do_setnat: Setting NAT on VRTP to Off [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3054 do_setnat: Setting NAT on UDPTL to Off [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '60aae82a-47d2fa21-848f4abc@192.168.30.194' of Response 1: Match Found [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3044 do_setnat: Setting NAT on RTP to Off [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3049 do_setnat: Setting NAT on VRTP to Off [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3054 do_setnat: Setting NAT on UDPTL to Off [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5770 process_sdp: Processing session-level SDP v=0... UNSUPPORTED. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5770 process_sdp: Processing session-level SDP o=- 1297104790 1297104790 IN IP4 192.168.30.194... UNSUPPORTED. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5770 process_sdp: Processing session-level SDP s=Polycom IP Phone... UNSUPPORTED. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5770 process_sdp: Processing session-level SDP c=IN IP4 192.168.30.194... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5770 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5770 process_sdp: Processing session-level SDP a=sendrecv... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5918 process_sdp: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5918 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5918 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5918 process_sdp: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5918 process_sdp: Processing media-level (audio) SDP a=fmtp:18 annexb=no... UNSUPPORTED. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5918 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:5943 process_sdp: T38 state changed to 0 on channel [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:6076 process_sdp: We're settling with these formats: 0x4 (ulaw) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:15733 handle_request_invite: Checking SIP call limits for device 6010 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3548 update_call_counter: Updating call counter for incoming call [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:3625 update_call_counter: Call from peer '6010' is 1 out of 8 [2011-02-07 13:53:39] DEBUG[4046]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [2011-02-07 13:53:39] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] DEBUG[4022]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 2 (In use) [2011-02-07 13:53:39] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] DEBUG[4074]: app_queue.c:718 handle_statechange: Device 'SIP/6010' changed to state '2' (In use) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4403 sip_new: *** Our native formats are 0x4 (ulaw) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4404 sip_new: *** Joint capabilities are 0x4 (ulaw) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4405 sip_new: *** Our capabilities are 0x4 (ulaw) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4406 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4429 sip_new: This channel will not be able to handle video. [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:9307 build_route: build_route: Contact hop: [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:15837 handle_request_invite: SIP/6010-00000022: New call is still down.... Trying... [2011-02-07 13:53:39] DEBUG[4046]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [2011-02-07 13:53:39] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] DEBUG[4022]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 2 (In use) [2011-02-07 13:53:39] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] DEBUG[4074]: app_queue.c:718 handle_statechange: Device 'SIP/6010' changed to state '2' (In use) [2011-02-07 13:53:39] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '6010' [2011-02-07 13:53:39] DEBUG[32231]: pbx.c:1873 pbx_extension_helper: Launching 'Set' [2011-02-07 13:53:39] -- Executing [*844@default-super:1] Set("SIP/6010-00000022", "GROUP(OUTGOING)=6010") in new stack [2011-02-07 13:53:39] DEBUG[32231]: pbx.c:1873 pbx_extension_helper: Launching 'Answer' [2011-02-07 13:53:39] -- Executing [*844@default-super:2] Answer("SIP/6010-00000022", "") in new stack [2011-02-07 13:53:39] DEBUG[32231]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [2011-02-07 13:53:39] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] DEBUG[4022]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 2 (In use) [2011-02-07 13:53:39] DEBUG[4074]: app_queue.c:718 handle_statechange: Device 'SIP/6010' changed to state '2' (In use) [2011-02-07 13:53:39] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] DEBUG[32231]: chan_sip.c:4028 sip_answer: SIP answering channel: SIP/6010-00000022 [2011-02-07 13:53:39] DEBUG[32231]: rtp.c:2119 ast_rtp_new_source: Setting the marker bit due to a source update [2011-02-07 13:53:39] DEBUG[32231]: chan_sip.c:7595 transmit_response_with_sdp: Setting framing from config on incoming call [2011-02-07 13:53:39] DEBUG[32231]: chan_sip.c:7313 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: True [2011-02-07 13:53:39] DEBUG[32231]: chan_sip.c:7314 add_sdp: ** Our prefcodec: 0x0 (nothing) [2011-02-07 13:53:39] DEBUG[32231]: chan_sip.c:7438 add_sdp: -- Done with adding codecs to SDP [2011-02-07 13:53:39] DEBUG[32231]: chan_sip.c:7537 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) [2011-02-07 13:53:39] DEBUG[32231]: pbx.c:1873 pbx_extension_helper: Launching 'Set' [2011-02-07 13:53:39] -- Executing [*844@default-super:3] Set("SIP/6010-00000022", "TIMEOUT(digit)=5") in new stack [2011-02-07 13:53:39] -- Digit timeout set to 5 [2011-02-07 13:53:39] DEBUG[4080]: manager.c:2277 process_message: Manager received command 'GetVar' [2011-02-07 13:53:39] DEBUG[32231]: pbx.c:1873 pbx_extension_helper: Launching 'Set' [2011-02-07 13:53:39] -- Executing [*844@default-super:4] Set("SIP/6010-00000022", "TIMEOUT(response)=10") in new stack [2011-02-07 13:53:39] -- Response timeout set to 10 [2011-02-07 13:53:39] DEBUG[32231]: pbx.c:1873 pbx_extension_helper: Launching 'VoiceMailMain' [2011-02-07 13:53:39] -- Executing [*844@default-super:5] VoiceMailMain("SIP/6010-00000022", "@default") in new stack [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] Extension Changed 6010[default-local] new state InUse for Notify User 6001 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] Extension Changed 6010[default-local] new state InUse for Notify User 6002 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:39] Extension Changed 6010[default-local] new state InUse for Notify User 6010 [2011-02-07 13:53:39] DEBUG[32231]: rtp.c:2970 ast_rtp_write: Ooh, format changed from unknown to ulaw [2011-02-07 13:53:39] DEBUG[32231]: rtp.c:2987 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160 [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034147, ts 000160, len 000160) [2011-02-07 13:53:39] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 160 sample intervals [2011-02-07 13:53:39] -- Playing 'vm-login' (language 'en') [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2307 __sip_ack: Acked pending invite 273 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '3e264a0e-4e371587-aaf032f4@192.168.30.107' of Request 273: Match Found [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034148, ts 000320, len 000160) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2307 __sip_ack: Acked pending invite 205 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '27293cf9a5a0b079' of Request 205: Match Found [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034149, ts 000480, len 000160) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '60aae82a-47d2fa21-848f4abc@192.168.30.194' of Response 2: Match Found [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034150, ts 000640, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034151, ts 000800, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034152, ts 000960, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034153, ts 001120, len 000160) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2307 __sip_ack: Acked pending invite 273 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '2c3d0a30-8fbbe50f-c1bc16f2@192.168.30.194' of Request 273: Match Found [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041294, ts 2089408508, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034154, ts 001280, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041295, ts 2089408668, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034155, ts 001440, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041296, ts 2089408828, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034156, ts 001600, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041297, ts 2089408988, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034157, ts 001760, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041298, ts 2089409148, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034158, ts 001920, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041299, ts 2089409308, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034159, ts 002080, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041300, ts 2089409468, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034160, ts 002240, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041301, ts 2089409628, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034161, ts 002400, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041302, ts 2089409788, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034162, ts 002560, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041303, ts 2089409948, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034163, ts 002720, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041304, ts 2089410108, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034164, ts 002880, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041305, ts 2089410268, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034165, ts 003040, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041306, ts 2089410428, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034166, ts 003200, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041307, ts 2089410588, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034167, ts 003360, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041308, ts 2089410748, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034168, ts 003520, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041309, ts 2089410908, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034169, ts 003680, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041310, ts 2089411068, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034170, ts 003840, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041311, ts 2089411228, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034171, ts 004000, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041312, ts 2089411388, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034172, ts 004160, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041313, ts 2089411548, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034173, ts 004320, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041314, ts 2089411708, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034174, ts 004480, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041315, ts 2089411868, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034175, ts 004640, len 000160) [2011-02-07 13:53:39] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 192.168.0.0 [2011-02-07 13:53:39] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 10.0.0.0 [2011-02-07 13:53:39] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 172.16.0.0 [2011-02-07 13:53:39] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 169.254.0.0 [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:4961 sip_alloc: Allocating new SIP dialog for 5f5285e9-22fee478-e7c3721b@192.168.30.86 - SUBSCRIBE (No RTP) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:9307 build_route: build_route: Contact hop: [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041316, ts 2089412028, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034176, ts 004800, len 000160) [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:9245 build_route: build_route: Retaining previous route: [2011-02-07 13:53:39] DEBUG[4046]: chan_sip.c:16949 handle_request_subscribe: Received SIP subscribe for unknown event package: missed-call-summary [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041317, ts 2089412188, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034177, ts 004960, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041318, ts 2089412348, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034178, ts 005120, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041319, ts 2089412508, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034179, ts 005280, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041320, ts 2089412668, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034180, ts 005440, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041321, ts 2089412828, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034181, ts 005600, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041322, ts 2089412988, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034182, ts 005760, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041323, ts 2089413148, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034183, ts 005920, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041324, ts 2089413308, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034184, ts 006080, len 000160) [2011-02-07 13:53:39] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041325, ts 2089413468, len 000160) [2011-02-07 13:53:39] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034185, ts 006240, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041326, ts 2089413628, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034186, ts 006400, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041327, ts 2089413788, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034187, ts 006560, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041328, ts 2089413948, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034188, ts 006720, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041329, ts 2089414108, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034189, ts 006880, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041330, ts 2089414268, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034190, ts 007040, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041331, ts 2089414428, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034191, ts 007200, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041332, ts 2089414588, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034192, ts 007360, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041333, ts 2089414748, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034193, ts 007520, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041334, ts 2089414908, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034194, ts 007680, len 000160) [2011-02-07 13:53:40] DEBUG[4046]: chan_sip.c:2229 __sip_autodestruct: Auto destroying SIP dialog 'e27a35a6-56688de2-6bbf54@66.252.148.166' [2011-02-07 13:53:40] DEBUG[4046]: chan_sip.c:3657 sip_destroy: Destroying SIP dialog e27a35a6-56688de2-6bbf54@66.252.148.166 [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041335, ts 2089415068, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034195, ts 007840, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041336, ts 2089415228, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034196, ts 008000, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041337, ts 2089415388, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034197, ts 008160, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041338, ts 2089415548, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034198, ts 008320, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041339, ts 2089415708, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034199, ts 008480, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041340, ts 2089415868, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034200, ts 008640, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041341, ts 2089416028, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034201, ts 008800, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041342, ts 2089416188, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034202, ts 008960, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041343, ts 2089416348, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034203, ts 009120, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041344, ts 2089416508, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034204, ts 009280, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041345, ts 2089416668, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034205, ts 009440, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041346, ts 2089416828, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034206, ts 009600, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041347, ts 2089416988, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034207, ts 009760, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041348, ts 2089417148, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034208, ts 009920, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041349, ts 2089417308, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034209, ts 010080, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041350, ts 2089417468, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034210, ts 010240, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041351, ts 2089417628, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034211, ts 010400, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041352, ts 2089417788, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034212, ts 010560, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041353, ts 2089417948, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034213, ts 010720, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041354, ts 2089418108, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034214, ts 010880, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041355, ts 2089418268, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034215, ts 011040, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041356, ts 2089418428, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034216, ts 011200, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041357, ts 2089418588, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034217, ts 011360, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041358, ts 2089418748, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034218, ts 011520, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041359, ts 2089418908, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034219, ts 011680, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041360, ts 2089419068, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034220, ts 011840, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041361, ts 2089419228, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034221, ts 012000, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041362, ts 2089419388, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034222, ts 012160, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041363, ts 2089419548, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034223, ts 012320, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041364, ts 2089419708, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034224, ts 012480, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041365, ts 2089419868, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034225, ts 012640, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041366, ts 2089420028, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034226, ts 012800, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041367, ts 2089420188, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034227, ts 012960, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041368, ts 2089420348, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034228, ts 013120, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041369, ts 2089420508, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034229, ts 013280, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041370, ts 2089420668, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034230, ts 013440, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041371, ts 2089420828, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034231, ts 013600, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041372, ts 2089420988, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034232, ts 013760, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041373, ts 2089421148, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034233, ts 013920, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041374, ts 2089421308, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034234, ts 014080, len 000160) [2011-02-07 13:53:40] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041375, ts 2089421468, len 000160) [2011-02-07 13:53:40] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034235, ts 014240, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041376, ts 2089421628, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034236, ts 014400, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041377, ts 2089421788, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034237, ts 014560, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041378, ts 2089421948, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034238, ts 014720, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041379, ts 2089422108, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034239, ts 014880, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041380, ts 2089422268, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034240, ts 015040, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041381, ts 2089422428, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034241, ts 015200, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041382, ts 2089422588, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034242, ts 015360, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041383, ts 2089422748, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034243, ts 015520, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041384, ts 2089422908, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034244, ts 015680, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041385, ts 2089423068, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034245, ts 015840, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041386, ts 2089423228, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034246, ts 016000, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041387, ts 2089423388, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034247, ts 016160, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041388, ts 2089423548, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034248, ts 016320, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041389, ts 2089423708, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034249, ts 016480, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041390, ts 2089423868, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034250, ts 016640, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041391, ts 2089424028, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034251, ts 016800, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041392, ts 2089424188, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034252, ts 016960, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041393, ts 2089424348, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034253, ts 017120, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041394, ts 2089424508, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034254, ts 017280, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041395, ts 2089424668, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034255, ts 017440, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041396, ts 2089424828, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034256, ts 017600, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041397, ts 2089424988, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034257, ts 017760, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041398, ts 2089425148, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034258, ts 017920, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041399, ts 2089425308, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034259, ts 018080, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041400, ts 2089425468, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034260, ts 018240, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041401, ts 2089425628, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034261, ts 018400, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041402, ts 2089425788, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034262, ts 018560, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041403, ts 2089425948, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034263, ts 018720, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041404, ts 2089426108, len 000160) [2011-02-07 13:53:41] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034264, ts 018880, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041405, ts 2089426268, len 000160) [2011-02-07 13:53:41] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 2 sample intervals [2011-02-07 13:53:41] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:41] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:41] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041406, ts 2089426428, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041407, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041407, ts 2089426588, len 000004, mark 1, event 00000006, end 0, duration 00160) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 54 (6), at 192.168.30.194 [2011-02-07 13:53:41] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '6' received on SIP/6010-00000022 [2011-02-07 13:53:41] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '6' on SIP/6010-00000022 [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041408, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041408, ts 2089426588, len 000004, mark 0, event 00000006, end 0, duration 00320) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041409, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041409, ts 2089426588, len 000004, mark 0, event 00000006, end 0, duration 00480) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041410, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041410, ts 2089426588, len 000004, mark 0, event 00000006, end 0, duration 00640) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041411, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041411, ts 2089426588, len 000004, mark 0, event 00000006, end 1, duration 01120) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 54 (6), at 192.168.30.194 [2011-02-07 13:53:41] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '6' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:41] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '6' on SIP/6010-00000022 [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041412, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041412, ts 2089426588, len 000004, mark 0, event 00000006, end 1, duration 01120) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041413, ts 2089426588, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041413, ts 2089426588, len 000004, mark 0, event 00000006, end 1, duration 01120) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041414, ts 2089427708, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041415, ts 2089427868, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041416, ts 2089428028, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041417, ts 2089428188, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041418, ts 2089428348, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041419, ts 2089428508, len 000160) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041420, ts 2089428668, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041420, ts 2089428668, len 000004, mark 1, event 00000000, end 0, duration 00160) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:41] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '0' received on SIP/6010-00000022 [2011-02-07 13:53:41] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '0' on SIP/6010-00000022 [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041421, ts 2089428668, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041421, ts 2089428668, len 000004, mark 0, event 00000000, end 0, duration 00320) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041422, ts 2089428668, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041422, ts 2089428668, len 000004, mark 0, event 00000000, end 0, duration 00480) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041423, ts 2089428668, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041423, ts 2089428668, len 000004, mark 0, event 00000000, end 0, duration 00640) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041424, ts 2089428668, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041424, ts 2089428668, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:41] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '0' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:41] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '0' on SIP/6010-00000022 [2011-02-07 13:53:41] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041425, ts 2089428668, len 000004) [2011-02-07 13:53:41] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041425, ts 2089428668, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:41] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041426, ts 2089428668, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041426, ts 2089428668, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041427, ts 2089429788, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041428, ts 2089429948, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041429, ts 2089430108, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041430, ts 2089430268, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041431, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041431, ts 2089430428, len 000004, mark 1, event 00000001, end 0, duration 00160) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.30.194 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '1' received on SIP/6010-00000022 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '1' on SIP/6010-00000022 [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041432, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041432, ts 2089430428, len 000004, mark 0, event 00000001, end 0, duration 00320) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041433, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041433, ts 2089430428, len 000004, mark 0, event 00000001, end 0, duration 00480) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041434, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041434, ts 2089430428, len 000004, mark 0, event 00000001, end 0, duration 00640) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041435, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041435, ts 2089430428, len 000004, mark 0, event 00000001, end 0, duration 00800) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041436, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041436, ts 2089430428, len 000004, mark 0, event 00000001, end 1, duration 01280) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.30.194 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '1' received on SIP/6010-00000022, duration 160 ms [2011-02-07 13:53:42] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '1' on SIP/6010-00000022 [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041437, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041437, ts 2089430428, len 000004, mark 0, event 00000001, end 1, duration 01280) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041438, ts 2089430428, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041438, ts 2089430428, len 000004, mark 0, event 00000001, end 1, duration 01280) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041439, ts 2089431708, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041440, ts 2089431868, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041441, ts 2089432028, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041442, ts 2089432188, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041443, ts 2089432348, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041444, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041444, ts 2089432508, len 000004, mark 1, event 00000000, end 0, duration 00160) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '0' received on SIP/6010-00000022 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '0' on SIP/6010-00000022 [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041445, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041445, ts 2089432508, len 000004, mark 0, event 00000000, end 0, duration 00320) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041446, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041446, ts 2089432508, len 000004, mark 0, event 00000000, end 0, duration 00480) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041447, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041447, ts 2089432508, len 000004, mark 0, event 00000000, end 0, duration 00640) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041448, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041448, ts 2089432508, len 000004, mark 0, event 00000000, end 1, duration 01040) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '0' received on SIP/6010-00000022, duration 130 ms [2011-02-07 13:53:42] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '0' on SIP/6010-00000022 [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041449, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041449, ts 2089432508, len 000004, mark 0, event 00000000, end 1, duration 01040) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041450, ts 2089432508, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041450, ts 2089432508, len 000004, mark 0, event 00000000, end 1, duration 01040) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041451, ts 2089433628, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041452, ts 2089433788, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041453, ts 2089433948, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041454, ts 2089434108, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041455, ts 2089434268, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041456, ts 2089434428, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041457, ts 2089434588, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041458, ts 2089434748, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041459, ts 2089434908, len 000160) [2011-02-07 13:53:42] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.150 with 192.168.0.0 [2011-02-07 13:53:42] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.150 with 10.0.0.0 [2011-02-07 13:53:42] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.150 with 172.16.0.0 [2011-02-07 13:53:42] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.150 with 169.254.0.0 [2011-02-07 13:53:42] DEBUG[4046]: chan_sip.c:4961 sip_alloc: Allocating new SIP dialog for 8876754a-11da9ab-c8682c6c@192.168.30.150 - SUBSCRIBE (No RTP) [2011-02-07 13:53:42] DEBUG[4046]: chan_sip.c:9307 build_route: build_route: Contact hop: [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041460, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041460, ts 2089435068, len 000004, mark 1, event 0000000b, end 0, duration 00160) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 35 (#), at 192.168.30.194 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '#' received on SIP/6010-00000022 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '#' on SIP/6010-00000022 [2011-02-07 13:53:42] DEBUG[4046]: chan_sip.c:9245 build_route: build_route: Retaining previous route: [2011-02-07 13:53:42] DEBUG[4046]: chan_sip.c:16949 handle_request_subscribe: Received SIP subscribe for unknown event package: missed-call-summary [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041461, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041461, ts 2089435068, len 000004, mark 0, event 0000000b, end 0, duration 00320) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041462, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041462, ts 2089435068, len 000004, mark 0, event 0000000b, end 0, duration 00480) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041463, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041463, ts 2089435068, len 000004, mark 0, event 0000000b, end 0, duration 00640) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041464, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041464, ts 2089435068, len 000004, mark 0, event 0000000b, end 1, duration 01040) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 35 (#), at 192.168.30.194 [2011-02-07 13:53:42] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '#' received on SIP/6010-00000022, duration 130 ms [2011-02-07 13:53:42] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '#' on SIP/6010-00000022 [2011-02-07 13:53:42] DEBUG[32231]: app_voicemail.c:7656 vm_authenticate: Before find user for mailbox 6010 [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:2797 ast_rtp_raw_write: Difference is 9432, ms is 1199 [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034265, ts 028472, len 000160) [2011-02-07 13:53:42] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 160 sample intervals [2011-02-07 13:53:42] -- Playing 'vm-password' (language 'en') [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041465, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041465, ts 2089435068, len 000004, mark 0, event 0000000b, end 1, duration 01040) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034266, ts 028632, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041466, ts 2089435068, len 000004) [2011-02-07 13:53:42] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041466, ts 2089435068, len 000004, mark 0, event 0000000b, end 1, duration 01040) [2011-02-07 13:53:42] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034267, ts 028792, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041467, ts 2089436188, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034268, ts 028952, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041468, ts 2089436348, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034269, ts 029112, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041469, ts 2089436508, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034270, ts 029272, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041470, ts 2089436668, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034271, ts 029432, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041471, ts 2089436828, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034272, ts 029592, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041472, ts 2089436988, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034273, ts 029752, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041473, ts 2089437148, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034274, ts 029912, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041474, ts 2089437308, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034275, ts 030072, len 000160) [2011-02-07 13:53:42] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041475, ts 2089437468, len 000160) [2011-02-07 13:53:42] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034276, ts 030232, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041476, ts 2089437628, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034277, ts 030392, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041477, ts 2089437788, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034278, ts 030552, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041478, ts 2089437948, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034279, ts 030712, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041479, ts 2089438108, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034280, ts 030872, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041480, ts 2089438268, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034281, ts 031032, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041481, ts 2089438428, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034282, ts 031192, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041482, ts 2089438588, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034283, ts 031352, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041483, ts 2089438748, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034284, ts 031512, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041484, ts 2089438908, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034285, ts 031672, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041485, ts 2089439068, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034286, ts 031832, len 000160) [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:4961 sip_alloc: Allocating new SIP dialog for 4fc4e6fe595dfcc050eff5a7225a8f11@10.0.0.253 - REGISTER (No RTP) [2011-02-07 13:53:43] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 66.252.148.166 with 192.168.0.0 [2011-02-07 13:53:43] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 66.252.148.166 with 10.0.0.0 [2011-02-07 13:53:43] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 66.252.148.166 with 172.16.0.0 [2011-02-07 13:53:43] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 66.252.148.166 with 169.254.0.0 [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:1952 ast_sip_ouraddrfor: Target address 66.252.148.166 is not local, substituting externip [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:8521 transmit_register: Scheduled a registration timeout for voip3.ziptel.ca id #299840 [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041486, ts 2089439228, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034287, ts 031992, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041487, ts 2089439388, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034288, ts 032152, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041488, ts 2089439548, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034289, ts 032312, len 000160) [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '4fc4e6fe595dfcc050eff5a7225a8f11@10.0.0.253' of Request 106: Match Found [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:1762 initialize_initreq: Initializing already initialized SIP dialog 4fc4e6fe595dfcc050eff5a7225a8f11@10.0.0.253 (presumably reinvite) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041489, ts 2089439708, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034290, ts 032472, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041490, ts 2089439868, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034291, ts 032632, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041491, ts 2089440028, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034292, ts 032792, len 000160) [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '4fc4e6fe595dfcc050eff5a7225a8f11@10.0.0.253' of Request 107: Match Found [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:13922 handle_response_register: Registration successful [2011-02-07 13:53:43] DEBUG[4046]: chan_sip.c:13925 handle_response_register: Cancelling timeout 299840 [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041492, ts 2089440188, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034293, ts 032952, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041493, ts 2089440348, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034294, ts 033112, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041494, ts 2089440508, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034295, ts 033272, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041495, ts 2089440668, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034296, ts 033432, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041496, ts 2089440828, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034297, ts 033592, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041497, ts 2089440988, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034298, ts 033752, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041498, ts 2089441148, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034299, ts 033912, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041499, ts 2089441308, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034300, ts 034072, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041500, ts 2089441468, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034301, ts 034232, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041501, ts 2089441628, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034302, ts 034392, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041502, ts 2089441788, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034303, ts 034552, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041503, ts 2089441948, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034304, ts 034712, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041504, ts 2089442108, len 000160) [2011-02-07 13:53:43] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034305, ts 034872, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041505, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041505, ts 2089442268, len 000004, mark 1, event 00000006, end 0, duration 00160) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 54 (6), at 192.168.30.194 [2011-02-07 13:53:43] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '6' received on SIP/6010-00000022 [2011-02-07 13:53:43] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '6' on SIP/6010-00000022 [2011-02-07 13:53:43] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 80 sample intervals [2011-02-07 13:53:43] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:43] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:43] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041506, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041506, ts 2089442268, len 000004, mark 0, event 00000006, end 0, duration 00320) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041507, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041507, ts 2089442268, len 000004, mark 0, event 00000006, end 0, duration 00480) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041508, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041508, ts 2089442268, len 000004, mark 0, event 00000006, end 0, duration 00640) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041509, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041509, ts 2089442268, len 000004, mark 0, event 00000006, end 1, duration 01120) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 54 (6), at 192.168.30.194 [2011-02-07 13:53:43] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '6' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:43] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '6' on SIP/6010-00000022 [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041510, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041510, ts 2089442268, len 000004, mark 0, event 00000006, end 1, duration 01120) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041511, ts 2089442268, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041511, ts 2089442268, len 000004, mark 0, event 00000006, end 1, duration 01120) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041512, ts 2089443388, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041513, ts 2089443548, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041514, ts 2089443708, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041515, ts 2089443868, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041516, ts 2089444028, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041517, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041517, ts 2089444188, len 000004, mark 1, event 00000000, end 0, duration 00160) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:43] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '0' received on SIP/6010-00000022 [2011-02-07 13:53:43] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '0' on SIP/6010-00000022 [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041518, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041518, ts 2089444188, len 000004, mark 0, event 00000000, end 0, duration 00320) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041519, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041519, ts 2089444188, len 000004, mark 0, event 00000000, end 0, duration 00480) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041520, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041520, ts 2089444188, len 000004, mark 0, event 00000000, end 0, duration 00640) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041521, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041521, ts 2089444188, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:43] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '0' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:43] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '0' on SIP/6010-00000022 [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041522, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041522, ts 2089444188, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041523, ts 2089444188, len 000004) [2011-02-07 13:53:43] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041523, ts 2089444188, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:43] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041524, ts 2089445308, len 000160) [2011-02-07 13:53:43] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041525, ts 2089445468, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041526, ts 2089445628, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041527, ts 2089445788, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041528, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041528, ts 2089445948, len 000004, mark 1, event 00000001, end 0, duration 00160) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '1' received on SIP/6010-00000022 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '1' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041529, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041529, ts 2089445948, len 000004, mark 0, event 00000001, end 0, duration 00320) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041530, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041530, ts 2089445948, len 000004, mark 0, event 00000001, end 0, duration 00480) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041531, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041531, ts 2089445948, len 000004, mark 0, event 00000001, end 0, duration 00640) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041532, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041532, ts 2089445948, len 000004, mark 0, event 00000001, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '1' received on SIP/6010-00000022, duration 130 ms [2011-02-07 13:53:44] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '1' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041533, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041533, ts 2089445948, len 000004, mark 0, event 00000001, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041534, ts 2089445948, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041534, ts 2089445948, len 000004, mark 0, event 00000001, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:941 ast_rtcp_read: Got RTCP report of 104 bytes [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041535, ts 2089447068, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041536, ts 2089447228, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041537, ts 2089447388, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041538, ts 2089447548, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041539, ts 2089447708, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041540, ts 2089447868, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041541, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041541, ts 2089448028, len 000004, mark 1, event 00000000, end 0, duration 00160) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '0' received on SIP/6010-00000022 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '0' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041542, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041542, ts 2089448028, len 000004, mark 0, event 00000000, end 0, duration 00320) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041543, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041543, ts 2089448028, len 000004, mark 0, event 00000000, end 0, duration 00480) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041544, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041544, ts 2089448028, len 000004, mark 0, event 00000000, end 0, duration 00640) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041545, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041545, ts 2089448028, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '0' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:44] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '0' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041546, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041546, ts 2089448028, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041547, ts 2089448028, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041547, ts 2089448028, len 000004, mark 0, event 00000000, end 1, duration 01120) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041548, ts 2089449148, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041549, ts 2089449308, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041550, ts 2089449468, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041551, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041551, ts 2089449628, len 000004, mark 1, event 00000006, end 0, duration 00160) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 54 (6), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '6' received on SIP/6010-00000022 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '6' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041552, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041552, ts 2089449628, len 000004, mark 0, event 00000006, end 0, duration 00320) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041553, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041553, ts 2089449628, len 000004, mark 0, event 00000006, end 0, duration 00480) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041554, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041554, ts 2089449628, len 000004, mark 0, event 00000006, end 0, duration 00640) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041555, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041555, ts 2089449628, len 000004, mark 0, event 00000006, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 54 (6), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '6' received on SIP/6010-00000022, duration 130 ms [2011-02-07 13:53:44] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '6' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041556, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041556, ts 2089449628, len 000004, mark 0, event 00000006, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041557, ts 2089449628, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041557, ts 2089449628, len 000004, mark 0, event 00000006, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000006 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041558, ts 2089450748, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041559, ts 2089450908, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041560, ts 2089451068, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041561, ts 2089451228, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041562, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041562, ts 2089451388, len 000004, mark 1, event 00000000, end 0, duration 00160) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '0' received on SIP/6010-00000022 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '0' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041563, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041563, ts 2089451388, len 000004, mark 0, event 00000000, end 0, duration 00320) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041564, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041564, ts 2089451388, len 000004, mark 0, event 00000000, end 0, duration 00480) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041565, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041565, ts 2089451388, len 000004, mark 0, event 00000000, end 0, duration 00640) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041566, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041566, ts 2089451388, len 000004, mark 0, event 00000000, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '0' received on SIP/6010-00000022, duration 130 ms [2011-02-07 13:53:44] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '0' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041567, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041567, ts 2089451388, len 000004, mark 0, event 00000000, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041568, ts 2089451388, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041568, ts 2089451388, len 000004, mark 0, event 00000000, end 1, duration 01040) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041569, ts 2089452508, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041570, ts 2089452668, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041571, ts 2089452828, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041572, ts 2089452988, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041573, ts 2089453148, len 000160) [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041574, ts 2089453308, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041574, ts 2089453308, len 000004, mark 1, event 00000001, end 0, duration 00160) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.30.194 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '1' received on SIP/6010-00000022 [2011-02-07 13:53:44] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '1' on SIP/6010-00000022 [2011-02-07 13:53:44] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041575, ts 2089453308, len 000004) [2011-02-07 13:53:44] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041575, ts 2089453308, len 000004, mark 0, event 00000001, end 0, duration 00320) [2011-02-07 13:53:44] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041576, ts 2089453308, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041576, ts 2089453308, len 000004, mark 0, event 00000001, end 0, duration 00480) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041577, ts 2089453308, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041577, ts 2089453308, len 000004, mark 0, event 00000001, end 0, duration 00640) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041578, ts 2089453308, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041578, ts 2089453308, len 000004, mark 0, event 00000001, end 1, duration 01120) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.30.194 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '1' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:45] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '1' on SIP/6010-00000022 [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041579, ts 2089453308, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041579, ts 2089453308, len 000004, mark 0, event 00000001, end 1, duration 01120) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041580, ts 2089453308, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041580, ts 2089453308, len 000004, mark 0, event 00000001, end 1, duration 01120) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041581, ts 2089454428, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041582, ts 2089454588, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041583, ts 2089454748, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041584, ts 2089454908, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041585, ts 2089455068, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041586, ts 2089455228, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041586, ts 2089455228, len 000004, mark 1, event 00000000, end 0, duration 00160) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '0' received on SIP/6010-00000022 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '0' on SIP/6010-00000022 [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041587, ts 2089455228, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041587, ts 2089455228, len 000004, mark 0, event 00000000, end 0, duration 00320) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041588, ts 2089455228, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041588, ts 2089455228, len 000004, mark 0, event 00000000, end 0, duration 00480) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041589, ts 2089455228, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041589, ts 2089455228, len 000004, mark 0, event 00000000, end 1, duration 00880) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.30.194 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '0' received on SIP/6010-00000022, duration 110 ms [2011-02-07 13:53:45] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '0' on SIP/6010-00000022 [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041590, ts 2089455228, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041590, ts 2089455228, len 000004, mark 0, event 00000000, end 1, duration 00880) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041591, ts 2089455228, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041591, ts 2089455228, len 000004, mark 0, event 00000000, end 1, duration 00880) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041592, ts 2089456188, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041593, ts 2089456348, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041594, ts 2089456508, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041595, ts 2089456668, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041596, ts 2089456828, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041597, ts 2089456988, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041598, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041598, ts 2089457148, len 000004, mark 1, event 0000000b, end 0, duration 00160) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 35 (#), at 192.168.30.194 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2545 __ast_read: DTMF begin '#' received on SIP/6010-00000022 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2549 __ast_read: DTMF begin ignored '#' on SIP/6010-00000022 [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041599, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041599, ts 2089457148, len 000004, mark 0, event 0000000b, end 0, duration 00320) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041600, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041600, ts 2089457148, len 000004, mark 0, event 0000000b, end 0, duration 00480) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041601, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041601, ts 2089457148, len 000004, mark 0, event 0000000b, end 0, duration 00640) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041602, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041602, ts 2089457148, len 000004, mark 0, event 0000000b, end 1, duration 01120) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:636 create_dtmf_frame: Sending dtmf: 35 (#), at 192.168.30.194 [2011-02-07 13:53:45] DTMF[32231]: channel.c:2463 __ast_read: DTMF end '#' received on SIP/6010-00000022, duration 140 ms [2011-02-07 13:53:45] DTMF[32231]: channel.c:2529 __ast_read: DTMF end passthrough '#' on SIP/6010-00000022 [2011-02-07 13:53:45] DEBUG[32231]: app_voicemail.c:7834 vm_execmain: After vm_authenticate [2011-02-07 13:53:45] DEBUG[32231]: app_voicemail.c:7868 vm_execmain: Before open_mailbox [2011-02-07 13:53:45] DEBUG[32231]: app.c:1052 ast_lock_path: Locked path '/var/spool/asterisk/voicemail/default/6010/Old' [2011-02-07 13:53:45] DEBUG[32231]: app.c:1073 ast_unlock_path: Unlocked path '/var/spool/asterisk/voicemail/default/6010/Old' [2011-02-07 13:53:45] DEBUG[32231]: app_voicemail.c:7874 vm_execmain: Number of old messages: 1 [2011-02-07 13:53:45] DEBUG[32231]: app.c:1052 ast_lock_path: Locked path '/var/spool/asterisk/voicemail/default/6010/INBOX' [2011-02-07 13:53:45] DEBUG[32231]: app.c:1073 ast_unlock_path: Unlocked path '/var/spool/asterisk/voicemail/default/6010/INBOX' [2011-02-07 13:53:45] DEBUG[32231]: app_voicemail.c:7881 vm_execmain: Number of new messages: 0 [2011-02-07 13:53:45] DEBUG[32231]: app.c:1052 ast_lock_path: Locked path '/var/spool/asterisk/voicemail/default/6010/Old' [2011-02-07 13:53:45] DEBUG[32231]: app.c:1073 ast_unlock_path: Unlocked path '/var/spool/asterisk/voicemail/default/6010/Old' [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:2797 ast_rtp_raw_write: Difference is 15544, ms is 1963 [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034306, ts 050576, len 000160) [2011-02-07 13:53:45] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 160 sample intervals [2011-02-07 13:53:45] -- Playing 'vm-youhave' (language 'en') [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041603, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041603, ts 2089457148, len 000004, mark 0, event 0000000b, end 1, duration 01120) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034307, ts 050736, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 101, seq 041604, ts 2089457148, len 000004) [2011-02-07 13:53:45] Got RTP RFC2833 from 192.168.30.194:2240 (type 101, seq 041604, ts 2089457148, len 000004, mark 0, event 0000000b, end 1, duration 01120) [2011-02-07 13:53:45] DEBUG[32231]: rtp.c:739 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034308, ts 050896, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041605, ts 2089458268, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034309, ts 051056, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041606, ts 2089458428, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034310, ts 051216, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041607, ts 2089458588, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034311, ts 051376, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041608, ts 2089458748, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034312, ts 051536, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041609, ts 2089458908, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034313, ts 051696, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041610, ts 2089459068, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034314, ts 051856, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041611, ts 2089459228, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034315, ts 052016, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041612, ts 2089459388, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034316, ts 052176, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041613, ts 2089459548, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034317, ts 052336, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041614, ts 2089459708, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034318, ts 052496, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041615, ts 2089459868, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034319, ts 052656, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041616, ts 2089460028, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034320, ts 052816, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041617, ts 2089460188, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034321, ts 052976, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041618, ts 2089460348, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034322, ts 053136, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041619, ts 2089460508, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034323, ts 053296, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041620, ts 2089460668, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034324, ts 053456, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041621, ts 2089460828, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034325, ts 053616, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041622, ts 2089460988, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034326, ts 053776, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041623, ts 2089461148, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034327, ts 053936, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041624, ts 2089461308, len 000160) [2011-02-07 13:53:45] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034328, ts 054096, len 000160) [2011-02-07 13:53:45] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041625, ts 2089461468, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034329, ts 054256, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041626, ts 2089461628, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034330, ts 054416, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041627, ts 2089461788, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034331, ts 054576, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041628, ts 2089461948, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034332, ts 054736, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041629, ts 2089462108, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034333, ts 054896, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041630, ts 2089462268, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034334, ts 055056, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041631, ts 2089462428, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034335, ts 055216, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041632, ts 2089462588, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034336, ts 055376, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041633, ts 2089462748, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034337, ts 055536, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041634, ts 2089462908, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034338, ts 055696, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041635, ts 2089463068, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034339, ts 055856, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041636, ts 2089463228, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034340, ts 056016, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041637, ts 2089463388, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034341, ts 056176, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041638, ts 2089463548, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034342, ts 056336, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041639, ts 2089463708, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034343, ts 056496, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041640, ts 2089463868, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034344, ts 056656, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041641, ts 2089464028, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034345, ts 056816, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041642, ts 2089464188, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034346, ts 056976, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041643, ts 2089464348, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034347, ts 057136, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041644, ts 2089464508, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034348, ts 057296, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041645, ts 2089464668, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034349, ts 057456, len 000160) [2011-02-07 13:53:46] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 139 sample intervals [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041646, ts 2089464828, len 000160) [2011-02-07 13:53:46] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:46] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:46] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034350, ts 057616, len 000160) [2011-02-07 13:53:46] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 160 sample intervals [2011-02-07 13:53:46] -- Playing 'digits/1' (language 'en') [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041647, ts 2089464988, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034351, ts 057776, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041648, ts 2089465148, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034352, ts 057936, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041649, ts 2089465308, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034353, ts 058096, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041650, ts 2089465468, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034354, ts 058256, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041651, ts 2089465628, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034355, ts 058416, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041652, ts 2089465788, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034356, ts 058576, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041653, ts 2089465948, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034357, ts 058736, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041654, ts 2089466108, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034358, ts 058896, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041655, ts 2089466268, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034359, ts 059056, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041656, ts 2089466428, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034360, ts 059216, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041657, ts 2089466588, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034361, ts 059376, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041658, ts 2089466748, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034362, ts 059536, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041659, ts 2089466908, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034363, ts 059696, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041660, ts 2089467068, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034364, ts 059856, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041661, ts 2089467228, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034365, ts 060016, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041662, ts 2089467388, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034366, ts 060176, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041663, ts 2089467548, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034367, ts 060336, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041664, ts 2089467708, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034368, ts 060496, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041665, ts 2089467868, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034369, ts 060656, len 000160) [2011-02-07 13:53:46] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 192.168.0.0 [2011-02-07 13:53:46] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 10.0.0.0 [2011-02-07 13:53:46] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 172.16.0.0 [2011-02-07 13:53:46] DEBUG[4046]: acl.c:370 ast_apply_ha: ##### Testing 192.168.30.86 with 169.254.0.0 [2011-02-07 13:53:46] DEBUG[4046]: chan_sip.c:4961 sip_alloc: Allocating new SIP dialog for 165d670a-fd2f90c5-c4d37724@192.168.30.86 - SUBSCRIBE (No RTP) [2011-02-07 13:53:46] DEBUG[4046]: chan_sip.c:9307 build_route: build_route: Contact hop: [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041666, ts 2089468028, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034370, ts 060816, len 000160) [2011-02-07 13:53:46] DEBUG[4046]: chan_sip.c:9245 build_route: build_route: Retaining previous route: [2011-02-07 13:53:46] DEBUG[4046]: chan_sip.c:16949 handle_request_subscribe: Received SIP subscribe for unknown event package: missed-call-summary [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041667, ts 2089468188, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034371, ts 060976, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041668, ts 2089468348, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034372, ts 061136, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041669, ts 2089468508, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034373, ts 061296, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041670, ts 2089468668, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034374, ts 061456, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041671, ts 2089468828, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034375, ts 061616, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041672, ts 2089468988, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034376, ts 061776, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041673, ts 2089469148, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034377, ts 061936, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041674, ts 2089469308, len 000160) [2011-02-07 13:53:46] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034378, ts 062096, len 000160) [2011-02-07 13:53:46] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041675, ts 2089469468, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034379, ts 062256, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041676, ts 2089469628, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034380, ts 062416, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041677, ts 2089469788, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034381, ts 062576, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041678, ts 2089469948, len 000160) [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 36 sample intervals [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034382, ts 062736, len 000160) [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 160 sample intervals [2011-02-07 13:53:47] -- Playing 'vm-Old' (language 'en') [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041679, ts 2089470108, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034383, ts 062896, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041680, ts 2089470268, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034384, ts 063056, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041681, ts 2089470428, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034385, ts 063216, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041682, ts 2089470588, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034386, ts 063376, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041683, ts 2089470748, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034387, ts 063536, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041684, ts 2089470908, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034388, ts 063696, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041685, ts 2089471068, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034389, ts 063856, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041686, ts 2089471228, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034390, ts 064016, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041687, ts 2089471388, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034391, ts 064176, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041688, ts 2089471548, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034392, ts 064336, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041689, ts 2089471708, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034393, ts 064496, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041690, ts 2089471868, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034394, ts 064656, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041691, ts 2089472028, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034395, ts 064816, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041692, ts 2089472188, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034396, ts 064976, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041693, ts 2089472348, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034397, ts 065136, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041694, ts 2089472508, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034398, ts 065296, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041695, ts 2089472668, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034399, ts 065456, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041696, ts 2089472828, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034400, ts 065616, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041697, ts 2089472988, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034401, ts 065776, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041698, ts 2089473148, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034402, ts 065936, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041699, ts 2089473308, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034403, ts 066096, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041700, ts 2089473468, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034404, ts 066256, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041701, ts 2089473628, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034405, ts 066416, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041702, ts 2089473788, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034406, ts 066576, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041703, ts 2089473948, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034407, ts 066736, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041704, ts 2089474108, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034408, ts 066896, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041705, ts 2089474268, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034409, ts 067056, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041706, ts 2089474428, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034410, ts 067216, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041707, ts 2089474588, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034411, ts 067376, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041708, ts 2089474748, len 000160) [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 38 sample intervals [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034412, ts 067536, len 000160) [2011-02-07 13:53:47] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 160 sample intervals [2011-02-07 13:53:47] -- Playing 'vm-message' (language 'en') [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041709, ts 2089474908, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034413, ts 067696, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041710, ts 2089475068, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034414, ts 067856, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041711, ts 2089475228, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034415, ts 068016, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041712, ts 2089475388, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034416, ts 068176, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041713, ts 2089475548, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034417, ts 068336, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041714, ts 2089475708, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034418, ts 068496, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041715, ts 2089475868, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034419, ts 068656, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041716, ts 2089476028, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034420, ts 068816, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041717, ts 2089476188, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034421, ts 068976, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041718, ts 2089476348, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034422, ts 069136, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041719, ts 2089476508, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034423, ts 069296, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041720, ts 2089476668, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034424, ts 069456, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041721, ts 2089476828, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034425, ts 069616, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041722, ts 2089476988, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034426, ts 069776, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041723, ts 2089477148, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034427, ts 069936, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041724, ts 2089477308, len 000160) [2011-02-07 13:53:47] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034428, ts 070096, len 000160) [2011-02-07 13:53:47] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041725, ts 2089477468, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034429, ts 070256, len 000160) [2011-02-07 13:53:48] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041726, ts 2089477628, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034430, ts 070416, len 000160) [2011-02-07 13:53:48] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041727, ts 2089477788, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034431, ts 070576, len 000160) [2011-02-07 13:53:48] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041728, ts 2089477948, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034432, ts 070736, len 000160) [2011-02-07 13:53:48] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041729, ts 2089478108, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034433, ts 070896, len 000160) [2011-02-07 13:53:48] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041730, ts 2089478268, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034434, ts 071056, len 000160) [2011-02-07 13:53:48] Got RTP packet from 192.168.30.194:2240 (type 00, seq 041731, ts 2089478428, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034435, ts 071216, len 000160) [2011-02-07 13:53:48] Sent RTP packet to 192.168.30.194:2240 (type 00, seq 034436, ts 071376, len 000160) [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:1774 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 60aae82a-47d2fa21-848f4abc@192.168.30.194 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:16669 handle_request_bye: Received bye, issuing owner hangup [2011-02-07 13:53:48] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:48] DEBUG[32231]: channel.c:2038 ast_settimeout: Scheduling timer at 0 sample intervals [2011-02-07 13:53:48] DEBUG[32231]: app.c:1052 ast_lock_path: Locked path '/var/spool/asterisk/voicemail/default/6010/Old' [2011-02-07 13:53:48] DEBUG[32231]: app.c:1073 ast_unlock_path: Unlocked path '/var/spool/asterisk/voicemail/default/6010/Old' [2011-02-07 13:53:48] DEBUG[32231]: app_voicemail.c:4245 run_externnotify: Executing /var/www/scopserv/telephony/scripts/vmnotify default 6010 0& [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:2480 __ast_pbx_run: Spawn extension (default-super,*844,5) exited non-zero on 'SIP/6010-00000022' [2011-02-07 13:53:48] == Spawn extension (default-super, *844, 5) exited non-zero on 'SIP/6010-00000022' [2011-02-07 13:53:48] DEBUG[32231]: channel.c:1603 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/6010-00000022' [2011-02-07 13:53:48] DEBUG[32231]: channel.c:1706 ast_hangup: Hanging up channel 'SIP/6010-00000022' [2011-02-07 13:53:48] DEBUG[32231]: chan_sip.c:3866 sip_hangup: Hangup call SIP/6010-00000022, SIP callid 60aae82a-47d2fa21-848f4abc@192.168.30.194) [2011-02-07 13:53:48] DEBUG[32231]: chan_sip.c:3548 update_call_counter: Updating call counter for incoming call [2011-02-07 13:53:48] DEBUG[32231]: chan_sip.c:3599 update_call_counter: Call from peer '6010' removed from call limit 8 [2011-02-07 13:53:48] DEBUG[32231]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [2011-02-07 13:53:48] DEBUG[32231]: cdr_radius.c:221 radius_log: Unable to create RADIUS record. CDR not recorded! [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '"David Brillert" <6010>' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '6010' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '*844' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is 'default-super' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is 'SIP/6010-00000022' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '(null)' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is 'VoiceMailMain' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '@default' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '2011-02-07 13:53:39' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '2011-02-07 13:53:39' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '2011-02-07 13:53:48' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '9' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '9' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is 'ANSWERED' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '6010' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '1297104819.79' [2011-02-07 13:53:48] DEBUG[32231]: pbx.c:1721 pbx_substitute_variables_helper_full: Function result is '(null)' [2011-02-07 13:53:48] DEBUG[32231]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/6010 [2011-02-07 13:53:48] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] DEBUG[4022]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 1 (Not in use) [2011-02-07 13:53:48] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] DEBUG[4074]: app_queue.c:718 handle_statechange: Device 'SIP/6010' changed to state '1' (Not in use) [2011-02-07 13:53:48] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] DEBUG[4022]: devicestate.c:287 do_state_change: Changing state for SIP/6010 - state 1 (Not in use) [2011-02-07 13:53:48] DEBUG[4022]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] DEBUG[4074]: app_queue.c:718 handle_statechange: Device 'SIP/6010' changed to state '1' (Not in use) [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] Extension Changed 6010[default-local] new state Idle for Notify User 6001 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] Extension Changed 6010[default-local] new state Idle for Notify User 6002 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:17984 sip_devicestate: Checking device state for peer 6010 [2011-02-07 13:53:48] Extension Changed 6010[default-local] new state Idle for Notify User 6010 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:2307 __sip_ack: Acked pending invite 274 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '3e264a0e-4e371587-aaf032f4@192.168.30.107' of Request 274: Match Found [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:2307 __sip_ack: Acked pending invite 274 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '2c3d0a30-8fbbe50f-c1bc16f2@192.168.30.194' of Request 274: Match Found [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:2307 __sip_ack: Acked pending invite 206 [2011-02-07 13:53:48] DEBUG[4046]: chan_sip.c:2339 __sip_ack: Stopping retransmission on '27293cf9a5a0b079' of Request 206: Match Found