[Home]

Summary:ASTERISK-10005: E&M Wink trucks no longer interpret dtmf digits correctly after 1.2.21.1
Reporter:chris dolese (dolesec)Labels:
Date Opened:2007-08-01 14:21:26Date Closed:2007-08-07 12:28:37
Priority:BlockerRegression?No
Status:Closed/CompleteComponents:Channels/chan_zap
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:( 0) 10360.patch
Description:the case in point i have is with a dedicated sprint t1 for our inbound toll free and outbound long distance -- the provider sends DNIS digits down the pipe which we then have to interpret and route based on the digits we see ... ie 800 215-6911 should be translated to 1652; specifically the traslations is

*<10 digit ANI>*<DNIS>*  

so a call from 4235551212 to 800-215-6911 with an assigned DNIS of 1652 should look like

*4235551212*1652*

instead it interprerts garbage and plays "no-service"


here is the output from 4 failed inbound calls a row with 1.2.23 followed by 4 successful inbound calls with 1.2.21.1  - nothing changes but the asterisk v ersion ...

failed calls:
Aug  1 10:57:01 VERBOSE[12797] logger.c:     -- Remote UNIX connection
Aug  1 10:57:23 VERBOSE[12864] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 10:57:24 VERBOSE[12866] logger.c:     -- Starting simple switch on 'Zap/2-1'
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Exception on 14, channel 1
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Exception on 14, channel 1
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Exception on 14, channel 1
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:57:25 WARNING[12864] chan_zap.c: getdtmf on channel 1: Operation now in progress
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 10:57:25 DEBUG[12864] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 10:57:25 VERBOSE[12864] logger.c:     -- Hungup 'Zap/1-1'
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Got event On hook(1) on channel 2 (index 0)
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: disabled echo cancellation on channel 2
Aug  1 10:57:26 WARNING[12866] chan_zap.c: getdtmf on channel 2: Operation now in progress
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Hangup: channel: 2 index = 0, normal = 15, callwait = -1, thirdcall = -1
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: disabled echo cancellation on channel 2
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
Aug  1 10:57:26 DEBUG[12866] chan_zap.c: Updated conferencing on 2, with 0 conference users
Aug  1 10:57:26 VERBOSE[12866] logger.c:     -- Hungup 'Zap/2-1'
Aug  1 10:57:52 VERBOSE[12872] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 10:57:53 VERBOSE[12874] logger.c:     -- Starting simple switch on 'Zap/2-1'
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Exception on 14, channel 1
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Exception on 14, channel 1
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Exception on 14, channel 1
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:57:54 WARNING[12872] chan_zap.c: getdtmf on channel 1: Operation now in progress
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 10:57:54 DEBUG[12872] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 10:57:54 VERBOSE[12872] logger.c:     -- Hungup 'Zap/1-1'
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: DTMF digit: 2 on Zap/2-1
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Exception on 15, channel 2
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Sent deferred digit string:
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: DTMF digit: * on Zap/2-1
Aug  1 10:57:55 DEBUG[12874] chan_zap.c: Enabled echo cancellation on channel 2
Aug  1 10:57:55 VERBOSE[12874] logger.c:   == Unknown extension '2*' in context 'from-pstn' requested
Aug  1 10:57:58 DEBUG[12874] channel.c: Scheduling timer at 160 sample intervals
Aug  1 10:57:58 VERBOSE[12874] logger.c:     -- Playing 'ss-noservice' (language 'en')
Aug  1 10:58:03 DEBUG[12874] channel.c: Scheduling timer at 0 sample intervals
Aug  1 10:58:03 DEBUG[12874] channel.c: Scheduling timer at 0 sample intervals
Aug  1 10:58:03 DEBUG[12874] chan_zap.c: Hangup: channel: 2 index = 0, normal = 15, callwait = -1, thirdcall = -1
Aug  1 10:58:03 DEBUG[12874] chan_zap.c: disabled echo cancellation on channel 2
Aug  1 10:58:03 DEBUG[12874] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
Aug  1 10:58:03 DEBUG[12874] chan_zap.c: Updated conferencing on 2, with 0 conference users
Aug  1 10:58:03 VERBOSE[12874] logger.c:     -- Hungup 'Zap/2-1'
Aug  1 10:58:03 DEBUG[12808] chan_zap.c: disabled echo cancellation on channel 2
Aug  1 10:58:10 VERBOSE[12878] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 10:58:11 VERBOSE[12880] logger.c:     -- Starting simple switch on 'Zap/2-1'
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:58:12 WARNING[12878] chan_zap.c: getdtmf on channel 1: Operation now in progress
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 10:58:12 DEBUG[12878] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 10:58:12 VERBOSE[12878] logger.c:     -- Hungup 'Zap/1-1'
Aug  1 10:58:12 VERBOSE[12883] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Exception on 15, channel 2
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Exception on 15, channel 2
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Exception on 15, channel 2
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Got event On hook(1) on channel 2 (index 0)
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: disabled echo cancellation on channel 2
Aug  1 10:58:13 WARNING[12880] chan_zap.c: getdtmf on channel 2: Operation now in progress
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Hangup: channel: 2 index = 0, normal = 15, callwait = -1, thirdcall = -1
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: disabled echo cancellation on channel 2
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
Aug  1 10:58:13 DEBUG[12880] chan_zap.c: Updated conferencing on 2, with 0 conference users
Aug  1 10:58:13 VERBOSE[12880] logger.c:     -- Hungup 'Zap/2-1'
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
Aug  1 10:58:14 DEBUG[12883] chan_zap.c: Sent deferred digit string:
Aug  1 10:58:19 DEBUG[12883] chan_zap.c: Enabled echo cancellation on channel 1
Aug  1 10:58:19 VERBOSE[12883] logger.c:     -- Executing NoOp("Zap/1-1", "No DID or CID Match") in new stack
Aug  1 10:58:19 VERBOSE[12883] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Aug  1 10:58:19 DEBUG[12799] channel.c: Avoiding initial deadlock for 'Zap/1-1'
Aug  1 10:58:19 DEBUG[12883] chan_zap.c: Took Zap/1-1 off hook
Aug  1 10:58:19 VERBOSE[12883] logger.c:     -- Executing Wait("Zap/1-1", "2") in new stack
Aug  1 10:58:20 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:20 DEBUG[12883] chan_zap.c: Got event Wink/Flash(3) on channel 1 (index 0)
Aug  1 10:58:20 DEBUG[12883] chan_zap.c: Got wink in weird state 6 on channel 1
Aug  1 10:58:21 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:21 DEBUG[12883] chan_zap.c: Got event Ring/Answered(2) on channel 1 (index 0)
Aug  1 10:58:21 WARNING[12883] chan_zap.c: Ring/Off-hook in strange state 6 on channel 1
Aug  1 10:58:21 VERBOSE[12883] logger.c:     -- Executing Playback("Zap/1-1", "ss-noservice") in new stack
Aug  1 10:58:21 DEBUG[12883] channel.c: Scheduling timer at 160 sample intervals
Aug  1 10:58:21 VERBOSE[12883] logger.c:     -- Playing 'ss-noservice' (language 'en')
Aug  1 10:58:22 DEBUG[12883] chan_zap.c: Exception on 14, channel 1
Aug  1 10:58:22 DEBUG[12883] chan_zap.c: Got event Alarm(4) on channel 1 (index 0)
Aug  1 10:58:22 WARNING[12883] chan_zap.c: Detected alarm on channel 1: Red Alarm
Aug  1 10:58:22 DEBUG[12883] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:58:22 DEBUG[12883] channel.c: Scheduling timer at 0 sample intervals
Aug  1 10:58:22 V
ERBOSE[12883] logger.c:   == Spawn extension (from-pstn, s, 4) exited non-zero on 'Zap/1-1'
Aug  1 10:58:22 DEBUG[12883] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 10:58:22 DEBUG[12883] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 10:58:22 DEBUG[12883] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1


