This job view page is being replaced by Spyglass soon. Check out the new job view.
PRjichenjc: [cinder-csi-plugin]add 'force-create' into tag param to support force…
ResultABORTED
Tests 0 failed / 0 succeeded
Started2022-04-14 09:45
Elapsed32m27s
Revision443d35406650e7d69f480a7a667ef62d19812014
Refs 1832

No Test Failures!


Error lines from build-log.txt

... skipping 310 lines ...
PLAY [all] *********************************************************************

TASK [Gathering Facts] *********************************************************
ok: [35.188.233.22]

TASK [install-golang : Check if go has satisfied requirement] ******************
fatal: [35.188.233.22]: FAILED! => {
    "changed": true,
    "cmd": "/usr/local/go/bin/go version |grep -Eo '([0-9]+\\.)+[0-9]+'",
    "delta": "0:00:00.003201",
    "end": "2022-04-14 09:49:18.185768",
    "rc": 1,
    "start": "2022-04-14 09:49:18.182567"
... skipping 2561 lines ...
Enabling module setenvif.
Enabling module filter.
Enabling module deflate.
Enabling module status.
Enabling module reqtimeout.
Enabling conf charset.
Enabling conf localized-error-pages.

Enabling conf other-vhosts-access-log.
Enabling conf security.
Enabling conf serve-cgi-bin.
Enabling site 000-default.
info: Executing deferred 'a2enconf javascript-common' for package javascript-common
Enabling conf javascript-common.
... skipping 131 lines ...
Using python 3.8 to install setuptools!=24.0.0,!=34.0.0,!=34.0.1,!=34.0.2,!=34.0.3,!=34.1.0,!=34.1.1,!=34.2.0,!=34.3.0,!=34.3.1,!=34.3.2,!=36.2.0,!=48.0.0,!=49.0.0
+ inc/python:pip_install:193               :   sudo -H LC_ALL=en_US.UTF-8 SETUPTOOLS_USE_DISTUTILS=stdlib http_proxy= https_proxy= no_proxy= PIP_FIND_LINKS= SETUPTOOLS_SYS_PATH_TECHNIQUE=rewrite python3.8 -m pip install -c /opt/stack/requirements/upper-constraints.txt --ignore-installed 'setuptools!=24.0.0,!=34.0.0,!=34.0.1,!=34.0.2,!=34.0.3,!=34.1.0,!=34.1.1,!=34.2.0,!=34.3.0,!=34.3.1,!=34.3.2,!=36.2.0,!=48.0.0,!=49.0.0'
Collecting setuptools!=24.0.0,!=34.0.0,!=34.0.1,!=34.0.2,!=34.0.3,!=34.1.0,!=34.1.1,!=34.2.0,!=34.3.0,!=34.3.1,!=34.3.2,!=36.2.0,!=48.0.0,!=49.0.0
  Downloading setuptools-57.4.0-py3-none-any.whl (819 kB)
     ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 819.0/819.0 KB 38.5 MB/s eta 0:00:00
Installing collected packages: setuptools
ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
launchpadlib 1.10.13 requires testresources, which is not installed.
Successfully installed setuptools-57.4.0
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
+ inc/python:pip_install:200               :   result=0
+ inc/python:pip_install:202               :   time_stop pip_install
+ functions-common:time_stop:2316          :   local name
... skipping 137 lines ...
  Building wheel for pyperclip (setup.py): started
  Building wheel for pyperclip (setup.py): finished with status 'done'
  Created wheel for pyperclip: filename=pyperclip-1.8.2-py3-none-any.whl size=11136 sha256=c4d394910869c272a85164add419c512b721f53242e608cd92db58df2eeace68
  Stored in directory: /root/.cache/pip/wheels/7f/1a/65/84ff8c386bec21fca6d220ea1f5498a0367883a78dd5ba6122
Successfully built future PrettyTable pyperclip
Installing collected packages: wcwidth, voluptuous, pyperclip, PrettyTable, extras, six, PyYAML, pyparsing, pbr, future, colorama, autopage, attrs, stevedore, cmd2, cliff, fixtures, testtools, python-subunit, stestr, os-testr
ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
launchpadlib 1.10.13 requires testresources, which is not installed.
Successfully installed PrettyTable-0.7.2 PyYAML-5.4.1 attrs-21.2.0 autopage-0.4.0 cliff-3.9.0 cmd2-2.1.2 colorama-0.4.4 extras-1.0.0 fixtures-3.0.0 future-0.18.2 os-testr-2.0.1 pbr-5.6.0 pyparsing-2.4.7 pyperclip-1.8.2 python-subunit-1.4.0 six-1.16.0 stestr-3.2.0 stevedore-3.4.0 testtools-2.5.0 voluptuous-0.12.1 wcwidth-0.2.5
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
+ inc/python:pip_install:200               :   result=0
+ inc/python:pip_install:202               :   time_stop pip_install
+ functions-common:time_stop:2316          :   local name
... skipping 110 lines ...
Collecting wcwidth===0.2.5
  Downloading wcwidth-0.2.5-py2.py3-none-any.whl (30 kB)
Collecting colorama===0.4.4
  Downloading colorama-0.4.4-py2.py3-none-any.whl (16 kB)
Building wheels for collected packages: prettytable, pyperclip, openstack-requirements
  Building wheel for prettytable (setup.py): started
  Building wheel for prettytable (setup.py): finished with status 'error'
  ERROR: Command errored out with exit status 1:
   command: /opt/stack/requirements/.venv/bin/python3 -u -c 'import sys, setuptools, tokenize; sys.argv[0] = '"'"'/tmp/pip-install-noo1j94s/prettytable/setup.py'"'"'; __file__='"'"'/tmp/pip-install-noo1j94s/prettytable/setup.py'"'"';f=getattr(tokenize, '"'"'open'"'"', open)(__file__);code=f.read().replace('"'"'\r\n'"'"', '"'"'\n'"'"');f.close();exec(compile(code, __file__, '"'"'exec'"'"'))' bdist_wheel -d /tmp/pip-wheel-4ev_u209
       cwd: /tmp/pip-install-noo1j94s/prettytable/
  Complete output (6 lines):
  usage: setup.py [global_opts] cmd1 [cmd1_opts] [cmd2 [cmd2_opts] ...]
     or: setup.py --help [cmd1 cmd2 ...]
     or: setup.py --help-commands
     or: setup.py cmd --help
  
  error: invalid command 'bdist_wheel'
  ----------------------------------------
  ERROR: Failed building wheel for prettytable
  Running setup.py clean for prettytable
  Building wheel for pyperclip (setup.py): started
  Building wheel for pyperclip (setup.py): finished with status 'error'
  ERROR: Command errored out with exit status 1:
   command: /opt/stack/requirements/.venv/bin/python3 -u -c 'import sys, setuptools, tokenize; sys.argv[0] = '"'"'/tmp/pip-install-noo1j94s/pyperclip/setup.py'"'"'; __file__='"'"'/tmp/pip-install-noo1j94s/pyperclip/setup.py'"'"';f=getattr(tokenize, '"'"'open'"'"', open)(__file__);code=f.read().replace('"'"'\r\n'"'"', '"'"'\n'"'"');f.close();exec(compile(code, __file__, '"'"'exec'"'"'))' bdist_wheel -d /tmp/pip-wheel-rb2bw34l
       cwd: /tmp/pip-install-noo1j94s/pyperclip/
  Complete output (6 lines):
  usage: setup.py [global_opts] cmd1 [cmd1_opts] [cmd2 [cmd2_opts] ...]
     or: setup.py --help [cmd1 cmd2 ...]
     or: setup.py --help-commands
     or: setup.py cmd --help
  
  error: invalid command 'bdist_wheel'
  ----------------------------------------
  ERROR: Failed building wheel for pyperclip
  Running setup.py clean for pyperclip
  Building wheel for openstack-requirements (setup.py): started
  Building wheel for openstack-requirements (setup.py): finished with status 'error'
  ERROR: Command errored out with exit status 1:
   command: /opt/stack/requirements/.venv/bin/python3 -u -c 'import sys, setuptools, tokenize; sys.argv[0] = '"'"'/tmp/pip-req-build-c45jpwys/setup.py'"'"'; __file__='"'"'/tmp/pip-req-build-c45jpwys/setup.py'"'"';f=getattr(tokenize, '"'"'open'"'"', open)(__file__);code=f.read().replace('"'"'\r\n'"'"', '"'"'\n'"'"');f.close();exec(compile(code, __file__, '"'"'exec'"'"'))' bdist_wheel -d /tmp/pip-wheel-o2zh8spc
       cwd: /tmp/pip-req-build-c45jpwys/
  Complete output (6 lines):
  usage: setup.py [global_opts] cmd1 [cmd1_opts] [cmd2 [cmd2_opts] ...]
     or: setup.py --help [cmd1 cmd2 ...]
     or: setup.py --help-commands
     or: setup.py cmd --help
  
  error: invalid command 'bdist_wheel'
  ----------------------------------------
  ERROR: Failed building wheel for openstack-requirements
  Running setup.py clean for openstack-requirements
Failed to build prettytable pyperclip openstack-requirements
Installing collected packages: certifi, pbr, attrs, extras, testtools, six, fixtures, wcwidth, prettytable, urllib3, PyYAML, charset-normalizer, idna, requests, stevedore, pyperclip, colorama, cmd2, autopage, pyparsing, cliff, packaging, Parsley, beagle, openstack-requirements
    Running setup.py install for prettytable: started
    Running setup.py install for prettytable: finished with status 'done'
    Running setup.py install for pyperclip: started
    Running setup.py install for pyperclip: finished with status 'done'
    Running setup.py install for openstack-requirements: started
... skipping 642 lines ...
update-alternatives: using /var/lib/mecab/dic/ipadic-utf8 to provide /var/lib/mecab/dic/debian (mecab-dictionary) in auto mode
Setting up libhtml-parser-perl (3.72-5) ...
Setting up libhttp-message-perl (6.22-1) ...
Setting up mysql-server-8.0 (8.0.28-0ubuntu0.20.04.3) ...
update-alternatives: using /etc/mysql/mysql.cnf to provide /etc/mysql/my.cnf (my.cnf) in auto mode
Renaming removed key_buffer and myisam-recover options (if present)
mysqld will log errors to /var/log/mysql/error.log

mysqld is running as pid 28735
Created symlink /etc/systemd/system/multi-user.target.wants/mysql.service → /lib/systemd/system/mysql.service.
Setting up libcgi-pm-perl (4.46-1) ...
Setting up libhtml-template-perl (2.97-1) ...
Setting up mysql-server (8.0.28-0ubuntu0.20.04.3) ...
Setting up libcgi-fast-perl (1:2.15-1) ...
... skipping 110 lines ...
Fetched 59.0 kB in 0s (898 kB/s)
Selecting previously unselected package radvd.
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 91508 files and directories currently installed.)
Preparing to unpack .../radvd_1%3a2.17-2_amd64.deb ...
Unpacking radvd (1:2.17-2) ...
Setting up radvd (1:2.17-2) ...
Job for radvd.service failed because the control process exited with error code.

