Restricting registration for fax modele extension

haywoodcox

Joined
Jun 11, 2009
Messages
79
Likes
0
Points
0
#1
Hi all,

Recently, my fax extension suddenly could not register. It was working fine before. But in the FOP and the Fax Visor, it is online and idle.

However, in CLI, it said restricting registration, when dial the call, it will get busy tone.

[Jul 14 12:35:45] NOTICE[3364]: chan_iax2.c:7564 update_registry: Restricting registration for peer '1727' to 60 seconds (requested 300)
[Jul 14 12:36:40] NOTICE[3364]: chan_iax2.c:7564 update_registry: Restricting registration for peer '1727' to 60 seconds (requested 300)
[Jul 14 12:37:35] NOTICE[3361]: chan_iax2.c:7564 update_registry: Restricting registration for peer '1727' to 60 seconds (requested 300)
[Jul 14 12:38:30] NOTICE[3363]: chan_iax2.c:7564 update_registry: Restricting registration for peer '1727' to 60 seconds (requested 300)
[Jul 14 12:39:25] NOTICE[3363]: chan_iax2.c:7564 update_registry: Restricting registration for peer '1727' to 60 seconds (requested 300)

I tried remove all fax extension in Fax module and IAX2, then create again. But result is the same.

The things I recently do is yum update, would that cause the problem? Thanks.
 

Patrick_elx

Joined
Dec 14, 2008
Messages
1,120
Likes
0
Points
0
#2
Your fax extension is properly registered. This message just mean that instead of allowing the registration to be for 300s the server only authorized it for 60s instead.

Nothing to worry about. If you want to remove this warning, you can change the max registration delay in the conf file.
 

haywoodcox

Joined
Jun 11, 2009
Messages
79
Likes
0
Points
0
#3
Hi Patrick,

However, when I dial the fax extensions x1727, it get busy tone, fail message is like below. Do you know why? Thanks.

