Googlevoice rings once, disconnects.

[IanWorthington]

Trying out googlevoice outbound for the first time. Seems to take a while to establish a call, ring once, then immediately hang up.

The only thing I've done differently from the instructions it is use my normal email address rather then a gmail address for the NOTIFICATION ADDRESS. Should that make a difference?


The log, masked to protect the innocent!, follows:

[2011-05-16 22:29:30] VERBOSE[22554] netsock2.c: == Using SIP RTP TOS bits 184
[2011-05-16 22:29:30] VERBOSE[22554] netsock2.c: == Using SIP RTP CoS mark 5
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [6xxxxx2428@from-internal:1] Macro("SIP/701-00000001", "user-callerid,SKIPTTL,") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/701-00000001", "AMPUSER=701") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/701-00000001", "0?report") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/701-00000001", "1?Set(REALCALLERIDNUM=701)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/701-00000001", "AMPUSER=701") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/701-00000001", "AMPUSERCIDNAME=701SoftPhone") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/701-00000001", "0?report") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/701-00000001", "AMPUSERCID=701") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/701-00000001", "CALLERID(all)="701SoftPhone" <701>") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/701-00000001", "0?Set(CHANNEL(language)=)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/701-00000001", "1?continue") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-user-callerid,s,19)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:19] Set("SIP/701-00000001", "CALLERID(number)=701") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:20] Set("SIP/701-00000001", "CALLERID(name)=701SoftPhone") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-user-callerid:21] NoOp("SIP/701-00000001", "Using CallerID "701SoftPhone" <701>") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [6xxxxx2428@from-internal:2] NoOp("SIP/701-00000001", "Calling Out Route: Default") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [6xxxxx2428@from-internal:3] Set("SIP/701-00000001", "MOHCLASS=default") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [6xxxxx2428@from-internal:4] Set("SIP/701-00000001", "_NODEST=") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [6xxxxx2428@from-internal:5] Macro("SIP/701-00000001", "record-enable,701,OUT,") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/701-00000001", "1?check") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-record-enable,s,4)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/701-00000001", "0?MacroExit()") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/701-00000001", "0?Group:OUT") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-record-enable,s,15)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-record-enable:15] GotoIf("SIP/701-00000001", "0?IN") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-record-enable:16] ExecIf("SIP/701-00000001", "1?MacroExit()") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [6xxxxx2428@from-internal:6] Macro("SIP/701-00000001", "dialout-trunk,10,6xxxxx2428,") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/701-00000001", "DIAL_TRUNK=10") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/701-00000001", "0?sub-pincheck,s,1") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/701-00000001", "0?disabletrunk,1") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/701-00000001", "DIAL_NUMBER=6xxxxx2428") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/701-00000001", "DIAL_TRUNK_OPTIONS=tr") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/701-00000001", "OUTBOUND_GROUP=OUT_10") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/701-00000001", "1?nomax") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/701-00000001", "0?skipoutcid") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/701-00000001", "DIAL_TRUNK_OPTIONS=") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/701-00000001", "outbound-callerid,10") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/701-00000001", "0?Set(CALLERPRES()=)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/701-00000001", "0?Set(REALCALLERIDNUM=701)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/701-00000001", "1?normcid") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/701-00000001", "USEROUTCID=") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/701-00000001", "EMERGENCYCID=") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/701-00000001", "TRUNKOUTCID=") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/701-00000001", "1?trunkcid") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-outbound-callerid,s,12)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/701-00000001", "0?Set(CALLERID(all)=)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/701-00000001", "0?Set(CALLERID(all)=)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/701-00000001", "0?Set(CALLERID(all)=)") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/701-00000001", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack

part 2 follows...

 

[IanWorthington]

continuation of log...

