Hello,
the upgrade itself worked the way it should, as it seems. But now I’ve got problems calling extensions, from internal as well as from trunk. The problem seems to be related to dialparties.agi as it always returnes with no extensions to dial.
See below for what I find in the asterisk full log when calling ext. 3345 from ext. 3344. If the parts before this are important I can post them as well. Similar things happen on trunk originated calls routed to a ring group. Note the (suspicious?) NOTICE “pbx.c: No such label ‘normdial’ in extension ‘s’ in context ‘macro-dial’” during dialparties.agi operation. System is Debian 4 with asterisk 1.2.13, freepbx running on php5/fcgi with the same permissions as asterisk.
As this issue is somewhat urgent (was very confident and forgot to make backups ) I appreciate any help. Thank you!
Andreas
[...]
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Goto (macro-dial,s,3)
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing AGI("SIP/3344-081b24c8", "dialparties.agi") in new stack
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
Feb 23 14:14:06 VERBOSE[16639] logger.c: dialparties.agi: Starting New Dialparties.agi
Feb 23 14:14:06 DEBUG[16643] manager.c: Manager received command 'login'
Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager.conf': Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found
Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Feb 23 14:14:06 VERBOSE[16643] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
Feb 23 14:14:06 DEBUG[16643] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Feb 23 14:14:06 DEBUG[16643] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Feb 23 14:14:06 DEBUG[16643] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Feb 23 14:14:06 DEBUG[16643] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Feb 23 14:14:06 VERBOSE[16643] logger.c: == Manager 'admin' logged on from 127.0.0.1
Feb 23 14:14:06 VERBOSE[16639] logger.c: dialparties.agi: Caller ID name is 'Andreas - PC Puut' number is '3344'
Feb 23 14:14:06 VERBOSE[16639] logger.c: dialparties.agi: Methodology of ring is 'none'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Added extension 3345 to extension map
Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'CF'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Extension 3345 cf is disabled
Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'DND'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Extension 3345 do not disturb is disabled
Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'CFB'
Feb 23 14:14:06 DEBUG[16639] db.c: Unable to find key '3345' in family 'CFU'
Feb 23 14:14:06 DEBUG[16643] manager.c: Manager received command 'ExtensionState'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: dbset CALLTRACE/3345 to 3344
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- dialparties.agi: Filtered ARG3: 3345
Feb 23 14:14:06 NOTICE[16639] pbx.c: No such label 'normdial' in extension 's' in context 'macro-dial'
Feb 23 14:14:06 DEBUG[16643] manager.c: Manager received command 'Logoff'
Feb 23 14:14:06 VERBOSE[16643] logger.c: == Manager 'admin' logged off from 127.0.0.1
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- AGI Script dialparties.agi completed, returning 0
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing NoOp("SIP/3344-081b24c8", "Returned from dialparties with no extensions to call and DIALSTATUS: ") in new stack
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing Set("SIP/3344-081b24c8", "SV_DIALSTATUS=") in new stack
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing GosubIf("SIP/3344-081b24c8", "0?docfu|1") in new stack
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '0'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing GosubIf("SIP/3344-081b24c8", "0?docfb|1") in new stack
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing Set("SIP/3344-081b24c8", "DIALSTATUS=") in new stack
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing NoOp("SIP/3344-081b24c8", "Voicemail is novm") in new stack
Feb 23 14:14:06 DEBUG[16639] pbx.c: Expression result is '1'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing GotoIf("SIP/3344-081b24c8", "1?s-|1") in new stack
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Goto (macro-exten-vm,s-,1)
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing PlayTones("SIP/3344-081b24c8", "congestion") in new stack
Feb 23 14:14:06 DEBUG[16639] channel.c: Prodding channel 'SIP/3344-081b24c8'
Feb 23 14:14:06 VERBOSE[16639] logger.c: -- Executing Congestion("SIP/3344-081b24c8", "10") in new stack
Feb 23 14:14:06 VERBOSE[16639] logger.c: == Spawn extension (macro-exten-vm, s-, 2) exited non-zero on 'SIP/3344-081b24c8' in macro 'exten-vm'
Feb 23 14:14:06 VERBOSE[16639] logger.c: == Spawn extension (macro-exten-vm, s-, 2) exited non-zero on 'SIP/3344-081b24c8'
Feb 23 14:14:06 DEBUG[16155] channel.c: Avoiding initial deadlock for 'SIP/3344-081b24c8'
Feb 23 14:14:06 DEBUG[16163] chan_sip.c: Failed to grab lock, trying again...
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '"Andreas - PC Puut" <3344>'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '3344'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '3345'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'from-internal'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'SIP/3344-081b24c8'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'Congestion'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '10'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '2008-02-23 14:14:06'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '2008-02-23 14:14:06'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '0'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '0'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'NO ANSWER'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is 'DOCUMENTATION'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '1203772446.3'
Feb 23 14:14:06 DEBUG[16639] pbx.c: Function result is '(null)'
Feb 23 14:14:06 DEBUG[16639] chan_sip.c: update_call_counter(3344) - decrement call limit counter
Feb 23 14:14:06 DEBUG[16163] chan_sip.c: Stopping retransmission on 'YzExY2QzZTE5Njc1YWU0YmVlMjkyNWJmOWQ1Yzc5MzI.' of Response 2: Match Not Found
Feb 23 14:14:09 NOTICE[16163] chan_sip.c: Unknown SIP command 'PUBLISH' from '192.168.240.75'
Feb 23 14:14:11 DEBUG[16163] chan_sip.c: Auto destroying call '[email protected]'
Feb 23 14:14:16 WARNING[16169] chan_oh323.c: Gatekeeper discovery failed.
Feb 23 14:14:16 VERBOSE[16169] logger.c: -- Retrying gatekeeper registration.