Restricting registration for fax modele extension

Discussion in 'General' started by haywoodcox, Jul 14, 2010.

  1. haywoodcox

    Joined:
    Jun 11, 2009
    Messages:
    79
    Likes Received:
    0
    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.
     
  2. Patrick_elx

    Joined:
    Dec 14, 2008
    Messages:
    1,120
    Likes Received:
    0
    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.
     
  3. haywoodcox

    Joined:
    Jun 11, 2009
    Messages:
    79
    Likes Received:
    0
    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'
     
  4. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    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.
     
  5. haywoodcox

    Joined:
    Jun 11, 2009
    Messages:
    79
    Likes Received:
    0
    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
     
  6. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    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.
     
  7. haywoodcox

    Joined:
    Jun 11, 2009
    Messages:
    79
    Likes Received:
    0
    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'
     
  8. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    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
     
  9. haywoodcox

    Joined:
    Jun 11, 2009
    Messages:
    79
    Likes Received:
    0
    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.
     

Share This Page