See "systemctl status radvd.service" and "journalctl -xe" for details.
invoke-rc.d: initscript radvd, action "start" failed.

● radvd.service - Router advertisement daemon for IPv6
     Loaded: loaded (]8;;file://devstack/lib/systemd/system/radvd.service/lib/systemd/system/radvd.service]8;;; disabled; vendor preset: enabled)
     Active: failed (Result: exit-code) since Thu 2022-04-14 09:53:02 UTC; 8ms ago

       Docs: ]8;;man:radvd(8)man:radvd(8)]8;;
    Process: 29881 ExecStartPre=/usr/sbin/radvd --logmethod stderr_clean --configtest (code=exited, status=1/FAILURE)

Apr 14 09:53:02 devstack systemd[1]: Starting Router advertisement daemon for IPv6...
Apr 14 09:53:02 devstack radvd[29881]: can't open /etc/radvd.conf: No such file or directory
Apr 14 09:53:02 devstack radvd[29881]: Insecure file permissions, but continuing anyway
Apr 14 09:53:02 devstack radvd[29881]: exiting, failed to read config file

Apr 14 09:53:02 devstack systemd[1]: radvd.service: Control process exited, code=exited, status=1/FAILURE
Apr 14 09:53:02 devstack systemd[1]: radvd.service: Failed with result 'exit-code'.

