PIAF dialing out problem

[adelthefarmer]

Performed a new installe of PIAF with 4 port FXO card.When dialing out using Xlite, the call says established, I see the trunk turn red in Panel, Report shows the call answered. But actually my cell or any destination never rings. I ran update-scripts and update-fixes. Everything looks normal except the destination never receives the call.
Please help as this is urgent and my brother's business depends on me. Thank you

 

[dswartz]

We'd need to see the last few entries in the log when this happens. /var/log/asterisk/full.

 

[briankelly63]

Do you have anything you can actually monitor the phone line with? A telephone linemans set perhaps

 

[adelthefarmer]

Thanks dswartz. I made a test call then copied last 3 pages. Please let me know if you require more. Thank you so much for responding.: I called from extension 103 called number 0661931819 (9 to dial out is stripped)

[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/104-0a1d6f20", "1?normcid") in new stack
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Goto (macro-outbound-callerid,s,6)
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/104-0a1d6f20", "USEROUTCID=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Set
[2010-04-18 00:18:10] DEBUG[3878] func_db.c: DB: DEVICE/104/emergency_cid not found in database.
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/104-0a1d6f20", "EMERGENCYCID=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Set
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/104-0a1d6f20", "TRUNKOUTCID=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Set
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/104-0a1d6f20", "1?trunkcid") in new stack
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Goto (macro-outbound-callerid,s,12)
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/104-0a1d6f20", "0|Set|CALLERID(all)=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/104-0a1d6f20", "0|Set|CALLERID(all)=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/104-0a1d6f20", "0|Set|CALLERID(all)=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/104-0a1d6f20", "0|Set|CALLERID(all)=") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/104-0a1d6f20", "0|SetCallerPres|prohib_passed_scre$
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Macro
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/104-0a1d6f20", "0|AGI|fixlocalprefix") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/104-0a1d6f20", "OUTNUM=0661931819") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Set
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/104-0a1d6f20", "custom=ZAP/g0") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Set
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/104-0a1d6f20", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)"$
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: ExecIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/104-0a1d6f20", "dialout-trunk-predial-hook|") in new st$
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/104-0a1d6f20", "") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: Macro
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/104-0a1d6f20", "0?bypass|1") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/104-0a1d6f20", "0?customtrunk") in new stack
[2010-04-18 00:18:10] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/104-0a1d6f20", "ZAP/g0/0661931819|300|") in new stack
[2010-04-18 00:18:10] DEBUG[3878] chan_zap.c: Dialing '0661931819'
[2010-04-18 00:18:10] DEBUG[3878] chan_zap.c: Deferring dialing...
[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Called g0/0661931819
[2010-04-18 00:18:11] DEBUG[3878] chan_zap.c: Sent deferred digit string: T066193181
[2010-04-18 00:18:13] DEBUG[3878] chan_zap.c: Engaged echo training on channel 1
[2010-04-18 00:18:15] DEBUG[3878] chan_zap.c: Echo cancellation already on
[2010-04-18 00:18:15] VERBOSE[3878] logger.c: -- Zap/1-1 answered SIP/104-0a1d6f20
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Hungup 'Zap/1-1'
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/104-0a1d6f20' in macro 'dialout-trun$
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/104-0a1d6f20'
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/104-0a1d6f20", "hangupcall|") in new stack
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/104-0a1d6f20", "1?skiprg") in new stack
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Goto (macro-hangupcall,s,4)
[2010-04-18 00:18:26] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/104-0a1d6f20", "1?skipblkvm") in new stack
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Goto (macro-hangupcall,s,7)
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/104-0a1d6f20", "1?skipblkvm") in new stack
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Goto (macro-hangupcall,s,7)
[2010-04-18 00:18:26] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/104-0a1d6f20", "1?theend") in new stack
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Goto (macro-hangupcall,s,9)
[2010-04-18 00:18:26] DEBUG[3878] app_macro.c: Executed application: GotoIf
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/104-0a1d6f20", "") in new stack
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/104-0a1d6f20' in macro 'hangupcall'
[2010-04-18 00:18:26] VERBOSE[3878] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/104-0a1d6f20'
[2010-04-18 00:18:28] VERBOSE[3608] logger.c: -- Remote UNIX connection
[2010-04-18 00:18:28] VERBOSE[4127] logger.c: -- Remote UNIX connection disconnected
[2010-04-18 00:18:28] VERBOSE[3608] logger.c: -- Remote UNIX connection
[2010-04-18 00:18:29] VERBOSE[4131] logger.c: -- Remote UNIX connection disconnected

 

[adelthefarmer]

Brian, thanks for responding. I tested the lines using an analog phone. But I do not know how I can monitor it. sorry.

 

[dswartz]

Ah, what I think is going on: asterisk with zap can't really tell when the telco is ready to accept digits, so it might be dropping the first one or so, which messes you up. Try changing your zap trunk to prepend a 'w' to the front of the dial pattern.

 

[briankelly63]

Here's a reason to be able to listen:

http://pbxinaflash.com/community/th...iling-sent-deferred-digit-string.4190/?t=4190

take a regular analog phone, connect one wire directly to the phone line take the other wire and connect it to something around a 2uf mylar capacitor or any non polarized decent size cap that you can find laying around then attach the other end of the cap to the other phone line wire.

The cap will prevent the phone from pulling dial tone and will allow you to listen to whats going on.

You MAY be able to get the same effect by not using the cap if you pick up the phone AFTER you know the card has seized dialtone but it would be better to find a cap!

Brian

 

[adelthefarmer]

Dswartz, are you saying add w to the outbound route dial pattern as w9|. ? right now it is 9|. Sorry as I am a beginner here. I will try it in the morning as the office is closed.

 

[adelthefarmer]

Thanks Brian. I will try it in the morning. Good night.

 

[blanchae]

[2010-04-18 00:18:10] VERBOSE[3878] logger.c: -- Called g0/0661931819
[2010-04-18 00:18:11] DEBUG[3878] chan_zap.c: Sent deferred digit string: T066193181

Notice T066193181 has one digit missing? There is a bug in 1.6.0.7 that if echotraining=yes, the last digit is not sent, then echo training commences then the last digit is sent. I don't see the last digit being sent. Turn off echotraining and see if that fixes the problem. - THIS COULD BE A WILD GOOSE CHASE. see following posting.

 

[blanchae]

Here's the solution found already in another thread on this forum. Asterisk is dialing the line before it is ready, hence adding the w (pause) in front of the line.

 

[dswartz]

Ah, someone else hit that . No, I was saying the 'w' needs to be sent before the real number. You are stripping the 9 off the front, I am saying you need to add 'w' to the number actually sent out.

 

[adelthefarmer]

Thank you all.
1- what does w tel the system? I checked the documentation and could not find the information.
2- where excatly to add w? I am using PBXINAFLASH, is it in the Trunks? I only have one default trunk configured called All Incominx (ZAP), do I add w there in the Outbound Dial Prefix?
If not, please let me know where?

Sorry for all the basic questions as all this is new to me. Thanks a Million.