== Parsing '/etc/asterisk/asterisk.conf': Found
== Parsing '/etc/asterisk/extconfig.conf': Found
Connected to Asterisk 1.4.33.1 currently running on p1tkdvpx01 (pid = 3229)
Verbosity is at least 4
-- Executing [1727@from-internal:1] Macro("SIP/1725-00000003", "exten-vm|novm|1727") in new stack
-- Executing [s@macro-exten-vm:1] Macro("SIP/1725-00000003", "user-callerid") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/1725-00000003", "AMPUSER=1725") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-user-callerid:2] GotoIf("SIP/1725-00000003", "0?report") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-user-callerid:3] ExecIf("SIP/1725-00000003", "1|Set|REALCALLERIDNUM=1725") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: ExecIf
-- Executing [s@macro-user-callerid:4] Set("SIP/1725-00000003", "AMPUSER=1725") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-user-callerid:5] Set("SIP/1725-00000003", "AMPUSERCIDNAME=Benny SIP Phone") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/1725-00000003", "0?report") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-user-callerid:7] Set("SIP/1725-00000003", "AMPUSERCID=1725") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-user-callerid:8] Set("SIP/1725-00000003", "CALLERID(all)="Benny SIP Phone" <1725>") in new sta ck
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-user-callerid:9] ExecIf("SIP/1725-00000003", "0|Set|CHANNEL(language)=") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: ExecIf
-- Executing [s@macro-user-callerid:10] GotoIf("SIP/1725-00000003", "0?continue") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-user-callerid:11] Set("SIP/1725-00000003", "__TTL=64") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/1725-00000003", "1?continue") in new stack
-- Goto (macro-user-callerid,s,19)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-user-callerid:19] NoOp("SIP/1725-00000003", "Using CallerID "Benny SIP Phone" <1725>") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Noop
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Macro
-- Executing [s@macro-exten-vm:2] Set("SIP/1725-00000003", "RingGroupMethod=none") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-exten-vm:3] Set("SIP/1725-00000003", "VMBOX=novm") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-exten-vm:4] Set("SIP/1725-00000003", "EXTTOCALL=1727") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
[Jul 15 11:29:20] DEBUG[7325]: func_db.c:70 function_db_read: DB: CFU/1727 not found in database.
-- Executing [s@macro-exten-vm:5] Set("SIP/1725-00000003", "CFUEXT=") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
[Jul 15 11:29:20] DEBUG[7325]: func_db.c:70 function_db_read: DB: CFB/1727 not found in database.
-- Executing [s@macro-exten-vm:6] Set("SIP/1725-00000003", "CFBEXT=") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-exten-vm:7] Set("SIP/1725-00000003", "RT=""") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-exten-vm:8] Macro("SIP/1725-00000003", "record-enable|1727|IN") in new stack
-- Executing [s@macro-record-enable:1] GotoIf("SIP/1725-00000003", "1?check") in new stack
-- Goto (macro-record-enable,s,4)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-record-enable:4] AGI("SIP/1725-00000003", "recordingcheck|20100715-112920|1279160960.3") in ne w stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck|20100715-112920|1279160960.3: Inbound recording not enabled
-- AGI Script recordingcheck completed, returning 0
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: AGI
-- Executing [s@macro-record-enable:5] MacroExit("SIP/1725-00000003", "") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Macro
-- Executing [s@macro-exten-vm:9] Macro("SIP/1725-00000003", "dial||tr|1727") in new stack
-- Executing [s@macro-dial:1] GotoIf("SIP/1725-00000003", "1?dial") in new stack
-- Goto (macro-dial,s,3)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-dial:3] AGI("SIP/1725-00000003", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
== Parsing '/etc/asterisk/manager.conf': Found
== Parsing '/etc/asterisk/manager_additional.conf': Found
== Parsing '/etc/asterisk/manager_custom.conf': Found
[Jul 15 11:29:20] WARNING[7328]: config.c:781 process_text_line: Unknown directive '#permit=192.168.1.0/255.255.255.0' a t line 18 of /etc/asterisk/manager_custom.conf
== Manager 'admin' logged on from 127.0.0.1
dialparties.agi: Caller ID name is 'Benny SIP Phone' number is '1725'
dialparties.agi: Methodology of ring is 'none'
-- dialparties.agi: Added extension 1727 to extension map
-- dialparties.agi: Extension 1727 cf is disabled
-- dialparties.agi: Extension 1727 do not disturb is disabled
> dialparties.agi: extnum 1727 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: ExtensionState: 0
dialparties.agi: Extension 1727 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 1727
-- dialparties.agi: dbset CALLTRACE/1727 to 1725
-- dialparties.agi: Filtered ARG3: 1727
== Manager 'admin' logged off from 127.0.0.1
-- AGI Script dialparties.agi completed, returning 0
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: AGI
-- Executing [s@macro-dial:7] Dial("SIP/1725-00000003", "IAX2/1727||tr") in new stack
[Jul 15 11:29:20] NOTICE[7325]: app_dial.c:1207 dial_exec_full: Hey! chan SIP/1725-00000003's context='macro-dial', and exten='s'
[Jul 15 11:29:20] DEBUG[7325]: chan_iax2.c:4000 create_addr: prepending 2 to prefs
-- Called 1727
[Jul 15 11:29:20] WARNING[3361]: chan_iax2.c:9189 socket_process: Call rejected by 127.0.0.1: No matching codec support
-- Hungup 'IAX2/1727-826'
== Everyone is busy/congested at this time (1:0/0/1)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Dial
-- Executing [s@macro-dial:8] Set("SIP/1725-00000003", "DIALSTATUS=CHANUNAVAIL") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-dial:9] GosubIf("SIP/1725-00000003", "0?CHANUNAVAIL|1") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GosubIf
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Macro
-- Executing [s@macro-exten-vm:10] GotoIf("SIP/1725-00000003", "0?exit|return") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-exten-vm:11] Set("SIP/1725-00000003", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-exten-vm:12] GosubIf("SIP/1725-00000003", "0?docfu|1") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GosubIf
-- Executing [s@macro-exten-vm:13] GosubIf("SIP/1725-00000003", "0?docfb|1") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GosubIf
-- Executing [s@macro-exten-vm:14] Set("SIP/1725-00000003", "DIALSTATUS=CHANUNAVAIL") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Set
-- Executing [s@macro-exten-vm:15] NoOp("SIP/1725-00000003", "Voicemail is novm") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: NoOp
-- Executing [s@macro-exten-vm:16] GotoIf("SIP/1725-00000003", "1?s-CHANUNAVAIL|1") in new stack
-- Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s-CHANUNAVAIL@macro-exten-vm:1] NoOp("SIP/1725-00000003", "IVR_RETVM: IVR_CONTEXT: ") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Noop
-- Executing [s-CHANUNAVAIL@macro-exten-vm:2] GotoIf("SIP/1725-00000003", "0?exit|1") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s-CHANUNAVAIL@macro-exten-vm:3] PlayTones("SIP/1725-00000003", "congestion") in new stack
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: Playtones
-- Executing [s-CHANUNAVAIL@macro-exten-vm:4] Congestion("SIP/1725-00000003", "10") in new stack
== Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 4) exited non-zero on 'SIP/1725-00000003' in macro 'exten-vm'
== Spawn extension (from-internal, 1727, 1) exited non-zero on 'SIP/1725-00000003'
-- Executing [h@from-internal:1] Macro("SIP/1725-00000003", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/1725-00000003", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,4)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-hangupcall:4] GotoIf("SIP/1725-00000003", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,7)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-hangupcall:7] GotoIf("SIP/1725-00000003", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
[Jul 15 11:29:20] DEBUG[7325]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-hangupcall:9] Hangup("SIP/1725-00000003", "") in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/1725-00000003' in macro 'hangupcall'
== Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1725-00000003'
 

