[Freeswitch-users] mod_verto - can't find user

Mon Mar 21 15:46:23 MSK 2016

It may be a bad acl configuration, it appears as an external call on public context.

I can also see you are using context default, you mentioned you are using fusionpbx, they do not use context default, fusionpbx uses a multi-tenant domain-name context.

Thanks,

Moishe Grunstein
Tornado Computer Systems, Inc.
212.400.7650 888.IPPBX.US
Service Request Email: support at nysolutions.com<mailto:support at nysolutions.com>
[cid:image001.jpg at 01C72F94.9EE45D60]<http://www.nysolutions.com/>
Computer Networking * Managed Services * IP Video Surveillance * Network Assessments * Web Solutions * Voice over IP * Disaster Recovery * Network Security * Site Surveys * CMS

From: freeswitch-users-bounces at lists.freeswitch.org [mailto:freeswitch-users-bounces at lists.freeswitch.org] On Behalf Of Shlomi Schwartz
Sent: Monday, March 21, 2016 4:04 AM
To: FreeSWITCH Users Help <freeswitch-users at lists.freeswitch.org>
Subject: Re: [Freeswitch-users] mod_verto - can't find user

Thanks for the reply, here is an extra log:

2016-03-21 03:56:51.333045 [INFO] mod_verto.c:3997 195.62.30.20:52875<http://195.62.30.20:52875> Client Connect.
2016-03-21 03:56:51.333045 [INFO] mod_verto.c:1973 195.62.30.20:52875<http://195.62.30.20:52875> Starting client thread.
2016-03-21 03:56:51.473025 [INFO] mod_verto.c:1262 195.62.30.20:52875<http://195.62.30.20:52875> re-connecting session 5b75d057-448e-3a56-e475-f4043dfa5f49
2016-03-21 03:56:51.613077 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0898102700 Connected.
2016-03-21 03:56:51.613077 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0898102700 released.
2016-03-21 03:56:59.693030 [DEBUG] mod_verto.c:1857 BAD READ -1000
2016-03-21 03:56:59.693030 [INFO] mod_verto.c:2000 195.62.30.20:52875<http://195.62.30.20:52875> Ending client thread.
2016-03-21 03:56:59.693030 [INFO] mod_verto.c:2007 195.62.30.20:52875<http://195.62.30.20:52875> Thread ended
2016-03-21 03:57:02.713024 [INFO] mod_verto.c:3997 195.62.30.20:52879<http://195.62.30.20:52879> Client Connect.
2016-03-21 03:57:02.713024 [INFO] mod_verto.c:1973 195.62.30.20:52879<http://195.62.30.20:52879> Starting client thread.
2016-03-21 03:57:02.873047 [INFO] mod_verto.c:1262 195.62.30.20:52879<http://195.62.30.20:52879> re-connecting session 5b75d057-448e-3a56-e475-f4043dfa5f49
2016-03-21 03:57:02.993016 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0898102700 Connected.
2016-03-21 03:57:03.013014 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0898102700 released.
2016-03-21 03:57:10.293081 [DEBUG] mod_verto.c:1857 BAD READ -1000
2016-03-21 03:57:10.293081 [INFO] mod_verto.c:2000 195.62.30.20:52879<http://195.62.30.20:52879> Ending client thread.
2016-03-21 03:57:10.293081 [INFO] mod_verto.c:2007 195.62.30.20:52879<http://195.62.30.20:52879> Thread ended
2016-03-21 03:57:13.493028 [INFO] mod_verto.c:3997 195.62.30.20:52883<http://195.62.30.20:52883> Client Connect.
2016-03-21 03:57:13.493028 [INFO] mod_verto.c:1973 195.62.30.20:52883<http://195.62.30.20:52883> Starting client thread.
2016-03-21 03:57:13.633074 [INFO] mod_verto.c:1262 195.62.30.20:52883<http://195.62.30.20:52883> re-connecting session 5b75d057-448e-3a56-e475-f4043dfa5f49
2016-03-21 03:57:13.793042 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f0898102700 Connected.
2016-03-21 03:57:13.793042 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f0898102700 released.
2016-03-21 03:58:08.073017 [INFO] mod_verto.c:3997 82.166.93.197:52545<http://82.166.93.197:52545> Client Connect.
2016-03-21 03:58:08.073017 [INFO] mod_verto.c:1973 82.166.93.197:52545<http://82.166.93.197:52545> Starting client thread.
2016-03-21 03:58:08.853008 [INFO] mod_verto.c:1262 82.166.93.197:52545<http://82.166.93.197:52545> re-connecting session C911C9C4-05EA-453D-8716-08AF865B65A1
2016-03-21 03:58:08.873031 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f08a8048530 Connected.
2016-03-21 03:58:08.873031 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f08a8048530 released.
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_session.c:613 N/A set UUID=B27A64E8-DC42-43EA-B75E-384E7E6A18F7
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [NOTICE] switch_channel.c:1101 New Channel verto.rtc/1008 [B27A64E8-DC42-43EA-B75E-384E7E6A18F7]
2016-03-21 03:58:14.053074 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f08a8048530 Connected.
2016-03-21 03:58:14.053074 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f08a8048530 released.

