> Hi Ellie,
>
> (1) the <DESCRIBE TABLES;> command return me the tables' name as expected
>
> -------------------------------
> impi impi_mapping impu
> -------------------------------
>
> (2) In Ellis logs I have the process start, an uncaught exception as
follows
>
>> 2014-09-22 16:14:54,708 I main:125 11875:140055109363456] Process 0
starting up
>> 2014-09-22 16:14:56,665 I main:128 11875:140055109363456] Going to
listen for HTTP on port 80
>> 2014-09-22 16:14:56,716 C main:142 11875:140055109363456] Not starting
HTTPS
>> 2014-09-22 16:14:57,202 W simple_httpclient:325 11875:140055109363456]
uncaught exception
>> Traceback (most recent call last):
>> File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/simple_httpclient.py",
line 323, in cleanup
>> yield
>> File
"/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/simple_httpclient.py",
line 177, in __init__
>> 0, 0)
>> gaierror: [Errno -5] No address associated with hostname
>
>
> Then a have a series of successful pings than I have several other
exception and service unavailable.
>
>> 2014-09-23 08:02:47,774 I main:125 1457:140015295420160] Process 0
starting up
>> 2014-09-23 08:02:47,824 I main:128 1457:140015295420160] Going to listen
for HTTP on port 80
>> 2014-09-23 08:02:47,824 C main:142 1457:140015295420160] Not starting
HTTPS
>> 2014-09-23 08:02:47,855 W iostream:507 1457:140015295420160] Connect
error on fd 8: ECONNREFUSED
>> 2014-09-23 08:02:47,855 E homestead:68 1457:140015295420160] Failed to
ping Homestead at http://192.168.0.88:8889/ping. Have you configured your
HOMESTEAD_URL?
>> 2014-09-23 08:04:04,166 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.41ms
>> 2014-09-23 08:05:24,230 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.19ms
>> 2014-09-23 08:05:37,898 D numbers:120 1457:140015295420160] Number
allocation API call (PSTN = false)
>> 2014-09-23 08:05:37,909 D numbers:125 1457:140015295420160] Allocating a
non-PSTN number
>> 2014-09-23 08:05:37,910 D numbers:139 1457:140015295420160] Fetched
1511165d-d614-478f-a1e7-5fe51e8677bf
>> 2014-09-23 08:05:37,911 D numbers:151 1457:140015295420160] Updated the
owner
>> 2014-09-23 08:05:37,911 D numbers:129 1457:140015295420160] SIP URI
sip:[email protected]
>> 2014-09-23 08:05:37,939 D numbers:158 1457:140015295420160] Populating
other servers...
>> 2014-09-23 08:05:37,939 D numbers:169 1457:140015295420160] About to
create private ID at Homestead
>> 2014-09-23 08:05:37,940 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:37,945 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:37,947 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:37,949 E _base:171 1457:140015295420160] Uncaught
exception POST /accounts/[email protected]/numbers/ (192.168.0.11)
>> HTTPRequest(protocol='http', host='192.168.0.88', method='POST',
uri='/accounts/[email protected]/numbers/', version='HTTP/1.1',
remote_ip='192.168.0.11', body='pstn=false', headers={'Content-Length':
'10', 'Cookie': 'Path=/;
username="c3lzdGVtLnRlc3RAZXhhbXBsZS5jb20=|1411459529|cbd896c44a4438e82207c49fee3c5d112aa0a196"',
'Accept-Encoding': 'gzip, deflate', 'Content-Type':
'application/x-www-form-urlencoded', 'Host': '192.168.0.88', 'Accept':
'*/*; q=0.5, application/xml', 'User-Agent': 'Ruby'})
>> 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 173, 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 92, in create_private_id
>> response = _sync_http_request(url, method="PUT", 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 232, in _sync_http_request
>> raise e
>> HTTPError: HTTP 503: Service Unavailable
>> 2014-09-23 08:05:37,950 E web:1447 1457:140015295420160] 500 POST
/accounts/[email protected]/numbers/ (192.168.0.11) 52.64ms
>> 2014-09-23 08:05:37,952 D numbers:120 1457:140015295420160] Number
allocation API call (PSTN = false)
>> 2014-09-23 08:05:37,953 D numbers:125 1457:140015295420160] Allocating a
non-PSTN number
>> 2014-09-23 08:05:37,954 D numbers:139 1457:140015295420160] Fetched
b9de2006-9f61-4f9d-ab3a-3864d1df0e9b
>> 2014-09-23 08:05:37,954 D numbers:151 1457:140015295420160] Updated the
owner
>> 2014-09-23 08:05:37,955 D numbers:129 1457:140015295420160] SIP URI
sip:[email protected]
>> 2014-09-23 08:05:37,972 D numbers:158 1457:140015295420160] Populating
other servers...
>> 2014-09-23 08:05:37,972 D numbers:169 1457:140015295420160] About to
create private ID at Homestead
>> 2014-09-23 08:05:37,972 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:37,976 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:37,978 E _base:171 1457:140015295420160] Uncaught
exception POST /accounts/[email protected]/numbers/ (192.168.0.11)
>> HTTPRequest(protocol='http', host='192.168.0.88', method='POST',
uri='/accounts/[email protected]/numbers/', version='HTTP/1.1',
remote_ip='192.168.0.11', body='pstn=false', headers={'Content-Length':
'10', 'Cookie': 'Path=/;
username="c3lzdGVtLnRlc3RAZXhhbXBsZS5jb20=|1411459529|cbd896c44a4438e82207c49fee3c5d112aa0a196"',
'Accept-Encoding': 'gzip, deflate', 'Content-Type':
'application/x-www-form-urlencoded', 'Host': '192.168.0.88', 'Accept':
'*/*; q=0.5, application/xml', 'User-Agent': 'Ruby'})
>> 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 173, 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 90, in create_private_id
>> uuid = _get_irs_uuid(_location(_sync_http_request(irs_url,
method="POST", 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 232, in _sync_http_request
>> raise e
>> HTTPError: HTTP 503: Service Unavailable
>> 2014-09-23 08:05:37,979 E web:1447 1457:140015295420160] 500 POST
/accounts/[email protected]/numbers/ (192.168.0.11) 26.91ms
>> 2014-09-23 08:05:38,130 D numbers:120 1457:140015295420160] Number
allocation API call (PSTN = false)
>> 2014-09-23 08:05:38,131 D numbers:125 1457:140015295420160] Allocating a
non-PSTN number
>> 2014-09-23 08:05:38,132 D numbers:139 1457:140015295420160] Fetched
1d53db97-40a8-454a-a52a-9ff6c8d10f0a
>> 2014-09-23 08:05:38,132 D numbers:151 1457:140015295420160] Updated the
owner
>> 2014-09-23 08:05:38,132 D numbers:129 1457:140015295420160] SIP URI
sip:[email protected]
>> 2014-09-23 08:05:38,134 D numbers:158 1457:140015295420160] Populating
other servers...
>> 2014-09-23 08:05:38,134 D numbers:169 1457:140015295420160] About to
create private ID at Homestead
>> 2014-09-23 08:05:38,134 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:38,138 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:38,139 E _base:171 1457:140015295420160] Uncaught
exception POST /accounts/[email protected]/numbers/ (192.168.0.11)
>> HTTPRequest(protocol='http', host='192.168.0.88', method='POST',
uri='/accounts/[email protected]/numbers/', version='HTTP/1.1',
remote_ip='192.168.0.11', body='pstn=false', headers={'Content-Length':
'10', 'Cookie': 'Path=/;
username="c3lzdGVtLnRlc3RAZXhhbXBsZS5jb20=|1411459529|cbd896c44a4438e82207c49fee3c5d112aa0a196"',
'Accept-Encoding': 'gzip, deflate', 'Content-Type':
'application/x-www-form-urlencoded', 'Host': '192.168.0.88', 'Accept':
'*/*; q=0.5, application/xml', 'User-Agent': 'Ruby'})
>> 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 173, 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 90, in create_private_id
>> uuid = _get_irs_uuid(_location(_sync_http_request(irs_url,
method="POST", 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 232, in _sync_http_request
>> raise e
>> HTTPError: HTTP 503: Service Unavailable
>> 2014-09-23 08:05:38,140 E web:1447 1457:140015295420160] 500 POST
/accounts/[email protected]/numbers/ (192.168.0.11) 10.42ms
>> 2014-09-23 08:05:47,153 D numbers:120 1457:140015295420160] Number
allocation API call (PSTN = false)
>> 2014-09-23 08:05:47,161 D numbers:125 1457:140015295420160] Allocating a
non-PSTN number
>> 2014-09-23 08:05:47,162 D numbers:139 1457:140015295420160] Fetched
9f1a4389-be3d-458f-946a-ec6994966524
>> 2014-09-23 08:05:47,162 D numbers:151 1457:140015295420160] Updated the
owner
>> 2014-09-23 08:05:47,162 D numbers:129 1457:140015295420160] SIP URI
sip:[email protected]
>> 2014-09-23 08:05:47,164 D numbers:158 1457:140015295420160] Populating
other servers...
>> 2014-09-23 08:05:47,164 D numbers:169 1457:140015295420160] About to
create private ID at Homestead
>> 2014-09-23 08:05:47,164 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,168 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,170 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,177 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=200,request_time=0.007186174392700195,buffer=<_io.BytesIO
object at
0x2d9c230>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2d9f390>,effective_url='
http://192.168.0.88:8889/private/6505550695%40example.com/associated_implicit_registration_sets/988b2ef8-1b79-4689-8c16-9a25486a5121',headers={'Content-Length':
'0', 'Content-Type': 'text/html; charset=UTF-8', 'Server':
'cyclone/1.0'},error=None)
>> 2014-09-23 08:05:47,177 D utils:89 1457:140015295420160] Still expecting
1 callbacks
>> 2014-09-23 08:05:47,178 D numbers:174 1457:140015295420160] Created
private ID at Homestead
>> 2014-09-23 08:05:47,178 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,180 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,190 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,203 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,206 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:05:47,209 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=200,request_time=0.002543926239013672,buffer=<_io.BytesIO
object at
0x2bdd8f0>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2d9aa50>,effective_url='
http://192.168.0.88:7888/org.etsi.ngn.simservs/users/sip%3A6505550695%40example.com/simservs.xml',headers={'Content-Length':
'2', 'Content-Type': 'application/json', 'Server':
'cyclone/1.0'},error=None)
>> 2014-09-23 08:05:47,209 D utils:89 1457:140015295420160] Still expecting
1 callbacks
>> 2014-09-23 08:05:47,221 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=200,request_time=0.01518392562866211,buffer=<_io.BytesIO
object at
0x2d9c110>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2d9f2d0>,effective_url='
http://192.168.0.88:8889/irs/988b2ef8-1b79-4689-8c16-9a25486a5121/service_profiles/25df0567-953b-430d-97dd-a02111856d81/filter_criteria',headers={'Content-Length':
'0', 'Content-Type': 'text/html; charset=UTF-8', 'Server':
'cyclone/1.0'},error=None)
>> 2014-09-23 08:05:47,221 D utils:87 1457:140015295420160] All requests
successful.
>> 2014-09-23 08:05:47,221 D numbers:192 1457:140015295420160] Successfully
updated all the backends
>> 2014-09-23 08:05:47,221 I web:1447 1457:140015295420160] 200 POST
/accounts/[email protected]/numbers/ (192.168.0.11) 68.35ms
>> 2014-09-23 08:05:47,222 D utils:89 1457:140015295420160] Still expecting
0 callbacks
>> 2014-09-23 08:06:21,201 I numbers:286 1457:140015295420160] Request to
delete sip:[email protected] by [email protected]
>> 2014-09-23 08:06:21,209 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:06:21,213 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=200,request_time=0.003526926040649414,buffer=<_io.BytesIO
object at
0x2d9c590>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2d9a890>,effective_url='
http://192.168.0.88:8889/public/sip%3A6505550836%40example.com/associated_private_ids',headers={'Content-Length':
'63', 'Etag': '"0b6cfe1e262ac95e6a458c8284ec764279ae3807"', 'Content-Type':
'application/json', 'Server': 'cyclone/1.0', 'Content-Encoding':
'gzip'},error=None)
>> 2014-09-23 08:06:21,213 D utils:87 1457:140015295420160] All requests
successful.
>> 2014-09-23 08:06:21,213 D numbers:221 1457:140015295420160] Got related
private ids
>> 2014-09-23 08:06:21,213 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:06:21,214 D utils:89 1457:140015295420160] Still expecting
0 callbacks
>> 2014-09-23 08:06:21,219 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=200,request_time=0.005959033966064453,buffer=<_io.BytesIO
object at
0x2d9c770>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2de7090>,effective_url='
http://192.168.0.88:8889/private/6505550836%40example.com/associated_public_ids',headers={'Content-Length':
'77', 'Etag': '"9a718cdc0cd935a77f9051ec44848d776423f821"', 'Content-Type':
'application/json', 'Server': 'cyclone/1.0', 'Content-Encoding':
'gzip'},error=None)
>> 2014-09-23 08:06:21,219 D utils:87 1457:140015295420160] All requests
successful.
>> 2014-09-23 08:06:21,220 D numbers:231 1457:140015295420160] Got related
public ids
>> 2014-09-23 08:06:21,220 D numbers:88 1457:140015295420160] Removing
owner of sip:[email protected]
>> 2014-09-23 08:06:21,240 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:06:21,242 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:06:21,275 W homestead:237 1457:140015295420160] Passing
SIP password in the clear over http
>> 2014-09-23 08:06:21,276 D utils:89 1457:140015295420160] Still expecting
0 callbacks
>> 2014-09-23 08:06:21,278 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=204,request_time=0.0019488334655761719,buffer=<_io.BytesIO
object at
0x2d9c8f0>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2d9a890>,effective_url='
http://192.168.0.88:7888/org.etsi.ngn.simservs/users/sip%3A6505550836%40example.com/simservs.xml',headers={'Content-Length':
'0', 'Content-Type': 'text/html; charset=UTF-8', 'Server':
'cyclone/1.0'},error=None)
>> 2014-09-23 08:06:21,278 D utils:89 1457:140015295420160] Still expecting
1 callbacks
>> 2014-09-23 08:06:21,279 D utils:79 1457:140015295420160] OK HTTP
response.
HTTPResponse(code=200,request_time=0.003699064254760742,buffer=<_io.BytesIO
object at
0x2d9c950>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest
object at 0x2c69b50>,effective_url='
http://192.168.0.88:8889/private/6505550836%40example.com',headers={'Content-Length':
'0', 'Content-Type': 'text/html; charset=UTF-8', 'Server':
'cyclone/1.0'},error=None)
>> 2014-09-23 08:06:21,279 D utils:87 1457:140015295420160] All requests
successful.
>> 2014-09-23 08:06:21,279 D numbers:292 1457:140015295420160] All requests
successful.
>> 2014-09-23 08:06:21,279 I web:1447 1457:140015295420160] 200 DELETE
/accounts/[email protected]/numbers/sip%3A6505550836%40example.com
(192.168.0.11) 78.73ms
>> 2014-09-23 08:06:21,280 D utils:89 1457:140015295420160] Still expecting
0 callbacks
>> 2014-09-23 08:13:34,674 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.22ms
>> 2014-09-23 08:16:24,831 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.27ms
>> 2014-09-23 08:16:54,843 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.20ms
>> 2014-09-23 08:19:34,996 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.20ms
>> 2014-09-23 08:20:25,047 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.19ms
>> 2014-09-23 08:23:45,213 I web:1447 1457:140015295420160] 200 GET /ping
(192.168.0.88) 0.27ms
>>
>
> (3) looking at the previous log suggests me to check homestead logs. In
homestead_prov-err.log I found some ConnectionRefusedError (connection was
refused by other side: 111: connection refused) and in the
homested_prov-err.log.old I found an InvalidRequestException (why= Keyspace
homestead_cache does not exist). But didn't I check it before without any
problem? It is still possible?
_______________________________________________
Clearwater mailing list
[email protected]
http://lists.projectclearwater.org/listinfo/clearwater
