Asterisk 1.4.22 sometimes no audio

Discussion in 'General' started by MST, Oct 22, 2010.

  1. MST

    MST

    Joined:
    Sep 25, 2009
    Messages:
    317
    Likes Received:
    0
    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
     

Share This Page