Not able Pick call from hard phone.

[nikhilvohra]

Hi Everyone

Please find my build details below:
Version:2.8-437a
Build:140425-1257
asterisk version: 1.4.39.2-vici
SVN Version: 2112

SETUP:- Quad Core Server, 4 GB ram. Sangoma A104 4 port card, One Nic card, GrandStream Hard Phone and Zoiper Soft phone.

HISTORY:- Installed ViciBox_Redux.x86_64-3.1.10. Upgraded to the latest SVN version. Reason for upgrade on Hook agents. Completed the upgrade without any errors. Followed the complete upgrade procedure. Setuped INGROUP-->DID-->Campain inbound/Blended--Phones On Hook Yes--->Users. Inbound calls are landing no problem till this point.

PROBLEM:-
1. Not able to pic phone in one go. What i exactly mean is even after picking the receiver it doesn't pops up to the screen and goes to the next agent. And some times it does. Happening with every agent every call. So it has become like in whose fortune this call is jokes apart, moving to next one.
2. When answering phone on the soft phone, call is taking a lot of time to pop on the agent screen after answer.
3. Even after when the call the answered and disposed later call stays in the conference. Both on softphone and hardphone.

Research Done:
1. Updated the GMT time according to our time zone in the Admin-->Servers, Admin-->system Setting, and Admin--> phones.
2. Updated the server time with the ntp server.
3. Server time and Agent machine timing are same.
4. Read in the forum so also did " ln -sf /usr/share/zoneinfo/Asia/Kolkata /etc/localtime".
5. Admin.php and vicidial.php also have the same time.

Findings:
1. The Customer Time on the agent screen when call lands there differs big time. For eg: Current time is 6:11 PM and call was showing 7:15 AM.
2. In the CLI out put the call the not getting the Meetme conference.


