Comment 3 for bug 1669468

Revision history for this message
Matt Riedemann (mriedem) wrote : Re: tempest.api.compute.servers.test_novnc.NoVNCConsoleTestJSON.test_novnc fails intermittently

http://logs.openstack.org/38/431038/3/check/gate-tempest-dsvm-neutron-multinode-full-ubuntu-xenial-nv/5e1d485/console.html#_2017-02-21_06_42_10_010507

2017-02-21 06:42:10.010507 | tempest.api.compute.servers.test_novnc.NoVNCConsoleTestJSON.test_novnc[id-c640fdff-8ab4-45a4-a5d8-7e6146cbd0dc]
2017-02-21 06:42:10.010542 | ---------------------------------------------------------------------------------------------------------------
2017-02-21 06:42:10.010548 |
2017-02-21 06:42:10.010558 | Captured traceback:
2017-02-21 06:42:10.010569 | ~~~~~~~~~~~~~~~~~~~
2017-02-21 06:42:10.010583 | Traceback (most recent call last):
2017-02-21 06:42:10.010606 | File "tempest/api/compute/servers/test_novnc.py", line 152, in test_novnc
2017-02-21 06:42:10.010621 | self._validate_rfb_negotiation()
2017-02-21 06:42:10.010646 | File "tempest/api/compute/servers/test_novnc.py", line 77, in _validate_rfb_negotiation
2017-02-21 06:42:10.010665 | 'Token must be invalid because the connection '
2017-02-21 06:42:10.010721 | File "/opt/stack/new/tempest/.tox/tempest/local/lib/python2.7/site-packages/unittest2/case.py", line 696, in assertFalse
2017-02-21 06:42:10.010737 | raise self.failureException(msg)
2017-02-21 06:42:10.010762 | AssertionError: True is not false : Token must be invalid because the connection closed.
2017-02-21 06:42:10.010768 |
2017-02-21 06:42:10.010774 |
2017-02-21 06:42:10.010785 | Captured pythonlogging:
2017-02-21 06:42:10.010796 | ~~~~~~~~~~~~~~~~~~~~~~~
2017-02-21 06:42:10.010848 | 2017-02-21 06:07:18,545 16286 INFO [tempest.lib.common.rest_client] Request (NoVNCConsoleTestJSON:test_novnc): 200 POST https://10.27.33.58:8774/v2.1/servers/82d4d4ca-c263-4ac5-85bc-a33488af5ff5/action 0.165s
2017-02-21 06:42:10.010905 | 2017-02-21 06:07:18,545 16286 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
2017-02-21 06:42:10.010925 | Body: {"os-getVNCConsole": {"type": "novnc"}}
2017-02-21 06:42:10.011109 | Response - Headers: {u'content-type': 'application/json', 'content-location': 'https://10.27.33.58:8774/v2.1/servers/82d4d4ca-c263-4ac5-85bc-a33488af5ff5/action', u'date': 'Tue, 21 Feb 2017 06:07:18 GMT', u'x-openstack-nova-api-version': '2.1', 'status': '200', u'content-length': '121', u'server': 'Apache/2.4.18 (Ubuntu)', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-d9681919-5b5e-4477-b38d-2734b660a099'}
2017-02-21 06:42:10.011153 | Body: {"console": {"url": "http://10.27.33.58:6080/vnc_auto.html?token=f8a52df3-8e0d-4d64-8877-07f607f84b74", "type": "novnc"}}

http://logstash.openstack.org/#dashboard/file/logstash.json?query=message%3A%5C%22in%20_validate_rfb_negotiation%5C%22&from=7d

203 hits in 7 days, master and stable/ocata, this mostly shows up in the neutron multinode job which is non-voting.