Amportal crash on incoming fax

Hi,

I’m using a fresh installed 5.211.65-2 FreePBX distro.
Phone calls are working as expected but I have strange problem when it comes to receiving fax.

Some infos first:

  • I’m using DAHDi with a beronet BN4S0 card.
  • System is working in Device and user mode.
  • I try to use the internal free fax module of freePBX

Actually I don’t know which files I should post.
In my full-log you just see that asterisk is restarting suddenly.
When I call our fax-extension externally I hear this fax-sounds. After a few seconds I see that asterisk is rebooting.

Do you have any idea?
thx
mts

Someone else reported this and said it was a spandsp issue but I was not able to replicate it.

What is your output of
rpm -qa | grep asterisk

and

rpm -qa | grep spandsp

On top of what Tony asked for, the full log will also be useful, just please ensure you give us enough of the call and a little after asterisk restarts.

Hi,
here the part of my full-log.
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered application ‘QueueLog’
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action Queues
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueStatus
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueSummary
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueAdd
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueRemove
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueuePause
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueLog
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueuePenalty
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueMemberRingInUse
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueRule
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueReload
[2013-12-24 11:04:27] VERBOSE[5136] manager.c: == Manager registered action QueueReset
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_VARIABLES’
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_EXISTS’
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_MEMBER’
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_MEMBER_COUNT’
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_MEMBER_LIST’
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_WAITING_COUNT’
[2013-12-24 11:04:27] VERBOSE[5136] pbx.c: == Registered custom function ‘QUEUE_MEMBER_PEN1ALTY’
[2013-12-24 11:04:27] VERBOSE[5136] loader.c: app_queue.so => (True Call Queueing)
[2013-12-24 11:04:27] VERBOSE[5136] asterisk.c: Asterisk Ready.
[2013-12-24 11:04:27] NOTICE[5176] chan_sip.c: Peer ‘152’ is now Reachable. (117ms / 2000ms)
[2013-12-24 11:04:27] NOTICE[5176] chan_sip.c: Peer ‘221’ is now Reachable. (398ms / 2000ms)
[2013-12-24 11:04:52] NOTICE[5160] chan_dahdi.c: PRI got event: HDLC Abort (6) on D-channel of span 3
[2013-12-24 11:04:54] VERBOSE[5159][C-00000000] sig_pri.c: – Accepting call from ‘0171xxxxxxxx’ to ‘41xxxx29’ on channel 0/1, span 2
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i2/0171xxxxxxxx-1”, “__FROM_DID=41xxxx29”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:2] Gosub(“DAHDI/i2/0171xxxxxxxx-1”, “app-blacklist-check,s,1()”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:1] GotoIf(“DAHDI/i2/0171xxxxxxxx-1”, “0?blacklisted”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i2/0171xxxxxxxx-1”, “CALLED_BLACKLIST=1”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:3] Return(“DAHDI/i2/0171xxxxxxxx-1”, “”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:3] Gosub(“DAHDI/i2/0171xxxxxxxx-1”, “cidlookup,cidlookup_4,1()”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i2/0171xxxxxxxx-1”, “CURLOPT(httptimeout)=7”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:2] Set(“DAHDI/i2/0171xxxxxxxx-1”, “CALLERID(name)=(Mobil) 0171xxxxxxxx”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:3] Return(“DAHDI/i2/0171xxxxxxxx-1”, “”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:4] Set(“DAHDI/i2/0171xxxxxxxx-1”, “CDR(did)=41xxxx29”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:5] ExecIf(“DAHDI/i2/0171xxxxxxxx-1”, “0 ?Set(CALLERID(name)=0171xxxxxxxx)”) in new stack
[2013-12-24 11:04:54] WARNING[5206][C-00000000] func_callerid.c: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead.
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:6] Set(“DAHDI/i2/0171xxxxxxxx-1”, “__CALLINGPRES_SV=allowed”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:7] Set(“DAHDI/i2/0171xxxxxxxx-1”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:8] Goto(“DAHDI/i2/0171xxxxxxxx-1”, “ext-fax,09,1”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Goto (ext-fax,09,1)
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:1] Set(“DAHDI/i2/0171xxxxxxxx-1”, “FAX_FOR=FaxUser (09)”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:2] NoOp(“DAHDI/i2/0171xxxxxxxx-1”, "Receiving Fax for: FaxUser (09), From: “(Mobil) 0171xxxxxxxx” “) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:3] Set(“DAHDI/i2/0171xxxxxxxx-1”, "[email protected]”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:4] Goto(“DAHDI/i2/0171xxxxxxxx-1”, “s,receivefax”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Goto (ext-fax,s,3)
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:3] StopPlayTones(“DAHDI/i2/0171xxxxxxxx-1”, “”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] pbx.c: – Executing [[email protected]:4] ReceiveFAX(“DAHDI/i2/0171xxxxxxxx-1”, “/var/spool/asterisk/fax/1387879494.0.tif,f”) in new stack
[2013-12-24 11:04:54] VERBOSE[5206][C-00000000] res_fax.c: – Channel ‘DAHDI/i2/0171xxxxxxxx-1’ receiving FAX ‘/var/spool/asterisk/fax/1387879494.0.tif’
[2013-12-24 11:04:55] VERBOSE[5206][C-00000000] chan_dahdi.c: – Channel 4 detected a CED tone towards the network.
[2013-12-24 11:04:56] ERROR[5161] chan_dahdi.c: PRI Span: 4 Unable to receive TEI from network in state 2(Assign awaiting TEI)!
[2013-12-24 11:05:01] Asterisk 11.6.0 built by root @ jenkins-el6-64.schmoozecom.net on a x86_64 running Linux on 2013-12-03 16:28:19 UTC
[2013-12-24 11:05:01] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/asterisk.conf’: Found
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action DBGet
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action DBPut
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action DBDel
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action DBDelTree
[2013-12-24 11:05:01] VERBOSE[5217] pbx.c: == Registered custom function ‘MESSAGE’
[2013-12-24 11:05:01] VERBOSE[5217] pbx.c: == Registered custom function ‘MESSAGE_DATA’
[2013-12-24 11:05:01] VERBOSE[5217] pbx.c: == Registered application ‘MessageSend’
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action MessageSend
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action DataGet
[2013-12-24 11:05:01] VERBOSE[5217] loader.c: Asterisk Dynamic Loader Starting:
[2013-12-24 11:05:01] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/modules.conf’: Found
[2013-12-24 11:05:01] NOTICE[5217] loader.c: 3 modules will be loaded.
[2013-12-24 11:05:01] VERBOSE[5217] channel.c: == Registered channel type ‘Local’ (Local Proxy Channel Driver)
[2013-12-24 11:05:01] VERBOSE[5217] manager.c: == Manager registered action LocalOptimizeAway
[2013-12-24 11:05:01] VERBOSE[5217] loader.c: chan_local.so => (Local Proxy Channel (Note: used internally by other modules))
[2013-12-24 11:05:01] VERBOSE[5217] loader.c: res_mwi_blf.so => (MWI BLF Subscriptions)
[2013-12-24 11:05:01] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/extensions.conf’: Found
[2013-12-24 11:05:01] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/extensions_override_freepbx.conf’: Found
[2013-12-24 11:05:01] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/extensions_additional.conf’: Found
[2013-12-24 11:05:01] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/globals_custom.conf’: Found
[2013-12-24 11:05:02] VERBOSE[5217] config.c: == Parsing ‘/var/tmp/exec.1387879501964741.140119160215488’: Found
[2013-12-24 11:05:02] WARNING[5217] config.c: No ‘=’ (equal sign) in line 1 of /var/tmp/exec.1387879501964741.140119160215488
[2013-12-24 11:05:02] VERBOSE[5217] config.c: == Parsing ‘/etc/asterisk/extensions_custom.conf’: Found
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘CAMPONTOGGLE’ to ‘*84’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘DYNAMIC_FEATURES’ to ‘apprecord’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘INTERCOMCODE’ to ‘*80’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTETCDIR’ to ‘/etc/asterisk’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTMODDIR’ to ‘/usr/lib64/asterisk/modules’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTVARLIBDIR’ to ‘/var/lib/asterisk’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTAGIDIR’ to ‘/var/lib/asterisk/agi-bin’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTSPOOLDIR’ to ‘/var/spool/asterisk’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTRUNDIR’ to ‘/var/run/asterisk’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘ASTLOGDIR’ to ‘/var/log/asterisk’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘CWINUSEBUSY’ to ‘true’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘AMPMGRUSER’ to ‘admin’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variable ‘AMPMGRPASS’ to ‘amp111’
[2013-12-24 11:05:02] VERBOSE[5217] pbx.c: == Setting global variabl