Apr 14 09:53:02 devstack systemd[1]: Failed to start Router advertisement daemon for IPv6.

Processing triggers for man-db (2.9.1-1) ...
Processing triggers for systemd (245.4-4ubuntu3.16) ...
+ functions-common:apt_get:1125            :   result=0
+ functions-common:apt_get:1128            :   time_stop apt-get
+ functions-common:time_stop:2316          :   local name
+ functions-common:time_stop:2317          :   local end_time
... skipping 468 lines ...
  Building wheel for etcd3 (setup.py): started
  Building wheel for etcd3 (setup.py): finished with status 'done'
  Created wheel for etcd3: filename=etcd3-0.12.0-py2.py3-none-any.whl size=42886 sha256=ae8c6e0f7629cb45a475225f0a1ed61d165a5b7717a857c1f213e23a0c0c4843
  Stored in directory: /root/.cache/pip/wheels/01/af/36/31f9f42a3b49c1998e9bdaec85a28e4c9a24c0d8a4f81293d7
Successfully built etcd3
Installing collected packages: six, tenacity, protobuf, grpcio, etcd3
ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
launchpadlib 1.10.13 requires testresources, which is not installed.
Successfully installed etcd3-0.12.0 grpcio-1.39.0 protobuf-3.17.3 six-1.16.0 tenacity-6.3.1
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
+ inc/python:pip_install:200               :   result=0
+ inc/python:pip_install:202               :   time_stop pip_install
+ functions-common:time_stop:2316          :   local name
... skipping 34 lines ...
  Downloading certifi-2021.5.30-py2.py3-none-any.whl (145 kB)
     ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 145.5/145.5 KB 36.3 MB/s eta 0:00:00
Collecting idna<4,>=2.5
  Downloading idna-3.2-py3-none-any.whl (59 kB)
     ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 59.6/59.6 KB 18.4 MB/s eta 0:00:00
Installing collected packages: certifi, urllib3, six, pbr, idna, charset-normalizer, requests, futurist, etcd3gw
ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
launchpadlib 1.10.13 requires testresources, which is not installed.
Successfully installed certifi-2021.5.30 charset-normalizer-2.0.4 etcd3gw-1.0.0 futurist-2.4.0 idna-3.2 pbr-5.6.0 requests-2.26.0 six-1.16.0 urllib3-1.26.6
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
+ inc/python:pip_install:200               :   result=0
+ inc/python:pip_install:202               :   time_stop pip_install
+ functions-common:time_stop:2316          :   local name
... skipping 315 lines ...
  Building wheel for wrapt (setup.py): started
  Building wheel for wrapt (setup.py): finished with status 'done'
  Created wheel for wrapt: filename=wrapt-1.12.1-cp38-cp38-linux_x86_64.whl size=78577 sha256=a2046ac190892fbab12cbde38f804c515848c5974556967c487aac812321c475
  Stored in directory: /root/.cache/pip/wheels/5f/fd/9e/b6cf5890494cb8ef0b5eaff72e5d55a70fb56316007d6dfe73
Successfully built pyinotify wrapt
Installing collected packages: wrapt, rfc3986, pytz, pyinotify, netifaces, netaddr, msgpack, iso8601, certifi, WebOb, urllib3, six, PyYAML, pyparsing, pbr, idna, decorator, charset-normalizer, stevedore, requests, python-dateutil, packaging, oslo.i18n, os-service-types, debtcollector, oslo.utils, oslo.context, oslo.config, keystoneauth1, dogpile.cache, oslo.serialization, python-keystoneclient, pycadf, oslo.log, oslo.cache, keystonemiddleware
ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
launchpadlib 1.10.13 requires testresources, which is not installed.
Successfully installed PyYAML-5.4.1 WebOb-1.8.7 certifi-2021.5.30 charset-normalizer-2.0.4 debtcollector-2.3.0 decorator-5.0.9 dogpile.cache-1.1.5 idna-3.2 iso8601-0.1.16 keystoneauth1-4.4.0 keystonemiddleware-9.3.0 msgpack-1.0.2 netaddr-0.8.0 netifaces-0.11.0 os-service-types-1.7.0 oslo.cache-2.8.2 oslo.config-8.7.1 oslo.context-3.3.2 oslo.i18n-5.1.0 oslo.log-4.6.0 oslo.serialization-4.2.0 oslo.utils-4.10.1 packaging-21.0 pbr-5.6.0 pycadf-3.1.1 pyinotify-0.9.6 pyparsing-2.4.7 python-dateutil-2.8.2 python-keystoneclient-4.3.0 pytz-2021.1 requests-2.26.0 rfc3986-1.5.0 six-1.16.0 stevedore-3.4.0 urllib3-1.26.6 wrapt-1.12.1
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
+ inc/python:pip_install:200               :   result=0
+ inc/python:pip_install:202               :   time_stop pip_install
+ functions-common:time_stop:2316          :   local name
... skipping 28 lines ...
+ inc/python:pip_install:193               :   sudo -H LC_ALL=en_US.UTF-8 SETUPTOOLS_USE_DISTUTILS=stdlib http_proxy= https_proxy= no_proxy= PIP_FIND_LINKS= SETUPTOOLS_SYS_PATH_TECHNIQUE=rewrite python3.8 -m pip install -c /opt/stack/requirements/upper-constraints.txt --ignore-installed python-memcached
Collecting python-memcached
  Downloading python_memcached-1.59-py2.py3-none-any.whl (16 kB)
