Leonardo Gomes Figueira
2004-Nov-19 07:28 UTC
[Asterisk-Users] Asterisk crashes with Unicall
Hi,
For the last 40 days i've been using Unicall on an Asterisk connected to
an Ericsson MD-110 PBX.
It was working fine for two weeks when there were just some random calls
but for the last two weeks when the load increased to between 5 and 10
simultaneous calls the system became unreliable with 2 main problems:
1- Some dropped calls when the call comes from Unicall: Unicall ->
IAX/SIP. When it comes from Zap (E1 PRI) there is no problem: Zap ->
IAX/SIP.
2- Asterisk crashes 2 or 3 times a day. Always when there is some
Unicall channel active.
To be sure that the crashes are Unicall related I created an test
enviroment:
2 servers with the same configuration:
- P4 2.8Ghz
- 512MB
- 1 Digium E100P (connected with each other using a E1 cross cable)
The test was: using an .call file to start a call from 1 server to the
other on an extension that dial to the first server, that dial to the
other and so on... until there is no more channels available.
The result: the calls start ringing in both servers until there is no
more channels free, then they start to timeout and hangup. Until here
there is no problem, but then suddenly one of the Asterisk servers
crashes. Sometimes the server that initiated the calls, sometimes the
other, there is no pattern. (I repeated the test several times and one
time both Asterisk crashed).
If I change the signalling to E1 PRI and make the same test there is no
problem (calls ring until no more channels are available and timeout
after some time).
Some messages from the Asterisk that crashed follows below (Got only the
last 200 lines, the complete log is 1800 lines / 192 Kb, too big for
posting here).
Is there some debugging info i can extract from this test and post here
to help ?
Thanks,
Leonardo
zaptel.conf:
span=1,1,0,cas,hdb3
cas=1-15:1101
cas=17-31:1101
unicall.conf:
[channels]
language=br
context=principal_in
rxwink=300
usecallerid=yes
hidecallerid=no
usecallingpres=yes
callprogress=no
restrictcid=no
immediate=no
callwaitingcallerid=no
threewaycalling=no
transfer=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
rxgain=0.0
txgain=0.0
protocolclass=mfcr2
protocolvariant=br,4,4
protocolend=cpe #"co" on the other server
group=1
callerid=asreceived
context=principal_in
channel=>1-15
channel=>17-31
extensions.conf:
[principal_in]
exten => _.,1,SetCallerId(6666)
exten => _.,2,Dial(UniCall/g1/${EXTEN},600)
Call file:
Channel: UniCall/g1/0000
Callerid: <6666>
MaxRetries: 0
RetryTime: 600
WaitTime: 600
Context: principal_in
Extension: 777
Priority: 1
Core file:
Core was generated by `/usr/sbin/asterisk -fg'.
Program terminated with signal 11, Segmentation fault.
#0 0x407fa684 in ?? ()
No debugging symbols on asterisk binary cause it was installed from the
RPM and the building process strips symbols. I can install other binary
with debugging if it helps...
Asterisk messages:
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Detected
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Making a new call with CRN
32769
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx bits 0xD [2/
2/101/ 0]
Nov 19 09:41:25 WARNING[7175]: UC event Detected
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 on [2/
2/101/ 0]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 1 on [2/
2/101/ 0]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 off [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 1 off [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 on [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 1 on [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 off [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 1 off [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 on [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 1 on [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 off [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 1 off [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 on [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 5 on [2/
2/102/101]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 0 off [2/
2/102/105]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 5 off [2/
2/102/105]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 6 on [2/
2/102/105]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 5 on [2/
2/102/105]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Rx tone 6 off [2/
2/102/103]
Nov 19 09:41:25 WARNING[7175]: UniCall: mfcr2 Tx tone 5 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 6 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 5 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 6 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 5 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 6 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 5 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 6 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 5 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 6 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 5 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 6 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 5 off [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone E on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 3 on [2/
2/102/103]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone E off [2/
2/103/106]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 3 off [2/
2/103/106]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 1 on [2/
2/103/106]
Nov 19 09:41:26 WARNING[7175]: UC event Offered
Nov 19 09:41:26 WARNING[7175]: Offered on channel 0 (ANI: 666, DNIS: 0000)
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 mfcr2_AcceptCall()
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 1 on [2/
4/103/106]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Rx tone 1 off [2/
4/103/107]
Nov 19 09:41:26 WARNING[7175]: UniCall: mfcr2 Tx tone 1 off [2/
4/103/107]
Nov 19 09:41:26 WARNING[7175]: UC event Accepted
Nov 19 09:41:26 WARNING[23575]: unicall_call called - 'g1/0000'
Nov 19 09:41:26 WARNING[23575]: unicall_call caller id - '6666'
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Making a new call with
CRN 32769
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 mfcr2_MakeCall()
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx bits 0x1 [1/
1/ 0/ 0]
Nov 19 09:41:26 WARNING[23575]: UC event Dialing
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Rx bits 0xD [1/
40/201/ 0]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx tone 0 on [2/
40/202/ 0]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Rx tone 1 on [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx tone 0 off [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Rx tone 1 off [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx tone 0 on [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Rx tone 1 on [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx tone 0 off [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Rx tone 1 off [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx tone 0 on [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Rx tone 1 on [2/
40/202/201]
Nov 19 09:41:26 WARNING[23575]: UniCall: mfcr2 Tx tone 0 off [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 1 off [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 0 on [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 on [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 0 off [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 off [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 on [2/
40/202/201]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 6 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 5 off [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone E on [2/
40/202/204]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 3 on [2/
40/202/208]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone E off [2/
40/202/208]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 3 off [2/
40/202/208]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 1 on [2/
40/202/208]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 1 on [2/
40/203/208]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Tx tone 1 off [2/
40/203/208]
Nov 19 09:41:27 WARNING[23575]: UniCall: mfcr2 Rx tone 1 off [2/
40/203/208]
Nov 19 09:41:27 WARNING[23575]: UC event Alerting
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 Rx bits 0x9 [1/
20/103/107]
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 Far end disconnected -
state 0x20
Nov 19 09:41:50 WARNING[9225]: UC event Far end disconnected
Nov 19 09:41:50 WARNING[9225]: Far disconnect cause 16
Nov 19 09:41:50 WARNING[9225]: Unable to forward frame
Nov 19 09:41:50 WARNING[9225]: unicall_hangup(UniCall/2-1)
Nov 19 09:41:50 WARNING[9225]: Causes 0 16
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 Clearing fwd
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 Tx bits 0x9 [1/
200/204/208]
Nov 19 09:41:50 NOTICE[9225]: Unable to create channel of type 'UniCall'
Nov 19 09:41:50 WARNING[9225]: unicall_hangup(UniCall/1-1)
Nov 19 09:41:50 WARNING[9225]: Causes 0 16
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[9225]: UniCall: mfcr2 Call disconnected - state
0x800
Nov 19 09:41:50 WARNING[7175]: UC event Drop call
Nov 19 09:41:50 WARNING[7175]: Doing a uc_ReleaseCall
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_ReleaseCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Tx bits 0x9 [1/
1000/106/107]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Destroying call with CRN 32769
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 release_guard_expired
Nov 19 09:41:50 WARNING[7175]: UC event Release call
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Rx bits 0x1 [1/ 1/
0/ 0]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Detected
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Making a new call with CRN
32770
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Tx bits 0xD [2/
2/101/ 0]
Nov 19 09:41:50 WARNING[7175]: UC event Detected
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 Rx bits 0x9 [1/
20/103/107]
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 Far end disconnected -
state 0x20
Nov 19 09:41:50 WARNING[10250]: UC event Far end disconnected
Nov 19 09:41:50 WARNING[10250]: Far disconnect cause 16
Nov 19 09:41:50 WARNING[10250]: Unable to forward frame
Nov 19 09:41:50 WARNING[10250]: unicall_hangup(UniCall/4-1)
Nov 19 09:41:50 WARNING[10250]: Causes 0 16
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 Clearing fwd
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 Tx bits 0x9 [1/
200/204/208]
Nov 19 09:41:50 NOTICE[10250]: Unable to create channel of type
'UniCall'
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Rx bits 0x9 [1/
200/210/ 0]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Call disconnected - state
0x200
Nov 19 09:41:50 WARNING[7175]: UC event Drop call
Nov 19 09:41:50 WARNING[7175]: Doing a uc_ReleaseCall
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_ReleaseCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Destroying call with CRN 32769
Nov 19 09:41:50 WARNING[7175]: UC event Release call
Nov 19 09:41:50 WARNING[10250]: unicall_hangup(UniCall/3-1)
Nov 19 09:41:50 WARNING[10250]: Causes 0 16
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[10250]: UniCall: mfcr2 Call disconnected - state
0x800
Nov 19 09:41:50 WARNING[7175]: UC event Drop call
Nov 19 09:41:50 WARNING[7175]: Doing a uc_ReleaseCall
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_ReleaseCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Tx bits 0x9 [1/
1000/106/107]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Destroying call with CRN 32769
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Rx bits 0x9 [2/
2/101/ 0]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Far end disconnected -
state 0x2
Nov 19 09:41:50 WARNING[7175]: UC event Far end disconnected
Nov 19 09:41:50 WARNING[7175]: Far disconnect cause 31
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Call disconnected - state
0x800
Nov 19 09:41:50 WARNING[7175]: UC event Drop call
Nov 19 09:41:50 WARNING[7175]: Doing a uc_ReleaseCall
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_ReleaseCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Tx bits 0x9 [1/
1000/106/ 0]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Destroying call with CRN 32770
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Rx bits 0x9 [1/
20/103/107]
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Far end disconnected -
state 0x20
Nov 19 09:41:50 WARNING[11275]: UC event Far end disconnected
Nov 19 09:41:50 WARNING[11275]: Far disconnect cause 16
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 release_guard_expired
Nov 19 09:41:50 WARNING[11275]: Unable to forward frame
Nov 19 09:41:50 WARNING[11275]: unicall_hangup(UniCall/6-1)
Nov 19 09:41:50 WARNING[11275]: Causes 0 16
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Clearing fwd
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Tx bits 0x9 [1/
200/204/208]
Nov 19 09:41:50 WARNING[11275]: unicall_call called - 'g1/h'
Nov 19 09:41:50 WARNING[11275]: unicall_call caller id - '6666'
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Making a new call with
CRN 32770
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 mfcr2_MakeCall()
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Tx bits 0x1 [1/
1/ 0/ 0]
Nov 19 09:41:50 WARNING[11275]: unicall_hangup(UniCall/2-1)
Nov 19 09:41:50 WARNING[11275]: Causes 0 16
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Rx bits 0x9 [1/
200/210/ 0]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Call disconnected - state
0x200
Nov 19 09:41:50 WARNING[7175]: UC event Drop call
Nov 19 09:41:50 WARNING[7175]: Doing a uc_ReleaseCall
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_ReleaseCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Destroying call with CRN 32769
Nov 19 09:41:50 WARNING[7175]: UC event Release call
Nov 19 09:41:50 WARNING[11275]: unicall_hangup(UniCall/5-1)
Nov 19 09:41:50 WARNING[11275]: Causes 0 16
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 mfcr2_DropCall()
Nov 19 09:41:50 WARNING[11275]: UniCall: mfcr2 Call disconnected - state
0x800
Nov 19 09:41:50 WARNING[7175]: UC event Drop call
Nov 19 09:41:50 WARNING[7175]: Doing a uc_ReleaseCall
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 mfcr2_ReleaseCall()
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Tx bits 0x9 [1/
1000/106/107]
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 Destroying call with CRN 32769
Nov 19 09:41:50 WARNING[7175]: UniCall: mfcr2 release_guard_expired
--
Leonardo Gomes Figueira
sabbath@planetarium.com.br