[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/701-00000001", "0?sub-flp-10,s,1") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/701-00000001", "OUTNUM=6xxxxx2428") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/701-00000001", "custom=AMP") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/701-00000001", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/701-00000001", "dialout-trunk-predial-hook,") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/701-00000001", "") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/701-00000001", "0?bypass,1") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/701-00000001", "1?customtrunk") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-dialout-trunk,s,22)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:22] Set("SIP/701-00000001", "pre_num=AMP:local/") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:23] Set("SIP/701-00000001", "the_num=OUTNUM") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:24] Set("SIP/701-00000001", "post_num=@gvoice") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:25] GotoIf("SIP/701-00000001", "1?outnum:skipoutnum") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Goto (macro-dialout-trunk,s,26)
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:26] Set("SIP/701-00000001", "the_num=6xxxxx2428") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] pbx.c: -- Executing [s@macro-dialout-trunk:27] Dial("SIP/701-00000001", "local/6xxxxx2428@gvoice,300,") in new stack
[2011-05-16 22:29:30] VERBOSE[22687] app_dial.c: -- Called 6xxxxx2428@gvoice
[2011-05-16 22:29:30] VERBOSE[22688] pbx.c: -- Executing [6xxxxx2428@gvoice:1] Wait("Local/6xxxxx2428@gvoice-2ec6;2", "1") in new stack
[2011-05-16 22:29:31] VERBOSE[22688] pbx.c: -- Executing [6xxxxx2428@gvoice:2] Set("Local/6xxxxx2428@gvoice-2ec6;2", "[email protected]") in new stack
[2011-05-16 22:29:31] VERBOSE[22688] pbx.c: -- Executing [6xxxxx2428@gvoice:3] JabberSend("Local/6xxxxx2428@gvoice-2ec6;2", "asterisk,[email protected],Placing GVoice Call: 6xxxxx2428") in new stack
[2011-05-16 22:29:31] VERBOSE[22688] pbx.c: -- Executing [6xxxxx2428@gvoice:4] GotoIf("Local/6xxxxx2428@gvoice-2ec6;2", "1?us:other") in new stack
[2011-05-16 22:29:31] VERBOSE[22688] pbx.c: -- Goto (gvoice,6xxxxx2428,5)
[2011-05-16 22:29:31] VERBOSE[22688] pbx.c: -- Executing [6xxxxx2428@gvoice:5] Dial("Local/6xxxxx2428@gvoice-2ec6;2", "Gtalk/asterisk/[email protected]") in new stack
[2011-05-16 22:29:31] VERBOSE[22688] app_dial.c: -- Called asterisk/[email protected]
[2011-05-16 22:29:34] DEBUG[22547] chan_gtalk.c: redirect [email protected]/srvres-MTAuMjI5LjE3Ni4yMjk6OTgxMA==
[2011-05-16 22:29:41] NOTICE[22554] chan_sip.c: -- Registration for '[email protected]' timed out, trying again (Attempt #49)
[2011-05-16 22:29:43] VERBOSE[22688] app_dial.c: -- Gtalk/[email protected] is ringing
[2011-05-16 22:29:43] VERBOSE[22687] app_dial.c: -- Local/6xxxxx2428@gvoice-2ec6;1 is ringing
[2011-05-16 22:29:44] DEBUG[22547] chan_gtalk.c: The client is guest
[2011-05-16 22:29:44] VERBOSE[22688] app_dial.c: -- Gtalk/[email protected] answered Local/6xxxxx2428@gvoice-2ec6;2
[2011-05-16 22:29:44] VERBOSE[22687] app_dial.c: -- Local/6xxxxx2428@gvoice-2ec6;1 answered SIP/701-00000001
[2011-05-16 22:29:47] VERBOSE[22688] pbx.c: == Spawn extension (gvoice, 6xxxxx2428, 5) exited non-zero on 'Local/6xxxxx2428@gvoice-2ec6;2'
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/701-00000001", "hangupcall,") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/701-00000001", "1?skiprg") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Goto (macro-hangupcall,s,4)
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/701-00000001", "1?skipblkvm") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Goto (macro-hangupcall,s,7)
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/701-00000001", "1?theend") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Goto (macro-hangupcall,s,9)
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/701-00000001", "") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/701-00000001' in macro 'hangupcall'
[2011-05-16 22:29:47] VERBOSE[22687] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/701-00000001'
[2011-05-16 22:29:47] VERBOSE[22687] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 27) exited non-zero on 'SIP/701-00000001' in macro 'dialout-trunk'
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: == Spawn extension (from-internal, 6xxxxx2428, 6) exited non-zero on 'SIP/701-00000001'
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [h@from-internal:1] Macro("SIP/701-00000001", "hangupcall") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/701-00000001", "1?skiprg") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Goto (macro-hangupcall,s,4)
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/701-00000001", "1?skipblkvm") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Goto (macro-hangupcall,s,7)
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/701-00000001", "1?theend") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Goto (macro-hangupcall,s,9)
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/701-00000001", "") in new stack
[2011-05-16 22:29:47] VERBOSE[22687] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/701-00000001' in macro 'hangupcall'
[2011-05-16 22:29:47] VERBOSE[22687] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/701-00000001'