Collecting six>=1.4.0
  Using cached six-1.16.0-py2.py3-none-any.whl (11 kB)
Installing collected packages: six, python-memcached
ERROR: pip's dependency resolver does not currently take into account all the packages that are installed. This behaviour is the source of the following dependency conflicts.
launchpadlib 1.10.13 requires testresources, which is not installed.
Successfully installed python-memcached-1.59 six-1.16.0
WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
+ inc/python:pip_install:200               :   result=0
+ inc/python:pip_install:202               :   time_stop pip_install
+ functions-common:time_stop:2316          :   local name
... skipping 445 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/keystone/keystone.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/keystone/keystone.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/keystone/keystone.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/keystone/keystone.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/keystone:configure_keystone:257      :   iniset /etc/keystone/keystone.conf DEFAULT debug True
+ lib/keystone:configure_keystone:259      :   '[' uwsgi == mod_wsgi ']'
+ lib/keystone:configure_keystone:263      :   write_uwsgi_config /etc/keystone/keystone-uwsgi-public.ini /usr/local/bin/keystone-wsgi-public /identity
+ lib/apache:write_uwsgi_config:254        :   local file=/etc/keystone/keystone-uwsgi-public.ini
+ lib/apache:write_uwsgi_config:255        :   local wsgi=/usr/local/bin/keystone-wsgi-public
+ lib/apache:write_uwsgi_config:256        :   local url=/identity
... skipping 775 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/glance/glance-api.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/glance/glance-api.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/glance/glance-api.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/glance/glance-api.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/glance:configure_glance:397          :   cp -p /opt/stack/glance/etc/glance-api-paste.ini /etc/glance/glance-api-paste.ini
+ lib/glance:configure_glance:400          :   iniset /etc/glance/glance-cache.conf DEFAULT debug True
+ lib/glance:configure_glance:401          :   iniset /etc/glance/glance-cache.conf DEFAULT use_syslog False
+ lib/glance:configure_glance:402          :   iniset /etc/glance/glance-cache.conf DEFAULT image_cache_dir /opt/stack/data/glance/cache/
+ lib/glance:configure_glance:403          :   iniset /etc/glance/glance-cache.conf DEFAULT auth_url http://10.0.2.15/identity
+ lib/glance:configure_glance:404          :   iniset /etc/glance/glance-cache.conf DEFAULT admin_tenant_name service
... skipping 825 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/cinder/cinder.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/cinder/cinder.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/cinder/cinder.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/cinder/cinder.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/cinder:configure_cinder:351          :   write_uwsgi_config /etc/cinder/cinder-api-uwsgi.ini /usr/local/bin/cinder-wsgi /volume
+ lib/apache:write_uwsgi_config:254        :   local file=/etc/cinder/cinder-api-uwsgi.ini
+ lib/apache:write_uwsgi_config:255        :   local wsgi=/usr/local/bin/cinder-wsgi
+ lib/apache:write_uwsgi_config:256        :   local url=/volume
+ lib/apache:write_uwsgi_config:257        :   local http=
+ lib/apache:write_uwsgi_config:258        :   local name=
... skipping 2035 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/nova/nova.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/nova/nova.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/nova/nova.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/nova/nova.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/nova:create_nova_conf:496            :   iniset /etc/nova/nova.conf upgrade_levels compute auto
+ lib/nova:create_nova_conf:498            :   write_uwsgi_config /etc/nova/nova-api-uwsgi.ini /usr/local/bin/nova-api-wsgi /compute
+ lib/apache:write_uwsgi_config:254        :   local file=/etc/nova/nova-api-uwsgi.ini
+ lib/apache:write_uwsgi_config:255        :   local wsgi=/usr/local/bin/nova-api-wsgi
+ lib/apache:write_uwsgi_config:256        :   local url=/compute
+ lib/apache:write_uwsgi_config:257        :   local http=
... skipping 194 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/nova/nova_cell1.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/nova/nova_cell1.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/nova/nova_cell1.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/nova/nova_cell1.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/nova:create_nova_conf:562            :   [[ superconductor == \s\i\n\g\l\e\c\o\n\d\u\c\t\o\r ]]
++ lib/nova:create_nova_conf:565            :   seq 1 1
+ lib/nova:create_nova_conf:565            :   for i in $(seq 1 $NOVA_NUM_CELLS)
+ lib/nova:create_nova_conf:566            :   local conf
+ lib/nova:create_nova_conf:567            :   local offset
++ lib/nova:create_nova_conf:568            :   conductor_conf 1
... skipping 649 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/placement/placement.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/placement/placement.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/placement/placement.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/placement/placement.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/placement:configure_placement:109    :   [[ uwsgi == \u\w\s\g\i ]]
+ lib/placement:configure_placement:110    :   write_uwsgi_config /etc/placement/placement-uwsgi.ini /usr/local/bin/placement-api /placement
+ lib/apache:write_uwsgi_config:254        :   local file=/etc/placement/placement-uwsgi.ini
+ lib/apache:write_uwsgi_config:255        :   local wsgi=/usr/local/bin/placement-api
+ lib/apache:write_uwsgi_config:256        :   local url=/placement
+ lib/apache:write_uwsgi_config:257        :   local http=
... skipping 318 lines ...
+ functions-common:is_opensuse:469         :   [[ -z Ubuntu ]]
+ functions-common:is_opensuse:473         :   [[ Ubuntu =~ (openSUSE) ]]
+ functions-common:is_suse:462             :   is_suse_linux_enterprise
+ functions-common:is_suse_linux_enterprise:481 :   [[ -z Ubuntu ]]
+ functions-common:is_suse_linux_enterprise:485 :   [[ Ubuntu =~ (^SUSE) ]]
+ lib/databases/mysql:configure_database_mysql:95 :   sudo mysqladmin -u root password password
mysqladmin: connect to server at 'localhost' failed
error: 'Access denied for user 'root'@'localhost' (using password: NO)'
+ lib/databases/mysql:configure_database_mysql:95 :   true
+ lib/databases/mysql:configure_database_mysql:99 :   is_ubuntu
+ functions-common:is_ubuntu:493           :   [[ -z deb ]]
+ functions-common:is_ubuntu:496           :   '[' deb = deb ']'
+ lib/databases/mysql:configure_database_mysql:99 :   '[' mysql == mariadb ']'
+ lib/databases/mysql:configure_database_mysql:102 :   local 'cmd_args=-uroot -ppassword -h127.0.0.1 '
... skipping 5842 lines ...
++ functions-common:get_or_create_user:835  :   local email=
+++ functions-common:get_or_create_user:849  :   oscwrap user create cinder --password '' --domain= --or-show -f value -c id
/usr/lib/python3/dist-packages/secretstorage/dhcrypto.py:15: CryptographyDeprecationWarning: int_from_bytes is deprecated, use int.from_bytes instead
  from cryptography.utils import int_from_bytes