######################################################CLI Out put######################################
[May 5 14:10:56] -- Accepting call from '01148595900' to '66463300' on channel 0/1, span 2
[May 5 14:10:56] -- Executing [66463300@trunkinbound:1] AGI("DAHDI/32-1", "agi-DID_route.agi") in new stack
[May 5 14:10:56] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[May 5 14:10:56] -- AGI Script agi-DID_route.agi completed, returning 0
[May 5 14:10:56] -- Executing [99909*3***DID@default:1] Answer("DAHDI/32-1", "") in new stack
[May 5 14:10:56] -- Executing [99909*3***DID@default:2] AGI("DAHDI/32-1", "agi-VDAD_ALL_inbound.agi") in new stack
[May 5 14:10:56] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[May 5 14:10:56] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:10:56] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:10:56] -- Playing 'max' (escape_digits=) (sample_offset 0)
[May 5 14:11:01] == Parsing '/etc/asterisk/manager.conf': [May 5 14:11:01] Found
[May 5 14:11:01] == Manager 'sendcron' logged on from 127.0.0.1
[May 5 14:11:01] -- Executing [192*168*000*026*5001@default:1] Goto("Local/192*168*000*026*5001@default-bd19,2", "default|5001|1") in new stack
[May 5 14:11:01] -- Goto (default,5001,1)
[May 5 14:11:01] -- Executing [5001@default:1] Dial("Local/192*168*000*026*5001@default-bd19,2", "SIP/5001|60|") in new stack
[May 5 14:11:01] -- Called 5001
[May 5 14:11:01] -- SIP/5001-0000001f is ringing
[May 5 14:11:02] == Parsing '/etc/asterisk/manager.conf': [May 5 14:11:02] Found
[May 5 14:11:02] == Manager 'sendcron' logged on from 127.0.0.1
[May 5 14:11:02] == Parsing '/etc/asterisk/manager.conf': [May 5 14:11:02] Found
[May 5 14:11:02] == Manager 'sendcron' logged on from 127.0.0.1
[May 5 14:11:02] == Manager 'sendcron' logged off from 127.0.0.1
[May 5 14:11:02] == Manager 'sendcron' logged off from 127.0.0.1
[May 5 14:11:03] -- Started music on hold, class 'default', on DAHDI/32-1
[May 5 14:11:05] -- SIP/5001-0000001f answered Local/192*168*000*026*5001@default-bd19,2
[May 5 14:11:05] > Channel Local/192*168*000*026*5001@default-bd19,1 was answered.
[May 5 14:11:05] -- Executing [8331*10*Y5051410560000000065*5001*5001@default:1] Playback("Local/192*168*000*026*5001@default-bd19,1", "sip-silence") in new stack
[May 5 14:11:05] -- <Local/192*168*000*026*5001@default-bd19,1> Playing 'sip-silence' (language 'en')
[May 5 14:11:05] WARNING[17391]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[May 5 14:11:05] -- Executing [8331*10*Y5051410560000000065*5001*5001@default:2] AGI("Local/192*168*000*026*5001@default-bd19,1", "agi-VDAD_RINGALL.agi|8331*10*Y5051410560000000065*5001*5001") in new stack
[May 5 14:11:05] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_RINGALL.agi
[May 5 14:11:05] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:05] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:05] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:05] -- AGI Script agi-VDAD_RINGALL.agi completed, returning 0
[May 5 14:11:05] -- Executing [8331*10*Y5051410560000000065*5001*5001@default:3] AGI("Local/192*168*000*026*5001@default-bd19,1", "agi-VDAD_RINGALL.agi|8331*10*Y5051410560000000065*5001*5001") in new stack
[May 5 14:11:05] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_RINGALL.agi
[May 5 14:11:05] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:05] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:05] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:05] -- AGI Script agi-VDAD_RINGALL.agi completed, returning 0
[May 5 14:11:05] -- Executing [8331*10*Y5051410560000000065*5001*5001@default:4] AGI("Local/192*168*000*026*5001@default-bd19,1", "agi-VDAD_RINGALL.agi|8331*10*Y5051410560000000065*5001*5001") in new stack
[May 5 14:11:05] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_RINGALL.agi
[May 5 14:11:06] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:06] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:06] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:06] -- AGI Script agi-VDAD_RINGALL.agi completed, returning 0
[May 5 14:11:06] -- Executing [8331*10*Y5051410560000000065*5001*5001@default:5] Hangup("Local/192*168*000*026*5001@default-bd19,1", "") in new stack
[May 5 14:11:06] == Spawn extension (default, 8331*10*Y5051410560000000065*5001*5001, 5) exited non-zero on 'Local/192*168*000*026*5001@default-bd19,1'
[May 5 14:11:06] -- Executing [h@default:1] DeadAGI("Local/192*168*000*026*5001@default-bd19,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[May 5 14:11:06] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[May 5 14:11:06] -- Executing [h@default:1] DeadAGI("Local/192*168*000*026*5001@default-bd19,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----5-----1") in new stack
[May 5 14:11:06] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---5-----1 completed, returning 0
[May 5 14:11:06] == Spawn extension (default, 5001, 1) exited non-zero on 'Local/192*168*000*026*5001@default-bd19,2'
[May 5 14:11:06] -- Stopped music on hold on DAHDI/32-1
[May 5 14:11:06] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:06] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:06] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[May 5 14:11:06] -- Playing 'generic_hold' (escape_digits=) (sample_offset 0)
[May 5 14:11:07] == Parsing '/etc/asterisk/manager.conf': [May 5 14:11:07] Found
[May 5 14:11:07] == Manager 'sendcron' logged on from 127.0.0.1
[May 5 14:11:07] == Manager 'sendcron' logged off from 127.0.0.1
[May 5 14:11:07] == Manager 'sendcron' logged off from 127.0.0.1
[May 5 14:11:10] -- Channel 0/1, span 2 got hangup request, cause 16
[May 5 14:11:10] -- Executing [h@default:1] DeadAGI("DAHDI/32-1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[May 5 14:11:10] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[May 5 14:11:10] -- Hungup 'DAHDI/32-1'
################################################################################################################################################

Please guide me on this issue. And If you need some more info i can provide that as well.


Thanks
Nikhil Vohra

[nikhilvohra]

Hi Matt and everyone its been over a week i posted this but nobody has replied to it. I am still stuck at the same issue. Tried whatever i could have from "googleing, to version change, to Remote login, to no delay option, and pretty much everything which i could have understood. I did posted all my configuration and all what i have did. Still no one has replied.

Please help me and if i have written something wrong or you need more info please ask.

I NEED HELP BIG TIME. PLEASE HELP.....

[geoff3dmg]

Test again and post the debug output from agi-VDAD_RINGALL.agi please.

[mflorell]

You should try adding several more lines of the "agi-VDAD_RINGALL.agi" in your extensions.conf, sometimes that is what is needed for this to work on some systems:

; this is used for the ring_all function in VICIDIAL
exten => _8331*.,1,Playback(sip-silence)
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,AGI(agi-VDAD_RINGALL.agi,${EXTEN})
exten => _8331*.,n,Hangup

[nikhilvohra]

Observed that after picking the hard phone receiver, call is taking a little more time to pop on the phone. Any clue for this? Is it due to more RING ALL lines we added?

[nikhilvohra]

Thanks Matt problem Solved. You are the Best. Really Thanks a lot

Resolution Steps:
1. Exactly i added 7 line of RING ALL file which was the minimum requirement for my system, as i tested with different number of lines.
2. Reloaded the Dial plan.
3. Made a Call again.
ANT ITS DONE

But Observed that after picking the hard phone receiver, call is taking a little more time to pop on the phone. Any clue for this? Is it due to more RING ALL lines we added?

Thanks

[nikhilvohra]

Need Help on this
Observed that after picking the hard phone receiver, call is taking a little more time to pop on the phone. Any clue for this? Is it due to more RING ALL lines we added?

[williamconley]

Show a CLI example of "more time to pop on the phone". How long? (In Seconds ...)

Also: You have a 4 port sangoma card, is that for phones or for trunks? (I will assume trunks). It is possible that the sangoma card is taking time to perform the final connect.

What is the path that these calls take to get to the phones? DID->Ingroup? DID->Call Menu->Phone? Once again, the CLI output would be useful to show the delay. Also make note of whether the times are accurate (ie: if the CLI shows the time at the moment of answer, is that the correct number of seconds since ring?).

You stated "quad core server" ... is that a 1Ghz quad core or a 3.3Ghz quad core?

Does this problem ALWAYS happen, or only during heavy load? (Does it happen on a single call with no other calls in the system?)