****** Test: #2 ****** Result: FAILURE ****** Phone: Landline Home Phone ****** DTMF Entered: 123# ****** DTMF Recieved by Read application: 111111222222333333 ****** Notes: This scenario worked fine in 1.4.24 but is now BROKEN in 1.4.24.1 w/ supplied patch -- Executing [INBOUND@acd_inbound_call:3] Read("SIP/4.68.250.148-07ba26c0", "test") in new stack Got RTP packet from 209.244.16.207:60358 (type 00, seq 064188, ts 1042981551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064189, ts 1042981711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064190, ts 1042981871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064191, ts 1042982031, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064192, ts 1042982191, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064193, ts 1042982351, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064194, ts 1042982511, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064195, ts 1042982671, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064196, ts 1042982831, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064197, ts 1042982991, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064198, ts 1042983151, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064199, ts 1042983311, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064200, ts 1042983471, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064201, ts 1042983631, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064202, ts 1042983791, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064203, ts 1042983951, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064204, ts 1042984111, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064205, ts 1042984271, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064206, ts 1042984431, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064207, ts 1042984591, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064208, ts 1042984751, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064209, ts 1042984911, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064210, ts 1042985071, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064211, ts 1042985231, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064212, ts 1042985391, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064213, ts 1042985551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064214, ts 1042985711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064215, ts 1042985871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064216, ts 1042986031, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064217, ts 1042986191, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064218, ts 1042986351, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064219, ts 1042986511, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064220, ts 1042986671, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064221, ts 1042986831, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064222, ts 1042986991, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064223, ts 1042987151, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064224, ts 1042987311, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064225, ts 1042987471, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064226, ts 1042987631, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064227, ts 1042987791, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064228, ts 1042987951, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064229, ts 1042988111, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064230, ts 1042988271, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064231, ts 1042988431, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064232, ts 1042988591, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064233, ts 1042988751, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064234, ts 1042988911, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064235, ts 1042989071, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064236, ts 1042989231, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064237, ts 1042989391, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064238, ts 1042989551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064239, ts 1042989711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064240, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064240, ts 1042989951, len 000004, mark 1, event 00000001, end 0, duration 00160) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990121, duration=20 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064241, ts 1042989871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064242, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064242, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 00193) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990125, duration=24 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064243, ts 1042990031, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064244, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064244, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 00353) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990145, duration=44 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064245, ts 1042990191, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990191, begin=1042989951, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 44 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064246, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064246, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 00507) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990164, duration=63 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064247, ts 1042990351, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990351, begin=1042989951, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 63 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064248, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064248, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 00664) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990184, duration=83 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064249, ts 1042990511, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990511, begin=1042989951, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 83 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064250, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064250, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 00835) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=83 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990205, duration=104 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064251, ts 1042990671, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990671, begin=1042989951, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 104 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064252, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064252, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 00985) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=104 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990224, duration=123 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064253, ts 1042990831, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990831, begin=1042989951, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 123 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064254, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064254, ts 1042989951, len 000004, mark 0, event 00000001, end 0, duration 01146) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=123 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042989951, timeout=1042990244, duration=143 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064255, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064255, ts 1042989951, len 000004, mark 0, event 00000001, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:766 process_rfc2833: normalend, dur=155 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 155 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064256, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064256, ts 1042989951, len 000004, mark 0, event 00000001, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064257, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064257, ts 1042989951, len 000004, mark 0, event 00000001, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064258, ts 1042989951, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064258, ts 1042989951, len 000004, mark 0, event 00000001, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064259, ts 1042990991, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064260, ts 1042991151, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064261, ts 1042991311, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064262, ts 1042991471, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064263, ts 1042991631, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064264, ts 1042991791, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064265, ts 1042991951, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064266, ts 1042992111, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064267, ts 1042992271, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064268, ts 1042992431, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064269, ts 1042992591, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064270, ts 1042992751, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064271, ts 1042992911, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064272, ts 1042993071, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064273, ts 1042993231, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064274, ts 1042993391, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064275, ts 1042993551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064276, ts 1042993711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064277, ts 1042993871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064278, ts 1042994031, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064279, ts 1042994191, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064280, ts 1042994351, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064281, ts 1042994511, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064282, ts 1042994671, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064283, ts 1042994831, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064284, ts 1042994991, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064285, ts 1042995151, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064286, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064286, ts 1042995391, len 000004, mark 1, event 00000002, end 0, duration 00160) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995561, duration=20 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064287, ts 1042995311, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064288, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064288, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 00193) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995565, duration=24 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064289, ts 1042995471, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064290, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064290, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 00356) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995585, duration=44 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064291, ts 1042995631, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995631, begin=1042995391, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 44 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064292, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064292, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 00510) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995604, duration=63 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064293, ts 1042995791, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995791, begin=1042995391, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 63 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064294, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064294, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 00672) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995625, duration=84 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064295, ts 1042995951, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995951, begin=1042995391, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 84 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064296, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064296, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 00831) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=84 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995644, duration=103 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064297, ts 1042996111, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996111, begin=1042995391, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 103 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064298, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064298, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 00997) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=103 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995665, duration=124 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064299, ts 1042996271, len 000160) [May 4 16:29:39] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996271, begin=1042995391, timeout=0 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 124 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064300, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064300, ts 1042995391, len 000004, mark 0, event 00000002, end 0, duration 01152) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=124 [May 4 16:29:39] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1042995391, timeout=1042995685, duration=144 [May 4 16:29:39] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:39] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064301, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064301, ts 1042995391, len 000004, mark 0, event 00000002, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:29:39] DEBUG[5762]: rtp.c:766 process_rfc2833: normalend, dur=155 [May 4 16:29:39] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 155 ms [May 4 16:29:39] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064302, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064302, ts 1042995391, len 000004, mark 0, event 00000002, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064303, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064303, ts 1042995391, len 000004, mark 0, event 00000002, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064304, ts 1042995391, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064304, ts 1042995391, len 000004, mark 0, event 00000002, end 1, duration 01240) [May 4 16:29:39] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064305, ts 1042996431, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064306, ts 1042996591, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064307, ts 1042996751, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064308, ts 1042996911, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064309, ts 1042997071, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064310, ts 1042997231, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064311, ts 1042997391, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064312, ts 1042997551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064313, ts 1042997711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064314, ts 1042997871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064315, ts 1042998031, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064316, ts 1042998191, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064317, ts 1042998351, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064318, ts 1042998511, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064319, ts 1042998671, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064320, ts 1042998831, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064321, ts 1042998991, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064322, ts 1042999151, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064323, ts 1042999311, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064324, ts 1042999471, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064325, ts 1042999631, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064326, ts 1042999791, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064327, ts 1042999951, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064328, ts 1043000111, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064329, ts 1043000271, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064330, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064330, ts 1043000511, len 000004, mark 1, event 00000003, end 0, duration 00160) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000681, duration=20 [May 4 16:29:40] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:40] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064331, ts 1043000431, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064332, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064332, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 00196) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000685, duration=24 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064333, ts 1043000591, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064334, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064334, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 00355) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000705, duration=44 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064335, ts 1043000751, len 000160) [May 4 16:29:40] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043000751, begin=1043000511, timeout=0 [May 4 16:29:40] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 44 ms [May 4 16:29:40] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064336, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064336, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 00517) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000725, duration=64 [May 4 16:29:40] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:40] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064337, ts 1043000911, len 000160) [May 4 16:29:40] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043000911, begin=1043000511, timeout=0 [May 4 16:29:40] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 64 ms [May 4 16:29:40] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064338, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064338, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 00672) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000745, duration=84 [May 4 16:29:40] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:40] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064339, ts 1043001071, len 000160) [May 4 16:29:40] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001071, begin=1043000511, timeout=0 [May 4 16:29:40] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 84 ms [May 4 16:29:40] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064340, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064340, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 00835) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=84 [May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000765, duration=104 [May 4 16:29:40] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:40] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064341, ts 1043001231, len 000160) [May 4 16:29:40] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001231, begin=1043000511, timeout=0 [May 4 16:29:40] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 104 ms [May 4 16:29:40] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064342, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064342, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 00995) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=104 [May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000785, duration=124 [May 4 16:29:40] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:40] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064343, ts 1043001391, len 000160) [May 4 16:29:40] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001391, begin=1043000511, timeout=0 [May 4 16:29:40] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 124 ms [May 4 16:29:40] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064344, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064344, ts 1043000511, len 000004, mark 0, event 00000003, end 0, duration 01157) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=124 [May 4 16:29:40] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043000511, timeout=1043000805, duration=144 [May 4 16:29:40] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:40] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064345, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064345, ts 1043000511, len 000004, mark 0, event 00000003, end 1, duration 01240) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:29:40] DEBUG[5762]: rtp.c:766 process_rfc2833: normalend, dur=155 [May 4 16:29:40] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 155 ms [May 4 16:29:40] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 101, seq 064346, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064346, ts 1043000511, len 000004, mark 0, event 00000003, end 1, duration 01240) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064347, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064347, ts 1043000511, len 000004, mark 0, event 00000003, end 1, duration 01240) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064348, ts 1043000511, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064348, ts 1043000511, len 000004, mark 0, event 00000003, end 1, duration 01240) [May 4 16:29:40] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064349, ts 1043001551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064350, ts 1043001711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064351, ts 1043001871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064352, ts 1043002031, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064353, ts 1043002191, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064354, ts 1043002351, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064355, ts 1043002511, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064356, ts 1043002671, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064357, ts 1043002831, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064358, ts 1043002991, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064359, ts 1043003151, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064360, ts 1043003311, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064361, ts 1043003471, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064362, ts 1043003631, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064363, ts 1043003791, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064364, ts 1043003951, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064365, ts 1043004111, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064366, ts 1043004271, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064367, ts 1043004431, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064368, ts 1043004591, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064369, ts 1043004751, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064370, ts 1043004911, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064371, ts 1043005071, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064372, ts 1043005231, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064373, ts 1043005391, len 000160) Got RTP packet from 209.244.16.207:60358 (type 00, seq 064374, ts 1043005551, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064375, ts 1043005791, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064375, ts 1043005791, len 000004, mark 1, event 0000000b, end 0, duration 00160) [May 4 16:29:41] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 4 16:29:41] DEBUG[5762]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:29:41] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043005791, timeout=1043005961, duration=20 [May 4 16:29:41] DTMF[5762]: channel.c:2297 __ast_read: DTMF begin '#' received on SIP/4.68.250.148-07ba26c0 [May 4 16:29:41] DTMF[5762]: channel.c:2301 __ast_read: DTMF begin ignored '#' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064376, ts 1043005711, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064377, ts 1043005791, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064377, ts 1043005791, len 000004, mark 0, event 0000000b, end 0, duration 00194) [May 4 16:29:41] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) May 4 16:29:41] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043005791, timeout=1043005965, duration=24 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064378, ts 1043005871, len 000160) Got RTP packet from 209.244.16.207:60358 (type 101, seq 064379, ts 1043005791, len 000004) Got RTP RFC2833 from 209.244.16.207:60358 (type 101, seq 064379, ts 1043005791, len 000004, mark 0, event 0000000b, end 0, duration 00360) [May 4 16:29:41] DEBUG[5762]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) May 4 16:29:41] DEBUG[5762]: rtp.c:795 process_rfc2833: ..... begin=1043005791, timeout=1043005986, duration=45 Got RTP packet from 209.244.16.207:60358 (type 00, seq 064380, ts 1043006031, len 000160) [May 4 16:29:41] DEBUG[5762]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043006031, begin=1043005791, timeout=0 [May 4 16:29:41] DTMF[5762]: channel.c:2229 __ast_read: DTMF end '#' received on SIP/4.68.250.148-07ba26c0, duration 45 ms [May 4 16:29:41] DTMF[5762]: channel.c:2282 __ast_read: DTMF end passthrough '#' on SIP/4.68.250.148-07ba26c0 -- User entered '111111222222333333' ****** Test: #3 ****** Result: FAILURE ****** Phone: Cellular Phone - Blackberry Curve - T-Mobile ****** DTMF Entered: 123# ****** DTMF Recieved by Read application: 111111111111111122222222222223333333333333333 ****** Notes: This scenario did NOT work 1.4.24 and is still BROKEN in 1.4.24.1 w/ supplied patch -- Executing [INBOUND@acd_inbound_call:3] Read("SIP/4.68.250.148-07ba26c0", "test") in new stack Got RTP packet from 63.215.29.129:61642 (type 00, seq 012265, ts 1042981231, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012266, ts 1042981391, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012267, ts 1042981551, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012268, ts 1042981711, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012269, ts 1042981871, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012270, ts 1042982031, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012271, ts 1042982191, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012272, ts 1042982351, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012273, ts 1042982511, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012274, ts 1042982671, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012275, ts 1042982831, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012276, ts 1042982991, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012277, ts 1042983151, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012278, ts 1042983311, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012279, ts 1042983471, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012280, ts 1042983631, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012281, ts 1042983791, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012282, ts 1042983951, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012283, ts 1042984111, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012284, ts 1042984271, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012285, ts 1042984431, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012286, ts 1042984591, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012287, ts 1042984751, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012288, ts 1042984911, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012289, ts 1042985071, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012290, ts 1042985231, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012291, ts 1042985391, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012292, ts 1042985551, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012293, ts 1042985711, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012294, ts 1042985871, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012295, ts 1042986031, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012296, ts 1042986191, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012297, ts 1042986351, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012298, ts 1042986511, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012299, ts 1042986671, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012300, ts 1042986831, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012301, ts 1042986991, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012302, ts 1042987151, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012303, ts 1042987311, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012304, ts 1042987471, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012305, ts 1042987631, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012306, ts 1042987791, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012307, ts 1042987951, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012308, ts 1042988111, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012309, ts 1042988271, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012310, ts 1042988431, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012311, ts 1042988591, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012312, ts 1042988751, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012313, ts 1042988911, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012314, ts 1042989071, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012315, ts 1042989231, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012316, ts 1042989391, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012317, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012317, ts 1042989511, len 000004, mark 1, event 00000001, end 0, duration 00160) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989681, duration=20 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012318, ts 1042989551, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012319, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012319, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 00318) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989700, duration=39 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012320, ts 1042989711, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042989711, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 39 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012321, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012321, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 00483) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989721, duration=60 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012322, ts 1042989871, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042989871, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 60 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012323, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012323, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 00644) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989741, duration=80 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012324, ts 1042990031, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990031, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 80 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012325, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012325, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 00801) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989761, duration=100 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012326, ts 1042990191, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990191, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 100 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012327, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012327, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 00970) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=100 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989782, duration=121 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012328, ts 1042990351, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990351, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 121 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012329, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012329, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 01126) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=121 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989801, duration=140 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012330, ts 1042990511, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990511, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 140 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012331, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012331, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 01290) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=140 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989822, duration=161 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012332, ts 1042990671, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990671, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 161 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012333, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012333, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 01446) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=161 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989841, duration=180 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012334, ts 1042990831, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990831, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 180 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012335, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012335, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 01605) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=180 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989861, duration=200 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012336, ts 1042990991, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042990991, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 200 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012337, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012337, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 01764) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=200 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989881, duration=220 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012338, ts 1042991151, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042991151, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 220 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012339, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012339, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 01920) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=220 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989901, duration=240 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012340, ts 1042991311, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042991311, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 240 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012341, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012341, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 02090) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=240 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989922, duration=261 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012342, ts 1042991471, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042991471, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 261 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012343, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012343, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 02250) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=261 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989942, duration=281 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012344, ts 1042991631, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042991631, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 281 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012345, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012345, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 02400) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=281 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989961, duration=300 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012346, ts 1042991791, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042991791, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 300 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012347, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012347, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 02563) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=300 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042989981, duration=320 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012348, ts 1042991951, len 000160) [May 4 16:32:21] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042991951, begin=1042989511, timeout=0 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 320 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012349, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012349, ts 1042989511, len 000004, mark 0, event 00000001, end 0, duration 02736) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=320 [May 4 16:32:21] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042989511, timeout=1042990003, duration=342 [May 4 16:32:21] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '1' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:21] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012350, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012350, ts 1042989511, len 000004, mark 0, event 00000001, end 1, duration 02737) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [May 4 16:32:21] DEBUG[5769]: rtp.c:766 process_rfc2833: normalend, dur=342 [May 4 16:32:21] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '1' received on SIP/4.68.250.148-07ba26c0, duration 342 ms [May 4 16:32:21] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '1' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012351, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012351, ts 1042989511, len 000004, mark 0, event 00000001, end 1, duration 02737) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012352, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012352, ts 1042989511, len 000004, mark 0, event 00000001, end 1, duration 02737) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012353, ts 1042989511, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012353, ts 1042989511, len 000004, mark 0, event 00000001, end 1, duration 02737) [May 4 16:32:21] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000001 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012354, ts 1042992111, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012355, ts 1042992271, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012356, ts 1042992431, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012357, ts 1042992591, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012358, ts 1042992751, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012359, ts 1042992911, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012360, ts 1042993071, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012361, ts 1042993231, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012362, ts 1042993391, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012363, ts 1042993551, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012364, ts 1042993711, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012365, ts 1042993871, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012366, ts 1042994031, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012367, ts 1042994191, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012368, ts 1042994351, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012369, ts 1042994511, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012370, ts 1042994671, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012371, ts 1042994831, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012372, ts 1042994991, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012373, ts 1042995151, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012374, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012374, ts 1042995271, len 000004, mark 1, event 00000002, end 0, duration 00160) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995441, duration=20 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012375, ts 1042995311, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012376, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012376, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 00319) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995460, duration=39 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012377, ts 1042995471, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995471, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 39 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012378, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012378, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 00497) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995483, duration=62 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012379, ts 1042995631, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995631, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 62 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012380, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012380, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 00648) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995502, duration=81 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012381, ts 1042995791, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995791, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 81 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012382, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012382, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 00804) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=81 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995521, duration=100 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012383, ts 1042995951, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042995951, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 100 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012384, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012384, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 00978) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=100 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995543, duration=122 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012385, ts 1042996111, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996111, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 122 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012386, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012386, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 01136) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=122 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995563, duration=142 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012387, ts 1042996271, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996271, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 142 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012388, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012388, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 01278) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=142 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995580, duration=159 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012389, ts 1042996431, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996431, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 159 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012390, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012390, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 01451) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=159 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995602, duration=181 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012391, ts 1042996591, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996591, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 181 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012392, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012392, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 01604) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=181 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995621, duration=200 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012393, ts 1042996751, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996751, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 200 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012394, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012394, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 01759) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=200 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995640, duration=219 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012395, ts 1042996911, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042996911, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 219 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012396, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012396, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 01924) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=219 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995661, duration=240 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012397, ts 1042997071, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042997071, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 240 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012398, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012398, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 02081) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=240 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995681, duration=260 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012399, ts 1042997231, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1042997231, begin=1042995271, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 260 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012400, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012400, ts 1042995271, len 000004, mark 0, event 00000002, end 0, duration 02257) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=260 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1042995271, timeout=1042995703, duration=282 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '2' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012401, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012401, ts 1042995271, len 000004, mark 0, event 00000002, end 1, duration 02280) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:766 process_rfc2833: normalend, dur=285 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '2' received on SIP/4.68.250.148-07ba26c0, duration 285 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '2' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012402, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012402, ts 1042995271, len 000004, mark 0, event 00000002, end 1, duration 02280) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012403, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012403, ts 1042995271, len 000004, mark 0, event 00000002, end 1, duration 02280) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012404, ts 1042995271, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012404, ts 1042995271, len 000004, mark 0, event 00000002, end 1, duration 02280) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000002 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012405, ts 1042997391, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012406, ts 1042997551, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012407, ts 1042997711, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012408, ts 1042997871, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012409, ts 1042998031, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012410, ts 1042998191, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012411, ts 1042998351, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012412, ts 1042998511, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012413, ts 1042998671, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012414, ts 1042998831, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012415, ts 1042998991, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012416, ts 1042999151, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012417, ts 1042999311, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012418, ts 1042999471, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012419, ts 1042999631, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012420, ts 1042999791, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012421, ts 1042999951, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012422, ts 1043000111, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012423, ts 1043000271, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012424, ts 1043000431, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012425, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012425, ts 1043000551, len 000004, mark 1, event 00000003, end 0, duration 00160) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000721, duration=20 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012426, ts 1043000591, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012427, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012427, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 00325) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000741, duration=40 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012428, ts 1043000751, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043000751, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 40 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012429, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012429, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 00486) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000761, duration=60 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012430, ts 1043000911, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043000911, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 60 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012431, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012431, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 00644) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000781, duration=80 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012432, ts 1043001071, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001071, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 80 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012433, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012433, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 00804) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=80 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000801, duration=100 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012434, ts 1043001231, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001231, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 100 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012435, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012435, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 00960) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=100 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000821, duration=120 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012436, ts 1043001391, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001391, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 120 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012437, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012437, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 01126) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=120 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000841, duration=140 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012438, ts 1043001551, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001551, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 140 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012439, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012439, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 01287) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=140 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000861, duration=160 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012440, ts 1043001711, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001711, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 160 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012441, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012441, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 01446) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=160 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000881, duration=180 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012442, ts 1043001871, len 000160) [May 4 16:32:22] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043001871, begin=1043000551, timeout=0 [May 4 16:32:22] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 180 ms [May 4 16:32:22] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012443, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012443, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 01609) [May 4 16:32:22] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:22] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=180 [May 4 16:32:22] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000902, duration=201 [May 4 16:32:22] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:22] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012444, ts 1043002031, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002031, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 201 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012445, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012445, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 01763) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=201 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000921, duration=220 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012446, ts 1043002191, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002191, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 220 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012447, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012447, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 01917) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=220 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000940, duration=239 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012448, ts 1043002351, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002351, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 239 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012449, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012449, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 02085) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=239 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000961, duration=260 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012450, ts 1043002511, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002511, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 260 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012451, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012451, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 02239) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=260 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043000980, duration=279 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012452, ts 1043002671, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002671, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 279 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012453, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012453, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 02397) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=279 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043001000, duration=299 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012454, ts 1043002831, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002831, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 299 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012455, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012455, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 02555) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=299 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043001020, duration=319 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012456, ts 1043002991, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043002991, begin=1043000551, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 319 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012457, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012457, ts 1043000551, len 000004, mark 0, event 00000003, end 0, duration 02716) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=319 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043000551, timeout=1043001040, duration=339 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '3' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012458, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012458, ts 1043000551, len 000004, mark 0, event 00000003, end 1, duration 02720) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:766 process_rfc2833: normalend, dur=340 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '3' received on SIP/4.68.250.148-07ba26c0, duration 340 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '3' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 101, seq 012459, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012459, ts 1043000551, len 000004, mark 0, event 00000003, end 1, duration 02720) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012460, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012460, ts 1043000551, len 000004, mark 0, event 00000003, end 1, duration 02720) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012461, ts 1043000551, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012461, ts 1043000551, len 000004, mark 0, event 00000003, end 1, duration 02720) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 00000003 (len = 4) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012462, ts 1043003151, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012463, ts 1043003311, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012464, ts 1043003471, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012465, ts 1043003631, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012466, ts 1043003791, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012467, ts 1043003951, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012468, ts 1043004111, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012469, ts 1043004271, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012470, ts 1043004431, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012471, ts 1043004591, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012472, ts 1043004751, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012473, ts 1043004911, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012474, ts 1043005071, len 000160) Got RTP packet from 63.215.29.129:61642 (type 00, seq 012475, ts 1043005231, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012476, ts 1043005351, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012476, ts 1043005351, len 000004, mark 1, event 0000000b, end 0, duration 00160) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:792 process_rfc2833: START, dur=20 [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043005351, timeout=1043005521, duration=20 [May 4 16:32:23] DTMF[5769]: channel.c:2297 __ast_read: DTMF begin '#' received on SIP/4.68.250.148-07ba26c0 [May 4 16:32:23] DTMF[5769]: channel.c:2301 __ast_read: DTMF begin ignored '#' on SIP/4.68.250.148-07ba26c0 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012477, ts 1043005391, len 000160) Got RTP packet from 63.215.29.129:61642 (type 101, seq 012478, ts 1043005351, len 000004) Got RTP RFC2833 from 63.215.29.129:61642 (type 101, seq 012478, ts 1043005351, len 000004, mark 0, event 0000000b, end 0, duration 00332) [May 4 16:32:23] DEBUG[5769]: rtp.c:729 process_rfc2833: - RTP 2833 Event: 0000000b (len = 4) [May 4 16:32:23] DEBUG[5769]: rtp.c:795 process_rfc2833: ..... begin=1043005351, timeout=1043005542, duration=41 Got RTP packet from 63.215.29.129:61642 (type 00, seq 012479, ts 1043005551, len 000160) [May 4 16:32:23] DEBUG[5769]: rtp.c:1331 ast_rtp_read: TIMEOUT now=1043005551, begin=1043005351, timeout=0 [May 4 16:32:23] DTMF[5769]: channel.c:2229 __ast_read: DTMF end '#' received on SIP/4.68.250.148-07ba26c0, duration 41 ms [May 4 16:32:23] DTMF[5769]: channel.c:2282 __ast_read: DTMF end passthrough '#' on SIP/4.68.250.148-07ba26c0 -- User entered '111111111111111122222222222223333333333333333'