successful calls:
Aug  1 11:31:01 VERBOSE[19096] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 11:31:02 DEBUG[19096] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:02 DEBUG[19096] chan_zap.c: DTMF digit: 4 on Zap/1-1
Aug  1 11:31:02 DEBUG[19096] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:02 DEBUG[19096] chan_zap.c: DTMF digit: 3 on Zap/1-1
Aug  1 11:31:02 DEBUG[19096] chan_zap.c: DTMF digit: 9 on Zap/1-1
Aug  1 11:31:02 DEBUG[19096] chan_zap.c: DTMF digit: 5 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 7 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 1 on Zap/1-1
Aug  1 11:31:03 DEBUG[19096] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:04 DEBUG[19096] chan_zap.c: DTMF digit: 5 on Zap/1-1
Aug  1 11:31:04 DEBUG[19096] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:04 DEBUG[19096] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:04 DEBUG[19096] chan_zap.c: Enabled echo cancellation on channel 1
Aug  1 11:31:04 VERBOSE[19096] logger.c:     -- Executing Set("Zap/1-1", "__FROM_DID=1652") in new stack
Aug  1 11:31:04 VERBOSE[19096] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
Aug  1 11:31:04 VERBOSE[19096] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=aheaton@landair.com") in new stack
Aug  1 11:31:04 VERBOSE[19096] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Aug  1 11:31:04 DEBUG[19096] chan_zap.c: Took Zap/1-1 off hook
Aug  1 11:31:04 VERBOSE[19096] logger.c:     -- Executing Wait("Zap/1-1", "5") in new stack
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: Exception on 14, channel 1
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:08 VERBOSE[19096] logger.c:   == Spawn extension (from-pstn, 1652, 5) exited non-zero on 'Zap/1-1'
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 11:31:08 DEBUG[19096] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 11:31:08 VERBOSE[19096] logger.c:     -- Hungup 'Zap/1-1'
Aug  1 11:31:15 VERBOSE[19102] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 4 on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 3 on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 9 on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 5 on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:16 DEBUG[19102] chan_zap.c: DTMF digit: 7 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 1 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 5 on Zap/1-1
Aug  1 11:31:17 DEBUG[19102] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:18 DEBUG[19102] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:18 DEBUG[19102] chan_zap.c: Enabled echo cancellation on channel 1
Aug  1 11:31:18 VERBOSE[19102] logger.c:     -- Executing Set("Zap/1-1", "__FROM_DID=1652") in new stack
Aug  1 11:31:18 VERBOSE[19102] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
Aug  1 11:31:18 VERBOSE[19102] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=aheaton@landair.com") in new stack
Aug  1 11:31:18 VERBOSE[19102] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Aug  1 11:31:18 DEBUG[19102] chan_zap.c: Took Zap/1-1 off hook
Aug  1 11:31:18 VERBOSE[19102] logger.c:     -- Executing Wait("Zap/1-1", "5") in new stack
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: Exception on 14, channel 1
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:22 VERBOSE[19102] logger.c:   == Spawn extension (from-pstn, 1652, 5) exited non-zero on 'Zap/1-1'
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 11:31:22 DEBUG[19102] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 11:31:22 VERBOSE[19102] logger.c:     -- Hungup 'Zap/1-1'
Aug  1 11:31:29 VERBOSE[19106] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: 4 on Zap/1-1
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: 3 on Zap/1-1
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:30 DEBUG[19106] chan_zap.c: DTMF digit: 0 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 9 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 3 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 7 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 4 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 1 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:31 DEBUG[19106] chan_zap.c: DTMF digit: 5 on Zap/1-1
Aug  1 11:31:32 DEBUG[19106] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:32 DEBUG[19106] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:32 DEBUG[19106] chan_zap.c: Enabled echo cancellation on channel 1
Aug  1 11:31:32 VERBOSE[19106] logger.c:     -- Executing Set("Zap/1-1", "__FROM_DID=1652") in new stack
Aug  1 11:31:32 VERBOSE[19106] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
Aug  1 11:31:32 VERBOSE[19106] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=aheaton@landair.com") in new stack
Aug  1 11:31:32 VERBOSE[19106] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Aug  1 11:31:32 DEBUG[19106] chan_zap.c: Took Zap/1-1 off hook
Aug  1 11:31:32 VERBOSE[19106] logger.c:     -- Executing Wait("Zap/1-1", "5") in new stack
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: Exception on 14, channel 1
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:36 VERBOSE[19106] logger.c:   == Spawn extension (from-pstn, 1652, 5) exited non-zero on 'Zap/1-1'
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 11:31:36 DEBUG[19106] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 11:31:36 VERBOSE[19106] logger.c:     -- Hungup 'Zap/1-1'
Aug  1 11:31:42 VERBOSE[19110] logger.c:     -- Starting simple switch on 'Zap/1-1'
Aug  1 11:31:42 DEBUG[19110] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:42 DEBUG[19110] chan_zap.c: DTMF digit: 4 on Zap/1-1
Aug  1 11:31:42 DEBUG[19110] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:42 DEBUG[19110] chan_zap.c: DTMF digit: 3 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 0 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 9 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 3 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 7 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: 4 on Zap/1-1
Aug  1 11:31:43 DEBUG[19110] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: DTMF digit: 1 on Zap/1-1
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: DTMF digit: 6 on Zap/1-1
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: DTMF digit: 5 on Zap/1-1
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: DTMF digit: 2 on Zap/1-1
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: DTMF digit: * on Zap/1-1
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: Enabled echo cancellation on channel 1
Aug  1 11:31:44 VERBOSE[19110] logger.c:     -- Executing Set("Zap/1-1", "__FROM_DID=1652") in new stack
Aug  1 11:31:44 VERBOSE[19110] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX=system") in new stack
Aug  1 11:31:44 VERBOSE[19110] logger.c:     -- Executing Set("Zap/1-1", "FAX_RX_EMAIL=aheaton@landair.com") in new stack
Aug  1 11:31:44 VERBOSE[19110] logger.c:     -- Executing Answer("Zap/1-1", "") in new stack
Aug  1 11:31:44 DEBUG[19110] chan_zap.c: Took Zap/1-1 off hook
Aug  1 11:31:44 VERBOSE[19110] logger.c:     -- Executing Wait("Zap/1-1", "5") in new stack
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: Exception on 14, channel 1
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:47 VERBOSE[19110] logger.c:   == Spawn extension (from-pstn, 1652, 5) exited non-zero on 'Zap/1-1'
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: disabled echo cancellation on channel 1
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Aug  1 11:31:47 DEBUG[19110] chan_zap.c: Updated conferencing on 1, with 0 conference users
Aug  1 11:31:47 VERBOSE[19110] logger.c:     -- Hungup 'Zap/1-1'