/usr/lib/python3/dist-packages/secretstorage/util.py:19: CryptographyDeprecationWarning: int_from_bytes is deprecated, use int.from_bytes instead
  from cryptography.utils import int_from_bytes
No password was supplied, authentication will fail when a user does not have a password.
+++ functions-common:oscwrap:2356            :   return 0
++ functions-common:get_or_create_user:847  :   user_id=367c3c3383bd413f953a9795c3538987
++ functions-common:get_or_create_user:848  :   echo 367c3c3383bd413f953a9795c3538987
+ lib/cinder:configure_cinder_internal_tenant:609 :   iniset /etc/cinder/cinder.conf DEFAULT cinder_internal_tenant_user_id 367c3c3383bd413f953a9795c3538987
[8829 Async create_cinder_accounts:58012]: finished create_cinder_accounts with result 0 in 16 seconds
+ inc/async:async_wait:197                 :   return 0
... skipping 228 lines ...
+ functions:setup_systemd_logging:695      :   local pidstr=
+ functions:setup_systemd_logging:696      :   [[ False == \T\r\u\e ]]
+ functions:setup_systemd_logging:700      :   pidstr='(pid=%(process)d) '
+ functions:setup_systemd_logging:702      :   iniset /etc/neutron/neutron.conf DEFAULT logging_debug_format_suffix '{{(pid=%(process)d) %(funcName)s %(pathname)s:%(lineno)d}}'
+ functions:setup_systemd_logging:704      :   iniset /etc/neutron/neutron.conf DEFAULT logging_context_format_string '%(color)s%(levelname)s %(name)s [%(global_request_id)s %(request_id)s %(project_name)s %(user_name)s%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:705      :   iniset /etc/neutron/neutron.conf DEFAULT logging_default_format_string '%(color)s%(levelname)s %(name)s [-%(color)s] %(instance)s%(color)s%(message)s'
+ functions:setup_systemd_logging:706      :   iniset /etc/neutron/neutron.conf DEFAULT logging_exception_prefix 'ERROR %(name)s %(instance)s'
+ lib/neutron-legacy:_configure_neutron_common:797 :   is_service_enabled tls-proxy
+ functions-common:is_service_enabled:1972 :   return 1
+ lib/neutron-legacy:_configure_neutron_common:803 :   _neutron_setup_rootwrap
+ lib/neutron-legacy:_neutron_setup_rootwrap:943 :   [[ True == \F\a\l\s\e ]]
+ lib/neutron-legacy:_neutron_setup_rootwrap:947 :   Q_CONF_ROOTWRAP_D=/etc/neutron/rootwrap.d
+ lib/neutron-legacy:_neutron_setup_rootwrap:948 :   [[ -d /etc/neutron/rootwrap.d ]]
... skipping 163 lines ...
+ lib/neutron_plugins/openvswitch_agent:neutron_plugin_configure_plugin_agent:36 :   _neutron_ovs_base_setup_bridge br-int
+ lib/neutron_plugins/ovs_base:_neutron_ovs_base_setup_bridge:32 :   local bridge=br-int
+ lib/neutron_plugins/ovs_base:_neutron_ovs_base_setup_bridge:33 :   neutron-ovs-cleanup --config-file /etc/neutron/neutron.conf
INFO neutron.common.config [-] Logging enabled!
INFO neutron.common.config [-] /usr/local/bin/neutron-ovs-cleanup version 19.1.1.dev116
DEBUG neutron.common.config [-] command line: /usr/local/bin/neutron-ovs-cleanup --config-file /etc/neutron/neutron.conf {{(pid=62647) setup_logging /opt/stack/neutron/neutron/common/config.py:112}}
ERROR ovsdbapp.backend.ovs_idl.idlutils [-] Unable to open stream to tcp:127.0.0.1:6640 to retrieve schema: Connection refused
INFO oslo.privsep.daemon [-] Running privsep helper: ['sudo', '/usr/local/bin/neutron-rootwrap', '/etc/neutron/rootwrap.conf', 'privsep-helper', '--config-file', '/etc/neutron/neutron.conf', '--privsep_context', 'neutron.privileged.ovs_vsctl_cmd', '--privsep_sock_path', '/tmp/tmpukfrdz72/privsep.sock']
INFO oslo.privsep.daemon [-] Spawned new privsep daemon via rootwrap
DEBUG oslo.privsep.daemon [-] Accepted privsep connection to /tmp/tmpukfrdz72/privsep.sock {{(pid=62647) __init__ /usr/local/lib/python3.8/dist-packages/oslo_privsep/daemon.py:363}}
INFO oslo.privsep.daemon [-] privsep daemon starting
INFO oslo.privsep.daemon [-] privsep process running with uid/gid: 0/0
INFO oslo.privsep.daemon [-] privsep process running with capabilities (eff/prm/inh): CAP_NET_ADMIN|CAP_SYS_ADMIN/CAP_NET_ADMIN|CAP_SYS_ADMIN/none
... skipping 2995 lines ...
++ lib/neutron_plugins/services/l3:_neutron_get_ext_gw_interface:143 :   sudo ovs-vsctl set Bridge br-ex other_config:disable-in-band=true
++ lib/neutron_plugins/services/l3:_neutron_get_ext_gw_interface:145 :   echo br-ex
+ lib/neutron_plugins/services/l3:_neutron_configure_router_v4:337 :   ext_gw_interface=br-ex
+ lib/neutron_plugins/services/l3:_neutron_configure_router_v4:349 :   [[ br-ex != \n\o\n\e ]]
+ lib/neutron_plugins/services/l3:_neutron_configure_router_v4:350 :   local cidr_len=24
+ lib/neutron_plugins/services/l3:_neutron_configure_router_v4:351 :   local 'testcmd=ip -o link | grep -q br-ex'
+ lib/neutron_plugins/services/l3:_neutron_configure_router_v4:352 :   test_with_retry 'ip -o link | grep -q br-ex' 'br-ex creation failed'
+ functions-common:test_with_retry:2254    :   local 'testcmd=ip -o link | grep -q br-ex'
+ functions-common:test_with_retry:2255    :   local 'failmsg=br-ex creation failed'
+ functions-common:test_with_retry:2256    :   local until=10
+ functions-common:test_with_retry:2257    :   local sleep=0.5
+ functions-common:test_with_retry:2259    :   time_start test_with_retry
+ functions-common:time_start:2302         :   local name=test_with_retry
+ functions-common:time_start:2303         :   local start_time=
+ functions-common:time_start:2304         :   [[ -n '' ]]
... skipping 1907 lines ...
+ functions:wait_for_compute:493           :   [[ 0 != 0 ]]
+ functions:wait_for_compute:497           :   return 0
+ ./stack.sh:main:1434                     :   service_check
+ functions-common:service_check:1609      :   local service
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@rabbit.service
Failed to get unit file state for devstack@rabbit.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@mysql.service
Failed to get unit file state for devstack@mysql.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@key.service
Failed to get unit file state for devstack@key.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@n-api.service
enabled
+ functions-common:service_check:1616      :   sudo systemctl status devstack@n-api.service --no-pager
● devstack@n-api.service - Devstack devstack@n-api.service
     Loaded: loaded (/etc/systemd/system/devstack@n-api.service; enabled; vendor preset: enabled)
