Hi Ellie,
Thanks. Followed steps as suggested but still Ellis UI displays pop-up while creating a number with "failed to update server refresh the browser", Even password also did not displayed on browser. But logs looks good. Attaching the latest logs. Can you please look at the logs and suggest me how to go ahead. One more thing, to create a number in Ellis, only 3 VMs are running (Ellis, Homer & Homestead). Remaining VMs i have shut down due to RAM size of my PC. This could of any problem?? Thanks in advance!! Regards, Sai ________________________________ From: Eleanor Merry <[email protected]> Sent: 08 January 2016 18:21 To: Sainath Ellendula (WT01 - Product Engineering Service); [email protected] Subject: RE: Ellis issue failed to update server Hi Sai, It looks like the Ellis database has some invalid entries (likely because of trying to use Ellis and an external HSS). The easiest way to go from here is to wipe out all of your subscribers and then start again. To do this: Check that Homestead isn’t using an external HSS. If you run ps -eaf | grep homestead, you can see what options Homestead is running with. Hss-peer should be empty, and dest-host should be set to 0.0.0.0 To delete all numbers on Ellis, run the following on Ellis: sudo mysql USE ellis UPDATE numbers SET owner_id=NULL; To delete all numbers on Homestead, run the following on Homestead: cqlsh USE homestead_provisioning; TRUNCATE public; TRUNCATE private; TRUNCATE service_profiles; TRUNCATE implicit_registration_sets; USE homestead_cache; TRUNCATE impi; TRUNCATE impu; TRUNCATE impi_mapping; To delete all numbers on Homer, run the following on Homer: cqlsh USE homer; TRUNCATE simservs; Ellie From: [email protected] [mailto:[email protected]] Sent: 08 January 2016 08:02 To: Eleanor Merry; [email protected] Subject: Ellis issue failed to update server Hi Ellie, I was unable to point out where exactly issue occurs while creating new private identity and getting error as "failed to update server refresh the browser". observed logs: 08-01-2016 07:53:55.125 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/public/sip%3A6505550924%40cw-ngv.com/associated_private_ids with code 404 08-01-2016 07:53:55.126 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=404,request_time=0.1031348705291748,buffer=<_io.BytesIO object at 0x7f4a3dc90b30>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f4a3e42d590>,effective_url='http://homestead.pes.com:8889/public/sip%3A6505550924%40cw-ngv.com/associated_private_ids',headers={'Content-Length': '95', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Date': 'Fri, 08 Jan 2016 07:53:27 GMT', 'Content-Type': 'application/json'},error=HTTPError('HTTP 404: Not Found',)) 08-01-2016 07:53:55.126 UTC WARNING numbers.py:114: Failed to fetch private identities from homestead 08-01-2016 07:53:55.126 UTC ERROR web.py:718: Cannot send error response after headers written Please find the updated /var/log/ellis *.log attached. Thanks in advance!! Regards, Sai The information contained in this electronic message and any attachments to this message are intended for the exclusive use of the addressee(s) and may contain proprietary, confidential or privileged information. If you are not the intended recipient, you should not disseminate, distribute or copy this e-mail. Please notify the sender immediately and destroy all copies of this message and any attachments. WARNING: Computer viruses can be transmitted via email. The recipient should check this email and any attachments for the presence of viruses. The company accepts no liability for any damage caused by any virus transmitted by this email. www.wipro.com<http://www.wipro.com> The information contained in this electronic message and any attachments to this message are intended for the exclusive use of the addressee(s) and may contain proprietary, confidential or privileged information. If you are not the intended recipient, you should not disseminate, distribute or copy this e-mail. Please notify the sender immediately and destroy all copies of this message and any attachments. WARNING: Computer viruses can be transmitted via email. The recipient should check this email and any attachments for the presence of viruses. The company accepts no liability for any damage caused by any virus transmitted by this email. www.wipro.com
08-01-2016 14:31:37.458 UTC INFO main.py:104: Process 0 starting up 08-01-2016 14:31:40.845 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:31:40.986 UTC WARNING iostream.py:507: Connect error on fd 8: ECONNREFUSED 08-01-2016 14:31:40.988 UTC ERROR homestead.py:68: Failed to ping Homestead at http://homestead.pes.com:8889/ping. Have you configured your HOMESTEAD_URL? 08-01-2016 14:31:49.009 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.99ms 08-01-2016 14:32:09.130 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.72ms 08-01-2016 14:32:29.255 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.75ms 08-01-2016 14:32:49.975 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.50ms 08-01-2016 14:33:10.207 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.83ms 08-01-2016 14:33:30.384 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.51ms 08-01-2016 14:33:53.695 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.57ms 08-01-2016 14:34:13.890 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.57ms 08-01-2016 14:34:34.032 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 2.03ms 08-01-2016 14:34:54.169 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.62ms 08-01-2016 14:35:14.503 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.67ms 08-01-2016 14:35:34.552 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.27ms 08-01-2016 14:35:54.707 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.76ms 08-01-2016 14:36:14.925 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.91ms 08-01-2016 14:36:35.091 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.74ms 08-01-2016 14:36:55.443 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.84ms 08-01-2016 14:37:15.597 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.82ms 08-01-2016 14:37:35.722 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.94ms 08-01-2016 14:37:55.913 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.54ms 08-01-2016 14:38:16.056 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.00ms 08-01-2016 14:38:26.104 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.85ms 08-01-2016 14:38:46.332 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.59ms 08-01-2016 14:39:06.461 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.98ms 08-01-2016 14:39:26.606 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.65ms 08-01-2016 14:39:46.787 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.58ms 08-01-2016 14:40:06.760 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.28ms 08-01-2016 14:40:27.072 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.93ms 08-01-2016 14:40:47.336 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.83ms 08-01-2016 14:41:07.445 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.77ms 08-01-2016 14:41:27.659 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.45ms 08-01-2016 14:41:47.852 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.71ms 08-01-2016 14:42:07.946 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.56ms 08-01-2016 14:42:28.098 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.66ms 08-01-2016 14:42:48.300 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.59ms 08-01-2016 14:43:08.440 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.63ms 08-01-2016 14:43:28.839 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.42ms 08-01-2016 14:43:49.215 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.74ms 08-01-2016 14:44:09.340 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.33ms 08-01-2016 14:44:09.659 UTC DEBUG static.py:80: Checking if login.html is allowed 08-01-2016 14:44:09.660 UTC DEBUG static.py:83: Path is login path, allowed. 08-01-2016 14:44:09.726 UTC INFO web.py:1447: 200 GET /login.html (0.0.0.0) 67.87ms 08-01-2016 14:44:09.755 UTC DEBUG static.py:80: Checking if css/bootstrap-responsive.css is allowed 08-01-2016 14:44:09.760 UTC DEBUG static.py:87: Path matches regex /css/.*, allowed 08-01-2016 14:44:09.834 UTC INFO web.py:1447: 200 GET /css/bootstrap-responsive.css (0.0.0.0) 78.70ms 08-01-2016 14:44:09.849 UTC DEBUG static.py:80: Checking if css/jquery.miniColors.css is allowed 08-01-2016 14:44:09.850 UTC DEBUG static.py:87: Path matches regex /css/.*, allowed 08-01-2016 14:44:09.868 UTC INFO web.py:1447: 200 GET /css/jquery.miniColors.css (0.0.0.0) 20.25ms 08-01-2016 14:44:09.873 UTC DEBUG static.py:80: Checking if js/jquery.js is allowed 08-01-2016 14:44:09.874 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.037 UTC INFO web.py:1447: 200 GET /js/jquery.js (0.0.0.0) 164.30ms 08-01-2016 14:44:10.043 UTC DEBUG static.py:80: Checking if js/jquery.total-storage.min.js is allowed 08-01-2016 14:44:10.044 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.096 UTC INFO web.py:1447: 200 GET /js/jquery.total-storage.min.js (0.0.0.0) 53.30ms 08-01-2016 14:44:10.097 UTC DEBUG static.py:80: Checking if js/jquery.miniColors.min.js is allowed 08-01-2016 14:44:10.117 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.148 UTC INFO web.py:1447: 200 GET /js/jquery.miniColors.min.js (0.0.0.0) 50.74ms 08-01-2016 14:44:10.149 UTC DEBUG static.py:80: Checking if js/login.js is allowed 08-01-2016 14:44:10.150 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.172 UTC INFO web.py:1447: 200 GET /js/login.js (0.0.0.0) 23.10ms 08-01-2016 14:44:10.188 UTC DEBUG static.py:80: Checking if js/jquery.validate.min.js is allowed 08-01-2016 14:44:10.188 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.212 UTC INFO web.py:1447: 200 GET /js/jquery.validate.min.js (0.0.0.0) 23.53ms 08-01-2016 14:44:19.352 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.04ms 08-01-2016 14:44:37.041 UTC DEBUG users.py:135: User email [email protected] ([email protected]) has hashed password u'$2a$10$eNS/hbNjH9PrYTBaPyOHbuFY.0xgmTF9SZXw8g7hc2t0apjHcahDy' 08-01-2016 14:44:37.398 UTC DEBUG session.py:58: User [email protected] provided correct password ([email protected]) ({'hashed_password': u'$2a$10$eNS/hbNjH9PrYTBaPyOHbuFY.0xgmTF9SZXw8g7hc2t0apjHcahDy', 'expires': None, 'user_id': 'f61468f4-a669-4e86-a837-b743bc9aad9f', 'email': u'[email protected]', 'full_name': u'sainath'}) 08-01-2016 14:44:37.406 UTC INFO web.py:1447: 302 POST /session?onsuccess=/index.html&onfailure=/login.html (0.0.0.0) 494.41ms 08-01-2016 14:44:37.485 UTC DEBUG static.py:80: Checking if js/bootstrap.min.js is allowed 08-01-2016 14:44:37.486 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:37.517 UTC INFO web.py:1447: 200 GET /js/bootstrap.min.js (0.0.0.0) 32.17ms 08-01-2016 14:44:38.304 UTC INFO web.py:1447: 200 GET /accounts/sainath.ellendula%40wipro.com/numbers/?cb=1aee7611edY0 (0.0.0.0) 13.36ms 08-01-2016 14:44:41.316 UTC DEBUG static.py:80: Checking if addressbook.html is allowed 08-01-2016 14:44:41.339 UTC INFO web.py:1447: 200 GET /addressbook.html (0.0.0.0) 23.21ms 08-01-2016 14:44:41.385 UTC DEBUG static.py:80: Checking if js/underscore.js is allowed 08-01-2016 14:44:41.386 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:41.455 UTC INFO web.py:1447: 200 GET /js/underscore.js (0.0.0.0) 70.07ms 08-01-2016 14:44:41.457 UTC DEBUG static.py:80: Checking if js/addressbook.js is allowed 08-01-2016 14:44:41.506 UTC INFO web.py:1447: 200 GET /js/addressbook.js (0.0.0.0) 49.90ms 08-01-2016 14:44:41.700 UTC INFO web.py:1447: 200 GET /gab (0.0.0.0) 8.10ms 08-01-2016 14:44:43.859 UTC INFO web.py:1447: 200 GET /accounts/sainath.ellendula%40wipro.com/numbers/?cb=1aee7627d3V0 (0.0.0.0) 12.13ms 08-01-2016 14:44:48.689 UTC DEBUG numbers.py:120: Number allocation API call (PSTN = false) 08-01-2016 14:44:48.697 UTC DEBUG numbers.py:125: non-PSTN 08-01-2016 14:44:48.755 UTC DEBUG numbers.py:147: Fetched 9a3ff2c3-c7ed-4e20-87c4-ebd21f40d3c0 08-01-2016 14:44:48.758 UTC DEBUG numbers.py:129: SIP URI sip:[email protected] 08-01-2016 14:44:48.816 UTC DEBUG numbers.py:159: Populating other servers... 08-01-2016 14:44:48.818 UTC DEBUG numbers.py:170: About to create private ID at Homestead 08-01-2016 14:44:48.818 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.850 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.869 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.931 UTC DEBUG numbers.py:175: Created private ID at Homestead 08-01-2016 14:44:48.931 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.949 UTC INFO homestead.py:166: {"associated_implicit_registration_sets": ["72454486-ceba-4a61-87aa-b53448c4bf7c"]} 08-01-2016 14:44:48.950 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:49.039 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:49.181 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:49.206 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:49.207 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/irs/72454486-ceba-4a61-87aa-b53448c4bf7c/service_profiles/df63b646-3be1-471e-beb0-a0cae32b51d1/filter_criteria 08-01-2016 14:45:09.234 UTC WARNING simple_httpclient.py:325: 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 -2] Name or service not known 08-01-2016 14:45:09.236 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.025679111480713,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6c036a0d0>,effective_url='http://homer.cw-ngv.com:7888/org.etsi.ngn.simservs/users/sip%3A6500511%40cw-ngv.com/simservs.xml',headers={},error=gaierror(-2, 'Name or service not known')) 08-01-2016 14:45:09.237 UTC WARNING numbers.py:195: Failed to update all the backends 08-01-2016 14:45:09.237 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:09.239 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/public/sip%3A6500511%40cw-ngv.com/associated_private_ids 08-01-2016 14:45:09.242 UTC DEBUG utils.py:94: Still expecting 2 callbacks 08-01-2016 14:45:09.245 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/irs/72454486-ceba-4a61-87aa-b53448c4bf7c/service_profiles/df63b646-3be1-471e-beb0-a0cae32b51d1/filter_criteria with code 599 08-01-2016 14:45:09.248 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.03780698776245,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6c02f5cd0>,effective_url='http://homestead.pes.com:8889/irs/72454486-ceba-4a61-87aa-b53448c4bf7c/service_profiles/df63b646-3be1-471e-beb0-a0cae32b51d1/filter_criteria',headers={},error=HTTPError('HTTP 599: Timeout',)) 08-01-2016 14:45:09.249 UTC DEBUG utils.py:94: Still expecting 1 callbacks 08-01-2016 14:45:09.252 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.059377193450927734,buffer=<_io.BytesIO object at 0x7ff6c02df110>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6bfd37450>,effective_url='http://homestead.pes.com:8889/private/6500511%40cw-ngv.com/associated_implicit_registration_sets/72454486-ceba-4a61-87aa-b53448c4bf7c',headers={'Date': 'Fri, 08 Jan 2016 14:44:16 GMT', 'Content-Length': '0', 'Content-Type': 'text/html; charset=UTF-8', 'Connection': 'close', 'Server': 'nginx/1.4.6 (Ubuntu)'},error=None) 08-01-2016 14:45:09.252 UTC DEBUG utils.py:88: Another response failed, skipping callbacks. 08-01-2016 14:45:09.253 UTC ERROR iostream.py:307: Uncaught exception, closing connection. Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:45:09.254 UTC ERROR ioloop.py:435: Exception in callback <tornado.stack_context._StackContextWrapper object at 0x7ff6c0369af8> Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/ioloop.py", line 421, in _run_callback callback() File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:45:09.298 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/public/sip%3A6500511%40cw-ngv.com/associated_private_ids with code 200 08-01-2016 14:45:09.298 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.05734896659851074,buffer=<_io.BytesIO object at 0x7ff6bfd6ccb0>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6bfd378d0>,effective_url='http://homestead.pes.com:8889/public/sip%3A6500511%40cw-ngv.com/associated_private_ids',headers={'Content-Length': '59', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Etag': '"bc5a8ad727956733f312dd89f35bb5890e0d7dca"', 'Date': 'Fri, 08 Jan 2016 14:44:37 GMT', 'Content-Type': 'application/json'},error=None) 08-01-2016 14:45:09.299 UTC DEBUG utils.py:92: All requests successful. 08-01-2016 14:45:09.299 UTC DEBUG numbers.py:224: Got related private ids 08-01-2016 14:45:09.300 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:09.300 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/private/6500511%40cw-ngv.com/associated_public_ids 08-01-2016 14:45:09.302 UTC DEBUG utils.py:94: Still expecting 0 callbacks 08-01-2016 14:45:09.336 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/private/6500511%40cw-ngv.com/associated_public_ids with code 200 08-01-2016 14:45:09.337 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.03498196601867676,buffer=<_io.BytesIO object at 0x7ff6bfd6cc50>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6c01f9910>,effective_url='http://homestead.pes.com:8889/private/6500511%40cw-ngv.com/associated_public_ids',headers={'Content-Length': '73', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Etag': '"213f9d0561bcc3afc7efd3ab51b8cf6c0b48cd1a"', 'Date': 'Fri, 08 Jan 2016 14:44:37 GMT', 'Content-Type': 'application/json'},error=None) 08-01-2016 14:45:09.337 UTC DEBUG utils.py:92: All requests successful. 08-01-2016 14:45:09.337 UTC DEBUG numbers.py:234: Got related public ids 08-01-2016 14:45:09.338 UTC INFO numbers.py:282: Returning number to pool before attempting deletion from Homestead and Homer 08-01-2016 14:45:09.339 UTC DEBUG numbers.py:88: Removing owner of sip:[email protected] 08-01-2016 14:45:09.798 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:09.823 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:10.249 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:10.249 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/private/6500511%40cw-ngv.com 08-01-2016 14:45:41.280 UTC INFO main.py:104: Process 0 starting up 08-01-2016 14:45:42.741 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:43.424 UTC INFO homestead.py:62: Pinged Homestead OK 08-01-2016 14:45:52.124 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 2.48ms 08-01-2016 14:46:03.488 UTC INFO web.py:1447: 304 GET /gab (0.0.0.0) 106.77ms 08-01-2016 14:46:05.729 UTC INFO web.py:1447: 200 GET /accounts/sainath.ellendula%40wipro.com/numbers/?cb=1aee77678b-0 (0.0.0.0) 41.58ms 08-01-2016 14:46:12.549 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.82ms 08-01-2016 14:46:35.658 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 9.36ms 08-01-2016 14:46:52.000 UTC DEBUG numbers.py:120: Number allocation API call (PSTN = false) 08-01-2016 14:46:52.011 UTC DEBUG numbers.py:125: non-PSTN 08-01-2016 14:46:52.071 UTC DEBUG numbers.py:147: Fetched 23711470-82e0-44d7-8dc8-962a4167fbe2 08-01-2016 14:46:52.073 UTC DEBUG numbers.py:129: SIP URI sip:[email protected] 08-01-2016 14:46:52.137 UTC DEBUG numbers.py:159: Populating other servers... 08-01-2016 14:46:52.138 UTC DEBUG numbers.py:170: About to create private ID at Homestead 08-01-2016 14:46:52.138 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.173 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.189 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.239 UTC DEBUG numbers.py:175: Created private ID at Homestead 08-01-2016 14:46:52.240 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.256 UTC INFO homestead.py:166: {"associated_implicit_registration_sets": ["e71c62a1-daae-4585-a420-f58667056b27"]} 08-01-2016 14:46:52.257 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.323 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.427 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.451 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:52.452 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/irs/e71c62a1-daae-4585-a420-f58667056b27/service_profiles/00738e2a-a67c-42ab-bdba-94a186108e5e/filter_criteria 08-01-2016 14:47:19.267 UTC INFO main.py:104: Process 0 starting up 08-01-2016 14:47:20.257 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:47:20.338 UTC INFO homestead.py:62: Pinged Homestead OK 08-01-2016 14:47:40.433 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 3.26ms 08-01-2016 14:48:00.601 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.22ms 08-01-2016 14:48:21.117 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.54ms 08-01-2016 14:48:40.973 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.82ms 08-01-2016 14:49:01.156 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.53ms 08-01-2016 14:49:21.263 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.39ms 08-01-2016 14:49:41.776 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 3.83ms 08-01-2016 14:50:01.920 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.77ms 08-01-2016 14:50:22.130 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 12.15ms 08-01-2016 14:50:42.234 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.54ms 08-01-2016 14:51:02.494 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.52ms 08-01-2016 14:51:22.777 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.80ms 08-01-2016 14:51:42.960 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.57ms 08-01-2016 14:52:03.113 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.76ms 08-01-2016 14:52:23.318 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.78ms 08-01-2016 14:52:53.532 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.90ms 08-01-2016 14:53:13.719 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.57ms
08-01-2016 14:31:37.349 UTC INFO main.py:104: Process 1 starting up 08-01-2016 14:31:40.821 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:31:40.997 UTC WARNING iostream.py:507: Connect error on fd 8: ECONNREFUSED 08-01-2016 14:31:41.000 UTC ERROR homestead.py:68: Failed to ping Homestead at http://homestead.pes.com:8889/ping. Have you configured your HOMESTEAD_URL? 08-01-2016 14:31:59.095 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 11.26ms 08-01-2016 14:32:19.152 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.36ms 08-01-2016 14:32:39.295 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.52ms 08-01-2016 14:33:00.176 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.32ms 08-01-2016 14:33:20.388 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.64ms 08-01-2016 14:33:43.686 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.84ms 08-01-2016 14:34:03.815 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.78ms 08-01-2016 14:34:23.915 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.38ms 08-01-2016 14:34:44.016 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.84ms 08-01-2016 14:35:04.322 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.40ms 08-01-2016 14:35:24.479 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.52ms 08-01-2016 14:35:44.673 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.73ms 08-01-2016 14:36:04.825 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.84ms 08-01-2016 14:36:25.005 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.78ms 08-01-2016 14:36:45.542 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.14ms 08-01-2016 14:37:05.484 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.66ms 08-01-2016 14:37:25.638 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.81ms 08-01-2016 14:37:45.915 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.45ms 08-01-2016 14:38:05.961 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.85ms 08-01-2016 14:38:18.926 UTC INFO web.py:1447: 200 GET /accounts/sainath.ellendula%40wipro.com/numbers/?cb=1aee70457cR0 (0.0.0.0) 354.63ms 08-01-2016 14:38:36.203 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.09ms 08-01-2016 14:38:56.392 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.49ms 08-01-2016 14:39:16.542 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.51ms 08-01-2016 14:39:36.668 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.92ms 08-01-2016 14:39:56.895 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 5.66ms 08-01-2016 14:40:17.029 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.77ms 08-01-2016 14:40:37.159 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.68ms 08-01-2016 14:40:57.336 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.66ms 08-01-2016 14:41:17.507 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.67ms 08-01-2016 14:41:37.821 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.79ms 08-01-2016 14:41:57.895 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.85ms 08-01-2016 14:42:18.025 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.58ms 08-01-2016 14:42:38.171 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.55ms 08-01-2016 14:42:58.329 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.93ms 08-01-2016 14:43:18.919 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.95ms 08-01-2016 14:43:39.005 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.06ms 08-01-2016 14:43:59.164 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.65ms 08-01-2016 14:44:09.641 UTC DEBUG static.py:80: Checking if is allowed 08-01-2016 14:44:09.648 UTC DEBUG static.py:99: Redirecting to /login.html 08-01-2016 14:44:09.651 UTC INFO web.py:1447: 302 GET / (0.0.0.0) 10.73ms 08-01-2016 14:44:09.741 UTC DEBUG static.py:80: Checking if css/bootstrap.min.css is allowed 08-01-2016 14:44:09.742 UTC DEBUG static.py:87: Path matches regex /css/.*, allowed 08-01-2016 14:44:09.875 UTC INFO web.py:1447: 200 GET /css/bootstrap.min.css (0.0.0.0) 134.80ms 08-01-2016 14:44:09.878 UTC DEBUG static.py:80: Checking if css/fileuploader.css is allowed 08-01-2016 14:44:09.881 UTC DEBUG static.py:87: Path matches regex /css/.*, allowed 08-01-2016 14:44:09.910 UTC INFO web.py:1447: 200 GET /css/fileuploader.css (0.0.0.0) 33.01ms 08-01-2016 14:44:09.913 UTC DEBUG static.py:80: Checking if css/style.css is allowed 08-01-2016 14:44:09.915 UTC DEBUG static.py:87: Path matches regex /css/.*, allowed 08-01-2016 14:44:09.931 UTC INFO web.py:1447: 200 GET /css/style.css (0.0.0.0) 19.06ms 08-01-2016 14:44:09.934 UTC DEBUG static.py:80: Checking if js/fileuploader.js is allowed 08-01-2016 14:44:09.934 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.107 UTC INFO web.py:1447: 200 GET /js/fileuploader.js (0.0.0.0) 174.18ms 08-01-2016 14:44:10.113 UTC DEBUG static.py:80: Checking if js/jquery.ba-bbq.min.js is allowed 08-01-2016 14:44:10.114 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.131 UTC INFO web.py:1447: 200 GET /js/jquery.ba-bbq.min.js (0.0.0.0) 18.30ms 08-01-2016 14:44:10.132 UTC DEBUG static.py:80: Checking if js/jquery.cookie.js is allowed 08-01-2016 14:44:10.133 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.179 UTC INFO web.py:1447: 200 GET /js/jquery.cookie.js (0.0.0.0) 46.53ms 08-01-2016 14:44:10.183 UTC DEBUG static.py:80: Checking if js/common.js is allowed 08-01-2016 14:44:10.191 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:10.241 UTC INFO web.py:1447: 200 GET /js/common.js (0.0.0.0) 61.98ms 08-01-2016 14:44:10.244 UTC DEBUG static.py:80: Checking if img/glyphicons-halflings-white.png is allowed 08-01-2016 14:44:10.245 UTC DEBUG static.py:87: Path matches regex /img/.*, allowed 08-01-2016 14:44:10.284 UTC INFO web.py:1447: 200 GET /img/glyphicons-halflings-white.png (0.0.0.0) 40.09ms 08-01-2016 14:44:29.403 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.89ms 08-01-2016 14:44:37.415 UTC DEBUG static.py:80: Checking if index.html is allowed 08-01-2016 14:44:37.445 UTC INFO web.py:1447: 200 GET /index.html?data=%7B%22username%22%3A%20%22sainath.ellendula%40wipro.com%22%2C%20%22full_name%22%3A%20%22sainath%22%7D&message=Created&status=201&success=true (0.0.0.0) 31.81ms 08-01-2016 14:44:37.489 UTC DEBUG static.py:80: Checking if js/app.js is allowed 08-01-2016 14:44:37.533 UTC INFO web.py:1447: 200 GET /js/app.js (0.0.0.0) 43.94ms 08-01-2016 14:44:39.504 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.18ms 08-01-2016 14:44:41.377 UTC DEBUG static.py:80: Checking if css/addressbook.css is allowed 08-01-2016 14:44:41.378 UTC DEBUG static.py:87: Path matches regex /css/.*, allowed 08-01-2016 14:44:41.389 UTC INFO web.py:1447: 200 GET /css/addressbook.css (0.0.0.0) 12.44ms 08-01-2016 14:44:41.390 UTC DEBUG static.py:80: Checking if js/backbone.js is allowed 08-01-2016 14:44:41.392 UTC DEBUG static.py:87: Path matches regex /js/(jquery|backbone|underscore|fileuploader|bootstrap|login|signup|forgotpassword|resetpassword|common|zxcvbn|zxcvbn-async|pwstrength|validate).*, allowed 08-01-2016 14:44:41.481 UTC INFO web.py:1447: 200 GET /js/backbone.js (0.0.0.0) 91.05ms 08-01-2016 14:44:41.626 UTC DEBUG static.py:80: Checking if js/templates/addressbook-contacts.html is allowed 08-01-2016 14:44:41.660 UTC INFO web.py:1447: 200 GET /js/templates/addressbook-contacts.html (0.0.0.0) 34.18ms 08-01-2016 14:44:43.215 UTC DEBUG static.py:80: Checking if is allowed 08-01-2016 14:44:43.230 UTC INFO web.py:1447: 200 GET / (0.0.0.0) 15.64ms 08-01-2016 14:44:46.938 UTC DEBUG numbers.py:120: Number allocation API call (PSTN = false) 08-01-2016 14:44:46.948 UTC DEBUG numbers.py:125: non-PSTN 08-01-2016 14:44:47.007 UTC DEBUG numbers.py:147: Fetched e792e5b3-3de7-4f75-ad4f-0ce550df7f99 08-01-2016 14:44:47.014 UTC DEBUG numbers.py:129: SIP URI sip:[email protected] 08-01-2016 14:44:47.079 UTC DEBUG numbers.py:159: Populating other servers... 08-01-2016 14:44:47.086 UTC DEBUG numbers.py:170: About to create private ID at Homestead 08-01-2016 14:44:47.087 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:47.689 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:47.725 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.155 UTC DEBUG numbers.py:175: Created private ID at Homestead 08-01-2016 14:44:48.156 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.192 UTC INFO homestead.py:166: {"associated_implicit_registration_sets": ["7e78ff36-d20d-4a4b-b25d-ae00787fa119"]} 08-01-2016 14:44:48.193 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.414 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.596 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.626 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:44:48.627 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/irs/7e78ff36-d20d-4a4b-b25d-ae00787fa119/service_profiles/cba1c218-335f-4113-92c4-1123c98aaa37/filter_criteria 08-01-2016 14:45:08.646 UTC WARNING simple_httpclient.py:325: 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 -2] Name or service not known 08-01-2016 14:45:09.226 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.596318006515503,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6bfd54d10>,effective_url='http://homer.cw-ngv.com:7888/org.etsi.ngn.simservs/users/sip%3A6500584%40cw-ngv.com/simservs.xml',headers={},error=gaierror(-2, 'Name or service not known')) 08-01-2016 14:45:09.227 UTC WARNING numbers.py:195: Failed to update all the backends 08-01-2016 14:45:09.227 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:09.227 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/public/sip%3A6500584%40cw-ngv.com/associated_private_ids 08-01-2016 14:45:09.229 UTC DEBUG utils.py:94: Still expecting 2 callbacks 08-01-2016 14:45:09.230 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/irs/7e78ff36-d20d-4a4b-b25d-ae00787fa119/service_profiles/cba1c218-335f-4113-92c4-1123c98aaa37/filter_criteria with code 599 08-01-2016 14:45:09.230 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.60214900970459,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6bfd5bdd0>,effective_url='http://homestead.pes.com:8889/irs/7e78ff36-d20d-4a4b-b25d-ae00787fa119/service_profiles/cba1c218-335f-4113-92c4-1123c98aaa37/filter_criteria',headers={},error=HTTPError('HTTP 599: Timeout',)) 08-01-2016 14:45:09.231 UTC DEBUG utils.py:94: Still expecting 1 callbacks 08-01-2016 14:45:09.232 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.42880892753601074,buffer=<_io.BytesIO object at 0x7ff6c02d5770>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6bfd54e50>,effective_url='http://homestead.pes.com:8889/private/6500584%40cw-ngv.com/associated_implicit_registration_sets/7e78ff36-d20d-4a4b-b25d-ae00787fa119',headers={'Date': 'Fri, 08 Jan 2016 14:44:16 GMT', 'Content-Length': '0', 'Content-Type': 'text/html; charset=UTF-8', 'Connection': 'close', 'Server': 'nginx/1.4.6 (Ubuntu)'},error=None) 08-01-2016 14:45:09.233 UTC DEBUG utils.py:88: Another response failed, skipping callbacks. 08-01-2016 14:45:09.238 UTC ERROR iostream.py:307: Uncaught exception, closing connection. Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:45:09.247 UTC ERROR ioloop.py:435: Exception in callback <tornado.stack_context._StackContextWrapper object at 0x7ff6bfd58158> Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/ioloop.py", line 421, in _run_callback callback() File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:45:09.263 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/public/sip%3A6500584%40cw-ngv.com/associated_private_ids with code 200 08-01-2016 14:45:09.264 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.03535795211791992,buffer=<_io.BytesIO object at 0x7ff6c0374bf0>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6c034f250>,effective_url='http://homestead.pes.com:8889/public/sip%3A6500584%40cw-ngv.com/associated_private_ids',headers={'Content-Length': '59', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Etag': '"e3aba9dbe866a07ba34bb46b2554355423e5806e"', 'Date': 'Fri, 08 Jan 2016 14:44:37 GMT', 'Content-Type': 'application/json'},error=None) 08-01-2016 14:45:09.264 UTC DEBUG utils.py:92: All requests successful. 08-01-2016 14:45:09.264 UTC DEBUG numbers.py:224: Got related private ids 08-01-2016 14:45:09.265 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:09.265 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/private/6500584%40cw-ngv.com/associated_public_ids 08-01-2016 14:45:09.267 UTC DEBUG utils.py:94: Still expecting 0 callbacks 08-01-2016 14:45:09.309 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/private/6500584%40cw-ngv.com/associated_public_ids with code 200 08-01-2016 14:45:09.309 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.043435096740722656,buffer=<_io.BytesIO object at 0x7ff6c0374b90>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7ff6c01f8390>,effective_url='http://homestead.pes.com:8889/private/6500584%40cw-ngv.com/associated_public_ids',headers={'Content-Length': '73', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Etag': '"d699b5565e4db03a90712b49c2d315e72a8ad401"', 'Date': 'Fri, 08 Jan 2016 14:44:37 GMT', 'Content-Type': 'application/json'},error=None) 08-01-2016 14:45:09.310 UTC DEBUG utils.py:92: All requests successful. 08-01-2016 14:45:09.310 UTC DEBUG numbers.py:234: Got related public ids 08-01-2016 14:45:09.311 UTC INFO numbers.py:282: Returning number to pool before attempting deletion from Homestead and Homer 08-01-2016 14:45:09.311 UTC DEBUG numbers.py:88: Removing owner of sip:[email protected] 08-01-2016 14:45:09.762 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:09.781 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:10.176 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:10.177 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/private/6500584%40cw-ngv.com 08-01-2016 14:45:41.311 UTC INFO main.py:104: Process 1 starting up 08-01-2016 14:45:42.747 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:45:43.423 UTC INFO homestead.py:62: Pinged Homestead OK 08-01-2016 14:46:02.942 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 2.58ms 08-01-2016 14:46:15.256 UTC DEBUG numbers.py:120: Number allocation API call (PSTN = false) 08-01-2016 14:46:15.373 UTC DEBUG numbers.py:125: non-PSTN 08-01-2016 14:46:15.402 UTC DEBUG numbers.py:147: Fetched 707134aa-c92a-4564-8d0e-615743513b56 08-01-2016 14:46:15.404 UTC DEBUG numbers.py:129: SIP URI sip:[email protected] 08-01-2016 14:46:15.512 UTC DEBUG numbers.py:159: Populating other servers... 08-01-2016 14:46:15.513 UTC DEBUG numbers.py:170: About to create private ID at Homestead 08-01-2016 14:46:15.513 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:15.560 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:15.588 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:15.681 UTC DEBUG numbers.py:175: Created private ID at Homestead 08-01-2016 14:46:15.681 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:15.703 UTC INFO homestead.py:166: {"associated_implicit_registration_sets": ["6860ec63-4adc-4cf9-82da-61d025b0e56a"]} 08-01-2016 14:46:15.704 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:15.830 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:15.974 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:16.001 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:16.002 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/irs/6860ec63-4adc-4cf9-82da-61d025b0e56a/service_profiles/0f716dd2-833a-4208-b1e8-722f10c86b26/filter_criteria 08-01-2016 14:46:36.050 UTC WARNING simple_httpclient.py:325: 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 -2] Name or service not known 08-01-2016 14:46:36.093 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.084568977355957,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f3110d0d0>,effective_url='http://homer.cw-ngv.com:7888/org.etsi.ngn.simservs/users/sip%3A6500854%40cw-ngv.com/simservs.xml',headers={},error=gaierror(-2, 'Name or service not known')) 08-01-2016 14:46:36.093 UTC WARNING numbers.py:195: Failed to update all the backends 08-01-2016 14:46:36.094 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:36.094 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/public/sip%3A6500854%40cw-ngv.com/associated_private_ids 08-01-2016 14:46:36.100 UTC DEBUG utils.py:94: Still expecting 2 callbacks 08-01-2016 14:46:36.101 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/irs/6860ec63-4adc-4cf9-82da-61d025b0e56a/service_profiles/0f716dd2-833a-4208-b1e8-722f10c86b26/filter_criteria with code 599 08-01-2016 14:46:36.101 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.098412036895752,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f310f3f50>,effective_url='http://homestead.pes.com:8889/irs/6860ec63-4adc-4cf9-82da-61d025b0e56a/service_profiles/0f716dd2-833a-4208-b1e8-722f10c86b26/filter_criteria',headers={},error=HTTPError('HTTP 599: Timeout',)) 08-01-2016 14:46:36.101 UTC DEBUG utils.py:94: Still expecting 1 callbacks 08-01-2016 14:46:36.106 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.09125804901123047,buffer=<_io.BytesIO object at 0x7f5f310e1650>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f30b6e4d0>,effective_url='http://homestead.pes.com:8889/private/6500854%40cw-ngv.com/associated_implicit_registration_sets/6860ec63-4adc-4cf9-82da-61d025b0e56a',headers={'Date': 'Fri, 08 Jan 2016 14:45:43 GMT', 'Content-Length': '0', 'Content-Type': 'text/html; charset=UTF-8', 'Connection': 'close', 'Server': 'nginx/1.4.6 (Ubuntu)'},error=None) 08-01-2016 14:46:36.107 UTC DEBUG utils.py:88: Another response failed, skipping callbacks. 08-01-2016 14:46:36.107 UTC ERROR iostream.py:307: Uncaught exception, closing connection. Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:46:36.111 UTC ERROR ioloop.py:435: Exception in callback <tornado.stack_context._StackContextWrapper object at 0x7f5f30b4cc00> Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/ioloop.py", line 421, in _run_callback callback() File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:46:36.142 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/public/sip%3A6500854%40cw-ngv.com/associated_private_ids with code 200 08-01-2016 14:46:36.143 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.04773092269897461,buffer=<_io.BytesIO object at 0x7f5f31160650>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f30b77090>,effective_url='http://homestead.pes.com:8889/public/sip%3A6500854%40cw-ngv.com/associated_private_ids',headers={'Content-Length': '59', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Etag': '"a9d5ddd3d07475c8fa77f189a5f1ccf27e34c362"', 'Date': 'Fri, 08 Jan 2016 14:46:04 GMT', 'Content-Type': 'application/json'},error=None) 08-01-2016 14:46:36.143 UTC DEBUG utils.py:92: All requests successful. 08-01-2016 14:46:36.144 UTC DEBUG numbers.py:224: Got related private ids 08-01-2016 14:46:36.152 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:36.153 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/private/6500854%40cw-ngv.com/associated_public_ids 08-01-2016 14:46:36.161 UTC DEBUG utils.py:94: Still expecting 0 callbacks 08-01-2016 14:46:36.186 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/private/6500854%40cw-ngv.com/associated_public_ids with code 200 08-01-2016 14:46:36.186 UTC DEBUG utils.py:84: OK HTTP response. HTTPResponse(code=200,request_time=0.03226804733276367,buffer=<_io.BytesIO object at 0x7f5f311605f0>,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f311661d0>,effective_url='http://homestead.pes.com:8889/private/6500854%40cw-ngv.com/associated_public_ids',headers={'Content-Length': '73', 'Content-Encoding': 'gzip', 'Server': 'nginx/1.4.6 (Ubuntu)', 'Connection': 'close', 'Etag': '"5e1dacdc386822218420a22f0a6ca13cfdd59f16"', 'Date': 'Fri, 08 Jan 2016 14:46:04 GMT', 'Content-Type': 'application/json'},error=None) 08-01-2016 14:46:36.186 UTC DEBUG utils.py:92: All requests successful. 08-01-2016 14:46:36.187 UTC DEBUG numbers.py:234: Got related public ids 08-01-2016 14:46:36.187 UTC INFO numbers.py:282: Returning number to pool before attempting deletion from Homestead and Homer 08-01-2016 14:46:36.191 UTC DEBUG numbers.py:88: Removing owner of sip:[email protected] 08-01-2016 14:46:36.472 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:36.487 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:36.731 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:46:36.731 UTC DEBUG homestead.py:271: Sending HTTP request to http://homestead.pes.com:8889/private/6500854%40cw-ngv.com 08-01-2016 14:46:56.763 UTC WARNING simple_httpclient.py:325: 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 -2] Name or service not known 08-01-2016 14:46:56.764 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.027386903762817,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f31166710>,effective_url='http://homer.cw-ngv.com:7888/org.etsi.ngn.simservs/users/sip%3A6500854%40cw-ngv.com/simservs.xml',headers={},error=gaierror(-2, 'Name or service not known')) 08-01-2016 14:46:56.764 UTC WARNING numbers.py:211: Failed to back out changes after failure 08-01-2016 14:46:56.767 UTC ERROR web.py:1447: 502 POST /accounts/sainath.ellendula%40wipro.com/numbers/?cb=1aee77678b-1 (0.0.0.0) 41517.95ms 08-01-2016 14:46:56.767 UTC DEBUG utils.py:94: Still expecting 1 callbacks 08-01-2016 14:46:56.768 UTC DEBUG utils.py:94: Still expecting 0 callbacks 08-01-2016 14:46:56.768 UTC DEBUG homestead.py:275: Received response from http://homestead.pes.com:8889/private/6500854%40cw-ngv.com with code 599 08-01-2016 14:46:56.769 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=20.036606073379517,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f5f310f3a10>,effective_url='http://homestead.pes.com:8889/private/6500854%40cw-ngv.com',headers={},error=HTTPError('HTTP 599: Timeout',)) 08-01-2016 14:46:56.769 UTC DEBUG utils.py:94: Still expecting 0 callbacks 08-01-2016 14:46:56.774 UTC ERROR iostream.py:307: Uncaught exception, closing connection. Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:46:56.774 UTC ERROR ioloop.py:435: Exception in callback <tornado.stack_context._StackContextWrapper object at 0x7f5f30b4cd60> Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/ioloop.py", line 421, in _run_callback callback() File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:46:56.775 UTC ERROR iostream.py:307: Uncaught exception, closing connection. Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:46:56.776 UTC ERROR ioloop.py:435: Exception in callback <tornado.stack_context._StackContextWrapper object at 0x7f5f31164310> Traceback (most recent call last): File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/ioloop.py", line 421, in _run_callback callback() File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/iostream.py", line 304, in wrapper callback(*args) File "/usr/share/clearwater/ellis/env/local/lib/python2.7/site-packages/tornado-2.3-py2.7.egg/tornado/httpserver.py", line 243, in _on_headers remote_ip = self.address[0] IndexError: string index out of range 08-01-2016 14:47:19.289 UTC INFO main.py:104: Process 1 starting up 08-01-2016 14:47:20.258 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 08-01-2016 14:47:20.339 UTC INFO homestead.py:62: Pinged Homestead OK 08-01-2016 14:47:30.361 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 2.89ms 08-01-2016 14:47:31.358 UTC INFO web.py:1447: 200 GET /gab (0.0.0.0) 132.35ms 08-01-2016 14:47:50.492 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 2.05ms 08-01-2016 14:48:10.798 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.54ms 08-01-2016 14:48:30.911 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.66ms 08-01-2016 14:48:51.055 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.75ms 08-01-2016 14:49:11.354 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.76ms 08-01-2016 14:49:32.915 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 5.47ms 08-01-2016 14:49:51.892 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.52ms 08-01-2016 14:50:12.041 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.65ms 08-01-2016 14:50:32.151 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.52ms 08-01-2016 14:50:52.284 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.73ms 08-01-2016 14:51:13.053 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.92ms 08-01-2016 14:51:33.072 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.00ms 08-01-2016 14:51:53.030 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.57ms 08-01-2016 14:52:13.173 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.68ms 08-01-2016 14:52:33.452 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.53ms 08-01-2016 14:52:43.441 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.59ms 08-01-2016 14:53:03.611 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.75ms
_______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
