Thanks Matt, after posting that issue I continued to search and poke about. I found this thread [mid-stream] http://www.mail-archive.com/[email protected]/msg00032.htmland followed through with the diagnostics suggested there...everything seemed to be configured as expected. Looking at the homestead log, I do see the POST and GET request coming in, but no response goes back. I also tried curl http...and there again I see the GET, but no answer.
In your suggestions, dig is fine, but the nc returned 1. I also tried "nc -w0 hs.micro.neartothis.com 8888" and it returned 0. 8-) Looking at the group for micro-homestead, it has 5 inbound tcp rules, including 1 for ellis on 8888. So, what's next? -John On Wed, Oct 23, 2013 at 9:53 AM, Matt Williams <[email protected] > wrote: > 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] 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%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]**** > > 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(135.245.48.12) > **** > > HTTPRequest(protocol='http', host='ellis.micro.neartothis.com', > method='POST', uri='/accounts/only1jpl%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', '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 (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] ([email protected]) has hashed password > '$2a$10$RmONSsmLtmDJhVOSzbn6pu5M/QOTLRyewVGVA3cfHWFq0NqnkdL5.'**** > > 2013-10-23 10:39:42,045 D session:58 1045:140710677980928] User > [email protected] provided correct password ([email protected]) > ({'hashed_password': > '$2a$10$RmONSsmLtmDJhVOSzbn6pu5M/QOTLRyewVGVA3cfHWFq0NqnkdL5.', 'expires': > None, 'user_id': '9e8e9b84-bdb5-49bf-bb53-145b2b1f9641', 'email': > 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 (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]**** > > 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(135.245.48.12) > **** > > HTTPRequest(protocol='http', host='ellis.micro.neartothis.com', > method='POST', uri='/accounts/only1jpl%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', '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 (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