B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [WARNING] switch_ivr.c:3767 can't find user [1000 at X.X.X.X]

B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] mod_verto.c:3553 Remote SDP verto.rtc/1008:
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 v=0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 o=- 7848321386842985721 2 IN IP4 127.0.0.1
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 s=-
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 t=0 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=group:BUNDLE audio
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=msid-semantic: WMS Local-Meida
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 m=audio 51977 UDP/TLS/RTP/SAVPF 111 103 104 9 102 0 8 106 105 13 127 126
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 c=IN IP4 82.166.93.197
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtcp:56900 IN IP4 82.166.93.197
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:3168280865 1 udp 2122260223 11.0.0.244 51977 typ host generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:605269763 1 udp 2122194687 10.131.189.56 60401 typ host generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:3168280865 2 udp 2122260222 11.0.0.244 56900 typ host generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:605269763 2 udp 2122194686 10.131.189.56 61611 typ host generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:1610196941 1 udp 1686052607 82.166.93.197 51977 typ srflx raddr 11.0.0.244 rport 51977 generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:1610196941 2 udp 1686052606 82.166.93.197 56900 typ srflx raddr 11.0.0.244 rport 56900 generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:4066106833 1 tcp 1518280447 11.0.0.244 52551 typ host tcptype passive generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:1787843571 1 tcp 1518214911 10.131.189.56 52552 typ host tcptype passive generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:4066106833 2 tcp 1518280446 11.0.0.244 52553 typ host tcptype passive generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:1787843571 2 tcp 1518214910 10.131.189.56 52554 typ host tcptype passive generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:2774247863 1 udp 1685987071 176.13.17.109 3179 typ srflx raddr 10.131.189.56 rport 60401 generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=candidate:2774247863 2 udp 1685987070 176.13.17.109 3209 typ srflx raddr 10.131.189.56 rport 61611 generation 0
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=ice-ufrag:/lAaBb3R+aNjW4Iy
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=ice-pwd:Uzvfu2w67cIGuS3pUcd+Ogrk
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=fingerprint:sha-256 52:A7:FD:0F:DA:6D:CA:6A:4C:1F:EE:E1:C3:DE:B7:B4:E8:48:68:92:5A:AD:4F:72:6A:DD:2B:44:B2:BD:6F:AC
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=setup:actpass
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=mid:audio
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=sendrecv
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtcp-mux
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:111 opus/48000/2
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=fmtp:111 minptime=10; useinbandfec=1
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:103 ISAC/16000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:104 ISAC/32000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:9 G722/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:102 ILBC/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:0 PCMU/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:8 PCMA/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:106 CN/32000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:105 CN/16000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:13 CN/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:127 red/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=rtpmap:126 telephone-event/8000
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=maxptime:60
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=ssrc:3648741370 cname:T1GgzljXKNQUWpXL
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=ssrc:3648741370 msid:Local-Meida Local-Audio
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=ssrc:3648741370 mslabel:Local-Meida
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 a=ssrc:3648741370 label:Local-Audio
B27A64E8-DC42-43EA-B75E-384E7E6A18F7
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:473 (verto.rtc/1008) Running State Change CS_INIT
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:516 (verto.rtc/1008) State INIT
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:40 verto.rtc/1008 Standard INIT
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/1008) State Change CS_INIT -> CS_ROUTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:516 (verto.rtc/1008) State INIT going to sleep
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:473 (verto.rtc/1008) Running State Change CS_ROUTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_channel.c:2247 (verto.rtc/1008) Callstate Change DOWN -> RINGING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:532 (verto.rtc/1008) State ROUTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] mod_rtc.c:89 verto.rtc/1008 RTC ROUTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [DEBUG] switch_core_state_machine.c:166 verto.rtc/1008 Standard ROUTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.053074 [INFO] mod_dialplan_xml.c:637 Processing FreeSWITCH User <1000>->1008 in context default
2016-03-21 03:58:14.053074 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f08a8048530 Connected.
2016-03-21 03:58:14.073050 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f08a8048530 released.
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 Dialplan: verto.rtc/1008 parsing [default->intercept-ext-polycom] continue=false

B27A64E8-DC42-43EA-B75E-384E7E6A18F7 Dialplan: verto.rtc/1008 Regex (FAIL) [intercept-ext-polycom] destination_number(1008) =~ /^\*97(\d+)$/ break=on-false