... skipping 6 lines ...
             ├─69413 nova-apiuWSGI master
             ├─69414 nova-apiuWSGI worker 1
             └─69415 nova-apiuWSGI worker 2

Apr 14 10:01:16 devstack devstack@n-api.service[69415]: [pid: 69415|app: 0|req: 17/35] 10.0.2.15 () {64 vars in 1322 bytes} [Thu Apr 14 10:01:16 2022] POST /compute/v2.1/flavors/5/os-extra_specs => generated 43 bytes in 15 msecs (HTTP/1.1 200) 9 headers in 358 bytes (1 switches on core 0)
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: DEBUG nova.api.openstack.wsgi [None req-3fd762ca-3849-4242-8e0f-11c8cfc5760b admin admin] Calling method '<bound method ServiceController.index of <nova.api.openstack.compute.services.ServiceController object at 0x7fe0f826af10>>' {{(pid=69414) _process_stack /opt/stack/nova/nova/api/openstack/wsgi.py:513}}
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: /usr/local/lib/python3.8/dist-packages/oslo_policy/policy.py:1075: UserWarning: Policy os_compute_api:os-services:list failed scope check. The token used to make the request was project scoped but the policy requires ['system'] scope. This behavior may change in the future where using the intended scope is required
Apr 14 10:01:23 devstack devstack@n-api.service[69414]:   warnings.warn(msg)
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: DEBUG oslo_concurrency.lockutils [None req-3fd762ca-3849-4242-8e0f-11c8cfc5760b admin admin] Lock "00000000-0000-0000-0000-000000000000" acquired by "nova.context.set_target_cell.<locals>.get_or_set_cached_cell_and_set_connections" :: waited 0.000s {{(pid=69414) inner /usr/local/lib/python3.8/dist-packages/oslo_concurrency/lockutils.py:355}}
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: DEBUG oslo_concurrency.lockutils [None req-3fd762ca-3849-4242-8e0f-11c8cfc5760b admin admin] Lock "00000000-0000-0000-0000-000000000000" released by "nova.context.set_target_cell.<locals>.get_or_set_cached_cell_and_set_connections" :: held 0.000s {{(pid=69414) inner /usr/local/lib/python3.8/dist-packages/oslo_concurrency/lockutils.py:367}}
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: DEBUG oslo_concurrency.lockutils [None req-3fd762ca-3849-4242-8e0f-11c8cfc5760b admin admin] Lock "fdc276e2-0c34-4b48-9272-89c67be92693" acquired by "nova.context.set_target_cell.<locals>.get_or_set_cached_cell_and_set_connections" :: waited 0.000s {{(pid=69414) inner /usr/local/lib/python3.8/dist-packages/oslo_concurrency/lockutils.py:355}}
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: DEBUG oslo_concurrency.lockutils [None req-3fd762ca-3849-4242-8e0f-11c8cfc5760b admin admin] Lock "fdc276e2-0c34-4b48-9272-89c67be92693" released by "nova.context.set_target_cell.<locals>.get_or_set_cached_cell_and_set_connections" :: held 0.000s {{(pid=69414) inner /usr/local/lib/python3.8/dist-packages/oslo_concurrency/lockutils.py:367}}
Apr 14 10:01:23 devstack devstack@n-api.service[69414]: INFO nova.api.openstack.requestlog [None req-3fd762ca-3849-4242-8e0f-11c8cfc5760b admin admin] 10.0.2.15 "GET /compute/v2.1/os-services?host=devstack&binary=nova-compute" status: 200 len: 192 microversion: 2.1 time: 0.045352
... skipping 20 lines ...
Apr 14 10:01:06 devstack nova-compute[76617]: DEBUG oslo_concurrency.lockutils [None req-ca860f80-b5f9-49b8-8f48-34e081ff3ebe None None] Lock "compute_resources" released by "nova.compute.resource_tracker.ResourceTracker._update_available_resource" :: held 0.337s {{(pid=76617) inner /usr/local/lib/python3.8/dist-packages/oslo_concurrency/lockutils.py:367}}
Apr 14 10:01:06 devstack nova-compute[76617]: DEBUG nova.service [None req-ca860f80-b5f9-49b8-8f48-34e081ff3ebe None None] Creating RPC server for service compute {{(pid=76617) start /opt/stack/nova/nova/service.py:182}}
Apr 14 10:01:06 devstack nova-compute[76617]: DEBUG nova.service [None req-ca860f80-b5f9-49b8-8f48-34e081ff3ebe None None] Join ServiceGroup membership for this service compute {{(pid=76617) start /opt/stack/nova/nova/service.py:199}}
Apr 14 10:01:06 devstack nova-compute[76617]: DEBUG nova.servicegroup.drivers.db [None req-ca860f80-b5f9-49b8-8f48-34e081ff3ebe None None] DB_Driver: join new ServiceGroup member devstack to the compute group, service = <Service: host=devstack, binary=nova-compute, manager_class_name=nova.compute.manager.ComputeManager> {{(pid=76617) join /opt/stack/nova/nova/servicegroup/drivers/db.py:44}}
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@n-cond.service
Failed to get unit file state for devstack@n-cond.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@n-sch.service
enabled
+ functions-common:service_check:1616      :   sudo systemctl status devstack@n-sch.service --no-pager
● devstack@n-sch.service - Devstack devstack@n-sch.service
     Loaded: loaded (/etc/systemd/system/devstack@n-sch.service; enabled; vendor preset: enabled)
