2022-07-28T10:20:09,414 Created temporary directory: /tmp/pip-ephem-wheel-cache-c7yizm45
2022-07-28T10:20:09,420 Created temporary directory: /tmp/pip-build-tracker-sush34cz
2022-07-28T10:20:09,420 Initialized build tracking at /tmp/pip-build-tracker-sush34cz
2022-07-28T10:20:09,421 Created build tracker: /tmp/pip-build-tracker-sush34cz
2022-07-28T10:20:09,421 Entered build tracker: /tmp/pip-build-tracker-sush34cz
2022-07-28T10:20:09,422 Created temporary directory: /tmp/pip-wheel-ipo0syy6
2022-07-28T10:20:09,491 Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2022-07-28T10:20:09,500 2 location(s) to search for versions of lsst-ctrl-bps-panda:
2022-07-28T10:20:09,500 * https://pypi.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,500 * https://www.piwheels.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,501 Fetching project page and analyzing links: https://pypi.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,502 Getting page https://pypi.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,506 Found index url https://pypi.org/simple
2022-07-28T10:20:09,622   Found link https://files.pythonhosted.org/packages/7f/2e/94421787e5b2757488e06e528ce01788fdca0e3e9c09b2d3aad245d2b26e/lsst-ctrl-bps-panda-1.0.0a20222901.tar.gz#sha256=dafc6036f49ad9d776acaf26d063c83b6372e04e2c1a1fa3c3b039be6f298642 (from https://pypi.org/simple/lsst-ctrl-bps-panda/), version: 1.0.0a20222901
2022-07-28T10:20:09,623   Skipping link: No binaries permitted for lsst-ctrl-bps-panda: https://files.pythonhosted.org/packages/36/a4/90d2f264116d12639c54ca476b6812ae460b252db6527dc7d6c686d045da/lsst_ctrl_bps_panda-1.0.0a20222901-py3-none-any.whl#sha256=c41635dd09737bf7ac7ff5e1d63a103d838611f6ea8b1eb0c2a2ed973075696e (from https://pypi.org/simple/lsst-ctrl-bps-panda/)
2022-07-28T10:20:09,624   Found link https://files.pythonhosted.org/packages/07/25/3fbf8197ed01ffdfcc531588617aeae99ede9fd8f765179ab908d2d7dc12/lsst-ctrl-bps-panda-1.0.0a20223000.tar.gz#sha256=f397c7c06476c640102e638cec6f66861edd0663fea27b8e5a3d97c56583dc49 (from https://pypi.org/simple/lsst-ctrl-bps-panda/), version: 1.0.0a20223000
2022-07-28T10:20:09,624   Skipping link: No binaries permitted for lsst-ctrl-bps-panda: https://files.pythonhosted.org/packages/07/46/b845af57fc04a4b25e860bdc35e3d79ad9fad65a228dbf3baf8fd3cdebcb/lsst_ctrl_bps_panda-1.0.0a20223000-py3-none-any.whl#sha256=3fcbf21cfc6b9c66aa0931b54cd763f0da53bcb7624e9514c1b62ec4b1e74795 (from https://pypi.org/simple/lsst-ctrl-bps-panda/)
2022-07-28T10:20:09,625   Found link https://files.pythonhosted.org/packages/6b/33/f81263622f273e47a11f7eb3f5e44738f14400d3ae69e1f633791272e429/lsst-ctrl-bps-panda-1.0.0a20223100.tar.gz#sha256=7afe09e72707021a9a4ce72a5001c68ef364e03b9b78bd71e4cc2cc97795dd09 (from https://pypi.org/simple/lsst-ctrl-bps-panda/), version: 1.0.0a20223100
2022-07-28T10:20:09,626   Skipping link: No binaries permitted for lsst-ctrl-bps-panda: https://files.pythonhosted.org/packages/08/22/1c2c61e8cc990ac16156cea183742e0eda321408e60818fcd08e74ab7c02/lsst_ctrl_bps_panda-1.0.0a20223100-py3-none-any.whl#sha256=545d0803e0b6bf9898a24562ea6d934357653313c1e96466461d91be158e1469 (from https://pypi.org/simple/lsst-ctrl-bps-panda/)
2022-07-28T10:20:09,626 Fetching project page and analyzing links: https://www.piwheels.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,627 Getting page https://www.piwheels.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,629 Found index url https://www.piwheels.org/simple
2022-07-28T10:20:09,880   Skipping link: No binaries permitted for lsst-ctrl-bps-panda: https://www.piwheels.org/simple/lsst-ctrl-bps-panda/lsst_ctrl_bps_panda-1.0.0a20223000-py3-none-any.whl#sha256=03b7b5b01971a541484cc6a5557501fdeba4bcf14695c504fff1122f00996741 (from https://www.piwheels.org/simple/lsst-ctrl-bps-panda/)
2022-07-28T10:20:09,881   Skipping link: No binaries permitted for lsst-ctrl-bps-panda: https://www.piwheels.org/simple/lsst-ctrl-bps-panda/lsst_ctrl_bps_panda-1.0.0a20222901-py3-none-any.whl#sha256=9f2ea3faa5f87efdc68d547ad6a8f5f3954623211f785758aeab7f74e72a0bd5 (from https://www.piwheels.org/simple/lsst-ctrl-bps-panda/)
2022-07-28T10:20:09,881 Skipping link: not a file: https://www.piwheels.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,882 Skipping link: not a file: https://pypi.org/simple/lsst-ctrl-bps-panda/
2022-07-28T10:20:09,927 Given no hashes to check 1 links for project 'lsst-ctrl-bps-panda': discarding no candidates
2022-07-28T10:20:09,970 Collecting lsst-ctrl-bps-panda==1.0.0a20223100
2022-07-28T10:20:09,974   Created temporary directory: /tmp/pip-unpack-inxb1llf
2022-07-28T10:20:10,068   Downloading lsst-ctrl-bps-panda-1.0.0a20223100.tar.gz (32 kB)
2022-07-28T10:20:10,232   Added lsst-ctrl-bps-panda==1.0.0a20223100 from https://files.pythonhosted.org/packages/6b/33/f81263622f273e47a11f7eb3f5e44738f14400d3ae69e1f633791272e429/lsst-ctrl-bps-panda-1.0.0a20223100.tar.gz#sha256=7afe09e72707021a9a4ce72a5001c68ef364e03b9b78bd71e4cc2cc97795dd09 to build tracker '/tmp/pip-build-tracker-sush34cz'
2022-07-28T10:20:10,251   Created temporary directory: /tmp/pip-build-env-fc79i0cs
2022-07-28T10:20:10,272   Created temporary directory: /tmp/pip-standalone-pip-3pwrj1rs
2022-07-28T10:20:12,554   Installing build dependencies: started
2022-07-28T10:20:12,556   Running command pip subprocess to install build dependencies
2022-07-28T10:20:23,837   Using pip 22.1.2 from /tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip (python 3.9)
2022-07-28T10:20:25,506   Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2022-07-28T10:20:29,691   Collecting setuptools
2022-07-28T10:20:29,971     Using cached https://www.piwheels.org/simple/setuptools/setuptools-63.2.0-py3-none-any.whl (1.2 MB)
2022-07-28T10:20:30,361   Collecting lsst-versions>=1.3.0
2022-07-28T10:20:30,420     Using cached https://www.piwheels.org/simple/lsst-versions/lsst_versions-1.3.0-py3-none-any.whl (10 kB)
2022-07-28T10:20:31,173   Collecting GitPython>=3.1
2022-07-28T10:20:31,219     Using cached https://www.piwheels.org/simple/gitpython/GitPython-3.1.27-py3-none-any.whl (181 kB)
2022-07-28T10:20:31,508   Collecting tomli>1.0.0
2022-07-28T10:20:31,537     Using cached https://www.piwheels.org/simple/tomli/tomli-2.0.1-py3-none-any.whl (12 kB)
2022-07-28T10:20:31,963   Collecting packaging
2022-07-28T10:20:31,999     Using cached https://www.piwheels.org/simple/packaging/packaging-21.3-py3-none-any.whl (40 kB)
2022-07-28T10:20:33,152   Collecting gitdb<5,>=4.0.1
2022-07-28T10:20:33,217     Using cached https://www.piwheels.org/simple/gitdb/gitdb-4.0.9-py3-none-any.whl (63 kB)
2022-07-28T10:20:33,920   Collecting pyparsing!=3.0.5,>=2.0.2
2022-07-28T10:20:33,961     Using cached https://www.piwheels.org/simple/pyparsing/pyparsing-3.0.9-py3-none-any.whl (98 kB)
2022-07-28T10:20:34,245   Collecting smmap<6,>=3.0.1
2022-07-28T10:20:34,319     Using cached https://www.piwheels.org/simple/smmap/smmap-5.0.0-py3-none-any.whl (24 kB)
2022-07-28T10:20:41,743   Installing collected packages: tomli, smmap, setuptools, pyparsing, packaging, gitdb, GitPython, lsst-versions
2022-07-28T10:20:49,525     Creating /tmp/pip-build-env-fc79i0cs/overlay/bin
2022-07-28T10:20:49,529     changing mode of /tmp/pip-build-env-fc79i0cs/overlay/bin/lsst-version to 755
2022-07-28T10:20:49,579   Successfully installed GitPython-3.1.27 gitdb-4.0.9 lsst-versions-1.3.0 packaging-21.3 pyparsing-3.0.9 setuptools-63.2.0 smmap-5.0.0 tomli-2.0.1
2022-07-28T10:20:49,776   --- Logging error ---
2022-07-28T10:20:49,792   Traceback (most recent call last):
2022-07-28T10:20:49,792     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/utils/logging.py", line 177, in emit
2022-07-28T10:20:49,793       self.console.print(renderable, overflow="ignore", crop=False, style=style)
2022-07-28T10:20:49,793     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_vendor/rich/console.py", line 1752, in print
2022-07-28T10:20:49,794       extend(render(renderable, render_options))
2022-07-28T10:20:49,794     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_vendor/rich/console.py", line 1390, in render
2022-07-28T10:20:49,795       for render_output in iter_render:
2022-07-28T10:20:49,795     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/utils/logging.py", line 134, in __rich_console__
2022-07-28T10:20:49,795       for line in lines:
2022-07-28T10:20:49,796     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_vendor/rich/segment.py", line 245, in split_lines
2022-07-28T10:20:49,796       for segment in segments:
2022-07-28T10:20:49,796     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_vendor/rich/console.py", line 1368, in render
2022-07-28T10:20:49,796       renderable = rich_cast(renderable)
2022-07-28T10:20:49,797     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_vendor/rich/protocol.py", line 36, in rich_cast
2022-07-28T10:20:49,797       renderable = cast_method()
2022-07-28T10:20:49,798     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 130, in __rich__
2022-07-28T10:20:49,798       pip_cmd = get_best_invocation_for_this_pip()
2022-07-28T10:20:49,798     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/utils/entrypoints.py", line 58, in get_best_invocation_for_this_pip
2022-07-28T10:20:49,799       if found_executable and os.path.samefile(
2022-07-28T10:20:49,799     File "/usr/lib/python3.9/genericpath.py", line 101, in samefile
2022-07-28T10:20:49,799       s2 = os.stat(f2)
2022-07-28T10:20:49,800   FileNotFoundError: [Errno 2] No such file or directory: '/usr/bin/pip3.9'
2022-07-28T10:20:49,800   Call stack:
2022-07-28T10:20:49,803     File "/usr/lib/python3.9/runpy.py", line 197, in _run_module_as_main
2022-07-28T10:20:49,803       return _run_code(code, main_globals, None,
2022-07-28T10:20:49,804     File "/usr/lib/python3.9/runpy.py", line 87, in _run_code
2022-07-28T10:20:49,804       exec(code, run_globals)
2022-07-28T10:20:49,804     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/__main__.py", line 31, in <module>
2022-07-28T10:20:49,805       sys.exit(_main())
2022-07-28T10:20:49,805     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/cli/main.py", line 70, in main
2022-07-28T10:20:49,805       return command.main(cmd_args)
2022-07-28T10:20:49,806     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/cli/base_command.py", line 101, in main
2022-07-28T10:20:49,806       return self._main(args)
2022-07-28T10:20:49,806     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/cli/base_command.py", line 223, in _main
2022-07-28T10:20:49,807       self.handle_pip_version_check(options)
2022-07-28T10:20:49,807     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/cli/req_command.py", line 148, in handle_pip_version_check
2022-07-28T10:20:49,808       pip_self_version_check(session, options)
2022-07-28T10:20:49,808     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 237, in pip_self_version_check
2022-07-28T10:20:49,808       logger.info("[present-rich] %s", upgrade_prompt)
2022-07-28T10:20:49,808     File "/usr/lib/python3.9/logging/__init__.py", line 1442, in info
2022-07-28T10:20:49,809       self._log(INFO, msg, args, **kwargs)
2022-07-28T10:20:49,809     File "/usr/lib/python3.9/logging/__init__.py", line 1585, in _log
2022-07-28T10:20:49,809       self.handle(record)
2022-07-28T10:20:49,810     File "/usr/lib/python3.9/logging/__init__.py", line 1595, in handle
2022-07-28T10:20:49,810       self.callHandlers(record)
2022-07-28T10:20:49,810     File "/usr/lib/python3.9/logging/__init__.py", line 1657, in callHandlers
2022-07-28T10:20:49,810       hdlr.handle(record)
2022-07-28T10:20:49,811     File "/usr/lib/python3.9/logging/__init__.py", line 948, in handle
2022-07-28T10:20:49,811       self.emit(record)
2022-07-28T10:20:49,811     File "/tmp/pip-standalone-pip-3pwrj1rs/__env_pip__.zip/pip/_internal/utils/logging.py", line 179, in emit
2022-07-28T10:20:49,811       self.handleError(record)
2022-07-28T10:20:49,812   Message: '[present-rich] %s'
2022-07-28T10:20:49,812   Arguments: (UpgradePrompt(old='22.1.2', new='22.2'),)
2022-07-28T10:20:50,375   Installing build dependencies: finished with status 'done'
2022-07-28T10:20:50,402   Getting requirements to build wheel: started
2022-07-28T10:20:50,404   Running command Getting requirements to build wheel
2022-07-28T10:20:52,688   Popen(['git', 'version'], cwd=/tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:20:52,713   Popen(['git', 'version'], cwd=/tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:20:54,590   /usr/local/lib/python3.9/dist-packages/setuptools/config/pyprojecttoml.py:102: _ExperimentalProjectMetadata: Support for project metadata in `pyproject.toml` is still experimental and may be removed (or change) in future releases.
2022-07-28T10:20:54,591     warnings.warn(msg, _ExperimentalProjectMetadata)
2022-07-28T10:20:56,628   running egg_info
2022-07-28T10:20:57,234   writing python/lsst_ctrl_bps_panda.egg-info/PKG-INFO
2022-07-28T10:20:57,801   writing dependency_links to python/lsst_ctrl_bps_panda.egg-info/dependency_links.txt
2022-07-28T10:21:00,609   writing requirements to python/lsst_ctrl_bps_panda.egg-info/requires.txt
2022-07-28T10:21:01,172   writing top-level names to python/lsst_ctrl_bps_panda.egg-info/top_level.txt
2022-07-28T10:21:01,805   package init file 'python/lsst/__init__.py' not found (or not a regular file)
2022-07-28T10:21:01,807   package init file 'python/lsst/ctrl/__init__.py' not found (or not a regular file)
2022-07-28T10:21:01,809   package init file 'python/lsst/ctrl/bps/__init__.py' not found (or not a regular file)
2022-07-28T10:21:01,814   package init file 'python/lsst/ctrl/bps/panda/conf_example/__init__.py' not found (or not a regular file)
2022-07-28T10:21:01,858   reading manifest file 'python/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:01,864   adding license file 'COPYRIGHT'
2022-07-28T10:21:01,865   adding license file 'LICENSE'
2022-07-28T10:21:01,871   writing manifest file 'python/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:02,228   Getting requirements to build wheel: finished with status 'done'
2022-07-28T10:21:02,271   Created temporary directory: /tmp/pip-standalone-pip-kuukocnb
2022-07-28T10:21:04,441   Installing backend dependencies: started
2022-07-28T10:21:04,443   Running command pip subprocess to install backend dependencies
2022-07-28T10:21:15,635   Using pip 22.1.2 from /tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip (python 3.9)
2022-07-28T10:21:17,272   Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2022-07-28T10:21:18,160   Collecting wheel
2022-07-28T10:21:18,194     Using cached https://www.piwheels.org/simple/wheel/wheel-0.37.1-py2.py3-none-any.whl (35 kB)
2022-07-28T10:21:24,875   Installing collected packages: wheel
2022-07-28T10:21:25,171     Creating /tmp/pip-build-env-fc79i0cs/normal/bin
2022-07-28T10:21:25,175     changing mode of /tmp/pip-build-env-fc79i0cs/normal/bin/wheel to 755
2022-07-28T10:21:25,202   Successfully installed wheel-0.37.1
2022-07-28T10:21:25,403   --- Logging error ---
2022-07-28T10:21:25,419   Traceback (most recent call last):
2022-07-28T10:21:25,420     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/utils/logging.py", line 177, in emit
2022-07-28T10:21:25,420       self.console.print(renderable, overflow="ignore", crop=False, style=style)
2022-07-28T10:21:25,420     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_vendor/rich/console.py", line 1752, in print
2022-07-28T10:21:25,421       extend(render(renderable, render_options))
2022-07-28T10:21:25,421     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_vendor/rich/console.py", line 1390, in render
2022-07-28T10:21:25,421       for render_output in iter_render:
2022-07-28T10:21:25,422     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/utils/logging.py", line 134, in __rich_console__
2022-07-28T10:21:25,422       for line in lines:
2022-07-28T10:21:25,422     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_vendor/rich/segment.py", line 245, in split_lines
2022-07-28T10:21:25,423       for segment in segments:
2022-07-28T10:21:25,423     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_vendor/rich/console.py", line 1368, in render
2022-07-28T10:21:25,423       renderable = rich_cast(renderable)
2022-07-28T10:21:25,423     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_vendor/rich/protocol.py", line 36, in rich_cast
2022-07-28T10:21:25,424       renderable = cast_method()
2022-07-28T10:21:25,424     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 130, in __rich__
2022-07-28T10:21:25,425       pip_cmd = get_best_invocation_for_this_pip()
2022-07-28T10:21:25,425     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/utils/entrypoints.py", line 58, in get_best_invocation_for_this_pip
2022-07-28T10:21:25,425       if found_executable and os.path.samefile(
2022-07-28T10:21:25,426     File "/usr/lib/python3.9/genericpath.py", line 101, in samefile
2022-07-28T10:21:25,426       s2 = os.stat(f2)
2022-07-28T10:21:25,426   FileNotFoundError: [Errno 2] No such file or directory: '/usr/bin/pip3.9'
2022-07-28T10:21:25,426   Call stack:
2022-07-28T10:21:25,431     File "/usr/lib/python3.9/runpy.py", line 197, in _run_module_as_main
2022-07-28T10:21:25,431       return _run_code(code, main_globals, None,
2022-07-28T10:21:25,431     File "/usr/lib/python3.9/runpy.py", line 87, in _run_code
2022-07-28T10:21:25,432       exec(code, run_globals)
2022-07-28T10:21:25,432     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/__main__.py", line 31, in <module>
2022-07-28T10:21:25,432       sys.exit(_main())
2022-07-28T10:21:25,433     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/cli/main.py", line 70, in main
2022-07-28T10:21:25,433       return command.main(cmd_args)
2022-07-28T10:21:25,433     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/cli/base_command.py", line 101, in main
2022-07-28T10:21:25,433       return self._main(args)
2022-07-28T10:21:25,434     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/cli/base_command.py", line 223, in _main
2022-07-28T10:21:25,434       self.handle_pip_version_check(options)
2022-07-28T10:21:25,435     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/cli/req_command.py", line 148, in handle_pip_version_check
2022-07-28T10:21:25,435       pip_self_version_check(session, options)
2022-07-28T10:21:25,435     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 237, in pip_self_version_check
2022-07-28T10:21:25,435       logger.info("[present-rich] %s", upgrade_prompt)
2022-07-28T10:21:25,436     File "/usr/lib/python3.9/logging/__init__.py", line 1442, in info
2022-07-28T10:21:25,436       self._log(INFO, msg, args, **kwargs)
2022-07-28T10:21:25,436     File "/usr/lib/python3.9/logging/__init__.py", line 1585, in _log
2022-07-28T10:21:25,437       self.handle(record)
2022-07-28T10:21:25,437     File "/usr/lib/python3.9/logging/__init__.py", line 1595, in handle
2022-07-28T10:21:25,437       self.callHandlers(record)
2022-07-28T10:21:25,437     File "/usr/lib/python3.9/logging/__init__.py", line 1657, in callHandlers
2022-07-28T10:21:25,438       hdlr.handle(record)
2022-07-28T10:21:25,438     File "/usr/lib/python3.9/logging/__init__.py", line 948, in handle
2022-07-28T10:21:25,438       self.emit(record)
2022-07-28T10:21:25,438     File "/tmp/pip-standalone-pip-kuukocnb/__env_pip__.zip/pip/_internal/utils/logging.py", line 179, in emit
2022-07-28T10:21:25,439       self.handleError(record)
2022-07-28T10:21:25,439   Message: '[present-rich] %s'
2022-07-28T10:21:25,439   Arguments: (UpgradePrompt(old='22.1.2', new='22.2'),)
2022-07-28T10:21:25,882   Installing backend dependencies: finished with status 'done'
2022-07-28T10:21:25,904   Created temporary directory: /tmp/pip-modern-metadata-f4uxjh92
2022-07-28T10:21:25,911   Preparing metadata (pyproject.toml): started
2022-07-28T10:21:25,913   Running command Preparing metadata (pyproject.toml)
2022-07-28T10:21:28,192   Popen(['git', 'version'], cwd=/tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:28,217   Popen(['git', 'version'], cwd=/tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:30,088   /usr/local/lib/python3.9/dist-packages/setuptools/config/pyprojecttoml.py:102: _ExperimentalProjectMetadata: Support for project metadata in `pyproject.toml` is still experimental and may be removed (or change) in future releases.
2022-07-28T10:21:30,089     warnings.warn(msg, _ExperimentalProjectMetadata)
2022-07-28T10:21:32,126   running dist_info
2022-07-28T10:21:32,738   creating /tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info
2022-07-28T10:21:33,335   writing /tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/PKG-INFO
2022-07-28T10:21:33,904   writing dependency_links to /tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/dependency_links.txt
2022-07-28T10:21:36,692   writing requirements to /tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/requires.txt
2022-07-28T10:21:37,257   writing top-level names to /tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/top_level.txt
2022-07-28T10:21:37,260   writing manifest file '/tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:37,895   package init file 'python/lsst/__init__.py' not found (or not a regular file)
2022-07-28T10:21:37,898   package init file 'python/lsst/ctrl/__init__.py' not found (or not a regular file)
2022-07-28T10:21:37,899   package init file 'python/lsst/ctrl/bps/__init__.py' not found (or not a regular file)
2022-07-28T10:21:37,904   package init file 'python/lsst/ctrl/bps/panda/conf_example/__init__.py' not found (or not a regular file)
2022-07-28T10:21:37,953   reading manifest file '/tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:37,956   adding license file 'COPYRIGHT'
2022-07-28T10:21:37,956   adding license file 'LICENSE'
2022-07-28T10:21:37,962   writing manifest file '/tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:37,965   creating '/tmp/pip-modern-metadata-f4uxjh92/lsst_ctrl_bps_panda-1.0.0a20223100.dist-info'
2022-07-28T10:21:38,145   adding license file "LICENSE" (matched pattern "LICEN[CS]E*")
2022-07-28T10:21:38,514   Preparing metadata (pyproject.toml): finished with status 'done'
2022-07-28T10:21:38,529   Source in /tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d has version 1.0.0a20223100, which satisfies requirement lsst-ctrl-bps-panda==1.0.0a20223100 from https://files.pythonhosted.org/packages/6b/33/f81263622f273e47a11f7eb3f5e44738f14400d3ae69e1f633791272e429/lsst-ctrl-bps-panda-1.0.0a20223100.tar.gz#sha256=7afe09e72707021a9a4ce72a5001c68ef364e03b9b78bd71e4cc2cc97795dd09
2022-07-28T10:21:38,531   Removed lsst-ctrl-bps-panda==1.0.0a20223100 from https://files.pythonhosted.org/packages/6b/33/f81263622f273e47a11f7eb3f5e44738f14400d3ae69e1f633791272e429/lsst-ctrl-bps-panda-1.0.0a20223100.tar.gz#sha256=7afe09e72707021a9a4ce72a5001c68ef364e03b9b78bd71e4cc2cc97795dd09 from build tracker '/tmp/pip-build-tracker-sush34cz'
2022-07-28T10:21:38,540 Created temporary directory: /tmp/pip-unpack-jfsnrt6s
2022-07-28T10:21:38,541 Building wheels for collected packages: lsst-ctrl-bps-panda
2022-07-28T10:21:38,550   Created temporary directory: /tmp/pip-wheel-e1y9lyj3
2022-07-28T10:21:38,551   Destination directory: /tmp/pip-wheel-e1y9lyj3
2022-07-28T10:21:38,556   Building wheel for lsst-ctrl-bps-panda (pyproject.toml): started
2022-07-28T10:21:38,558   Running command Building wheel for lsst-ctrl-bps-panda (pyproject.toml)
2022-07-28T10:21:40,759   Popen(['git', 'version'], cwd=/tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:40,783   Popen(['git', 'version'], cwd=/tmp/pip-wheel-ipo0syy6/lsst-ctrl-bps-panda_48dae0fd09cc4f18b7a1274d56d3ec5d, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:42,671   /usr/local/lib/python3.9/dist-packages/setuptools/config/pyprojecttoml.py:102: _ExperimentalProjectMetadata: Support for project metadata in `pyproject.toml` is still experimental and may be removed (or change) in future releases.
2022-07-28T10:21:42,671     warnings.warn(msg, _ExperimentalProjectMetadata)
2022-07-28T10:21:44,166   running bdist_wheel
2022-07-28T10:21:44,873   running build
2022-07-28T10:21:44,874   running build_py
2022-07-28T10:21:45,473   package init file 'python/lsst/__init__.py' not found (or not a regular file)
2022-07-28T10:21:45,476   package init file 'python/lsst/ctrl/__init__.py' not found (or not a regular file)
2022-07-28T10:21:45,477   package init file 'python/lsst/ctrl/bps/__init__.py' not found (or not a regular file)
2022-07-28T10:21:45,481   creating build
2022-07-28T10:21:45,481   creating build/lib
2022-07-28T10:21:45,482   creating build/lib/lsst
2022-07-28T10:21:45,484   creating build/lib/lsst/ctrl
2022-07-28T10:21:45,485   creating build/lib/lsst/ctrl/bps
2022-07-28T10:21:45,486   creating build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,488   copying python/lsst/ctrl/bps/panda/panda_auth_drivers.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,492   copying python/lsst/ctrl/bps/panda/idds_tasks.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,497   copying python/lsst/ctrl/bps/panda/version.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,500   copying python/lsst/ctrl/bps/panda/cmd_line_embedder.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,504   copying python/lsst/ctrl/bps/panda/panda_service.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,510   copying python/lsst/ctrl/bps/panda/__init__.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,513   copying python/lsst/ctrl/bps/panda/panda_auth_utils.py -> build/lib/lsst/ctrl/bps/panda
2022-07-28T10:21:45,519   creating build/lib/lsst/ctrl/bps/panda/cli
2022-07-28T10:21:45,520   copying python/lsst/ctrl/bps/panda/cli/__init__.py -> build/lib/lsst/ctrl/bps/panda/cli
2022-07-28T10:21:45,523   copying python/lsst/ctrl/bps/panda/cli/panda_auth.py -> build/lib/lsst/ctrl/bps/panda/cli
2022-07-28T10:21:45,526   package init file 'python/lsst/ctrl/bps/panda/conf_example/__init__.py' not found (or not a regular file)
2022-07-28T10:21:45,529   creating build/lib/lsst/ctrl/bps/panda/edgenode
2022-07-28T10:21:45,531   copying python/lsst/ctrl/bps/panda/edgenode/__init__.py -> build/lib/lsst/ctrl/bps/panda/edgenode
2022-07-28T10:21:45,534   copying python/lsst/ctrl/bps/panda/edgenode/cmd_line_decoder.py -> build/lib/lsst/ctrl/bps/panda/edgenode
2022-07-28T10:21:45,540   creating build/lib/lsst/ctrl/bps/panda/cli/cmd
2022-07-28T10:21:45,541   copying python/lsst/ctrl/bps/panda/cli/cmd/__init__.py -> build/lib/lsst/ctrl/bps/panda/cli/cmd
2022-07-28T10:21:45,545   copying python/lsst/ctrl/bps/panda/cli/cmd/panda_auth_commands.py -> build/lib/lsst/ctrl/bps/panda/cli/cmd
2022-07-28T10:21:45,548   running egg_info
2022-07-28T10:21:46,749   writing python/lsst_ctrl_bps_panda.egg-info/PKG-INFO
2022-07-28T10:21:47,311   writing dependency_links to python/lsst_ctrl_bps_panda.egg-info/dependency_links.txt
2022-07-28T10:21:50,115   writing requirements to python/lsst_ctrl_bps_panda.egg-info/requires.txt
2022-07-28T10:21:50,676   writing top-level names to python/lsst_ctrl_bps_panda.egg-info/top_level.txt
2022-07-28T10:21:50,728   reading manifest file 'python/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:50,732   adding license file 'COPYRIGHT'
2022-07-28T10:21:50,732   adding license file 'LICENSE'
2022-07-28T10:21:50,739   writing manifest file 'python/lsst_ctrl_bps_panda.egg-info/SOURCES.txt'
2022-07-28T10:21:50,746   creating build/lib/lsst/ctrl/bps/panda/conf_example
2022-07-28T10:21:50,748   copying python/lsst/ctrl/bps/panda/conf_example/example_panda_SLAC.yaml -> build/lib/lsst/ctrl/bps/panda/conf_example
2022-07-28T10:21:50,753   copying python/lsst/ctrl/bps/panda/conf_example/pipelines_check_idf.yaml -> build/lib/lsst/ctrl/bps/panda/conf_example
2022-07-28T10:21:51,960   installing to build/bdist.linux-armv7l/wheel
2022-07-28T10:21:51,961   running install
2022-07-28T10:21:52,024   running install_lib
2022-07-28T10:21:52,621   creating build/bdist.linux-armv7l
2022-07-28T10:21:52,622   creating build/bdist.linux-armv7l/wheel
2022-07-28T10:21:52,625   creating build/bdist.linux-armv7l/wheel/lsst
2022-07-28T10:21:52,628   creating build/bdist.linux-armv7l/wheel/lsst/ctrl
2022-07-28T10:21:52,631   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:52,634   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,636   copying build/lib/lsst/ctrl/bps/panda/panda_auth_drivers.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,640   copying build/lib/lsst/ctrl/bps/panda/idds_tasks.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,646   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/cli
2022-07-28T10:21:52,649   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/cli/cmd
2022-07-28T10:21:52,650   copying build/lib/lsst/ctrl/bps/panda/cli/cmd/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/cli/cmd
2022-07-28T10:21:52,654   copying build/lib/lsst/ctrl/bps/panda/cli/cmd/panda_auth_commands.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/cli/cmd
2022-07-28T10:21:52,658   copying build/lib/lsst/ctrl/bps/panda/cli/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/cli
2022-07-28T10:21:52,661   copying build/lib/lsst/ctrl/bps/panda/cli/panda_auth.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/cli
2022-07-28T10:21:52,664   copying build/lib/lsst/ctrl/bps/panda/version.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,668   copying build/lib/lsst/ctrl/bps/panda/cmd_line_embedder.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,672   copying build/lib/lsst/ctrl/bps/panda/panda_service.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,678   copying build/lib/lsst/ctrl/bps/panda/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,682   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/conf_example
2022-07-28T10:21:52,684   copying build/lib/lsst/ctrl/bps/panda/conf_example/example_panda_SLAC.yaml -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/conf_example
2022-07-28T10:21:52,689   copying build/lib/lsst/ctrl/bps/panda/conf_example/pipelines_check_idf.yaml -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/conf_example
2022-07-28T10:21:52,693   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/edgenode
2022-07-28T10:21:52,695   copying build/lib/lsst/ctrl/bps/panda/edgenode/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/edgenode
2022-07-28T10:21:52,698   copying build/lib/lsst/ctrl/bps/panda/edgenode/cmd_line_decoder.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda/edgenode
2022-07-28T10:21:52,703   copying build/lib/lsst/ctrl/bps/panda/panda_auth_utils.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/panda
2022-07-28T10:21:52,706   running install_egg_info
2022-07-28T10:21:53,304   Copying python/lsst_ctrl_bps_panda.egg-info to build/bdist.linux-armv7l/wheel/lsst_ctrl_bps_panda-1.0.0a20223100-py3.9.egg-info
2022-07-28T10:21:53,329   running install_scripts
2022-07-28T10:21:53,409   adding license file "LICENSE" (matched pattern "LICEN[CS]E*")
2022-07-28T10:21:53,424   creating build/bdist.linux-armv7l/wheel/lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/WHEEL
2022-07-28T10:21:53,428   creating '/tmp/pip-wheel-e1y9lyj3/tmpqm3oq8gk/lsst_ctrl_bps_panda-1.0.0a20223100-py3-none-any.whl' and adding 'build/bdist.linux-armv7l/wheel' to it
2022-07-28T10:21:53,436   adding 'lsst/ctrl/bps/panda/__init__.py'
2022-07-28T10:21:53,439   adding 'lsst/ctrl/bps/panda/cmd_line_embedder.py'
2022-07-28T10:21:53,446   adding 'lsst/ctrl/bps/panda/idds_tasks.py'
2022-07-28T10:21:53,449   adding 'lsst/ctrl/bps/panda/panda_auth_drivers.py'
2022-07-28T10:21:53,452   adding 'lsst/ctrl/bps/panda/panda_auth_utils.py'
2022-07-28T10:21:53,458   adding 'lsst/ctrl/bps/panda/panda_service.py'
2022-07-28T10:21:53,461   adding 'lsst/ctrl/bps/panda/version.py'
2022-07-28T10:21:53,464   adding 'lsst/ctrl/bps/panda/cli/__init__.py'
2022-07-28T10:21:53,478   adding 'lsst/ctrl/bps/panda/cli/panda_auth.py'
2022-07-28T10:21:53,482   adding 'lsst/ctrl/bps/panda/cli/cmd/__init__.py'
2022-07-28T10:21:53,484   adding 'lsst/ctrl/bps/panda/cli/cmd/panda_auth_commands.py'
2022-07-28T10:21:53,489   adding 'lsst/ctrl/bps/panda/conf_example/example_panda_SLAC.yaml'
2022-07-28T10:21:53,491   adding 'lsst/ctrl/bps/panda/conf_example/pipelines_check_idf.yaml'
2022-07-28T10:21:53,494   adding 'lsst/ctrl/bps/panda/edgenode/__init__.py'
2022-07-28T10:21:53,497   adding 'lsst/ctrl/bps/panda/edgenode/cmd_line_decoder.py'
2022-07-28T10:21:53,516   adding 'lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/LICENSE'
2022-07-28T10:21:53,519   adding 'lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/METADATA'
2022-07-28T10:21:53,521   adding 'lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/WHEEL'
2022-07-28T10:21:53,522   adding 'lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/top_level.txt'
2022-07-28T10:21:53,524   adding 'lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/zip-safe'
2022-07-28T10:21:53,526   adding 'lsst_ctrl_bps_panda-1.0.0a20223100.dist-info/RECORD'
2022-07-28T10:21:53,531   removing build/bdist.linux-armv7l/wheel
2022-07-28T10:21:53,918   Building wheel for lsst-ctrl-bps-panda (pyproject.toml): finished with status 'done'
2022-07-28T10:21:53,933   Created wheel for lsst-ctrl-bps-panda: filename=lsst_ctrl_bps_panda-1.0.0a20223100-py3-none-any.whl size=40104 sha256=11507f863ed2c67c8c85820ddffcf9f8be64d94f69ebc09225565e0a438f6a3c
2022-07-28T10:21:53,935   Stored in directory: /tmp/pip-ephem-wheel-cache-c7yizm45/wheels/eb/9b/59/94aaf56ff42fb7c5d8c9ffc4df3f1d97f80d1b229804dccfc5
2022-07-28T10:21:53,960 Successfully built lsst-ctrl-bps-panda
2022-07-28T10:21:53,970 Removed build tracker: '/tmp/pip-build-tracker-sush34cz'