When using SIPStation, calls answered by queue member get put on hold

General Help
1

I have a system configured with a Queue and multiple extensions. I have been testing using the google voice trunk and everything works normally.

I log into the queue (*451) and I can call my google voice number and the IP phone will ring at my desk. Perfect.

I purchased a SIPStation trunk, and tested calls forwarding to my extension directly, and everything worked. I assumed that the system would also work with the Queues once I set the SIPStation DID to route to the Queue. It did not.

When I call into the SIPStation number, the queue agent’s phone rings, but once the agent answeres, the caller is put on hold and cannot be taken off hold.

I am wondering if anyone has experienced any similar problems?

Here is a log with Google Voice and a log with SIPStation. I do not notice any differences.

============== GOOGLE VOICE =================
– Executing [[email protected]@from-google:1] Set(“Gtalk/+15705780184-28cc”, “CALLERID(name)=+15705780184”) in new stack
– Executing [[email protected]@from-google:2] GotoIf(“Gtalk/+15705780184-28cc”, “0?notrim”) in new stack
– Executing [[email protected]@from-google:3] Set(“Gtalk/+15705780184-28cc”, “CALLERID(name)=5705780184”) in new stack
– Executing [[email protected]@from-google:4] Set(“Gtalk/+15705780184-28cc”, “CALLERID(number)=5705780184”) in new stack
– Executing [[email protected]@from-google:5] Answer(“Gtalk/+15705780184-28cc”, “”) in new stack
– Executing [[email protected]@from-google:6] Wait(“Gtalk/+15705780184-28cc”, “1”) in new stack
– Executing [[email protected]@from-google:7] SendDTMF(“Gtalk/+15705780184-28cc”, “1”) in new stack
– Executing [[email protected]@from-google:8] Goto(“Gtalk/+15705780184-28cc”, “from-trunk,5706300777,1”) in new stack
– Goto (from-trunk,5706300777,1)
– Executing [5706300777@from-trunk:1] AGI(“Gtalk/+15705780184-28cc”, “crm/incoming_call.php,5705780184”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/crm/incoming_call.php
– <Gtalk/+15705780184-28cc>AGI Script crm/incoming_call.php completed, returning 0
– Executing [5706300777@from-trunk:2] ExecIf(“Gtalk/+15705780184-28cc”, “0 ?Set(CALLERID(name)=5705780184)”) in new stack
– Executing [5706300777@from-trunk:3] Set(“Gtalk/+15705780184-28cc”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [5706300777@from-trunk:4] Set(“Gtalk/+15705780184-28cc”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [5706300777@from-trunk:5] Goto(“Gtalk/+15705780184-28cc”, “ext-queues,1,1”) in new stack
– Goto (ext-queues,1,1)
– Executing [1@ext-queues:1] Macro(“Gtalk/+15705780184-28cc”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Gtalk/+15705780184-28cc”, “AMPUSER=5705780184”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“Gtalk/+15705780184-28cc”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“Gtalk/+15705780184-28cc”, “1?Set(REALCALLERIDNUM=5705780184)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“Gtalk/+15705780184-28cc”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“Gtalk/+15705780184-28cc”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“Gtalk/+15705780184-28cc”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“Gtalk/+15705780184-28cc”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:10] Set(“Gtalk/+15705780184-28cc”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:11] GotoIf(“Gtalk/+15705780184-28cc”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] Set(“Gtalk/+15705780184-28cc”, “CALLERID(number)=5705780184”) in new stack
– Executing [s@macro-user-callerid:19] Set(“Gtalk/+15705780184-28cc”, “CALLERID(name)=5705780184”) in new stack
– Executing [s@macro-user-callerid:20] NoOp(“Gtalk/+15705780184-28cc”, “Using CallerID “5705780184” <5705780184>”) in new stack
– Executing [1@ext-queues:2] Answer(“Gtalk/+15705780184-28cc”, “”) in new stack
– Executing [1@ext-queues:3] Set(“Gtalk/+15705780184-28cc”, “__BLKVM_OVERRIDE=BLKVM/1/Gtalk/+15705780184-28cc”) in new stack
– Executing [1@ext-queues:4] Set(“Gtalk/+15705780184-28cc”, “__BLKVM_BASE=1”) in new stack
– Executing [1@ext-queues:5] Set(“Gtalk/+15705780184-28cc”, “DB(BLKVM/1/Gtalk/+15705780184-28cc)=TRUE”) in new stack
– Executing [1@ext-queues:6] ExecIf(“Gtalk/+15705780184-28cc”, “1?Set(_DIAL_OPTIONS=trM(auto-blkvm))”) in new stack
– Executing [1@ext-queues:7] Set(“Gtalk/+15705780184-28cc”, “__NODEST=1”) in new stack
– Executing [1@ext-queues:8] Set(“Gtalk/+15705780184-28cc”, “MONITOR_FILENAME=/var/spool/asterisk/monitor/q1-20110328-162630-1301343988.8”) in new stack
– Executing [1@ext-queues:9] Set(“Gtalk/+15705780184-28cc”, “__MOHCLASS=none”) in new stack
– Executing [1@ext-queues:10] Set(“Gtalk/+15705780184-28cc”, “__CWIGNORE=TRUE”) in new stack
– Executing [1@ext-queues:11] Queue(“Gtalk/+15705780184-28cc”, “1,tr,120”) in new stack
– Called Local/425@from-queue/n
– Executing [425@from-queue:1] Set(“Local/425@from-queue-432f;2”, “QAGENT=425”) in new stack
– Executing [425@from-queue:2] Goto(“Local/425@from-queue-432f;2”, “1,1”) in new stack
– Goto (from-queue,1,1)
– Executing [1@from-queue:1] Goto(“Local/425@from-queue-432f;2”, “from-internal,425,1”) in new stack
– Goto (from-internal,425,1)
– Executing [425@from-internal:1] Macro(“Local/425@from-queue-432f;2”, “exten-vm,425,425”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“Local/425@from-queue-432f;2”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Local/425@from-queue-432f;2”, “AMPUSER=5705780184”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“Local/425@from-queue-432f;2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“Local/425@from-queue-432f;2”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:10] Set(“Local/425@from-queue-432f;2”, “__TTL=63”) in new stack
– Executing [s@macro-user-callerid:11] GotoIf(“Local/425@from-queue-432f;2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] Set(“Local/425@from-queue-432f;2”, “CALLERID(number)=5705780184”) in new stack
– Executing [s@macro-user-callerid:19] Set(“Local/425@from-queue-432f;2”, “CALLERID(name)=5705780184”) in new stack
– Executing [s@macro-user-callerid:20] NoOp(“Local/425@from-queue-432f;2”, “Using CallerID “5705780184” <5705780184>”) in new stack
– Executing [s@macro-exten-vm:2] Set(“Local/425@from-queue-432f;2”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“Local/425@from-queue-432f;2”, “VMBOX=425”) in new stack
– Executing [s@macro-exten-vm:4] Set(“Local/425@from-queue-432f;2”, “__EXTTOCALL=425”) in new stack
– Executing [s@macro-exten-vm:5] Set(“Local/425@from-queue-432f;2”, “CFUEXT=”) in new stack
– Executing [s@macro-exten-vm:6] Set(“Local/425@from-queue-432f;2”, “CFBEXT=”) in new stack
– Executing [s@macro-exten-vm:7] Set(“Local/425@from-queue-432f;2”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:8] Macro(“Local/425@from-queue-432f;2”, “record-enable,425,IN”) in new stack
– Executing [s@macro-record-enable:1] MacroExit(“Local/425@from-queue-432f;2”, “”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“Local/425@from-queue-432f;2”, “dial-one,15,trM(auto-blkvm),425”) in new stack
– Executing [s@macro-dial-one:1] Set(“Local/425@from-queue-432f;2”, “DEXTEN=425”) in new stack
– Executing [s@macro-dial-one:2] Set(“Local/425@from-queue-432f;2”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“Local/425@from-queue-432f;2”, “0?screen,1”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“Local/425@from-queue-432f;2”, “0?cf,1”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“Local/425@from-queue-432f;2”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“Local/425@from-queue-432f;2”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“Local/425@from-queue-432f;2”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“Local/425@from-queue-432f;2”, “EXTHASCW=”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“Local/425@from-queue-432f;2”, “1?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,12)
– Executing [s@macro-dial-one:12] GotoIf(“Local/425@from-queue-432f;2”, “0?docfu:skip3”) in new stack
– Goto (macro-dial-one,s,16)
– Executing [s@macro-dial-one:16] GotoIf(“Local/425@from-queue-432f;2”, “1?next2:continue”) in new stack
– Goto (macro-dial-one,s,17)
– Executing [s@macro-dial-one:17] GotoIf(“Local/425@from-queue-432f;2”, “1?continue”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [s@macro-dial-one:25] GotoIf(“Local/425@from-queue-432f;2”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“Local/425@from-queue-432f;2”, “1?dstring,1:dlocal,1”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“Local/425@from-queue-432f;2”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“Local/425@from-queue-432f;2”, “DEVICES=425”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“Local/425@from-queue-432f;2”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“Local/425@from-queue-432f;2”, “0?Set(DEVICES=25)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“Local/425@from-queue-432f;2”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“Local/425@from-queue-432f;2”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“Local/425@from-queue-432f;2”, “THISDIAL=SIP/425”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“Local/425@from-queue-432f;2”, “1?zap2dahdi,1”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“Local/425@from-queue-432f;2”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“Local/425@from-queue-432f;2”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“Local/425@from-queue-432f;2”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“Local/425@from-queue-432f;2”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“Local/425@from-queue-432f;2”, “THISPART2=SIP/425”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“Local/425@from-queue-432f;2”, “0?Set(THISPART2=DAHDI/425)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“Local/425@from-queue-432f;2”, “NEWDIAL=SIP/425&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“Local/425@from-queue-432f;2”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“Local/425@from-queue-432f;2”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“Local/425@from-queue-432f;2”, “THISDIAL=SIP/425”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“Local/425@from-queue-432f;2”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“Local/425@from-queue-432f;2”, “DSTRING=SIP/425&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“Local/425@from-queue-432f;2”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“Local/425@from-queue-432f;2”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“Local/425@from-queue-432f;2”, “DSTRING=SIP/425”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“Local/425@from-queue-432f;2”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“Local/425@from-queue-432f;2”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“Local/425@from-queue-432f;2”, “1?skiptrace”) in new stack
– Goto (macro-dial-one,s,30)
– Executing [s@macro-dial-one:30] Set(“Local/425@from-queue-432f;2”, “D_OPTIONS=trM(auto-blkvm)”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“Local/425@from-queue-432f;2”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“Local/425@from-queue-432f;2”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“Local/425@from-queue-432f;2”, “1?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“Local/425@from-queue-432f;2”, “0?qwait,1”) in new stack
– Executing [s@macro-dial-one:35] Set(“Local/425@from-queue-432f;2”, “__CWIGNORE=TRUE”) in new stack
– Executing [s@macro-dial-one:36] Set(“Local/425@from-queue-432f;2”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] Dial(“Local/425@from-queue-432f;2”, “SIP/425,15,trM(auto-blkvm)”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called 425
– Local/425@from-queue-432f;1 is ringing
– SIP/425-00000004 is ringing
– Local/425@from-queue-432f;1 is ringing
– SIP/425-00000004 answered Local/425@from-queue-432f;2
– Local/425@from-queue-432f;1 connected line has changed. Saving it until answer for Gtalk/+15705780184-28cc
– Executing [s@macro-auto-blkvm:1] Set(“SIP/425-00000004”, “__MACRO_RESULT=”) in new stack
– Executing [s@macro-auto-blkvm:2] NoOp(“SIP/425-00000004”, “Deleting: BLKVM/1/Gtalk/+15705780184-28cc TRUE”) in new stack
– Local/425@from-queue-432f;1 answered Gtalk/+15705780184-28cc
================== END GOOGLE VOICE =============

================== SIPStation ====================
root@pbx:~# asterisk -rvvvvvvvvvvvvvvv
Verbosity was 8 and is now 15
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [5702667771@from-pstn:1] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__FROM_DID=5702667771”) in new stack
– Executing [5702667771@from-pstn:2] ExecIf(“SIP/fpbx-1-8605bf6a-00000002”, “0 ?Set(CALLERID(name)=+15705780184)”) in new stack
– Executing [5702667771@from-pstn:3] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__CALLINGPRES_SV=prohib_not_screened”) in new stack
– Executing [5702667771@from-pstn:4] Set(“SIP/fpbx-1-8605bf6a-00000002”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [5702667771@from-pstn:5] Goto(“SIP/fpbx-1-8605bf6a-00000002”, “ext-queues,99,1”) in new stack
– Goto (ext-queues,99,1)
– Executing [99@ext-queues:1] Macro(“SIP/fpbx-1-8605bf6a-00000002”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/fpbx-1-8605bf6a-00000002”, “AMPUSER=+15705780184”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/fpbx-1-8605bf6a-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/fpbx-1-8605bf6a-00000002”, “1?Set(REALCALLERIDNUM=+15705780184)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/fpbx-1-8605bf6a-00000002”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/fpbx-1-8605bf6a-00000002”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/fpbx-1-8605bf6a-00000002”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“SIP/fpbx-1-8605bf6a-00000002”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:11] GotoIf(“SIP/fpbx-1-8605bf6a-00000002”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] Set(“SIP/fpbx-1-8605bf6a-00000002”, “CALLERID(number)=+15705780184”) in new stack
– Executing [s@macro-user-callerid:19] Set(“SIP/fpbx-1-8605bf6a-00000002”, “CALLERID(name)=+15705780184”) in new stack
– Executing [s@macro-user-callerid:20] NoOp(“SIP/fpbx-1-8605bf6a-00000002”, “Using CallerID “+15705780184” <+15705780184>”) in new stack
– Executing [99@ext-queues:2] Answer(“SIP/fpbx-1-8605bf6a-00000002”, “”) in new stack
– Executing [99@ext-queues:3] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__BLKVM_OVERRIDE=BLKVM/99/SIP/fpbx-1-8605bf6a-00000002”) in new stack
– Executing [99@ext-queues:4] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__BLKVM_BASE=99”) in new stack
– Executing [99@ext-queues:5] Set(“SIP/fpbx-1-8605bf6a-00000002”, “DB(BLKVM/99/SIP/fpbx-1-8605bf6a-00000002)=TRUE”) in new stack
– Executing [99@ext-queues:6] ExecIf(“SIP/fpbx-1-8605bf6a-00000002”, “1?Set(_DIAL_OPTIONS=trM(auto-blkvm))”) in new stack
– Executing [99@ext-queues:7] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__NODEST=99”) in new stack
– Executing [99@ext-queues:8] Set(“SIP/fpbx-1-8605bf6a-00000002”, “MONITOR_FILENAME=/var/spool/asterisk/monitor/q99-20110328-162551-1301343951.4”) in new stack
– Executing [99@ext-queues:9] Set(“SIP/fpbx-1-8605bf6a-00000002”, “__MOHCLASS=none”) in new stack
– Executing [99@ext-queues:10] Queue(“SIP/fpbx-1-8605bf6a-00000002”, “99,tr,”) in new stack
– Executing [425@from-queue:1] Set(“Local/425@from-queue-babe;2”, “QAGENT=425”) in new stack
– Executing [425@from-queue:2] Goto(“Local/425@from-queue-babe;2”, “99,1”) in new stack
– Goto (from-queue,99,1)
– Executing [99@from-queue:1] Goto(“Local/425@from-queue-babe;2”, “from-internal,425,1”) in new stack
– Goto (from-internal,425,1)
– Executing [425@from-internal:1] Macro(“Local/425@from-queue-babe;2”, “exten-vm,425,425”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“Local/425@from-queue-babe;2”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“Local/425@from-queue-babe;2”, “AMPUSER=+15705780184”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“Local/425@from-queue-babe;2”, “1?report”) in new stack
– Goto (macro-user-callerid,s,9)
– Executing [s@macro-user-callerid:9] GotoIf(“Local/425@from-queue-babe;2”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:10] Set(“Local/425@from-queue-babe;2”, “__TTL=63”) in new stack
– Executing [s@macro-user-callerid:11] GotoIf(“Local/425@from-queue-babe;2”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [s@macro-user-callerid:18] Set(“Local/425@from-queue-babe;2”, “CALLERID(number)=+15705780184”) in new stack
– Executing [s@macro-user-callerid:19] Set(“Local/425@from-queue-babe;2”, “CALLERID(name)=+15705780184”) in new stack
– Executing [s@macro-user-callerid:20] NoOp(“Local/425@from-queue-babe;2”, “Using CallerID “+15705780184” <+15705780184>”) in new stack
– Executing [s@macro-exten-vm:2] Set(“Local/425@from-queue-babe;2”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“Local/425@from-queue-babe;2”, “VMBOX=425”) in new stack
– Executing [s@macro-exten-vm:4] Set(“Local/425@from-queue-babe;2”, “__EXTTOCALL=425”) in new stack
– Executing [s@macro-exten-vm:5] Set(“Local/425@from-queue-babe;2”, “CFUEXT=”) in new stack
– Executing [s@macro-exten-vm:6] Set(“Local/425@from-queue-babe;2”, “CFBEXT=”) in new stack
– Executing [s@macro-exten-vm:7] Set(“Local/425@from-queue-babe;2”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:8] Macro(“Local/425@from-queue-babe;2”, “record-enable,425,IN”) in new stack
– Executing [s@macro-record-enable:1] MacroExit(“Local/425@from-queue-babe;2”, “”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“Local/425@from-queue-babe;2”, “dial-one,15,trM(auto-blkvm),425”) in new stack
– Executing [s@macro-dial-one:1] Set(“Local/425@from-queue-babe;2”, “DEXTEN=425”) in new stack
– Executing [s@macro-dial-one:2] Set(“Local/425@from-queue-babe;2”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“Local/425@from-queue-babe;2”, “0?screen,1”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“Local/425@from-queue-babe;2”, “0?cf,1”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“Local/425@from-queue-babe;2”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“Local/425@from-queue-babe;2”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“Local/425@from-queue-babe;2”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“Local/425@from-queue-babe;2”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“Local/425@from-queue-babe;2”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,23)
– Executing [s@macro-dial-one:23] GotoIf(“Local/425@from-queue-babe;2”, “1?next3:continue”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] ExecIf(“Local/425@from-queue-babe;2”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
– Executing [s@macro-dial-one:25] GotoIf(“Local/425@from-queue-babe;2”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“Local/425@from-queue-babe;2”, “1?dstring,1:dlocal,1”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“Local/425@from-queue-babe;2”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“Local/425@from-queue-babe;2”, “DEVICES=425”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“Local/425@from-queue-babe;2”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“Local/425@from-queue-babe;2”, “0?Set(DEVICES=25)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“Local/425@from-queue-babe;2”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“Local/425@from-queue-babe;2”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“Local/425@from-queue-babe;2”, “THISDIAL=SIP/425”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“Local/425@from-queue-babe;2”, “1?zap2dahdi,1”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“Local/425@from-queue-babe;2”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“Local/425@from-queue-babe;2”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“Local/425@from-queue-babe;2”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“Local/425@from-queue-babe;2”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“Local/425@from-queue-babe;2”, “THISPART2=SIP/425”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“Local/425@from-queue-babe;2”, “0?Set(THISPART2=DAHDI/425)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“Local/425@from-queue-babe;2”, “NEWDIAL=SIP/425&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“Local/425@from-queue-babe;2”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“Local/425@from-queue-babe;2”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“Local/425@from-queue-babe;2”, “THISDIAL=SIP/425”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“Local/425@from-queue-babe;2”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“Local/425@from-queue-babe;2”, “DSTRING=SIP/425&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“Local/425@from-queue-babe;2”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“Local/425@from-queue-babe;2”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“Local/425@from-queue-babe;2”, “DSTRING=SIP/425”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“Local/425@from-queue-babe;2”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“Local/425@from-queue-babe;2”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“Local/425@from-queue-babe;2”, “1?skiptrace”) in new stack
– Goto (macro-dial-one,s,30)
– Executing [s@macro-dial-one:30] Set(“Local/425@from-queue-babe;2”, “D_OPTIONS=trM(auto-blkvm)”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“Local/425@from-queue-babe;2”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“Local/425@from-queue-babe;2”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“Local/425@from-queue-babe;2”, “1?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“Local/425@from-queue-babe;2”, “0?qwait,1”) in new stack
– Executing [s@macro-dial-one:35] Set(“Local/425@from-queue-babe;2”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“Local/425@from-queue-babe;2”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] Dial(“Local/425@from-queue-babe;2”, “SIP/425,15,trM(auto-blkvm)”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called 425
– Local/425@from-queue-babe;1 is ringing
– SIP/425-00000003 is ringing
– Local/425@from-queue-babe;1 is ringing
– Local/425@from-queue-babe;1 connected line has changed. Saving it until answer for SIP/fpbx-1-8605bf6a-00000002
– SIP/425-00000003 answered Local/425@from-queue-babe;2
– Executing [s@macro-auto-blkvm:1] Set(“SIP/425-00000003”, “__MACRO_RESULT=”) in new stack
– Executing [s@macro-auto-blkvm:2] NoOp(“SIP/425-00000003”, “Deleting: BLKVM/99/SIP/fpbx-1-8605bf6a-00000002 TRUE”) in new stack
– Local/425@from-queue-babe;1 answered SIP/fpbx-1-8605bf6a-00000002
=================== END SIP STATION ==================================

2

Sounds like this one was resolved though it’s not clear exactly what the issue was.

From what I was told by the SIP engineer with SIPSTATION, the installation may have been a little buggy after some changes on the PBX and removing and re-installing the trunks everything seemed to start working.

Glad everything is back up, I guess it will always be a mystery exactly what was going on???

3

you only captured the SIP packets there, you want to capture all the packets and then put it into wireshark to see what happens.

(You could probably also email the sipstation support once you have a full capture, tell them what the behavior is, and with that file they may be able to have a look and tell you what is up since they have a pretty good understanding of SIP dialogs).

4

Here is a packet capture for me for the whole call.

5

without a pcap trace on these it’s kind of hard to say what is going on, the pcap trace will show other signaling that might not otherwise be apparent as well as where the rtp streams are coming from.

6

I have the same exact problem. I used the SIPStation module to configure my trunk. When calls come through my SIPStation DID, the caller can navigate through my IVR like normal, then my extension rings and when I answer, the other end shortly is there then the call is put right on hold just as described above. The caller gets hold music. I can transfer the call to myself and they are taken off hold.

I can’t find any settings in the PBX causing this. The trunk works well otherwise as outbound calls are fine. Even if I have the SIPStation DID go to the extension direct on inbound, same issue. I’m not sure about the RTP steam being cut off as it is my PBX hold music.

7

that is quite odd.

Have you tried doing a pcap trace on the call to see what might be going on with your end?

It almost sounds like the rtp stream is cut off and something on the callers end is kicking in music as a result of the lack of an rtp stream (which would be related to firewall issues usually).

Does the same thing happen despite where you call from, such as a cell phone, some standard pots line somewhere, etc. (e.g. try different sources)?

8

The call is answered by a Grandstream phone, I can hear the caller for under a second, then the caller hears hold music that is not located on my system. Once the grandstream phone hangs up, the caller is disconnected, or if we wait 20 or so seconds, the call is hung up.

My logs look like everything should work, but it does not.

9

what makes you say they get put on hold?

the trace doesn’t show that, it shows the call being answered.

Maybe a bit more of an explanation might provide some insight into what is really going on.