... skipping 68 lines ...
Apr 14 10:01:06 devstack devstack@placement-api.service[70597]: [pid: 70597|app: 0|req: 4/9] 10.0.2.15 () {60 vars in 3540 bytes} [Thu Apr 14 10:01:06 2022] GET /placement/traits?name=in:COMPUTE_IMAGE_TYPE_QCOW2,HW_CPU_X86_MMX,COMPUTE_NET_VIF_MODEL_SPAPR_VLAN,COMPUTE_STORAGE_BUS_IDE,COMPUTE_SECURITY_UEFI_SECURE_BOOT,COMPUTE_GRAPHICS_MODEL_CIRRUS,COMPUTE_NET_VIF_MODEL_VIRTIO,COMPUTE_TRUSTED_CERTS,COMPUTE_DEVICE_TAGGING,COMPUTE_STORAGE_BUS_SCSI,COMPUTE_VOLUME_MULTI_ATTACH,COMPUTE_IMAGE_TYPE_AKI,COMPUTE_GRAPHICS_MODEL_QXL,HW_CPU_X86_AMD_SVM,COMPUTE_GRAPHICS_MODEL_NONE,COMPUTE_STORAGE_BUS_FDC,COMPUTE_NET_VIF_MODEL_RTL8139,COMPUTE_SOCKET_PCI_NUMA_AFFINITY,COMPUTE_GRAPHICS_MODEL_VMVGA,COMPUTE_VOLUME_ATTACH_WITH_TAG,HW_CPU_HYPERTHREADING,COMPUTE_NET_ATTACH_INTERFACE_WITH_TAG,COMPUTE_NET_VIF_MODEL_E1000E,COMPUTE_RESCUE_BFV,COMPUTE_NET_VIF_MODEL_VMXNET3,COMPUTE_IMAGE_TYPE_ARI,COMPUTE_GRAPHICS_MODEL_VGA,HW_CPU_X86_SSE2,COMPUTE_NET_ATTACH_INTERFACE,COMPUTE_GRAPHICS_MODEL_BOCHS,COMPUTE_IMAGE_TYPE_AMI,COMPUTE_STORAGE_BUS_USB,COMPUTE_IMAGE_TYPE_ISO,COMPUTE_ACCELERATORS,COMPUTE_VOLUME_EXTEND,COMPUTE_STORAGE_BUS_VIRTIO,COMPUTE_NET_VIF_MODEL_NE2K_PCI,COMPUTE_GRAPHICS_MODEL_VIRTIO,HW_CPU_X86_SVM,COMPUTE_NODE,HW_CPU_X86_SSE,COMPUTE_IMAGE_TYPE_RAW,COMPUTE_STORAGE_BUS_SATA,COMPUTE_NET_VIF_MODEL_PCNET,COMPUTE_NET_VIF_MODEL_E1000 => generated 1289 bytes in 9 msecs (HTTP/1.1 200) 6 headers in 225 bytes (1 switches on core 0)
Apr 14 10:01:06 devstack devstack@placement-api.service[70596]: DEBUG placement.requestlog [req-e35a5028-ec8b-4088-b85c-868ca836c20a req-719416c0-4e01-4955-9705-c3a5f83b866a None None] Starting request: 10.0.2.15 "PUT /placement/resource_providers/1192e322-6c82-428b-990a-29dcc60e705b/traits" {{(pid=70596) __call__ /opt/stack/placement/placement/requestlog.py:55}}
Apr 14 10:01:06 devstack devstack@placement-api.service[70596]: INFO placement.requestlog [req-e35a5028-ec8b-4088-b85c-868ca836c20a req-719416c0-4e01-4955-9705-c3a5f83b866a service placement] 10.0.2.15 "PUT /placement/resource_providers/1192e322-6c82-428b-990a-29dcc60e705b/traits" status: 200 len: 1324 microversion: 1.6
Apr 14 10:01:06 devstack devstack@placement-api.service[70596]: [pid: 70596|app: 0|req: 6/10] 10.0.2.15 () {64 vars in 1463 bytes} [Thu Apr 14 10:01:06 2022] PUT /placement/resource_providers/1192e322-6c82-428b-990a-29dcc60e705b/traits => generated 1324 bytes in 43 msecs (HTTP/1.1 200) 6 headers in 225 bytes (1 switches on core 0)
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@placement-client.service
Failed to get unit file state for devstack@placement-client.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@g-api.service
enabled
+ functions-common:service_check:1616      :   sudo systemctl status devstack@g-api.service --no-pager
● devstack@g-api.service - Devstack devstack@g-api.service
     Loaded: loaded (/etc/systemd/system/devstack@g-api.service; enabled; vendor preset: enabled)
