[Home]

Summary:ASTERISK-12390: Hangug MFCr2 Brazil-Telefonica Sao Paulo
Reporter:Samuel Almachar (samuka)Labels:
Date Opened:2008-07-15 18:24:05Date Closed:2011-06-07 14:08:22
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Channels/chan_zap/NewFeature
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:Moy, frist, congratulations for well work

It's running the last rev. from svn, (http://svn.digium.com/svn/asterisk/team/moy/mfcr2-1.4) on Asterisk 1.4 and the last lib openr2 from SVN too.

When the sip channel start a call, to PSTN, PSTN answer and so the sip channel do hangup, the call stay activeted on PSTN until PSTN hangup too.

The same situation when the call come from PSTN to SIP and sip do Hangup.

ON PSTN, PRI ISDN (embratel), the same situation, don't this Problens, it ís working normal.

Maybe the MFC signal disconnect, from my asterrisk not was "send" to Oper. Telefonica.

Could you help me ?

PS. Additional Info, about logs.

Thanks,

Best Rgsd,

Samuel

(sorry my english)


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

------------------zapata.conf
signalling=mfcr2
mfcr2_variant=br
mfcr2_get_ani_first=no
mfcr2_max_ani=20
mfcr2_max_dnis=4
mfcr2_category=national_subscriber
mfcr2_logdir=span1
mfcr2_logging=all
mfcr2_mfback_timeout=-1
;mfcr2_metering_pulse_timeout=300
mfcr2_allow_collect_calls=no
mfcr2_double_answer=yes

context=telefonica
group=1
callgroup=1
pickupgroup=1
rxgain=0.0
txgain=-6.0
channel => 1-15
channel => 17-31

-------------extention.conf
[saida]
exten => _X.,1,Set(LANGUAGE()=br)
exten => _X.,2,Set(CALLERID(num)="0451")
exten => _X.,3,Dial,ZAP/g1/${EXTEN}|90
exten => _X.,4,Hangup()

[telefonica]
exten => _0452,1,NoOp(${MFCR2_CATEGORY})
exten => _0452,2,Dial,SIP/testeusr2
exten => _0452,3,Hangup()

-------------------------sip.conf
[testeusr2]
type=friend
secret=xxxxxxxxxxx
qualify=yes
jbforce=yes
nat=yes
host=dynamic
canreinvite=no
context=saida
callerid=0452
relaxdtmf=yes
language=br
disallow=all
allow=g729




------------------------------------DUMP CONSOLE FROM PSTN, SIP HANGUP
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 3 - ABCD Rx << 0x9
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 3 - No change in bits
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Rx << 0x3
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Bits changed from 0x8 to 0x0
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Tx >> [SEIZE_ACK] 0xC
[Jul 15 20:09:15] NOTICE[1727]: chan_zap.c:918 zt_r2_on_call_init: New MFC/R2 call detected on chan 10.
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 0 [ON]
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Getting DNIS digit 0
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - DNIS so far: 0, expected length: 4
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Requesting next DNIS with signal 0x31.
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 1 [ON]
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 0 [OFF]
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 1 [OFF]
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 4 [ON]
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Getting DNIS digit 4
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - DNIS so far: 04, expected length: 4
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Requesting next DNIS with signal 0x31.
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 1 [ON]
[Jul 15 20:09:15] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 4 [OFF]
...................
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ANI so far: 1121977000, expected length: 20
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 5 [ON]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 0 [OFF]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 5 [OFF]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << F [ON]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Got end of ANI
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Requesting change to Group II with signal 0x33
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 3 [ON]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << F [OFF]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 3 [OFF]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 1 [ON]
[Jul 15 20:09:17] NOTICE[1727]: chan_zap.c:951 zt_r2_on_call_offered: MFC/R2 call offered on chan 10. DNIS = 0452, ANI = XXXXXXXXXX, Category = National Subscriber
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 1 [ON]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Rx << 1 [OFF]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - MF Tx >> 1 [OFF]
[Jul 15 20:09:17] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - calling callback on chan 10
[Jul 15 20:09:17] NOTICE[1727]: chan_zap.c:1016 zt_r2_on_call_accepted: MFC/R2 call has been accepted on chan 10
   -- Executing [0452@telefonica:1] NoOp("Zap/10-1", "National Subscriber") in new stack
   -- Executing [0452@telefonica:3] Dial("Zap/10-1", "SIP/testeusr2") in new stack
   -- Called testeusr2
   -- SIP/testeusr2-08712000 is ringing
   -- SIP/testeusr2-08712000 answered Zap/10-1
[Jul 15 20:09:21] DEBUG[1727]: chan_zap.c:3217 zt_answer: Accepting MFC/R2 call on chan 10
[Jul 15 20:09:21] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Tx >> [ANSWER] 0x4
[Jul 15 20:09:21] DEBUG[1727]: chan_zap.c:1043 zt_r2_on_call_answered: MFC/R2 call has been answered on chan 10
[Jul 15 20:09:22] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - calling callback on chan 10
[Jul 15 20:09:22] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Tx >> [CLEAR_BACK] 0xC
[Jul 15 20:09:22] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - calling callback on chan 10
[Jul 15 20:09:22] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Tx >> [ANSWER] 0x4
......................
SIP HANGUP
......................
 == Spawn extension (telefonica, 0452, 3) exited non-zero on 'Zap/10-1'
   -- Hungup 'Zap/10-1'

