Hi Forum
We have been using asterisk successfully for over 18 months now with few problems. Recently, though, I have been getting odd problems with parked calls.
Scenario: call received by receptionist, placed in parking, receptionist rings another extension (and tells them that someone is on the phone for them), when the other person attempts to pick up the parked call there is nothing there.
I switched on verbose logging and below is a transcript of a dropped parked call:-
Call being parked:-
Oct 6 17:18:22 VERBOSE[27996]: – Executing ParkedCall(“SIP/20-7fd5”, “702”) in new stack
Oct 6 17:18:22 VERBOSE[27996]: – Stopped music on hold on Zap/2-1
Oct 6 17:18:22 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:22 VERBOSE[27996]: – Channel SIP/20-7fd5 connected to parked call 702
Oct 6 17:18:22 DEBUG[27996]: Ooh, format changed from unknown to ulaw
Oct 6 17:18:47 DEBUG[27996]: Sending dtmf: 35 (#), at 192.168.0.163
Oct 6 17:18:47 DEBUG[27996]: Got AST_BRIDGE_DTMF_CHANNEL_0 on c0 (SIP/20-7fd5)
Oct 6 17:18:47 DEBUG[27996]: Bridge stops bridging channels SIP/20-7fd5 and Zap/2-1
Oct 6 17:18:47 VERBOSE[27996]: – Started music on hold, class ‘default’, on Zap/2-1
Oct 6 17:18:47 DEBUG[27996]: Scheduling timer at 160 sample intervals
Oct 6 17:18:47 DEBUG[27996]: Scheduling timer at 160 sample intervals
Oct 6 17:18:47 VERBOSE[27996]: – Playing ‘pbx-transfer’ (language ‘en’)
Oct 6 17:18:47 DEBUG[27996]: Sending dtmf: 55 (7), at 192.168.0.163
Oct 6 17:18:47 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:48 DEBUG[27996]: Sending dtmf: 48 (0), at 192.168.0.163
Oct 6 17:18:48 DEBUG[27996]: Sending dtmf: 48 (0), at 192.168.0.163
Oct 6 17:18:48 VERBOSE[27996]: – Stopped music on hold on Zap/2-1
Oct 6 17:18:48 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:48 VERBOSE[27996]: – Started music on hold, class ‘default’, on Zap/2-1
Oct 6 17:18:48 DEBUG[27996]: Scheduling timer at 160 sample intervals
Oct 6 17:18:48 VERBOSE[27996]: == Parked Zap/2-1 on 701. Will timeout back to gmappsmain,s,4 in 180 seconds
Oct 6 17:18:48 DEBUG[27996]: Difference is 4752, ms is 614
Oct 6 17:18:48 DEBUG[27996]: Scheduling timer at 160 sample intervals
Oct 6 17:18:48 VERBOSE[27996]: – Playing ‘digits/7’ (language ‘en’)
Oct 6 17:18:49 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:49 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:49 DEBUG[27996]: Scheduling timer at 160 sample intervals
Oct 6 17:18:49 VERBOSE[27996]: – Playing ‘digits/0’ (language ‘en’)
Oct 6 17:18:49 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:49 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:49 DEBUG[27996]: Scheduling timer at 160 sample intervals
Oct 6 17:18:49 VERBOSE[27996]: – Playing ‘digits/1’ (language ‘en’)
Oct 6 17:18:50 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:50 DEBUG[27996]: Scheduling timer at 0 sample intervals
Oct 6 17:18:50 VERBOSE[27996]: – Added extension ‘701’ priority 1 to parkedcalls
Oct 6 17:18:50 VERBOSE[27996]: == Spawn extension (default, 702, 1) exited non-zero on 'SIP/20-7fd5’
Oct 6 17:18:50 DEBUG[27996]: update_user_counter(20) - decrement inUse counter
Reception ringing someone else:-
Oct 6 17:18:56 VERBOSE[27999]: – Executing Dial(“SIP/20-2cb4”, “SIP/39|30|rt”) in new stack
Oct 6 17:18:56 DEBUG[27999]: SIMPLE DIAL (NO URL)
Oct 6 17:18:56 DEBUG[27999]: Setting NAT on RTP to 0
Oct 6 17:18:56 DEBUG[27999]: Outgoing Call for 39
Oct 6 17:18:56 DEBUG[27999]: Call from user ‘39’ is 1 out of 1
Oct 6 17:18:56 VERBOSE[27999]: – Called 39
Oct 6 17:18:56 VERBOSE[27999]: – SIP/39-c171 is ringing
Oct 6 17:18:58 VERBOSE[27999]: – SIP/39-c171 answered SIP/20-2cb4
Oct 6 17:18:58 VERBOSE[27999]: – Attempting native bridge of SIP/20-2cb4 and SIP/39-c171
Oct 6 17:18:58 DEBUG[27999]: Ooh, format changed from unknown to ulaw
Oct 6 17:18:58 DEBUG[27999]: Ooh, format changed from unknown to ulaw
Oct 6 17:19:04 DEBUG[27999]: Didn’t get a frame from channel: SIP/20-2cb4
Oct 6 17:19:04 DEBUG[27999]: Bridge stops bridging channels SIP/20-2cb4 and SIP/39-c171
Oct 6 17:19:04 DEBUG[27999]: update_user_counter(39) - decrement outUse counter
Oct 6 17:19:04 DEBUG[27999]: Exiting with DIALSTATUS=ANSWER.
Oct 6 17:19:04 VERBOSE[27999]: == Spawn extension (default, 39, 1) exited non-zero on 'SIP/20-2cb4’
Oct 6 17:19:04 DEBUG[27999]: update_user_counter(20) - decrement inUse counter
Call being dropped:-
Oct 6 17:19:07 VERBOSE[28004]: – Executing ParkedCall(“SIP/20-bd8e”, “701”) in new stack
Oct 6 17:19:07 VERBOSE[28004]: – Stopped music on hold on Zap/2-1
Oct 6 17:19:07 DEBUG[28004]: Scheduling timer at 0 sample intervals
Oct 6 17:19:07 VERBOSE[28004]: – Channel SIP/20-bd8e connected to parked call 701
Oct 6 17:19:07 DEBUG[28004]: Ooh, format changed from unknown to ulaw
Oct 6 17:19:12 DEBUG[28004]: Didn’t get a frame from channel: SIP/20-bd8e
Oct 6 17:19:12 DEBUG[28004]: Bridge stops bridging channels SIP/20-bd8e and Zap/2-1
Oct 6 17:19:12 DEBUG[28004]: Set option AUDIO MODE, value: ON(1) on Zap/2-1
Oct 6 17:19:12 DEBUG[28004]: Hangup: channel: 2 index = 0, normal = 20, callwait = -1, thirdcall = -1
Oct 6 17:19:12 DEBUG[28004]: Not yet hungup… Calling hangup once with icause, and clearing call
Oct 6 17:19:12 DEBUG[28004]: disabled echo cancellation on channel 2
Oct 6 17:19:12 DEBUG[28004]: Set option TDD MODE, value: OFF(0) on Zap/2-1
Oct 6 17:19:12 DEBUG[28004]: Updated conferencing on 2, with 0 conference users
Oct 6 17:19:12 DEBUG[28004]: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
Oct 6 17:19:12 DEBUG[28004]: disabled echo cancellation on channel 2
Oct 6 17:19:12 VERBOSE[28004]: – Hungup 'Zap/2-1’
Oct 6 17:19:12 VERBOSE[28004]: == Spawn extension (default, 701, 1) exited non-zero on 'SIP/20-bd8e’
Oct 6 17:19:12 DEBUG[28004]: update_user_counter(20) - decrement inUse counter
It looks as though the original call receiver somehow picks up the parked call. Has anyone seen this before or have any ideas?
Cheers
Frustrated SysAdmin 