After asterisk has restarted I just have my “regular” Warnings:
PRI got event: HDLC Abort (6) on D-channel of span 3
grep asterisk:

[[email protected] asterisk]# rpm -qa | grep asterisk
asterisk-sounds-extra-en-alaw-1.4.9-1_centos5.noarch
asterisk11-flite-debuginfo-11.6.0-20.x86_64
asterisk11-dahdi-11.6.0-20_centos6.x86_64
asterisk11-curl-11.6.0-20_centos6.x86_64
asterisk-sounds-extra-en-ulaw-1.4.9-1_centos5.noarch
asterisk-sounds-extra-en-gsm-1.4.9-1_centos5.noarch
asterisk11-core-11.6.0-20_centos6.x86_64
asterisk11-addons-core-11.6.0-20_centos6.x86_64
asterisk11-addons-ooh323-11.6.0-20_centos6.x86_64
asterisk11-voicemail-11.6.0-20_centos6.x86_64
asterisk-sounds-core-en-ulaw-1.4.21-1_centos5.noarch
asterisk-sounds-core-en-alaw-1.4.21-1_centos5.noarch
asterisk11-11.6.0-20_centos6.x86_64
asterisk11-addons-11.6.0-20_centos6.x86_64
asterisk11-odbc-11.6.0-20_centos6.x86_64
asterisk11-res_digium_phone-1.0.1_centos6-20.x86_64
asterisk-version-switch-1.0.0.0-4_centos6.noarch
asterisk11-addons-mysql-11.6.0-20_centos6.x86_64
asterisk11-doc-11.6.0-20_centos6.x86_64
asterisk11-addons-bluetooth-11.6.0-20_centos6.x86_64
asterisk-sounds-core-en-gsm-1.4.21-1_centos5.noarch
asterisk11-flite-11.6.0-20.x86_64