... skipping 16 lines ...
Apr 14 10:01:14 devstack devstack@g-api.service[79010]: DEBUG glance_store._drivers.filesystem [None req-85542ffe-b859-403c-b917-bb5669d5a53b admin admin] Wrote 16300544 bytes to /opt/stack/data/glance/images/597eb1bb-00e9-461a-aac7-9945f9891bfa with checksum b874c39491a2377b8490f5f1e89761a4 and multihash 6b813aa46bb90b4da216a4d19376593fa3f4fc7e617f03a92b7fe11e9a3981cbe8f0959dbebe36225e5f53dc4492341a4863cac4ed1ee0909f3fc78ef9c3e869 {{(pid=79010) add /usr/local/lib/python3.8/dist-packages/glance_store/_drivers/filesystem.py:773}}
Apr 14 10:01:14 devstack devstack@g-api.service[79010]: INFO glance.location [None req-85542ffe-b859-403c-b917-bb5669d5a53b admin admin] Image format matched and virtual size computed: 117440512
Apr 14 10:01:14 devstack devstack@g-api.service[79010]: DEBUG glance_store.backend [None req-85542ffe-b859-403c-b917-bb5669d5a53b admin admin] Skipping store.set_acls... not implemented. {{(pid=79010) set_acls /usr/local/lib/python3.8/dist-packages/glance_store/backend.py:509}}
Apr 14 10:01:14 devstack devstack@g-api.service[79010]: [pid: 79010|app: 0|req: 3/4] 127.0.0.1 () {40 vars in 856 bytes} [Thu Apr 14 10:01:13 2022] PUT /v2/images/597eb1bb-00e9-461a-aac7-9945f9891bfa/file => generated 0 bytes in 767 msecs (HTTP/1.1 204) 4 headers in 171 bytes (1 switches on core 0)
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@g-reg.service
Failed to get unit file state for devstack@g-reg.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@cinder.service
Failed to get unit file state for devstack@cinder.service: No such file or directory
+ functions-common:service_check:1610      :   for service in ${ENABLED_SERVICES//,/ }
+ functions-common:service_check:1612      :   sudo systemctl is-enabled devstack@c-api.service
enabled
+ functions-common:service_check:1616      :   sudo systemctl status devstack@c-api.service --no-pager
● devstack@c-api.service - Devstack devstack@c-api.service
     Loaded: loaded (/etc/systemd/system/devstack@c-api.service; enabled; vendor preset: enabled)
... skipping 1630 lines ...