dicko

Joined
Oct 24, 2008
Messages
4,099
Likes
0
Points
0
#4
It often helps to actually read the logs that you post before you post them, you never know maybe a "blinding flash" of enlightenment will hit you, I cared to do so and this stuck out like a sore thumb. . .

[Jul 15 11:29:20] WARNING[3361]: chan_iax2.c:9189 socket_process: Call rejected by 127.0.0.1: No matching codec support
-- Hungup 'IAX2/1727-826'
== Everyone is busy/congested at this time (1:0/0/1)


You probably know by now that fax only works with a g711 codec, did you catch that part of your post? Do you know how to fix it? if not there is always the FM, it often helps to "R it" ;)

dicko

p.s.

how did the CID passthrogh work out? , you didn't say.
 

haywoodcox

Joined
Jun 11, 2009
Messages
79
Likes
0
Points
0
#5
Hi Dicko,

Thanks for your help. Sorry for overlook that. After I added allow=alaw&ulaw in x1727, it works fine now. As I have set only allow ilbc & gsm in the sip_general_customs.conf

Thanks again
 

dicko

Joined
Oct 24, 2008
Messages
4,099
Likes
0
Points
0
#6
and the CID pass-through?

Please don't apologize, just learn from your errors/omissions, you ask questions we try to help, but a little learning on your part will go a long way and save you asking in the first place, feedback is always appreciated. It is our time also !

dicko

p.s. if you want to get and send faxes it probably not a good idea to deny g711 in "sip_general_customs.conf" (sic)

Please haywoodcox and anyone else who stumbles here, understand the the rules and precedences of asterisk's "file inclusions", ALL the config files follow them. When you get that in your head it all becomes "logical" and functional.
 

haywoodcox

Joined
Jun 11, 2009
Messages
79
Likes
0
Points
0
#7
Hi all,

Though I could get the fax tone now. But it can't receive any fax.

Even I just send fax internally from a fax machine to the extension. It get connected a while and then get dropped.

But it didn't mentioned the reason for hungup in the console message.

-- Called 1835
-- Call accepted by 127.0.0.1 (format alaw)
-- Format for call is alaw
-- IAX2/1835-12441 is ringing
-- IAX2/1835-12441 answered IAX2/pbxhk-2277
-- Executing [h@macro-dial:1] Macro("IAX2/pbxhk-2277", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("IAX2/pbxhk-2277", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,4)
[Jul 16 12:47:05] DEBUG[6585]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-hangupcall:4] GotoIf("IAX2/pbxhk-2277", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,7)
[Jul 16 12:47:05] DEBUG[6585]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-hangupcall:7] GotoIf("IAX2/pbxhk-2277", "1?theend") in new stack
-- Goto (macro-hangupcall,s,9)
[Jul 16 12:47:05] DEBUG[6585]: app_macro.c:379 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-hangupcall:9] Hangup("IAX2/pbxhk-2277", "") in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'IAX2/pbxhk-2277' in macro 'hangupcall'
== Spawn h extension (macro-dial, h, 1) exited non-zero on 'IAX2/pbxhk-2277'
-- Hungup 'IAX2/1835-12441'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'IAX2/pbxhk-2277' in macro 'dial'
== Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'IAX2/pbxhk-2277' in macro 'exten-vm'
== Spawn extension (from-internal, 1835, 1) exited non-zero on 'IAX2/pbxhk-2277'
-- Hungup 'IAX2/pbxhk-2277'
 

dicko

Joined
Oct 24, 2008
Messages
4,099
Likes
0
Points
0
#8
Then you need to look in the hylafax logs, the asterisk logs just know about phone calls, nothing about what was in them, this one obviously succeeded.

cd /var/spool/hylafax/log && less `ls -rt c* |tail -1`


this will cd to where the logs are written, find the latest log and "pipe" it through less, you will normally see what went wrong in there. (for a real time trace replace 'less' with 'tail -f ' and run the command after the call is "up" )

p.s.

If you post from /var/log/asterisk/full instead of the asterisk cli the same entries will be shown with a "time-stamp" this makes it easier to diagnose as you will retain a temporal trace also.


p.p.s

To diagnose the basic iax2 stuff it is often easier to send from one iax2 modem to another, then analyze both logs, if that doesn't work suspect your hardware as being underpowered, if however it does work that way (and I bet it does) then you will need to look elsewhere.


dicko
 

haywoodcox

Joined
Jun 11, 2009
Messages
79
Likes
0
Points
0
#9
Thanks Dicko,

I changed it to "None" for fax dectection type on the incoming route, and also some codec change between IAX trunk. And it works fine now.
 

Members online

No members online now.

Latest posts

Forum statistics

Threads
30,900
Messages
130,884
Members
17,561
Latest member
marouen
Top