Facebook
From ayhanilhan, 8 Months ago, written in Python.
Embed
Download Paste or View Raw
Hits: 241
  1. stestr run  tempest.scenario.test_shelve_instance.TestShelveInstance.test_shelve_volume_backed_instance
  2.  
  3. {0} tempest.scenario.test_shelve_instance.TestShelveInstance.test_shelve_volume_backed_instance [55.185040s] ... FAILED
  4.  
  5. Captured traceback:
  6. ~~~~~~~~~~~~~~~~~~~
  7.     Traceback (most recent call last):
  8.  
  9.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/common/utils/__init__.py", line 70, in wrapper
  10.     return f(*func_args, **func_kwargs)
  11.  
  12.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/scenario/test_shelve_instance.py", line 120, in test_shelve_volume_backed_instance
  13.     self._create_server_then_shelve_and_unshelve(boot_from_volume=True)
  14.  
  15.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/scenario/test_shelve_instance.py", line 101, in _create_server_then_shelve_and_unshelve
  16.     timestamp2 = self.get_timestamp(instance_ip,
  17.  
  18.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/scenario/manager.py", line 1163, in get_timestamp
  19.     timestamp = ssh_client.exec_command('sudo cat %s/timestamp'
  20.  
  21.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/lib/common/utils/linux/remote_client.py", line 31, in wrapper
  22.     return function(self, *args, **kwargs)
  23.  
  24.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/lib/common/utils/linux/remote_client.py", line 115, in exec_command
  25.     return self.ssh_client.exec_command(cmd)
  26.  
  27.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/lib/common/ssh.py", line 238, in exec_command
  28.     raise exceptions.SSHExecCommandFailed(
  29.  
  30.     tempest.lib.exceptions.SSHExecCommandFailed: Command 'set -eu -o pipefail; PATH=$PATH:/sbin:/usr/sbin; sudo cat /tmp/timestamp', exit status: 1, stderr:
  31. cat: /tmp/timestamp: No such file or directory
  32.  
  33. stdout:
  34.  
  35.  
  36.  
  37. Captured pythonlogging:
  38. ~~~~~~~~~~~~~~~~~~~~~~~
  39.     2023-08-18 09:58:32,846 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 POST https://myurl.com:8774/v2.1/os-keypairs 0.207s
  40. 2023-08-18 09:58:33,128 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-groups 0.281s
  41. 2023-08-18 09:58:33,243 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.114s
  42. 2023-08-18 09:58:33,355 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.112s
  43. 2023-08-18 09:58:33,435 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.081s
  44. 2023-08-18 09:58:33,529 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.093s
  45. 2023-08-18 09:58:33,636 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.107s
  46. 2023-08-18 09:58:33,717 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.081s
  47. 2023-08-18 09:58:34,063 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:5000/v3/auth/tokens 0.343s
  48. 2023-08-18 09:58:34,240 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:9696/v2.0/networks 0.177s
  49. 2023-08-18 09:58:34,240 1935344 INFO     [tempest.lib.common.fixed_network] (TestShelveInstance:test_shelve_volume_backed_instance) Found network {'id': '8ab536e2-17ff-41cb-abfe-7acf0c4081f4', 'name': 'tempest-bgp-net', 'tenant_id': 'd14aee1cdd6d44c8b87ee19d3a097323', 'admin_state_up': True, 'mtu': 1450, 'status': 'ACTIVE', 'subnets': ['da75e700-6462-4a1c-90c6-fc102ae77766'], 'shared': True, 'availability_zone_hints': [], 'availability_zones': ['nova'], 'ipv4_address_scope': '51360fb5-a536-47f2-8bc2-e2937f933af9', 'ipv6_address_scope': None, 'router:external': False, 'description': '', 'port_security_enabled': True, 'dns_domain': '', 'tags': [], 'created_at': '2023-08-17T10:27:46Z', 'updated_at': '2023-08-17T10:30:58Z', 'revision_number': 4, 'project_id': 'd14aee1cdd6d44c8b87ee19d3a097323', 'provider:network_type': 'vxlan', 'provider:physical_network': None, 'provider:segmentation_id': 868} available for tenant
  50. 2023-08-18 09:58:34,547 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8776/v3/7f9af036b539495cab471bde8e98b74d/volumes 0.306s
  51. 2023-08-18 09:58:34,602 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8776/v3/7f9af036b539495cab471bde8e98b74d/volumes/7f7241e2-f599-4358-8ee5-66f13513ea42 0.049s
  52. 2023-08-18 09:58:35,645 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8776/v3/7f9af036b539495cab471bde8e98b74d/volumes/7f7241e2-f599-4358-8ee5-66f13513ea42 0.035s
  53. 2023-08-18 09:58:35,651 1935344 INFO     [tempest.common.waiters] volume 7f7241e2-f599-4358-8ee5-66f13513ea42 reached available after waiting for 1.651414 seconds
  54. 2023-08-18 09:58:36,473 1935344 IN
  55.     FO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8774/v2.1/servers 0.822s
  56. 2023-08-18 09:58:36,628 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.153s
  57. 2023-08-18 09:58:37,809 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.173s
  58. 2023-08-18 09:58:37,815 1935344 INFO     [tempest.common.waiters] State transition "BUILD/scheduling" ==> "BUILD/None" after 1 second wait
  59. 2023-08-18 09:58:39,020 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.204s
  60. 2023-08-18 09:58:39,026 1935344 INFO     [tempest.common.waiters] State transition "BUILD/None" ==> "BUILD/block_device_mapping" after 3 second wait
  61. 2023-08-18 09:58:40,222 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.194s
  62. 2023-08-18 09:58:40,228 1935344 INFO     [tempest.common.waiters] State transition "BUILD/block_device_mapping" ==> "BUILD/spawning" after 4 second wait
  63. 2023-08-18 09:58:41,381 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.152s
  64. 2023-08-18 09:58:41,388 1935344 INFO     [tempest.common.waiters] State transition "BUILD/spawning" ==> "ACTIVE/None" after 5 second wait
  65. 2023-08-18 09:58:41,588 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.200s
  66. 2023-08-18 09:58:41,595 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  67. 2023-08-18 09:58:48,705 1935344 WARNING  [tempest.lib.common.ssh] Failed to establish authenticated ssh connection to ubuntu@myinstanceipaddress ([Errno None] Unable to connect to port 22 on myinstanceipaddress). Number attempts: 1. Retry after 2 seconds.
  68. 2023-08-18 09:58:51,209 1935344 WARNING  [tempest.lib.common.ssh] Failed to establish authenticated ssh connection to ubuntu@myinstanceipaddress ([Errno None] Unable to connect to port 22 on myinstanceipaddress). Number attempts: 2. Retry after 3 seconds.
  69. 2023-08-18 09:58:54,731 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  70. 2023-08-18 09:58:54,774 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  71. 2023-08-18 09:58:54,774 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  72. 2023-08-18 09:58:54,775 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  73. 2023-08-18 09:58:54,783 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  74. 2023-08-18 09:58:54,822 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  75. 2023-08-18 09:58:54,822 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  76. 2023-08-18 09:58:56,115 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  77. 2023-08-18 09:58:56,123 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  78. 2023-08-18 09:58:56,167 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  79. 2023-08-18 09:58:56,167 1935344 INFO     [tempest
  80.     .lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  81. 2023-08-18 09:58:56,521 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa/action 0.094s
  82. 2023-08-18 09:58:56,666 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.145s
  83. 2023-08-18 09:58:57,830 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.132s
  84. 2023-08-18 09:58:58,987 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.149s
  85. 2023-08-18 09:59:00,111 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.117s
  86. 2023-08-18 09:59:01,250 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.132s
  87. 2023-08-18 09:59:02,387 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.129s
  88. 2023-08-18 09:59:03,526 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.131s
  89. 2023-08-18 09:59:03,532 1935344 INFO     [tempest.common.waiters] State transition "ACTIVE/shelving" ==> "SHELVED_OFFLOADED/None" after 7 second wait
  90. 2023-08-18 09:59:04,037 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa/action 0.505s
  91. 2023-08-18 09:59:04,291 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.254s
  92. 2023-08-18 09:59:05,406 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.107s
  93. 2023-08-18 09:59:06,553 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.140s
  94. 2023-08-18 09:59:06,559 1935344 INFO     [tempest.common.waiters] State transition "SHELVED_OFFLOADED/unshelving" ==> "SHELVED_OFFLOADED/spawning" after 2 second wait
  95. 2023-08-18 09:59:07,697 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.136s
  96. 2023-08-18 09:59:08,946 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.241s
  97. 2023-08-18 09:59:08,952 1935344 INFO     [tempest.common.waiters] State transition "SHELVED_OFFLOADED/spawning" ==> "ACTIVE/None" after 4 second wait
  98. 2023-08-18 09:59:08,953 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:
  99.    22' as 'ubuntu' with public key authentication
  100. 2023-08-18 09:59:24,308 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  101. 2023-08-18 09:59:24,351 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  102. 2023-08-18 09:59:24,351 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  103. 2023-08-18 09:59:24,351 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  104. 2023-08-18 09:59:24,362 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  105. 2023-08-18 09:59:24,404 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  106. 2023-08-18 09:59:24,404 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  107. 2023-08-18 09:59:25,347 1935344 ERROR    [tempest.lib.common.utils.linux.remote_client] (TestShelveInstance:test_shelve_volume_backed_instance) Executing command on myinstanceipaddress failed. Error: Command 'set -eu -o pipefail; PATH=$PATH:/sbin:/usr/sbin; sudo cat /tmp/timestamp', exit status: 1, stderr:
  108. cat: /tmp/timestamp: No such file or directory
  109.  
  110. stdout:
  111.  
  112. 2023-08-18 09:59:25,455 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 204 DELETE https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.107s
  113. 2023-08-18 09:59:25,583 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.127s
  114. 2023-08-18 09:59:26,664 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.073s
  115. 2023-08-18 09:59:27,707 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 404 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.036s
  116. 2023-08-18 09:59:27,786 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 204 DELETE https://myurl.com:9696/v2.0/security-groups/700544d4-94a6-47b7-a81f-8013b1dfa8f8 0.079s
  117. 2023-08-18 09:59:27,822 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 202 DELETE https://myurl.com:8774/v2.1/os-keypairs/tempest-TestShelveInstance-1571633657 0.036s
  118.  
  119.  
  120. ==============================
  121. Failed 1 tests - output below:
  122. ==============================
  123.  
  124. tempest.scenario.test_shelve_instance.TestShelveInstance.test_shelve_volume_backed_instance[compute,id-c1b6318c-b9da-490b-9c67-9339b627271f,image,network,slow,volume]
  125. ----------------------------------------------------------------------------------------------------------------------------------------------------------------------
  126.  
  127. Captured traceback:
  128. ~~~~~~~~~~~~~~~~~~~
  129.     Traceback (most recent call last):
  130.  
  131.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/common/utils/__init__.py", line 70, in wrapper
  132.     return f(*func_args, **func_kwargs)
  133.  
  134.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/scenario/test_shelve_instance.py", line 120, in test_shelve_volume_backed_instance
  135.     self._create_server_then_shelve_and_unshelve(boot_from_volume=True)
  136.  
  137.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/scenario/test_shelve_instance.py", line 101, in _create_server_then_shelve_and_unshelve
  138.     timestamp2 = self.get_timestamp(instance_ip,
  139.  
  140.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/scenario/manager.py", line 1163, in get_timestamp
  141.     timestamp = ssh_client.exec_command('sudo cat %s/timestamp'
  142.  
  143.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/lib/common/utils/linux/remote_client.py", line 31, in wrapper
  144.     return function(self, *args, **kwargs)
  145.  
  146.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/lib/common/utils/linux/remote_client.py", line 115, in exec_command
  147.     return self.ssh_client.exec_command(cmd)
  148.  
  149.       File "/opt/venv/tempest/lib/python3.8/site-packages/tempest/lib/common/ssh.py", line 238, in exec_command
  150.     raise exceptions.SSHExecCommandFailed(
  151.  
  152.     tempest.lib.exceptions.SSHExecCommandFailed: Command 'set -eu -o pipefail; PATH=$PATH:/sbin:/usr/sbin; sudo cat /tmp/timestamp', exit status: 1, stderr:
  153. cat: /tmp/timestamp: No such file or directory
  154.  
  155. stdout:
  156.  
  157.  
  158.  
  159. Captured pythonlogging:
  160. ~~~~~~~~~~~~~~~~~~~~~~~
  161.     2023-08-18 09:58:32,846 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 POST https://myurl.com:8774/v2.1/os-keypairs 0.207s
  162. 2023-08-18 09:58:33,128 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-groups 0.281s
  163. 2023-08-18 09:58:33,243 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.114s
  164. 2023-08-18 09:58:33,355 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.112s
  165. 2023-08-18 09:58:33,435 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.081s
  166. 2023-08-18 09:58:33,529 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.093s
  167. 2023-08-18 09:58:33,636 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.107s
  168. 2023-08-18 09:58:33,717 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:9696/v2.0/security-group-rules 0.081s
  169. 2023-08-18 09:58:34,063 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 201 POST https://myurl.com:5000/v3/auth/tokens 0.343s
  170. 2023-08-18 09:58:34,240 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:9696/v2.0/networks 0.177s
  171. 2023-08-18 09:58:34,240 1935344 INFO     [tempest.lib.common.fixed_network] (TestShelveInstance:test_shelve_volume_backed_instance) Found network {'id': '8ab536e2-17ff-41cb-abfe-7acf0c4081f4', 'name': 'tempest-bgp-net', 'tenant_id': 'd14aee1cdd6d44c8b87ee19d3a097323', 'admin_state_up': True, 'mtu': 1450, 'status': 'ACTIVE', 'subnets': ['da75e700-6462-4a1c-90c6-fc102ae77766'], 'shared': True, 'availability_zone_hints': [], 'availability_zones': ['nova'], 'ipv4_address_scope': '51360fb5-a536-47f2-8bc2-e2937f933af9', 'ipv6_address_scope': None, 'router:external': False, 'description': '', 'port_security_enabled': True, 'dns_domain': '', 'tags': [], 'created_at': '2023-08-17T10:27:46Z', 'updated_at': '2023-08-17T10:30:58Z', 'revision_number': 4, 'project_id': 'd14aee1cdd6d44c8b87ee19d3a097323', 'provider:network_type': 'vxlan', 'provider:physical_network': None, 'provider:segmentation_id': 868} available for tenant
  172. 2023-08-18 09:58:34,547 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8776/v3/7f9af036b539495cab471bde8e98b74d/volumes 0.306s
  173. 2023-08-18 09:58:34,602 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8776/v3/7f9af036b539495cab471bde8e98b74d/volumes/7f7241e2-f599-4358-8ee5-66f13513ea42 0.049s
  174. 2023-08-18 09:58:35,645 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8776/v3/7f9af036b539495cab471bde8e98b74d/volumes/7f7241e2-f599-4358-8ee5-66f13513ea42 0.035s
  175. 2023-08-18 09:58:35,651 1935344 INFO     [tempest.common.waiters] volume 7f7241e2-f599-4358-8ee5-66f13513ea42 reached available after waiting for 1.651414 seconds
  176. 2023-08-18 09:58:36,473 1935344 IN
  177.     FO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8774/v2.1/servers 0.822s
  178. 2023-08-18 09:58:36,628 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.153s
  179. 2023-08-18 09:58:37,809 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.173s
  180. 2023-08-18 09:58:37,815 1935344 INFO     [tempest.common.waiters] State transition "BUILD/scheduling" ==> "BUILD/None" after 1 second wait
  181. 2023-08-18 09:58:39,020 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.204s
  182. 2023-08-18 09:58:39,026 1935344 INFO     [tempest.common.waiters] State transition "BUILD/None" ==> "BUILD/block_device_mapping" after 3 second wait
  183. 2023-08-18 09:58:40,222 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.194s
  184. 2023-08-18 09:58:40,228 1935344 INFO     [tempest.common.waiters] State transition "BUILD/block_device_mapping" ==> "BUILD/spawning" after 4 second wait
  185. 2023-08-18 09:58:41,381 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.152s
  186. 2023-08-18 09:58:41,388 1935344 INFO     [tempest.common.waiters] State transition "BUILD/spawning" ==> "ACTIVE/None" after 5 second wait
  187. 2023-08-18 09:58:41,588 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.200s
  188. 2023-08-18 09:58:41,595 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  189. 2023-08-18 09:58:48,705 1935344 WARNING  [tempest.lib.common.ssh] Failed to establish authenticated ssh connection to ubuntu@myinstanceipaddress ([Errno None] Unable to connect to port 22 on myinstanceipaddress). Number attempts: 1. Retry after 2 seconds.
  190. 2023-08-18 09:58:51,209 1935344 WARNING  [tempest.lib.common.ssh] Failed to establish authenticated ssh connection to ubuntu@myinstanceipaddress ([Errno None] Unable to connect to port 22 on myinstanceipaddress). Number attempts: 2. Retry after 3 seconds.
  191. 2023-08-18 09:58:54,731 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  192. 2023-08-18 09:58:54,774 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  193. 2023-08-18 09:58:54,774 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  194. 2023-08-18 09:58:54,775 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  195. 2023-08-18 09:58:54,783 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  196. 2023-08-18 09:58:54,822 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  197. 2023-08-18 09:58:54,822 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  198. 2023-08-18 09:58:56,115 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  199. 2023-08-18 09:58:56,123 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  200. 2023-08-18 09:58:56,167 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  201. 2023-08-18 09:58:56,167 1935344 INFO     [tempest
  202.     .lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  203. 2023-08-18 09:58:56,521 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa/action 0.094s
  204. 2023-08-18 09:58:56,666 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.145s
  205. 2023-08-18 09:58:57,830 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.132s
  206. 2023-08-18 09:58:58,987 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.149s
  207. 2023-08-18 09:59:00,111 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.117s
  208. 2023-08-18 09:59:01,250 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.132s
  209. 2023-08-18 09:59:02,387 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.129s
  210. 2023-08-18 09:59:03,526 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.131s
  211. 2023-08-18 09:59:03,532 1935344 INFO     [tempest.common.waiters] State transition "ACTIVE/shelving" ==> "SHELVED_OFFLOADED/None" after 7 second wait
  212. 2023-08-18 09:59:04,037 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 202 POST https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa/action 0.505s
  213. 2023-08-18 09:59:04,291 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.254s
  214. 2023-08-18 09:59:05,406 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.107s
  215. 2023-08-18 09:59:06,553 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.140s
  216. 2023-08-18 09:59:06,559 1935344 INFO     [tempest.common.waiters] State transition "SHELVED_OFFLOADED/unshelving" ==> "SHELVED_OFFLOADED/spawning" after 2 second wait
  217. 2023-08-18 09:59:07,697 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.136s
  218. 2023-08-18 09:59:08,946 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:test_shelve_volume_backed_instance): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.241s
  219. 2023-08-18 09:59:08,952 1935344 INFO     [tempest.common.waiters] State transition "SHELVED_OFFLOADED/spawning" ==> "ACTIVE/None" after 4 second wait
  220. 2023-08-18 09:59:08,953 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:
  221.    22' as 'ubuntu' with public key authentication
  222. 2023-08-18 09:59:24,308 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  223. 2023-08-18 09:59:24,351 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  224. 2023-08-18 09:59:24,351 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  225. 2023-08-18 09:59:24,351 1935344 INFO     [tempest.lib.common.ssh] Creating ssh connection to 'myinstanceipaddress:22' as 'ubuntu' with public key authentication
  226. 2023-08-18 09:59:24,362 1935344 INFO     [paramiko.transport] Connected (version 2.0, client OpenSSH_8.2p1)
  227. 2023-08-18 09:59:24,404 1935344 INFO     [paramiko.transport] Authentication (publickey) successful!
  228. 2023-08-18 09:59:24,404 1935344 INFO     [tempest.lib.common.ssh] ssh connection to ubuntu@myinstanceipaddress successfully created
  229. 2023-08-18 09:59:25,347 1935344 ERROR    [tempest.lib.common.utils.linux.remote_client] (TestShelveInstance:test_shelve_volume_backed_instance) Executing command on myinstanceipaddress failed. Error: Command 'set -eu -o pipefail; PATH=$PATH:/sbin:/usr/sbin; sudo cat /tmp/timestamp', exit status: 1, stderr:
  230. cat: /tmp/timestamp: No such file or directory
  231.  
  232. stdout:
  233.  
  234. 2023-08-18 09:59:25,455 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 204 DELETE https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.107s
  235. 2023-08-18 09:59:25,583 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.127s
  236. 2023-08-18 09:59:26,664 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 200 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.073s
  237. 2023-08-18 09:59:27,707 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 404 GET https://myurl.com:8774/v2.1/servers/b1564dd8-73b5-486a-ad1b-ebc4e9e0c3aa 0.036s
  238. 2023-08-18 09:59:27,786 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 204 DELETE https://myurl.com:9696/v2.0/security-groups/700544d4-94a6-47b7-a81f-8013b1dfa8f8 0.079s
  239. 2023-08-18 09:59:27,822 1935344 INFO     [tempest.lib.common.rest_client] Request (TestShelveInstance:_run_cleanups): 202 DELETE https://myurl.com:8774/v2.1/os-keypairs/tempest-TestShelveInstance-1571633657 0.036s
  240.  
  241.  
  242.  
  243. ======
  244. Totals
  245. ======
  246. Ran: 1 tests in 55.1850 sec.
  247.  - Passed: 0
  248.  - Skipped: 0
  249.  - Expected Fail: 0
  250.  - Unexpected Success: 0
  251.  - Failed: 1
  252. Sum of execute time for each test: 55.1850 sec.
  253.  
  254. ==============
  255. Worker Balance
  256. ==============
  257.  - Worker 0 (1 tests) => 0:00:55.185040
  258.  
  259. No tests were successful during the run
  260.