****** ADDITIONAL INFORMATION ******

i have a test box i could allow access to

the same results are received with version 1.2.22

Comments:By: chris dolese (dolesec) 2007-08-02 15:30:09

also have report from another user ...

kubaober



Joined: 18 Mar 2007
Posts: 1

Posted: 02 Aug 2007 18:28    Post subject: T1 EM&W DID regression in 1.4.8  

--------------------------------------------------------------------------------

The same problem occurs in asterisk 1.4.8 (version 1.4.7.1 works just fine). It's on XO T1 DID trunks. A following line appears in logs when you dial in

[Aug 2 09:45:28] WARNING[12763] chan_zap.c: getdtmf on channel 2: Success

The net effect is for the channel to be hanged-up quickly after answering the inbound call. XO's system tries to connect on the next sequential DID trun, which fails in the same manner, and then the caller hears "all circuits are busy -- please try you call again later".

#zaptel.conf
span=1,1,0,esf,b8zs
e&m=1-6

#zapata.conf
signalling=em_w
channel=>1-6
By: Russell Bryant (russell) 2007-08-03 14:12:18

Give the attached patch a try
By: chris dolese (dolesec) 2007-08-03 16:09:25

so far so good

i'll report back midweek next week

thanks
By: Digium Subversion (svnbot) 2007-08-07 12:26:32

