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

Mon Apr 12 13:09:08 CDT 2010

Just to clarify,

Are you using this installation inside a Xen VM? or the server is hosting
some? Any reason to be using a Xen enable kernel?

Best Regards

Alexandre Alencar
http://blog.alexandrealencar.net/
http://www.alexandrealencar.net/
COBIT, ITIL, CSM, LPI, MCP-I

2010/4/12 Moises Silva <moises.silva at gmail.com>

> 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
>>
>
>
> --
> _____________________________________________________________________
> -- Bandwidth and Colocation Provided by http://www.api-digital.com --
>
> asterisk-r2 mailing list
> To UNSUBSCRIBE or update options visit:
>   http://lists.digium.com/mailman/listinfo/asterisk-r2
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-r2/attachments/20100412/e06fc728/attachment-0001.htm 