Tzafrir Cohen schrieb: > On Fri, Oct 26, 2007 at 09:31:31AM +0200, Frank Segtrop wrote: > >> Package: libpri1.0 >> Version: 1.4.1-2 >> Severity: normal >> >> >> >> -- Accepting overlap voice call from '195' to '0' on channel 0/2, span 1 >> -- Starting simple switch on 'Zap/2-1' >> -- Hungup 'Zap/2-1' >> > > What version of Asterisk do you use? > Asterisk 1.4.13-BRIstuffed-0.4.0-test4 built by pbuilder @ grnetbox on a x86_64 running Linux on 2007-10-10 19:23:45 UTC > Do you have overlapdial enabled in zapata.conf? > Yes, sure. > Much of the relevant logic is in Asterisk's chan_zap. > > Could you also please post a more complete log? > > in logger.conf enable the "full" line and run in the CLI: > > logger reload > core set verbose 5 > core set debug 5 > pri debug span 1 > > And then please post the part of /var/log/asterisk/full relating ot the > call . > >
[Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=27 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Message type: SETUP (5) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [04 03 80 90 a3] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Bearer Capability (len= 5) [ Ext: 1 Q.931 Std: 0 Info transfer capability: Speech (0) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Ext: 1 User information layer 1: A-Law (35) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [18 01 83] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Channel ID (len= 3) [ Ext: 1 IntID: Implicit Other Spare: 0 Preferred Dchan: 0 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < ChanSel: Any channel selectedNo channel selected [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [6c 05 01 80 31 39 35] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Calling Number (len= 7) [ Ext: 0 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Presentation: Presentation permitted, user number not screened (0) '195' ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [70 02 81 30] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '0' ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [7d 02 91 81] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < High-layer compatibilty (len= 4) [ [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 0x91 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 0x81 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Making new call for cr 1 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing Q.931 Call Setup [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 4 (cs0, Bearer Capability) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 24 (cs0, Channel Identification) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 108 (cs0, Calling Party Number) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 125 (cs0, High-layer Compatibility) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 q931.c:4224 q931_receive: call 1 on channel -1 enters state 6 (Call Present) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] DEBUG[21244] chan_zap.c: Found empty available channel 0/2 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 q931.c:3301 q931_setup_ack: call 1 on channel 2 enters state 25 (Overlap Receiving) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > Protocol Discriminator: Q.931 (8) len=11 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > Call Ref: len= 1 (reference 129/0x81) (Terminator) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > Message type: SETUP ACKNOWLEDGE (13) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > [18 01 8a] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > Channel ID (len= 3) [ Ext: 1 IntID: Implicit Other Spare: 0 Exclusive Dchan: 0 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > ChanSel: B2 channel [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > [1e 02 81 88] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 > Ext: 1 Progress Description: Inband information or appropriate pattern now available. (8) ] [Oct 26 15:20:54] DEBUG[21244] chan_zap.c: Enabled echo cancellation on channel 2 [Oct 26 15:20:54] VERBOSE[21244] logger.c: -- Accepting overlap voice call from '195' to '0' on channel 0/2, span 1 [Oct 26 15:20:54] VERBOSE[25920] logger.c: -- Starting simple switch on 'Zap/2-1' [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [70 02 81 31] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '0' ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [70 02 81 37] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '1' ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < [70 02 81 37] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '7' ] [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:54] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < [70 02 81 39] [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '9' ] [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < [70 02 81 32] [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '2' ] [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < [70 02 81 35] [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '5' ] [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:55] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < [70 02 81 30] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '0' ] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < [70 02 81 38] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '8' ] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < [70 02 81 30] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '0' ] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Call Ref: len= 1 (reference 1/0x1) (Originator) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Message type: INFORMATION (123) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < [70 02 81 32] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 < Called Number (len= 4) [ Ext: 1 TON: Unknown Number Type (0) NPI: ISDN/Telephony Numbering Plan (E.164/E.163) (1) '2' ] [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Processing IE 112 (cs0, Called Party Number) [Oct 26 15:20:56] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: No such possible extension '0' in context 'matflow-phones' [Oct 26 15:21:02] DEBUG[25920] channel.c: Hanging up channel 'Zap/2-1' [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: zt_hangup(Zap/2-1) [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1 [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: Hangup: channel: 2 index = 0, normal = 19, callwait = -1, thirdcall = -1 [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Overlap Receiving, peerstate Overlap sending [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 q931.c:3649 q931_release_complete: call 1 on channel 2 enters state 0 (Null) [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 > Protocol Discriminator: Q.931 (8) len=8 [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 > Call Ref: len= 1 (reference 129/0x81) (Terminator) [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 > Message type: RELEASE COMPLETE (90) [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 > [08 02 81 81] [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1) [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 > Ext: 1 Cause: Unallocated (unassigned) number (1), class = Normal Event (0) ] [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Null, peerstate Null [Oct 26 15:21:02] VERBOSE[25920] logger.c: 1 NEW_HANGUP DEBUG: Destroying the call, ourstate Null, peerstate Null [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: disabled echo cancellation on channel 2 [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1 [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: Updated conferencing on 2, with 0 conference users [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1 [Oct 26 15:21:02] DEBUG[25920] chan_zap.c: disabled echo cancellation on channel 2 [Oct 26 15:21:02] VERBOSE[25920] logger.c: -- Hungup 'Zap/2-1' [Oct 26 15:21:02] DEBUG[25920] devicestate.c: Notification of state change to be queued on device/channel Zap/2-1 [Oct 26 15:21:02] NOTICE[25920] cdr.c: CDR on channel 'Zap/2-1' not posted [Oct 26 15:21:02] DEBUG[21223] devicestate.c: No provider found, checking channel drivers for Zap - 2 [Oct 26 15:21:02] DEBUG[21223] devicestate.c: Changing state for Zap/2 - state 0 (Unknown) [Oct 26 15:21:02] DEBUG[25923] app_queue.c: Device 'Zap/2' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Oct 26 15:21:02] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:04] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:04] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:14] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:14] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:14] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter [Oct 26 15:21:14] VERBOSE[21244] logger.c: 1 -- Restarting T203 counter
signature.asc
Description: OpenPGP digital signature