grep spandsp

spandsp-0.0.6-0.8.pre21.el6.x86_64
spandsp-devel-0.0.6-0.8.pre21.el6.x86_64

Thanks in advance!
mts

I just found some more logs:
This is from the messages log:

Dec 24 11:29:09 localhost kernel: asterisk[6165]: segfault at c02 ip 0000000000000c02 sp 00007eff20f6a2e8 error 14 in asterisk[400000+231000]

And I saw this on the console:
/user/sbin/safe_asterisk: line 159: 6176 Segmentation fault (core dumped) nice -n $PRIORITY ${ASTSBINDIR}/asterisk -f ${CLIARGS} ${ASTARGS} > /dev/${TTY} 2>&1 < /dev/{TTY}

I found this in freepbx.log:
[2013-Dec-24 11:05:02] [CRITICAL] (admin/bootstrap.php:135) - Connection attmempt to AMI failed
It is marked as critical.

sorry, I forgot:
followed by:

Astersik ended with exit status 139
Automatically restarting Asterisk

Well that does not make sense. You should have asterisk 11.7 installed. Please try a

yum update asterisk*

Then restart asterisk and test faxing.

Hi,

just made the update. Unfortunately no difference.
Does the internal fax client use the libiax2? Yesterday I started to install hylafax and installed accidentally libiax2. Maybe I have some incompatibilities now. 
I have not completed the hylafax installation yet as I am not sure if I would need it.

Did you change your repo servers?

no.
BTW: asterisk is also crashing when I call the fax internally (by calling 666), so it is definitely no related to the DAHDi stuff.

This should be resolved now. Just yum update to get the latest Asterisk that should have the fix.

yum update asterisk*

im have made no change at all on my conf and im getting this…

[[email protected] asterisk]# /usr/sbin/safe_asterisk: line 159: 5532 Segmentation fault (core dumped) nice -n $PRIORITY ${ASTSBINDIR}/asterisk -f ${CLIARGS} ${ASTARGS} > /dev/${TTY} 2>&1 < /dev/${TTY}
Asterisk ended with exit status 139
Asterisk exited on signal 11.
Automatically restarting Asterisk.

can you help me?

tnx in advance

How about you provide information like distro version and freepbx version and asterisk version

On top of distro and version information, do you have a file that looks like “core.” in your /tmp directory?

ALPHA-6.12.65-3
Release Date-01-25-14
FreePBX 12, Linux 6.5
Asterisk 11 or 12
thats the latest distro… i think.

That’s the latest “alpha” distribution, by self definition expect it to be broken.

ok… so i have to re install it all?
wich is the best ?

tnx

You should probably install what you are comfortable with, if you choose an alpha you implicitly agree to provide feedback to the publisher, etc. etc. etc.

IMHO it was probably a mistake for Schmooze to offer both the stable and the alpha on their download page without explaining the difference between them upfront and what should be expected after downloading each.

(I am sure they will fix that soon :slight_smile: )