[asterisk-r2] doubt about how to handle calls with openr2

Mon Apr 12 12:51:34 CDT 2010

Logs are ok and make things clear. This has nothing to do with
configuration.

The receiving side is not detecting the MF tones sent my the originating
side. I smell a driver problem.

Verify with dahdi_monitor ( do man dahdi_monitor to learn how to use it )
that audio is actually being received by the incoming leg. If no audio, then
that is the problem, no audio, no tones, no call :-)

Moises Silva
Senior Software Engineer
Sangoma Technologies Inc. | 50 McIntosh Drive, Suite 120, Markham ON L3R 9T3
Canada
t. 1 905 474 1990 x 128 | e. moy at sangoma.com

2010/4/12 Juan Manuel Rafael Suárez <jrafael at telsyssolution.com>

>  Here the log
>
>
>
>
>
> Originating :
>
>
>
>
>
>
>
>
>
> [Apr 12 12:40:17] VERBOSE[3013] logger.c:   == Using SIP RTP TOS bits 184
>
> [Apr 12 12:40:17] VERBOSE[3013] logger.c:   == Using SIP RTP CoS mark 5
>
> [Apr 12 12:40:17] VERBOSE[3013] logger.c:   == Using SIP VRTP TOS bits 136
>
> [Apr 12 12:40:17] VERBOSE[3013] logger.c:   == Using SIP VRTP CoS mark 6
>
> [Apr 12 12:40:17] VERBOSE[5381] logger.c:     -- Executing [1 at from-internal:1]
> Dial("SIP/100-09ba2930", "DAHDI/g0/1") in new stack
>
> [Apr 12 12:40:17] DEBUG[5381] chan_dahdi.c: No MFC/R2 category specified
> for chan DAHDI/1-1, using default National Subscriber
>
> [Apr 12 12:40:17] DEBUG[5381] chan_dahdi.c: Chan 1 - Requested to make call
> (ANI=100, DNIS=1, category=National Subscriber)
>
> [Apr 12 12:40:17] DEBUG[5381] chan_dahdi.c: Chan 1 - Call started at Mon
> Apr 12 12:40:17 2010 on chan 1 [openr2 version 1.3.0, revision (release)]
>
> [Apr 12 12:40:17] DEBUG[5381] chan_dahdi.c: Chan 1 - Outgoing call
> proceeding: ANI=100, DNIS=1, Category=National Subscriber
>
> [Apr 12 12:40:17] DEBUG[5381] chan_dahdi.c: Chan 1 - CAS Tx >> [SEIZE] 0x00
>
> [Apr 12 12:40:17] DEBUG[5381] chan_dahdi.c: Chan 1 - CAS Raw Tx >> 0x01
>
> [Apr 12 12:40:17] VERBOSE[5381] logger.c:     -- Called g0/1
>
> [Apr 12 12:40:17] DEBUG[4290] chan_dahdi.c: Chan 1 - Bits changed from 0x08
> to 0x0C
>
> [Apr 12 12:40:17] DEBUG[4290] chan_dahdi.c: Chan 1 - CAS Rx << [SEIZE ACK]
> 0x0C
>
> [Apr 12 12:40:17] DEBUG[4290] chan_dahdi.c: Chan 1 - MFC/R2 call
> acknowledge!
>
> [Apr 12 12:40:17] DEBUG[4290] chan_dahdi.c: Chan 1 - Sending DNIS digit 1
>
> [Apr 12 12:40:17] DEBUG[4290] chan_dahdi.c: Chan 1 - MF Tx >> 1 [ON]
>
>
>
>
>
> *---------Here I hang up the call cause no answer*
>
>
>
>
>
> [Apr 12 12:40:41] DEBUG[5381] chan_dahdi.c: disconnecting MFC/R2 call on
> chan 1
>
> [Apr 12 12:40:41] DEBUG[5381] chan_dahdi.c: Chan 1 - CAS Tx >> [CLEAR
> FORWARD] 0x08
>
> [Apr 12 12:40:41] DEBUG[5381] chan_dahdi.c: Chan 1 - CAS Raw Tx >> 0x09
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Hungup 'DAHDI/1-1'
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:   == Spawn extension
> (from-internal, 1, 1) exited non-zero on 'SIP/100-09ba2930'
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing [h at from-internal:1]
> Macro("SIP/100-09ba2930", "hangupcall") in new stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing
> [s at macro-hangupcall:1] ResetCDR("SIP/100-09ba2930", "vw") in new stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing
> [s at macro-hangupcall:2] NoCDR("SIP/100-09ba2930", "") in new stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing
> [s at macro-hangupcall:3] GotoIf("SIP/100-09ba2930", "1?skiprg") in new stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Goto
> (macro-hangupcall,s,6)
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing
> [s at macro-hangupcall:6] GotoIf("SIP/100-09ba2930", "1?skipblkvm") in new
> stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Goto
> (macro-hangupcall,s,9)
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing
> [s at macro-hangupcall:9] GotoIf("SIP/100-09ba2930", "1?theend") in new stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Goto
> (macro-hangupcall,s,11)
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:     -- Executing
> [s at macro-hangupcall:11] Hangup("SIP/100-09ba2930", "") in new stack
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:   == Spawn extension
> (macro-hangupcall, s, 11) exited non-zero on 'SIP/100-09ba2930' in macro
> 'hangupcall'
>
> [Apr 12 12:40:41] VERBOSE[5381] logger.c:   == Spawn extension
> (from-internal, h, 1) exited non-zero on 'SIP/100-09ba2930'
>
> [Apr 12 12:40:41] DEBUG[4290] chan_dahdi.c: Chan 1 - Bits changed from 0x0C
> to 0x08
>
> [Apr 12 12:40:41] DEBUG[4290] chan_dahdi.c: Chan 1 - CAS Rx << [IDLE] 0x08
>
> [Apr 12 12:40:41] DEBUG[4290] chan_dahdi.c: Chan 1 - Call ended
>
> [Apr 12 12:40:41] DEBUG[4290] chan_dahdi.c: Chan 1 - CAS Tx >> [IDLE] 0x08
>
> [Apr 12 12:40:41] DEBUG[4290] chan_dahdi.c: Chan 1 - CAS Raw Tx >> 0x09
>
> [Apr 12 12:40:41] NOTICE[4290] chan_dahdi.c: MFC/R2 call end on chan 1
>
>
>
>
>
> Inside mfcr2/span1 debug (receiving side)
>
>
>
>
>
> [12:37:59:129] [Thread: 84269968] [Chan 1] - Call started at Mon Apr 12
> 12:37:59 2010 on chan 1 [openr2 version 1.3.0, revision (release)]
>
> [12:37:59:129] [Thread: 84269968] [Chan 1] - Outgoing call proceeding:
> ANI=100, DNIS=1, Category=National Subscriber
>
> [12:37:59:129] [Thread: 84269968] [Chan 1] - CAS Tx >> [SEIZE] 0x00
>
> [12:37:59:129] [Thread: 84269968] [Chan 1] - CAS Raw Tx >> 0x01
>
> [12:37:59:129] [Thread: 84269968] [Chan 1] - scheduled timer id 2
> (r2_seize)
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - Bits changed from 0x08 to 0x0C
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - CAS Rx << [SEIZE ACK] 0x0C
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - Attempting to cancel timer
> timer 2
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - timer id 2 found, cancelling
> it now
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - MFC/R2 call acknowledge!
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - Sending DNIS digit 1
>
> [12:37:59:135] [Thread: 84269968] [Chan 1] - MF Tx >> 1 [ON]
>
> [12:38:24:546] [Thread: 84269968] [Chan 1] - Attempting to cancel timer
> timer 0
>
> [12:38:24:546] [Thread: 84269968] [Chan 1] - Cannot cancel timer 0
>
> [12:38:24:546] [Thread: 84269968] [Chan 1] - CAS Tx >> [CLEAR FORWARD] 0x08
>
> [12:38:24:546] [Thread: 84269968] [Chan 1] - CAS Raw Tx >> 0x09
>
> [12:38:24:554] [Thread: 4995984] [Chan 1] - Bits changed from 0x0C to 0x08
>
> [12:38:24:554] [Thread: 4995984] [Chan 1] - CAS Rx << [IDLE] 0x08
>
> [12:38:24:554] [Thread: 4995984] [Chan 1] - Call ended
>
> [12:38:24:554] [Thread: 4995984] [Chan 1] - Attempting to cancel timer
> timer 0
>
> [12:38:24:554] [Thread: 4995984] [Chan 1] - Cannot cancel timer 0
>
>
>
>
>
>
>
>
>
>
>
> Receving asterisk
>
>
>
> [Apr 12 12:40:17] NOTICE[17076] chan_dahdi.c: New MFC/R2 call detected on
> chan 1.
>
>
>
> *---- Here the call was cancelled.*
>
>
>
> [Apr 12 12:40:41] NOTICE[17076] chan_dahdi.c: MFC/R2 call disconnected on
> chan 1
>
> [Apr 12 12:40:41] NOTICE[17076] chan_dahdi.c: MFC/R2 call end on chan 1
>
>
>
> Inside mfcr2/span1 debug (receiving side)
>
>
>
> [12:37:59:136] [Thread: 3085118352] [Chan 1] - Call started at Mon Apr 12
> 12:37:59 2010 on chan 1 [openr2 version 1.3.0, revision (release)]
>
> [12:37:59:136] [Thread: 3085118352] [Chan 1] - Initialized R2 MF detector
>
> [12:37:59:136] [Thread: 3085118352] [Chan 1] - CAS Tx >> [SEIZE ACK] 0x0C
>
> [12:37:59:136] [Thread: 3085118352] [Chan 1] - CAS Raw Tx >> 0x0D
>
> [12:38:24:552] [Thread: 3085118352] [Chan 1] - Bits changed from 0x00 to
> 0x08
>
> [12:38:24:552] [Thread: 3085118352] [Chan 1] - CAS Rx << [CLEAR FORWARD]
> 0x08
>
> [12:38:24:552] [Thread: 3085118352] [Chan 1] - Far end disconnected.
> Reason: Normal Clearing
>
> [12:38:24:552] [Thread: 3085118352] [Chan 1] - Call ended
>
> [12:38:24:552] [Thread: 3085118352] [Chan 1] - Attempting to cancel timer
> timer 0
>
> [12:38:24:552] [Thread: 3085118352] [Chan 1] - Cannot cancel timer 0
>
>
>
>
>
>
>
> Is it ok with debug??
>
>
>
>
>
> JM
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-r2/attachments/20100412/4574243c/attachment-0001.htm 