Ticket #3317 (closed Bugs: fixed)

Opened 3 years ago

Last modified 3 years ago

Parking Lot endless loop

Reported by: fredtips Assigned to: p_lindheimer
Priority: major Milestone: 2.6
Component: Parking Lot Version: 2.5-branch
Keywords: Cc:
Confirmation: Need Feedback SVN Revision (if applicable): 7967
Backend Engine: Asterisk 1.4.x Backend Engine Version:

Description (Last modified by p_lindheimer)

Outside call comes in from DID number. Extension 3300 or 3301 or 3302 parks the call. If outside call hangs up while in parking lot, this starts the endless loop.

Background. We where running FreePBX 2.4xx on an Asterisk 1.2xx box. Upgraded FreePBX to 2.5 and that is when the problem started. Then I used backup utility in FreePBX to backup data, Then did a fresh install of PBXinAFlash 1.3 64 bit asterisk version 1.4xx. Installed all patches and updates then restored data. Result is same problem but system no longer crashes. When condition happens, CPU goes up to 80% and cannot transfer calls.

Asterisk Version 1.4.22 Freepbx Version 2.5.0.1 Below is from the full log file 

    VERBOSE[15739] logger.c: == Spawn extension (park-dial, SIP/3301, 1) exited non-zero on 'IAX2/PSTN-VoIP-6799'

[Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [h@park-dial:1] NoOp?("IAX2/PSTN-VoIP-6799", "Parked Call Timed Out and Got Orphaned") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [h@park-dial:2] Set("IAX2/PSTN-VoIP-6799", "CALLERID(name)=Park:MS2104760115") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [h@park-dial:3] Goto("IAX2/PSTN-VoIP-6799", "ext-group|600|1") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (ext-group,600,1) [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:1] Macro("IAX2/PSTN-VoIP-6799", "user-callerid|") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [s@macro-user-callerid:1] Set("IAX2/PSTN-VoIP-6799", "AMPUSER=2104760115") in new stack [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Executed application: Set [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Extension s, macroexten 600, priority 1 returned normally even though call was hung up [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:2] GotoIf?("IAX2/PSTN-VoIP-6799", "0?skipdb") in new stack [Oct 22 12:35:43] VERBOSE[27573] logger.c: Extension Changed 3301[ext-local] new state Idle for Notify User 3302 [Oct 22 12:35:43] VERBOSE[27573] logger.c: Extension Changed 3301[ext-local] new state Idle for Notify User 3301 [Oct 22 12:35:43] VERBOSE[27573] logger.c: Extension Changed 3301[ext-local] new state Idle for Notify User 3300 [Oct 22 12:35:43] DEBUG[15739] func_db.c: DB: BLKVM/600/IAX2/PSTN-VoIP-6799 not found in database. [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:3] GotoIf?("IAX2/PSTN-VoIP-6799", "0?skipov") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:4] Set("IAX2/PSTN-VoIP-6799", "NODEST=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:5] Set("IAX2/PSTN-VoIP-6799", "BLKVM_OVERRIDE=BLKVM/600/IAX2/PSTN-VoIP-6799") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:6] Set("IAX2/PSTN-VoIP-6799", "BLKVM_BASE=600") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:7] Set("IAX2/PSTN-VoIP-6799", "DB(BLKVM/600/IAX2/PSTN-VoIP-6799)=TRUE") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:8] Set("IAX2/PSTN-VoIP-6799", "RRNODEST=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:9] Set("IAX2/PSTN-VoIP-6799", "NODEST=600") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:10] GotoIf?("IAX2/PSTN-VoIP-6799", "0?REPCID") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:11] GotoIf?("IAX2/PSTN-VoIP-6799", "1?REPCID") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (ext-group,600,15) [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:15] NoOp?("IAX2/PSTN-VoIP-6799", "CALLERID(name) is Park:MS2104760115") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:16] Set("IAX2/PSTN-VoIP-6799", "_RGPREFIX=MS") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:17] Set("IAX2/PSTN-VoIP-6799", "CALLERID(name)=MSPark:MS2104760115") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:18] Set("IAX2/PSTN-VoIP-6799", "RecordMethod?=Group") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:19] Macro("IAX2/PSTN-VoIP-6799", "record-enable|3300-3301-3302-3309|Group") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [s@macro-record-enable:1] GotoIf?("IAX2/PSTN-VoIP-6799", "1?check") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (macro-record-enable,s,4) [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Executed application: GotoIf? [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Extension s, macroexten 600, priority 3 returned normally even though call was hung up [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:20] Set("IAX2/PSTN-VoIP-6799", "RingGroupMethod?=ringall") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:21] Macro("IAX2/PSTN-VoIP-6799", "dial|20|trw|3300-3301-3302-3309") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [s@macro-dial:1] GotoIf?("IAX2/PSTN-VoIP-6799", "1?dial") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (macro-dial,s,3) [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Executed application: GotoIf? [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Extension s, macroexten 600, priority 2 returned normally even though call was hung up [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:22] Set("IAX2/PSTN-VoIP-6799", "RingGroupMethod?=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:23] GotoIf?("IAX2/PSTN-VoIP-6799", "0?nodest") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:24] Set("IAX2/PSTN-VoIP-6799", "NODEST=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:25] DBdel("IAX2/PSTN-VoIP-6799", "BLKVM/600/IAX2/PSTN-VoIP-6799") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- DBdel: family=BLKVM, key=600/IAX2/PSTN-VoIP-6799 [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [600@ext-group:26] Goto("IAX2/PSTN-VoIP-6799", "ext-group|601|1") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (ext-group,601,1) [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:1] Macro("IAX2/PSTN-VoIP-6799", "user-callerid|") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [s@macro-user-callerid:1] Set("IAX2/PSTN-VoIP-6799", "AMPUSER=2104760115") in new stack [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Executed application: Set [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Extension s, macroexten 601, priority 1 returned normally even though call was hung up [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:2] GotoIf?("IAX2/PSTN-VoIP-6799", "0?skipdb") in new stack [Oct 22 12:35:43] DEBUG[15739] func_db.c: DB: BLKVM/600/IAX2/PSTN-VoIP-6799 not found in database. [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:3] GotoIf?("IAX2/PSTN-VoIP-6799", "0?skipov") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:4] Set("IAX2/PSTN-VoIP-6799", "NODEST=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:5] Set("IAX2/PSTN-VoIP-6799", "BLKVM_OVERRIDE=BLKVM/601/IAX2/PSTN-VoIP-6799") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:6] Set("IAX2/PSTN-VoIP-6799", "BLKVM_BASE=601") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:7] Set("IAX2/PSTN-VoIP-6799", "DB(BLKVM/601/IAX2/PSTN-VoIP-6799)=TRUE") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:8] Set("IAX2/PSTN-VoIP-6799", "RRNODEST=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:9] Set("IAX2/PSTN-VoIP-6799", "NODEST=601") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:10] GotoIf?("IAX2/PSTN-VoIP-6799", "0?REPCID") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:11] GotoIf?("IAX2/PSTN-VoIP-6799", "0?REPCID") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:12] NoOp?("IAX2/PSTN-VoIP-6799", "Current RGPREFIX is MS....stripping from Caller ID") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:13] Set("IAX2/PSTN-VoIP-6799", "CALLERID(name)=Park:MS2104760115") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:14] Set("IAX2/PSTN-VoIP-6799", "_RGPREFIX=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:15] NoOp?("IAX2/PSTN-VoIP-6799", "CALLERID(name) is Park:MS2104760115") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:16] Set("IAX2/PSTN-VoIP-6799", "_RGPREFIX=MS") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:17] Set("IAX2/PSTN-VoIP-6799", "CALLERID(name)=MSPark:MS2104760115") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:18] Set("IAX2/PSTN-VoIP-6799", "RecordMethod?=Group") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:19] Macro("IAX2/PSTN-VoIP-6799", "record-enable|3300-3301-3302-3308-3309|Group") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [s@macro-record-enable:1] GotoIf?("IAX2/PSTN-VoIP-6799", "1?check") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (macro-record-enable,s,4) [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Executed application: GotoIf? [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Extension s, macroexten 601, priority 3 returned normally even though call was hung up [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:20] Set("IAX2/PSTN-VoIP-6799", "RingGroupMethod?=ringall") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:21] Macro("IAX2/PSTN-VoIP-6799", "dial|25|trw|3300-3301-3302-3308-3309") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [s@macro-dial:1] GotoIf?("IAX2/PSTN-VoIP-6799", "1?dial") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Goto (macro-dial,s,3) [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Executed application: GotoIf? [Oct 22 12:35:43] DEBUG[15739] app_macro.c: Extension s, macroexten 601, priority 2 returned normally even though call was hung up [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:22] Set("IAX2/PSTN-VoIP-6799", "RingGroupMethod?=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:23] GotoIf?("IAX2/PSTN-VoIP-6799", "0?nodest") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:24] Set("IAX2/PSTN-VoIP-6799", "NODEST=") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:25] DBdel("IAX2/PSTN-VoIP-6799", "BLKVM/601/IAX2/PSTN-VoIP-6799") in new stack [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- DBdel: family=BLKVM, key=601/IAX2/PSTN-VoIP-6799 [Oct 22 12:35:43] VERBOSE[15739] logger.c: -- Executing [601@ext-group:26] Goto("IAX2/PSTN-VoIP-6799", "ext-group|600|1") in new stack

Change History

10/22/08 13:25:12 changed by mbrevda

  • status changed from new to closed.
  • resolution set to duplicate.

#3307, Please do not start a new ticket un-necessarily

10/22/08 13:42:30 changed by p_lindheimer

  • confirmation changed from Unreviewed to Need Feedback.
  • description changed.

the trace is no readable, can you repost and view it first before submitting.

You said you applied some patches, which patches? And where is the log trace of the call being parked, which should show the original caller hanging up to see what is going on there?

If the call was hung up but yet the parking function did not hang up the call, it sounds like a bug in asterisk but you mention patches, did you patch asterisk and thus the bug might be induced by that patch?

Also - it sounds like you restored from a backup that was done on a different version of FreePBX, did I read that correctly, because that is not supported?

I'm not doubting that there is a problem on your system. But I'm picking up bits and pieces of information from your description that is sounding like some non-standard things are going on here in which case, it's really not possible to pursue and may be isolated to your system only? Am I reading that wrong?

(follow-up: ↓ 4 ) 10/22/08 14:16:40 changed by fredtips

I ran update-scripts function of PBXinAflash. I am running seven other FreePBX boxes using the call park function. Only this one with 2.5 is giving me problems. The backup was from Freepbx 2.5.0 to Freepbx 2.5.0. This system has been running successfully nonstop for over 2 years through various upgrades to Freepbx. It wasn't until I upgraded to 2.5.0 that this happened. There is no log event for the call being parked. How do I increase the log level so I can capture and send to you?

(in reply to: ↑ 3 ) 10/22/08 14:41:53 changed by fredtips

Replying to fredtips:

I ran update-scripts function of PBXinAflash. I am running seven other FreePBX boxes using the call park function. Only this one with 2.5 is giving me problems. The backup was from Freepbx 2.5.0 to Freepbx 2.5.0. This system has been running successfully nonstop for over 2 years through various upgrades to Freepbx. It wasn't until I upgraded to 2.5.0 that this happened. There is no log event for the call being parked. How do I increase the log level so I can capture and send to you?

08/03/09 14:22:12 changed by jjshoe

  • svn_rev set to 7967.

fixed in r7967

08/07/09 17:20:26 changed by p_lindheimer

  • resolution changed from duplicate to fixed.

(In [7969]) Merged revisions 7967-7968 via svnmerge from http://svn.freepbx.org/modules/branches/2.5

........

r7967 | jjshoe | 2009-08-03 12:19:34 -0700 (Mon, 03 Aug 2009) | 2 lines

fixes #3611, fixes #3435, fixes #3317, fixes #3307 Fixed pattern matching to prevent deadly parked call loop

........

r7968 | jjshoe | 2009-08-03 12:28:18 -0700 (Mon, 03 Aug 2009) | 4 lines

fixes #3735 Don't send the actual secret in the email

Also warn if a secret is blank.

........