Thanks for the logs! It looks as though Ellis is successfully communicating with Homestead, but failing to communicate with Homer (the XDMS).
Ellis is trying to connect to "homer.verified". Is that server installed and running and, if so, please can you share its "monit status" and /var/log/homer/homer_*.txt? Cheers, Matt From: Clearwater [mailto:[email protected]] On Behalf Of Bud Asterisk Sent: 02 April 2016 19:01 To: [email protected] Subject: [Project Clearwater] new user creation fails [Mic Drop] Can log into my Ellis node fine. When I try to create a new user I get the infamous 'failed to update....' popup error. Here are Ellis logs with debug enabled: 02-04-2016 17:44:06.442 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.468 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.474 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.475 UTC WARNING iostream.py:507: Connect error on fd 17: ECONNREFUSED 02-04-2016 17:44:06.475 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=0.0007290840148925781,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f30dd4b11d0>,effective_url='http://homer.verified:7888/org.etsi.ngn.simservs/users/sip%3A6505550020%40verified/simservs.xml',headers={},error=HTTPError('HTTP 599: Connection closed',)) 02-04-2016 17:44:06.476 UTC WARNING numbers.py:195: Failed to update all the backends 02-04-2016 17:44:06.476 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.484 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.494 UTC INFO numbers.py:282: Returning number to pool before attempting deletion from Homestead and Homer 02-04-2016 17:44:06.498 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.504 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.559 UTC WARNING homestead.py:309: Passing SIP password in the clear over http 02-04-2016 17:44:06.560 UTC WARNING iostream.py:507: Connect error on fd 18: ECONNREFUSED 02-04-2016 17:44:06.562 UTC WARNING utils.py:78: Non-OK HTTP response. HTTPResponse(code=599,request_time=0.0010280609130859375,buffer=None,_body=None,time_info={},request=<tornado.httpclient.HTTPRequest object at 0x7f30dd4b63d0>,effective_url='http://homer.verified:7888/org.etsi.ngn.simservs/users/sip%3A6505550020%40verified/simservs.xml',headers={},error=HTTPError('HTTP 599: Connection closed',)) 02-04-2016 17:44:06.562 UTC WARNING numbers.py:211: Failed to back out changes after failure 02-04-2016 17:44:06.564 UTC ERROR web.py:1447: 502 POST /accounts/john%40doe.com/numbers/?cb=1ca4d84b57W1<http://40doe.com/numbers/?cb=1ca4d84b57W1> (0.0.0.0) 179.05ms 02-04-2016 17:44:17.961 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.31ms 02-04-2016 17:44:38.352 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.36ms 02-04-2016 17:44:58.086 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.51ms 02-04-2016 17:45:18.143 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.42ms 02-04-2016 17:45:38.464 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.52ms 02-04-2016 17:45:58.237 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.34ms 02-04-2016 17:46:18.284 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.45ms 02-04-2016 17:46:38.684 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.35ms 02-04-2016 17:46:58.401 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.34ms 02-04-2016 17:47:18.472 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.35ms 02-04-2016 17:47:38.792 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.33ms 02-04-2016 17:47:58.564 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.42ms 02-04-2016 17:48:18.627 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.36ms 02-04-2016 17:48:39.017 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.34ms 02-04-2016 17:48:58.731 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.33ms 02-04-2016 17:49:18.789 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.37ms 02-04-2016 17:49:39.178 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.50ms 02-04-2016 17:49:58.874 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.34ms 02-04-2016 17:50:18.955 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.62ms 02-04-2016 17:50:39.340 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.42ms 02-04-2016 17:50:59.057 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.47ms 02-04-2016 17:51:19.114 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.50ms 02-04-2016 17:51:39.493 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.41ms 02-04-2016 17:51:59.226 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 1.23ms 02-04-2016 17:52:19.287 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.72ms 02-04-2016 17:52:39.652 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.38ms 02-04-2016 17:52:59.369 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.62ms 02-04-2016 17:53:19.450 UTC INFO web.py:1447: 200 GET /ping (0.0.0.0) 0.35ms here is a subset of homstead provisioning: 02-04-2016 17:55:56.053 UTC INFO base.py:274: Sending 200 response to localhost for GET http://http_homestead_prov/public/sip%3A6505550638%40verified/associated_private_ids 02-04-2016 17:55:56.055 UTC INFO base.py:261: Received request from localhost - GET http://http_homestead_prov/private/6505550638%40verified/associated_public_ids 02-04-2016 17:55:56.069 UTC INFO base.py:274: Sending 200 response to localhost for GET http://http_homestead_prov/private/6505550638%40verified/associated_public_ids 02-04-2016 17:55:56.076 UTC INFO base.py:261: Received request from localhost - GET http://http_homestead_prov/private/6505550638%40verified/associated_implicit_registration_sets 02-04-2016 17:55:56.080 UTC INFO base.py:274: Sending 200 response to localhost for GET http://http_homestead_prov/private/6505550638%40verified/associated_implicit_registration_sets 02-04-2016 17:55:56.082 UTC INFO base.py:261: Received request from localhost - DELETE http://http_homestead_prov/irs/3dadbf97-b8cd-445b-8140-f9f0f486ef51 02-04-2016 17:55:56.101 UTC WARNING models.py:265: Not pushing to cache since IRS doesn't contain a SIP URI 02-04-2016 17:55:56.114 UTC INFO base.py:274: Sending 200 response to localhost for PUT http://http_homestead_prov/irs/3dadbf97-b8cd-445b-8140-f9f0f486ef51/service_profiles/77fee9d3-9298-499e-b3a3-c86797b75afc/filter_criteria 02-04-2016 17:55:56.123 UTC WARNING models.py:265: Not pushing to cache since IRS doesn't contain a SIP URI 02-04-2016 17:55:56.142 UTC WARNING models.py:265: Not pushing to cache since IRS doesn't contain a SIP URI 02-04-2016 17:55:56.156 UTC INFO base.py:274: Sending 200 response to localhost for DELETE http://http_homestead_prov/irs/3dadbf97-b8cd-445b-8140-f9f0f486ef51 02-04-2016 17:55:56.160 UTC INFO base.py:261: Received request from localhost - DELETE http://http_homestead_prov/private/6505550638%40verified 02-04-2016 17:55:56.164 UTC INFO base.py:274: Sending 200 response to localhost for DELETE http://http_homestead_prov/private/6505550638%40verified Any insights as to what else I may have set up wrong would help!
_______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