PSTN CHANNEL STAY LOCKED
*CLI> mfcr2 show channels
  Chan Variant    Max ANI         Max DNIS   ANI First            Tx State   Rx State
     1 BR         20              4          No                   IDLE       IDLE
...
    11 BR         20              4          No                   ANSWER     SEIZE

*CLI> zap show channel 11
Channel: 11
File Descriptor: 30
Span: 1
Extension:
Dialing: no
Context: telefonica
Caller ID: 1121977000
Calling TON: 0
Caller ID name: 1121977000
Destroy: 0
InAlarm: 0
Signalling Type: MFC/R2
Radio: 0
Owner: <None>
Real: <None>
Callwait: <None>
Threeway: <None>
Confno: -1
Propagated Conference: -1
Real in conference: 0
DSP: no
Relax DTMF: no
Dialing/CallwaitCAS: 0/0
Default law: alaw
Fax Handled: no
Pulse phone: no
Echo Cancellation: 128 taps unless TDM bridged, currently OFF
MFC/R2 MF State: MF Engine Off
MFC/R2 MF Group: Backward Group B
MFC/R2 State: Answer Transmitted
MFC/R2 Call State: Answered
MFC/R2 Call Files Enabled: No
MFC/R2 Variant: BR
MFC/R2 Max ANI: 20
MFC/R2 Max DNIS: 4
MFC/R2 Get ANI First: No
MFC/R2 MF Back Timeout: 1500ms
MFC/R2 R2 Metering Pulse Timeout: 300ms
MFC/R2 Rx State: SEIZE
MFC/R2 Tx State: ANSWER
MFC/R2 MF Tx Signal: 0
MFC/R2 MF Rx Signal: 0
MFC/R2 Call Files Directory: /var/log/asterisk/mfcr2/span1
Actual Confinfo: Num/0, Mode/0x0000
Actual Confmute: No
Hookstate (FXS only): Offhook

*CLI> zap show channel 11
Channel: 11
File Descriptor: 30
Span: 1
Extension:
Dialing: no
Context: telefonica
Caller ID: XXXXXXXXXX
Calling TON: 0
Caller ID name: XXXXXXXXXX
Destroy: 0
InAlarm: 0
Signalling Type: MFC/R2
Radio: 0
Owner: <None>
Real: <None>
Callwait: <None>
Threeway: <None>
Confno: -1
Propagated Conference: -1
Real in conference: 0
DSP: no
Relax DTMF: no
Dialing/CallwaitCAS: 0/0
Default law: alaw
Fax Handled: no
Pulse phone: no
Echo Cancellation: 128 taps unless TDM bridged, currently OFF
MFC/R2 MF State: MF Engine Off
MFC/R2 MF Group: Backward Group B
MFC/R2 State: Answer Transmitted
MFC/R2 Call State: Answered
MFC/R2 Call Files Enabled: No
MFC/R2 Variant: BR
MFC/R2 Max ANI: 20
MFC/R2 Max DNIS: 4
MFC/R2 Get ANI First: No
MFC/R2 MF Back Timeout: 1500ms
MFC/R2 R2 Metering Pulse Timeout: 300ms
MFC/R2 Rx State: SEIZE
MFC/R2 Tx State: ANSWER
MFC/R2 MF Tx Signal: 0
MFC/R2 MF Rx Signal: 0
MFC/R2 Call Files Directory: /var/log/asterisk/mfcr2/span1
Actual Confinfo: Num/0, Mode/0x0000
Actual Confmute: No
Hookstate (FXS only): Offhook

....................................
AFTER "FISICAL" HANGUP FROM PSTN
....................................

[Jul 15 20:10:28] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 3 - ABCD Rx << 0xB
[Jul 15 20:10:28] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 3 - No change in bits
[Jul 15 20:10:28] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Rx << 0xB
[Jul 15 20:10:28] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Bits changed from 0x0 to 0x8
[Jul 15 20:10:28] NOTICE[1727]: chan_zap.c:1116 zt_r2_write_log: Chan 10 - Far end disconnected. Reason: Normal Clearing
[Jul 15 20:10:28] NOTICE[1727]: chan_zap.c:1076 zt_r2_on_call_disconnected: MFC/R2 call disconnected on chan 10
[Jul 15 20:10:28] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - Call ended
[Jul 15 20:10:28] DEBUG[1727]: chan_zap.c:1128 zt_r2_write_log: Chan 10 - ABCD Tx >> [IDLE] 0x8
[Jul 15 20:10:28] NOTICE[1727]: chan_zap.c:988 zt_r2_on_call_end: MFC/R2 call end on chan 10
Comments:By: Samuel Almachar (samuka) 2008-07-16 07:42:15

============== SOLVED ==================
This problem it is because my mistake self. I'm working under FreeBSD 7, and the port package of Asterisk is 1.4.20.1 version, so I have to do manual patch of yours SVN version. Yesterday i re-checkup the source of chan_zap.c, I forgot some line on function zt_hangup().

Sorry again, You realy do a great job, I wish that your project will be include in futeres releases Asterisk.

ps. I'm not a C program men, but if my work(port FreeBSD) help something, let me know, if I have yours autorization, I will try include some final patch in OS FreeBSD Asterisk ports by his maintainer.