Asterisk 1.4.22 sometimes no audio

MST

Joined
Sep 25, 2009
Messages
317
Likes
0
Points
16
#1
Hello All,

I have been trying to figure it out the resent problem with "no audio" on some inbound / outbound calls. This happens seldom but it happens.

This could be issue with internet connection but with Asterisk itself. here is log example in one of calls using SIP trunk

-Silence Superstition is off
-echotraining is uncommended
-codec on trunk and phones is g729

Code:
[Oct 20 23:47:21] VERBOSE[18924] logger.c:     -- DBdel: family=BLKVM, key=601/SIP/IP_VOIP_PROVIDER-b7b14960
[Oct 20 23:47:21] VERBOSE[18924] logger.c:     -- DBdel: Error deleting key from database.
[Oct 20 23:47:21] DEBUG[18924] app_macro.c: Executed application: DBDel
[Oct 20 23:47:21] VERBOSE[18924] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/IP_VOIP_PROVIDER-b7b14960", "1?theend") in new stack
[Oct 20 23:47:21] VERBOSE[18924] logger.c:     -- Goto (macro-hangupcall,s,9)
[Oct 20 23:47:21] DEBUG[18924] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:21] VERBOSE[18924] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("SIP/IP_VOIP_PROVIDER-b7b14960", "") in new stack
[Oct 20 23:47:21] VERBOSE[18924] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/IP_VOIP_PROVIDER-b7b14960' in macro 'hangupcall'
[Oct 20 23:47:21] VERBOSE[18924] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/IP_VOIP_PROVIDER-b7b14960'
[Oct 20 23:47:21] VERBOSE[18967] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Oct 20 23:47:21] VERBOSE[18967] logger.c: Found
[Oct 20 23:47:21] VERBOSE[18967] logger.c:   == Parsing '/etc/asterisk/manager_additional.conf': [Oct 20 23:47:21] VERBOSE[18967] logger.c: Found
[Oct 20 23:47:21] VERBOSE[18967] logger.c:   == Parsing '/etc/asterisk/manager_custom.conf': [Oct 20 23:47:21] VERBOSE[18967] logger.c: Found
[Oct 20 23:47:21] VERBOSE[18967] logger.c:   == Manager 'admin' logged on from 127.0.0.1
[Oct 20 23:47:21] VERBOSE[18967] logger.c:   == Manager 'admin' logged off from 127.0.0.1
[Oct 20 23:47:21] VERBOSE[18968] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Oct 20 23:47:21] VERBOSE[18968] logger.c: Found
[Oct 20 23:47:21] VERBOSE[18968] logger.c:   == Parsing '/etc/asterisk/manager_additional.conf': [Oct 20 23:47:21] VERBOSE[18968] logger.c: Found
[Oct 20 23:47:21] VERBOSE[18968] logger.c:   == Parsing '/etc/asterisk/manager_custom.conf': [Oct 20 23:47:21] VERBOSE[18968] logger.c: Found
[Oct 20 23:47:21] VERBOSE[18968] logger.c:   == Manager 'admin' logged on from 127.0.0.1
[Oct 20 23:47:21] VERBOSE[18968] logger.c:   == Manager 'admin' logged off from 127.0.0.1
[Oct 20 23:47:34] VERBOSE[18969] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Oct 20 23:47:34] VERBOSE[18969] logger.c: Found
[Oct 20 23:47:34] VERBOSE[18969] logger.c:   == Parsing '/etc/asterisk/manager_additional.conf': [Oct 20 23:47:34] VERBOSE[18969] logger.c: Found
[Oct 20 23:47:34] VERBOSE[18969] logger.c:   == Parsing '/etc/asterisk/manager_custom.conf': [Oct 20 23:47:34] VERBOSE[18969] logger.c: Found
[Oct 20 23:47:34] VERBOSE[18969] logger.c:   == Manager 'admin' logged on from 127.0.0.1
[Oct 20 23:47:34] VERBOSE[18969] logger.c:   == Manager 'admin' logged off from 127.0.0.1
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [18005555555@from-internal:1] Macro("SIP/102-b7b252b8", "user-callerid|SKIPTTL|") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/102-b7b252b8", "AMPUSER=102") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/102-b7b252b8", "0?report") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/102-b7b252b8", "1|Set|REALCALLERIDNUM=102") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/102-b7b252b8", "AMPUSER=102") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/102-b7b252b8", "AMPUSERCIDNAME=USER") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/102-b7b252b8", "0?report") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/102-b7b252b8", "AMPUSERCID=102") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:8] Set("SIP/102-b7b252b8", "CALLERID(all)="USER" <102>") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:9] ExecIf("SIP/102-b7b252b8", "0|Set|CHANNEL(language)=") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:10] GotoIf("SIP/102-b7b252b8", "1?continue") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Goto (macro-user-callerid,s,19)
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-user-callerid:19] NoOp("SIP/102-b7b252b8", "Using CallerID "USER" <102>") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Noop
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [18005555555@from-internal:2] Set("SIP/102-b7b252b8", "_NODEST=") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [18005555555@from-internal:3] Macro("SIP/102-b7b252b8", "record-enable|102|OUT|") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-record-enable:1] GotoIf("SIP/102-b7b252b8", "1?check") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Goto (macro-record-enable,s,4)
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-record-enable:4] AGI("SIP/102-b7b252b8", "recordingcheck|20101020-234734|1287611254.62987") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Oct 20 23:47:34] VERBOSE[18973] logger.c:   recordingcheck|20101020-234734|1287611254.62987: Outbound recording not enabled
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- AGI Script recordingcheck completed, returning 0
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: AGI
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-record-enable:5] MacroExit("SIP/102-b7b252b8", "") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [18005555555@from-internal:4] Macro("SIP/102-b7b252b8", "dialout-trunk|5|18005555555||") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/102-b7b252b8", "DIAL_TRUNK=5") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] DEBUG[18973] func_db.c: DB: AMPUSER/102/pinless not found in database.
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/102-b7b252b8", "0?sub-pincheck|s|1") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GosubIf
[Oct 20 23:47:34] DEBUG[18973] func_db.c: DB: AMPUSER/102/pinless not found in database.
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/102-b7b252b8", "0?disabletrunk|1") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/102-b7b252b8", "DIAL_NUMBER=18005555555") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/102-b7b252b8", "DIAL_TRUNK_OPTIONS=trwW") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/102-b7b252b8", "OUTBOUND_GROUP=OUT_5") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/102-b7b252b8", "1?nomax") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Goto (macro-dialout-trunk,s,9)
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/102-b7b252b8", "0?skipoutcid") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/102-b7b252b8", "DIAL_TRUNK_OPTIONS=wW") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/102-b7b252b8", "outbound-callerid|5") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/102-b7b252b8", "0|SetCallerPres|") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/102-b7b252b8", "0|Set|REALCALLERIDNUM=102") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/102-b7b252b8", "1?normcid") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Goto (macro-outbound-callerid,s,6)
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/102-b7b252b8", "USEROUTCID=") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] DEBUG[18973] func_db.c: DB: DEVICE/102/emergency_cid not found in database.
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/102-b7b252b8", "EMERGENCYCID=") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/102-b7b252b8", "TRUNKOUTCID=<12241234546>") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/102-b7b252b8", "1?trunkcid") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Goto (macro-outbound-callerid,s,12)
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/102-b7b252b8", "1|Set|CALLERID(all)=<12241234546>") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Last app: Set|CALLERID(all)=<12241234546>
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/102-b7b252b8", "0|Set|CALLERID(all)=") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/102-b7b252b8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Macro
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/102-b7b252b8", "0|AGI|fixlocalprefix") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/102-b7b252b8", "OUTNUM=18005555555") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/102-b7b252b8", "custom=SIP/VOIP_Provider") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Set
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/102-b7b252b8", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)wW") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: ExecIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:16] Macro("SIP/102-b7b252b8", "dialout-trunk-predial-hook|") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/102-b7b252b8", "") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: Macro
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/102-b7b252b8", "0?bypass|1") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/102-b7b252b8", "0?customtrunk") in new stack
[Oct 20 23:47:34] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Executing [s@macro-dialout-trunk:19] Dial("SIP/102-b7b252b8", "SIP/VOIP_Provider/18005555555|300|wW") in new stack
[Oct 20 23:47:34] VERBOSE[18973] logger.c:     -- Called VOIP_Provider/18005555555
[Oct 20 23:47:40] VERBOSE[18973] logger.c:     -- SIP/VOIP_Provider-0a086be0 is making progress passing it to SIP/102-b7b252b8
[Oct 20 23:47:55] VERBOSE[18973] logger.c:     -- SIP/VOIP_Provider-0a086be0 answered SIP/102-b7b252b8
[Oct 20 23:47:57] DEBUG[18973] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Oct 20 23:47:57] DEBUG[18973] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2010-10-20 23:47:34','12241234546','12241234546','18005555555','from-internal', 'SIP/102-b7b252b8','SIP/VOIP_Provider-0a086be0','Dial','SIP/VOIP_Provider/18005555555|300|wW',23,2,'ANSWERED',3,'','')
[Oct 20 23:47:57] VERBOSE[18973] logger.c:   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/102-b7b252b8' in macro 'dialout-trunk'
[Oct 20 23:47:57] VERBOSE[18973] logger.c:   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/102-b7b252b8'
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Executing [h@macro-dialout-trunk:1] Macro("SIP/102-b7b252b8", "hangupcall|") in new stack
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/102-b7b252b8", "1?skiprg") in new stack
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Goto (macro-hangupcall,s,4)
[Oct 20 23:47:57] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/102-b7b252b8", "1?skipblkvm") in new stack
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Goto (macro-hangupcall,s,7)
[Oct 20 23:47:57] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/102-b7b252b8", "1?theend") in new stack
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Goto (macro-hangupcall,s,9)
[Oct 20 23:47:57] DEBUG[18973] app_macro.c: Executed application: GotoIf
[Oct 20 23:47:57] VERBOSE[18973] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("SIP/102-b7b252b8", "") in new stack
[Oct 20 23:47:57] VERBOSE[18973] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/102-b7b252b8' in macro 'hangupcall'
[Oct 20 23:47:57] VERBOSE[18973] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/102-b7b252b8'
[Oct 20 23:47:57] VERBOSE[18975] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Oct 20 23:47:57] VERBOSE[18975] logger.c: Found
[Oct 20 23:47:57] VERBOSE[18975] logger.c:   == Parsing '/etc/asterisk/manager_additional.conf': [Oct 20 23:47:57] VERBOSE[18975] logger.c: Found
[Oct 20 23:47:57] VERBOSE[18975] logger.c:   == Parsing '/etc/asterisk/manager_custom.conf': [Oct 20 23:47:57] VERBOSE[18975] logger.c: Found
[Oct 20 23:47:57] VERBOSE[18975] logger.c:   == Manager 'admin' logged on from 127.0.0.1
Can you advice if could be the problem with bridging the audio from VoipProvider to extension?

Thank you
 

Members online

No members online now.

Latest posts

Forum statistics

Threads
30,902
Messages
130,886
Members
17,563
Latest member
dineshr
Top