Repository: asterisk
Revision: 78370

------------------------------------------------------------------------
r78370 | russell | 2007-08-07 12:26:30 -0500 (Tue, 07 Aug 2007) | 4 lines

Revert patch committed for issue ASTERISK-9377.  It broke E&M trunks.
(closes issue ASTERISK-10005)
(closes issue ASTERISK-10009)

------------------------------------------------------------------------
By: Digium Subversion (svnbot) 2007-08-07 12:27:57

Repository: asterisk
Revision: 78371

------------------------------------------------------------------------
r78371 | russell | 2007-08-07 12:27:56 -0500 (Tue, 07 Aug 2007) | 12 lines

Merged revisions 78370 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.2

........
r78370 | russell | 2007-08-07 12:44:04 -0500 (Tue, 07 Aug 2007) | 4 lines

Revert patch committed for issue ASTERISK-9377.  It broke E&M trunks.
(closes issue ASTERISK-10005)
(closes issue ASTERISK-10009)

........

------------------------------------------------------------------------
By: Digium Subversion (svnbot) 2007-08-07 12:28:37

Repository: asterisk
Revision: 78372

------------------------------------------------------------------------
r78372 | russell | 2007-08-07 12:28:36 -0500 (Tue, 07 Aug 2007) | 20 lines

Merged revisions 78371 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.4

................
r78371 | russell | 2007-08-07 12:45:30 -0500 (Tue, 07 Aug 2007) | 12 lines

Merged revisions 78370 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.2

........
r78370 | russell | 2007-08-07 12:44:04 -0500 (Tue, 07 Aug 2007) | 4 lines

Revert patch committed for issue ASTERISK-9377.  It broke E&M trunks.
(closes issue ASTERISK-10005)
(closes issue ASTERISK-10009)

........

................

------------------------------------------------------------------------