B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [INFO] switch_core_state_machine.c:241 No Route, Aborting
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [NOTICE] switch_core_state_machine.c:242 Hangup verto.rtc/1008 [CS_ROUTING] [NO_ROUTE_DESTINATION]
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:532 (verto.rtc/1008) State ROUTING going to sleep
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:473 (verto.rtc/1008) Running State Change CS_HANGUP
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:739 (verto.rtc/1008) Callstate Change RINGING -> HANGUP
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/1008) State HANGUP
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:60 verto.rtc/1008 Standard HANGUP, cause: NO_ROUTE_DESTINATION
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/1008) State HANGUP going to sleep
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:508 (verto.rtc/1008) State Change CS_HANGUP -> CS_REPORTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:473 (verto.rtc/1008) Running State Change CS_REPORTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.073050 [DEBUG] switch_core_state_machine.c:827 (verto.rtc/1008) State REPORTING
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.093015 [DEBUG] switch_core_state_machine.c:104 verto.rtc/1008 Standard REPORTING, cause: NO_ROUTE_DESTINATION
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.093015 [DEBUG] switch_core_state_machine.c:827 (verto.rtc/1008) State REPORTING going to sleep
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.093015 [DEBUG] switch_core_state_machine.c:499 (verto.rtc/1008) State Change CS_REPORTING -> CS_DESTROY
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.093015 [DEBUG] switch_core_session.c:1646 Session 117 (verto.rtc/1008) Locked, Waiting on external entities
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.093015 [NOTICE] switch_core_session.c:1664 Session 117 (verto.rtc/1008) Ended
B27A64E8-DC42-43EA-B75E-384E7E6A18F7 2016-03-21 03:58:14.093015 [NOTICE] switch_core_session.c:1668 Close Channel verto.rtc/1008 [CS_DESTROY]

On Sun, Mar 20, 2016 at 5:40 PM, Moishe Grunstein <max at nysolutions.com<mailto:max at nysolutions.com>> wrote:
A little more of the log will help, make sure the domain/context is correct.

Thanks,

Moishe Grunstein
Tornado Computer Systems, Inc.
212.400.7650 888.IPPBX.US<http://888.IPPBX.US>
Service Request Email: support at nysolutions.com<mailto:support at nysolutions.com>
[cid:image001.jpg at 01C72F94.9EE45D60]<http://www.nysolutions.com/>
Computer Networking * Managed Services * IP Video Surveillance * Network Assessments * Web Solutions * Voice over IP * Disaster Recovery * Network Security * Site Surveys * CMS

From: freeswitch-users-bounces at lists.freeswitch.org<mailto:freeswitch-users-bounces at lists.freeswitch.org> [mailto:freeswitch-users-bounces at lists.freeswitch.org<mailto:freeswitch-users-bounces at lists.freeswitch.org>] On Behalf Of Shlomi Schwartz
Sent: Sunday, March 20, 2016 10:54 AM
To: FreeSWITCH Users Help <freeswitch-users at lists.freeswitch.org<mailto:freeswitch-users at lists.freeswitch.org>>
Subject: [Freeswitch-users] mod_verto - can't find user

Hi All, (Sorry for the newbe question) I'm using mod verto with: <param name="blind-reg" value="true"/>
user login works fine, and I get: {"jsonrpc":"2.0","id":1,"result":{"message":"logged in","sessid":"E1778D07-8A99-4DBF-88EE-2C57740205D0"}}
however when dialing I can see this warning in the log: switch_ivr.c:3767 can't find user [1008 at X.X.X.X]
And no call is initiated, what am I missing?

This message may contain confidential and/or privileged information.
If you are not the addressee or authorized to receive this on behalf of the addressee you must not use, copy, disclose or take action based on this message or any information herein.
If you have received this message in error, please advise the sender immediately by reply email and delete this message. Thank you.

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org<mailto:consulting at freeswitch.org>
http://www.freeswitchsolutions.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://confluence.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org<mailto:FreeSWITCH-users at lists.freeswitch.org>
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

--
[https://signature.s3.amazonaws.com/2015/lp_logo.png]

Shlomi Schwartz

R&D Technical Leader

T: +972-74-700-4511

[https://signature.s3.amazonaws.com/2015/LinkedIn.png]<http://www.linkedin.com/company/164748>

[https://signature.s3.amazonaws.com/2015/Twitter.png]<http://twitter.com/liveperson>

[https://signature.s3.amazonaws.com/2015/Facebook.png]<http://www.facebook.com/LivePersonInc>

We Create Meaningful Connections

[https://signature.s3.amazonaws.com/2015/banners/Inc_email_banner_2.8.2016.jpg]<http://info.liveperson.com/201602/liz-welch/liveperson-robert-locascio-on-death-of-the-800-call.html>

This message may contain confidential and/or privileged information.
If you are not the addressee or authorized to receive this on behalf of the addressee you must not use, copy, disclose or take action based on this message or any information herein.
If you have received this message in error, please advise the sender immediately by reply email and delete this message. Thank you.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20160321/2a667acb/attachment-0001.html 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: image001.jpg
Type: image/jpeg
Size: 2424 bytes
Desc: image001.jpg
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20160321/2a667acb/attachment-0001.jpg 