John,
Great to hear that you've got the All-In-One VM working, and that you've
successfully installed on EC2!
Thanks for providing the ellis log. The interesting line is:
2013-10-23 10:40:02,673 W utils:73 1045:140710677980928] Non-OK HTTP response.
HTTPResponse(code=599,request_time=20.019735097885132,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at
0x36b8ad0>,effective_url='http://hs.micro.neartothis.com:8888/public/sip%3A6505550826%40micro.neartothis.com/associated_private_ids',headers={},error=HTTPError('HTTP
599: Timeout',))
This shows that ellis tried to communicate with homestead (the HSS
gateway/cache) but timed out.
Please can you check whether hs.micro.neartothis.com is reachable on TCP port
8888 from ellis? I'd suggest checking
* DNS - if you type "dig hs.micro.neartothis.com" on your ellis node,
does it give you the correct IP address?
* security groups - if you type "nc -w 0 hs.cw-ngv.com 8888 ; echo $?"
this will try to connect on port 8888 - it will print 0 if it successfully
connected and a non-zero value if not.
I hope that helps - please let me know if you still see problems or have any
further questions.
Thanks,
Matt
From: [email protected]
[mailto:[email protected]] On Behalf Of John
Letourneau
Sent: 23 October 2013 12:16
To: [email protected]
Subject: [Clearwater] New Install, cannot setup new account
I have created a new deployment using chef in EC2. After a few attempts,
deletes, edits, retries, it ran smoothly [nice job on that people!!].
I then visit the ellis web page and try a signup. I fill in the form and
submit. I get the Please Wait screen and then an error popup "Failed to update
server (see detailed diagnostics in developer console). Please refresh the
page."
I can click "Logout", and then I can login using the new user. Now I get the
screen to Add Private Identity, and after some seconds the popup is displayed
again. Clicking Create....wait...same popup.
I included relevant pieces of the ellis log below. Not sure if the stack trace
leads to the timeout OR if the py code does not deal with timeouts very well 8-)
Thanks,
-John
PS. I have done an install, signup, and made a test call using the All-In-One
on a vm. I'm moving up to more complex networks now.
/var/log/ellis-0.log...
2013-10-23 10:35:57,339 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.34ms
2013-10-23 10:36:17,353 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.33ms
2013-10-23 10:36:37,366 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.33ms
2013-10-23 10:36:51,338 I users:75 1045:140710677980928] Request to create new
account
2013-10-23 10:36:51,338 D _base:141 1045:140710677980928] No NGV-Signup-Code
code in header, looking in body
2013-10-23 10:36:51,340 I users:61 1045:140710677980928] User email
[email protected]<mailto:[email protected]> not found
2013-10-23 10:36:51,597 I web:1447 1045:140710677980928] 302 POST
/accounts/?onsuccess=/index.html%23first&onfailure=/signup.html (135.245.48.12)
260.54ms
2013-10-23 10:36:51,760 D static:80 1045:140710677980928] Checking if
index.html is allowed
2013-10-23 10:36:51,802 I web:1447 1045:140710677980928] 200 GET
/index.html?data=%7B%22username%22%3A%20%22only1jpl%40gmail.com<http://40gmail.com>%22%2C%20%22full_name%22%3A%20%22john%22%7D&message=Created&status=201&success=true
(135.245.48.12) 42.71ms
2013-10-23 10:36:52,159 D static:80 1045:140710677980928] Checking if
js/bootstrap.min.js is allowed
2013-10-23 10:36:52,159 D static:87 1045:140710677980928] Path matches regex
/js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*,
allowed
2013-10-23 10:36:52,160 I web:1447 1045:140710677980928] 304 GET
/js/bootstrap.min.js (135.245.48.12) 1.15ms
2013-10-23 10:36:52,250 D static:80 1045:140710677980928] Checking if js/app.js
is allowed
2013-10-23 10:36:52,253 I web:1447 1045:140710677980928] 304 GET /js/app.js
(135.245.48.12) 2.74ms
2013-10-23 10:36:52,357 D numbers:119 1045:140710677980928] Number allocation
API call (PSTN = false)
2013-10-23 10:36:52,359 D numbers:120 1045:140710677980928] Allocating a
non-PSTN number
2013-10-23 10:36:52,360 D numbers:133 1045:140710677980928] Fetched
0101d9b6-953f-4cc5-8055-370d5474a732
2013-10-23 10:36:52,361 D numbers:140 1045:140710677980928] Updated the owner
2013-10-23 10:36:52,361 D numbers:128 1045:140710677980928] SIP URI
sip:[email protected]<mailto:sip%[email protected]>
2013-10-23 10:36:52,366 D numbers:157 1045:140710677980928] Populating other
servers...
2013-10-23 10:36:52,366 W homestead:229 1045:140710677980928] Passing SIP
password in the clear over http
2013-10-23 10:37:12,387 E _base:171 1045:140710677980928] Uncaught exception
POST
/accounts/only1jpl%40gmail.com/numbers/?cb=ab1a77f0aP0<http://40gmail.com/numbers/?cb=ab1a77f0aP0>
(135.245.48.12)
HTTPRequest(protocol='http',
host='ellis.micro.neartothis.com<http://ellis.micro.neartothis.com>',
method='POST',
uri='/accounts/only1jpl%40gmail.com/numbers/?cb=ab1a77f0aP0<http://40gmail.com/numbers/?cb=ab1a77f0aP0>',
version='HTTP/1.1', remote_ip='135.245.48.12', body='', headers={'Origin':
'http://ellis.micro.neartothis.com', 'Content-Length': '0', 'Accept-Language':
'en-US,en;q=0.8', 'Accept-Encoding': 'gzip,deflate,sdch', 'X-Bluecoat-Via':
'951d499292535715', 'Host':
'ellis.micro.neartothis.com<http://ellis.micro.neartothis.com>', 'Accept':
'*/*', 'User-Agent': 'Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36
(KHTML, like Gecko) Chrome/30.0.1599.101 Safari/537.36', 'Connection':
'Keep-Alive', 'X-Requested-With': 'XMLHttpRequest', 'Cache-Control':
'max-age=0', 'Referer':
'http://ellis.micro.neartothis.com/index.html?data=%7B%22username%22%3A%20%22only1jpl%40gmail.com%22%2C%20%22full_name%22%3A%20%22john%22%7D&message=Created&status=201&success=true',
'Cookie':
'username=b25seTFqcGxAZ21haWwuY29t|1382524611|644843769d89583b25a29f7c0e10728b40eee7e4'})
Traceback (most recent call last):
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/web.py",
line 1000, in _stack_context_handle_exception
raise_exc_info((type, value, traceback))
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/web.py",
line 1118, in wrapper
return method(self, *args, **kwargs)
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/api/numbers.py",
line 170, in post
self._request_group.callback())
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/remote/homestead.py",
line 88, in create_private_id
put_password(private_id, password, None) # No callback makes this
synchronous
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/remote/homestead.py",
line 110, in put_password
return _sync_http_request(url, method="PUT", headers=headers, body=body)
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/remote/homestead.py",
line 224, in _sync_http_request
raise e
HTTPError: HTTP 599: Timeout
2013-10-23 10:37:12,389 E web:1447 1045:140710677980928] 500 POST
/accounts/only1jpl%40gmail.com/numbers/?cb=ab1a77f0aP0<http://40gmail.com/numbers/?cb=ab1a77f0aP0>
(135.245.48.12) 20031.63ms
2013-10-23 10:37:47,410 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.34ms
2013-10-23 10:38:07,423 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.35ms
<snip>
2013-10-23 10:39:27,474 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.34ms
2013-10-23 10:39:41,795 D users:136 1045:140710677980928] User email
[email protected]<mailto:[email protected]>
([email protected]<mailto:[email protected]>) has hashed password
'$2a$10$RmONSsmLtmDJhVOSzbn6pu5M/QOTLRyewVGVA3cfHWFq0NqnkdL5.'
2013-10-23 10:39:42,045 D session:58 1045:140710677980928] User
[email protected]<mailto:[email protected]> provided correct password
([email protected]<mailto:[email protected]>) ({'hashed_password':
'$2a$10$RmONSsmLtmDJhVOSzbn6pu5M/QOTLRyewVGVA3cfHWFq0NqnkdL5.', 'expires':
None, 'user_id': '9e8e9b84-bdb5-49bf-bb53-145b2b1f9641', 'email':
u'[email protected]<mailto:u%[email protected]>', 'full_name': u'john'})
2013-10-23 10:39:42,046 I web:1447 1045:140710677980928] 302 POST
/session?onsuccess=/index.html&onfailure=/login.html (135.245.48.12) 253.51ms
2013-10-23 10:39:42,653 W homestead:229 1045:140710677980928] Passing SIP
password in the clear over http
2013-10-23 10:40:02,673 W utils:73 1045:140710677980928] Non-OK HTTP response.
HTTPResponse(code=599,request_time=20.019735097885132,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at
0x36b8ad0>,effective_url='http://hs.micro.neartothis.com:8888/public/sip%3A6505550826%40micro.neartothis.com/associated_private_ids',headers={},error=HTTPError('HTTP
599: Timeout',))
2013-10-23 10:40:02,674 W numbers:113 1045:140710677980928] Failed to fetch
private identities from homestead
2013-10-23 10:40:02,677 E web:1447 1045:140710677980928] 502 GET
/accounts/only1jpl%40gmail.com/numbers/?cb=ab1aa16daT0<http://40gmail.com/numbers/?cb=ab1aa16daT0>
(135.245.48.12) 20027.42ms
2013-10-23 10:40:02,677 D utils:89 1045:140710677980928] Still expecting 0
callbacks
2013-10-23 10:40:37,521 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.33ms
<snip>
2013-10-23 10:41:49,868 D numbers:119 1045:140710677980928] Number allocation
API call (PSTN = false)
2013-10-23 10:41:49,870 D numbers:120 1045:140710677980928] Allocating a
non-PSTN number
2013-10-23 10:41:49,871 D numbers:133 1045:140710677980928] Fetched
0138ca2c-e8b2-4ca2-9575-29be489b985b
2013-10-23 10:41:49,872 D numbers:140 1045:140710677980928] Updated the owner
2013-10-23 10:41:49,873 D numbers:128 1045:140710677980928] SIP URI
sip:[email protected]<mailto:sip%[email protected]>
2013-10-23 10:41:49,878 D numbers:157 1045:140710677980928] Populating other
servers...
2013-10-23 10:41:49,878 W homestead:229 1045:140710677980928] Passing SIP
password in the clear over http
2013-10-23 10:42:09,898 E _base:171 1045:140710677980928] Uncaught exception
POST
/accounts/only1jpl%40gmail.com/numbers/?cb=ab1aa16daT1<http://40gmail.com/numbers/?cb=ab1aa16daT1>
(135.245.48.12)
HTTPRequest(protocol='http',
host='ellis.micro.neartothis.com<http://ellis.micro.neartothis.com>',
method='POST',
uri='/accounts/only1jpl%40gmail.com/numbers/?cb=ab1aa16daT1<http://40gmail.com/numbers/?cb=ab1aa16daT1>',
version='HTTP/1.1', remote_ip='135.245.48.12', body='pstn=false',
headers={'Origin': 'http://ellis.micro.neartothis.com', 'Content-Length': '10',
'Accept-Language': 'en-US,en;q=0.8', 'Accept-Encoding': 'gzip,deflate,sdch',
'X-Bluecoat-Via': '951d499292535715', 'Host':
'ellis.micro.neartothis.com<http://ellis.micro.neartothis.com>', 'Accept':
'*/*', 'User-Agent': 'Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36
(KHTML, like Gecko) Chrome/30.0.1599.101 Safari/537.36', 'Connection':
'Keep-Alive', 'X-Requested-With': 'XMLHttpRequest', 'Referer':
'http://ellis.micro.neartothis.com/index.html?data=%7B%22username%22%3A%20%22only1jpl%40gmail.com%22%2C%20%22full_name%22%3A%20%22john%22%7D&message=Created&status=201&success=true',
'Cookie':
'username=b25seTFqcGxAZ21haWwuY29t|1382524782|adea82860ca78afa56cdb7230cf2ace5f811e6b6',
'Content-Type': 'application/x-www-form-urlencoded; charset=UTF-8'})
Traceback (most recent call last):
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/web.py",
line 1000, in _stack_context_handle_exception
raise_exc_info((type, value, traceback))
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/web.py",
line 1118, in wrapper
return method(self, *args, **kwargs)
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/api/numbers.py",
line 170, in post
self._request_group.callback())
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/remote/homestead.py",
line 88, in create_private_id
put_password(private_id, password, None) # No callback makes this
synchronous
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/remote/homestead.py",
line 110, in put_password
return _sync_http_request(url, method="PUT", headers=headers, body=body)
File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/ellis-0.1-py2.7.egg/metaswitch/ellis/remote/homestead.py",
line 224, in _sync_http_request
raise e
HTTPError: HTTP 599: Timeout
2013-10-23 10:42:09,900 E web:1447 1045:140710677980928] 500 POST
/accounts/only1jpl%40gmail.com/numbers/?cb=ab1aa16daT1<http://40gmail.com/numbers/?cb=ab1aa16daT1>
(135.245.48.12) 20031.78ms
2013-10-23 10:42:47,603 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.33ms
2013-10-23 10:43:07,616 I web:1447 1045:140710677980928] 200 GET /ping
(127.0.0.1) 0.33ms
_______________________________________________
Clearwater mailing list
[email protected]
http://lists.projectclearwater.org/listinfo/clearwater
