2022-07-28T10:20:11,642 Created temporary directory: /tmp/pip-ephem-wheel-cache-8euh5ch7
2022-07-28T10:20:11,648 Created temporary directory: /tmp/pip-build-tracker-nt3z24r3
2022-07-28T10:20:11,649 Initialized build tracking at /tmp/pip-build-tracker-nt3z24r3
2022-07-28T10:20:11,649 Created build tracker: /tmp/pip-build-tracker-nt3z24r3
2022-07-28T10:20:11,650 Entered build tracker: /tmp/pip-build-tracker-nt3z24r3
2022-07-28T10:20:11,651 Created temporary directory: /tmp/pip-wheel-0l11gp4u
2022-07-28T10:20:11,714 Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2022-07-28T10:20:11,728 2 location(s) to search for versions of lsst-ctrl-bps:
2022-07-28T10:20:11,728 * https://pypi.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:11,728 * https://www.piwheels.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:11,729 Fetching project page and analyzing links: https://pypi.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:11,730 Getting page https://pypi.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:11,733 Found index url https://pypi.org/simple
2022-07-28T10:20:11,849   Found link https://files.pythonhosted.org/packages/26/b8/4260f27558326771e5db73535baf8f288698d1d482ddae835401a00cc0e3/lsst-ctrl-bps-24.0.0a20222903.tar.gz#sha256=e47000ec7b934f844deb37168df58e0b8a096d7067dd371aa57450c1ad0604b0 (from https://pypi.org/simple/lsst-ctrl-bps/), version: 24.0.0a20222903
2022-07-28T10:20:11,850   Skipping link: No binaries permitted for lsst-ctrl-bps: https://files.pythonhosted.org/packages/fb/7a/33838f2efd52193deef328f910b3e8f64620284abf782525a9e5e88178de/lsst_ctrl_bps-24.0.0a20222903-py3-none-any.whl#sha256=1d2b87c5ed5672e631073b811d0b374552de83c5d6e7a3d00f7424aa91a4969b (from https://pypi.org/simple/lsst-ctrl-bps/)
2022-07-28T10:20:11,850   Found link https://files.pythonhosted.org/packages/68/92/5478f2c22c965a16a460fbc23edd172d6429bdc6aff31b80fd8f733c57fa/lsst-ctrl-bps-24.0.0a20223000.tar.gz#sha256=88a02a5f3b17093e37daad73b6bddcd12d92e93474edf96e1c0b46fb995b4e9a (from https://pypi.org/simple/lsst-ctrl-bps/), version: 24.0.0a20223000
2022-07-28T10:20:11,851   Skipping link: No binaries permitted for lsst-ctrl-bps: https://files.pythonhosted.org/packages/e5/28/5d86875dd42622d74ddc9ba5688c578a1f90c403c6fd74437a082f078084/lsst_ctrl_bps-24.0.0a20223000-py3-none-any.whl#sha256=d73ce571e4ddcdae92b7f2116d397e7e37865dd7128294b7ca378325236ddee8 (from https://pypi.org/simple/lsst-ctrl-bps/)
2022-07-28T10:20:11,852   Found link https://files.pythonhosted.org/packages/ec/7d/b88595bfbadcf01c894de681c163dc24162e0b1caaaa39982a2979c3baaa/lsst-ctrl-bps-24.0.0a20223100.tar.gz#sha256=e5269f2d215761cc7a7e7b725ce6e52a0ff4f371cdaf35cad1216ef9bc7401aa (from https://pypi.org/simple/lsst-ctrl-bps/), version: 24.0.0a20223100
2022-07-28T10:20:11,852   Skipping link: No binaries permitted for lsst-ctrl-bps: https://files.pythonhosted.org/packages/67/31/1d53e6b9faacecec7bbb4171965c9c703a435176fcb9de8779341cd6b32f/lsst_ctrl_bps-24.0.0a20223100-py3-none-any.whl#sha256=7dda006f7216bb4a03c8df0ee2d9447e8f6999512e65c16a3f457fff4a397aea (from https://pypi.org/simple/lsst-ctrl-bps/)
2022-07-28T10:20:11,853 Fetching project page and analyzing links: https://www.piwheels.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:11,854 Getting page https://www.piwheels.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:11,856 Found index url https://www.piwheels.org/simple
2022-07-28T10:20:12,066   Skipping link: No binaries permitted for lsst-ctrl-bps: https://www.piwheels.org/simple/lsst-ctrl-bps/lsst_ctrl_bps-24.0.0a20223000-py3-none-any.whl#sha256=9bd96a178e9b14e6886b41caeb6ab5a58c8b3c8aa7d94562e474b8096ae34055 (from https://www.piwheels.org/simple/lsst-ctrl-bps/)
2022-07-28T10:20:12,067   Skipping link: No binaries permitted for lsst-ctrl-bps: https://www.piwheels.org/simple/lsst-ctrl-bps/lsst_ctrl_bps-24.0.0a20222903-py3-none-any.whl#sha256=0ad0861b1677d5f03b0f9351afd8482a1e21769650aee2547636547ad5c1ccbb (from https://www.piwheels.org/simple/lsst-ctrl-bps/)
2022-07-28T10:20:12,067 Skipping link: not a file: https://www.piwheels.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:12,068 Skipping link: not a file: https://pypi.org/simple/lsst-ctrl-bps/
2022-07-28T10:20:12,114 Given no hashes to check 1 links for project 'lsst-ctrl-bps': discarding no candidates
2022-07-28T10:20:12,156 Collecting lsst-ctrl-bps==24.0.0a20223100
2022-07-28T10:20:12,161   Created temporary directory: /tmp/pip-unpack-5t3dmp8m
2022-07-28T10:20:12,252   Downloading lsst-ctrl-bps-24.0.0a20223100.tar.gz (74 kB)
2022-07-28T10:20:12,528   Added lsst-ctrl-bps==24.0.0a20223100 from https://files.pythonhosted.org/packages/ec/7d/b88595bfbadcf01c894de681c163dc24162e0b1caaaa39982a2979c3baaa/lsst-ctrl-bps-24.0.0a20223100.tar.gz#sha256=e5269f2d215761cc7a7e7b725ce6e52a0ff4f371cdaf35cad1216ef9bc7401aa to build tracker '/tmp/pip-build-tracker-nt3z24r3'
2022-07-28T10:20:12,549   Created temporary directory: /tmp/pip-build-env-t1_fk790
2022-07-28T10:20:12,571   Created temporary directory: /tmp/pip-standalone-pip-mzf8ks4f
2022-07-28T10:20:15,040   Installing build dependencies: started
2022-07-28T10:20:15,042   Running command pip subprocess to install build dependencies
2022-07-28T10:20:26,441   Using pip 22.1.2 from /tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip (python 3.9)
2022-07-28T10:20:28,156   Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2022-07-28T10:20:32,342   Collecting setuptools
2022-07-28T10:20:32,623     Using cached https://www.piwheels.org/simple/setuptools/setuptools-63.2.0-py3-none-any.whl (1.2 MB)
2022-07-28T10:20:33,054   Collecting lsst-versions>=1.3.0
2022-07-28T10:20:33,100     Using cached https://www.piwheels.org/simple/lsst-versions/lsst_versions-1.3.0-py3-none-any.whl (10 kB)
2022-07-28T10:20:34,255   Collecting GitPython>=3.1
2022-07-28T10:20:34,326     Using cached https://www.piwheels.org/simple/gitpython/GitPython-3.1.27-py3-none-any.whl (181 kB)
2022-07-28T10:20:35,555   Collecting packaging
2022-07-28T10:20:35,688     Using cached https://www.piwheels.org/simple/packaging/packaging-21.3-py3-none-any.whl (40 kB)
2022-07-28T10:20:35,959   Collecting tomli>1.0.0
2022-07-28T10:20:35,989     Using cached https://www.piwheels.org/simple/tomli/tomli-2.0.1-py3-none-any.whl (12 kB)
2022-07-28T10:20:36,302   Collecting gitdb<5,>=4.0.1
2022-07-28T10:20:36,371     Using cached https://www.piwheels.org/simple/gitdb/gitdb-4.0.9-py3-none-any.whl (63 kB)
2022-07-28T10:20:37,068   Collecting pyparsing!=3.0.5,>=2.0.2
2022-07-28T10:20:37,126     Using cached https://www.piwheels.org/simple/pyparsing/pyparsing-3.0.9-py3-none-any.whl (98 kB)
2022-07-28T10:20:37,374   Collecting smmap<6,>=3.0.1
2022-07-28T10:20:37,429     Using cached https://www.piwheels.org/simple/smmap/smmap-5.0.0-py3-none-any.whl (24 kB)
2022-07-28T10:20:43,653   Installing collected packages: tomli, smmap, setuptools, pyparsing, packaging, gitdb, GitPython, lsst-versions
2022-07-28T10:20:51,483     Creating /tmp/pip-build-env-t1_fk790/overlay/bin
2022-07-28T10:20:51,488     changing mode of /tmp/pip-build-env-t1_fk790/overlay/bin/lsst-version to 755
2022-07-28T10:20:51,538   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.
2022-07-28T10:20:51,539   sphinx 5.0.0 requires importlib-metadata>=4.4; python_version < "3.10", but you have importlib-metadata 4.0.1 which is incompatible.
2022-07-28T10:20:51,540   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:51,740   --- Logging error ---
2022-07-28T10:20:51,756   Traceback (most recent call last):
2022-07-28T10:20:51,757     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/utils/logging.py", line 177, in emit
2022-07-28T10:20:51,757       self.console.print(renderable, overflow="ignore", crop=False, style=style)
2022-07-28T10:20:51,757     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_vendor/rich/console.py", line 1752, in print
2022-07-28T10:20:51,758       extend(render(renderable, render_options))
2022-07-28T10:20:51,758     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_vendor/rich/console.py", line 1390, in render
2022-07-28T10:20:51,759       for render_output in iter_render:
2022-07-28T10:20:51,759     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/utils/logging.py", line 134, in __rich_console__
2022-07-28T10:20:51,760       for line in lines:
2022-07-28T10:20:51,760     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_vendor/rich/segment.py", line 245, in split_lines
2022-07-28T10:20:51,760       for segment in segments:
2022-07-28T10:20:51,761     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_vendor/rich/console.py", line 1368, in render
2022-07-28T10:20:51,761       renderable = rich_cast(renderable)
2022-07-28T10:20:51,761     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_vendor/rich/protocol.py", line 36, in rich_cast
2022-07-28T10:20:51,762       renderable = cast_method()
2022-07-28T10:20:51,762     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 130, in __rich__
2022-07-28T10:20:51,762       pip_cmd = get_best_invocation_for_this_pip()
2022-07-28T10:20:51,762     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/utils/entrypoints.py", line 58, in get_best_invocation_for_this_pip
2022-07-28T10:20:51,763       if found_executable and os.path.samefile(
2022-07-28T10:20:51,763     File "/usr/lib/python3.9/genericpath.py", line 101, in samefile
2022-07-28T10:20:51,763       s2 = os.stat(f2)
2022-07-28T10:20:51,764   FileNotFoundError: [Errno 2] No such file or directory: '/usr/bin/pip3.9'
2022-07-28T10:20:51,764   Call stack:
2022-07-28T10:20:51,770     File "/usr/lib/python3.9/runpy.py", line 197, in _run_module_as_main
2022-07-28T10:20:51,770       return _run_code(code, main_globals, None,
2022-07-28T10:20:51,771     File "/usr/lib/python3.9/runpy.py", line 87, in _run_code
2022-07-28T10:20:51,771       exec(code, run_globals)
2022-07-28T10:20:51,771     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/__main__.py", line 31, in <module>
2022-07-28T10:20:51,772       sys.exit(_main())
2022-07-28T10:20:51,772     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/cli/main.py", line 70, in main
2022-07-28T10:20:51,772       return command.main(cmd_args)
2022-07-28T10:20:51,773     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/cli/base_command.py", line 101, in main
2022-07-28T10:20:51,773       return self._main(args)
2022-07-28T10:20:51,774     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/cli/base_command.py", line 223, in _main
2022-07-28T10:20:51,774       self.handle_pip_version_check(options)
2022-07-28T10:20:51,774     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/cli/req_command.py", line 148, in handle_pip_version_check
2022-07-28T10:20:51,775       pip_self_version_check(session, options)
2022-07-28T10:20:51,775     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 237, in pip_self_version_check
2022-07-28T10:20:51,775       logger.info("[present-rich] %s", upgrade_prompt)
2022-07-28T10:20:51,775     File "/usr/lib/python3.9/logging/__init__.py", line 1442, in info
2022-07-28T10:20:51,776       self._log(INFO, msg, args, **kwargs)
2022-07-28T10:20:51,776     File "/usr/lib/python3.9/logging/__init__.py", line 1585, in _log
2022-07-28T10:20:51,776       self.handle(record)
2022-07-28T10:20:51,777     File "/usr/lib/python3.9/logging/__init__.py", line 1595, in handle
2022-07-28T10:20:51,777       self.callHandlers(record)
2022-07-28T10:20:51,777     File "/usr/lib/python3.9/logging/__init__.py", line 1657, in callHandlers
2022-07-28T10:20:51,778       hdlr.handle(record)
2022-07-28T10:20:51,778     File "/usr/lib/python3.9/logging/__init__.py", line 948, in handle
2022-07-28T10:20:51,778       self.emit(record)
2022-07-28T10:20:51,778     File "/tmp/pip-standalone-pip-mzf8ks4f/__env_pip__.zip/pip/_internal/utils/logging.py", line 179, in emit
2022-07-28T10:20:51,779       self.handleError(record)
2022-07-28T10:20:51,779   Message: '[present-rich] %s'
2022-07-28T10:20:51,779   Arguments: (UpgradePrompt(old='22.1.2', new='22.2'),)
2022-07-28T10:20:52,328   Installing build dependencies: finished with status 'done'
2022-07-28T10:20:52,356   Getting requirements to build wheel: started
2022-07-28T10:20:52,358   Running command Getting requirements to build wheel
2022-07-28T10:20:54,671   Popen(['git', 'version'], cwd=/tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:20:54,696   Popen(['git', 'version'], cwd=/tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:20:56,566   /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:56,567     warnings.warn(msg, _ExperimentalProjectMetadata)
2022-07-28T10:20:58,584   running egg_info
2022-07-28T10:20:59,184   writing python/lsst_ctrl_bps.egg-info/PKG-INFO
2022-07-28T10:20:59,748   writing dependency_links to python/lsst_ctrl_bps.egg-info/dependency_links.txt
2022-07-28T10:21:02,524   writing requirements to python/lsst_ctrl_bps.egg-info/requires.txt
2022-07-28T10:21:03,081   writing top-level names to python/lsst_ctrl_bps.egg-info/top_level.txt
2022-07-28T10:21:03,766   reading manifest file 'python/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:03,774   reading manifest template 'MANIFEST.in'
2022-07-28T10:21:03,778   adding license file 'COPYRIGHT'
2022-07-28T10:21:03,779   adding license file 'LICENSE'
2022-07-28T10:21:03,788   writing manifest file 'python/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:04,141   Getting requirements to build wheel: finished with status 'done'
2022-07-28T10:21:04,189   Created temporary directory: /tmp/pip-standalone-pip-jbat0ldu
2022-07-28T10:21:06,521   Installing backend dependencies: started
2022-07-28T10:21:06,523   Running command pip subprocess to install backend dependencies
2022-07-28T10:21:17,869   Using pip 22.1.2 from /tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip (python 3.9)
2022-07-28T10:21:19,604   Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
2022-07-28T10:21:20,481   Collecting wheel
2022-07-28T10:21:20,517     Using cached https://www.piwheels.org/simple/wheel/wheel-0.37.1-py2.py3-none-any.whl (35 kB)
2022-07-28T10:21:25,980   Installing collected packages: wheel
2022-07-28T10:21:26,269     Creating /tmp/pip-build-env-t1_fk790/normal/bin
2022-07-28T10:21:26,274     changing mode of /tmp/pip-build-env-t1_fk790/normal/bin/wheel to 755
2022-07-28T10:21:26,301   Successfully installed wheel-0.37.1
2022-07-28T10:21:26,494   --- Logging error ---
2022-07-28T10:21:26,510   Traceback (most recent call last):
2022-07-28T10:21:26,510     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/utils/logging.py", line 177, in emit
2022-07-28T10:21:26,511       self.console.print(renderable, overflow="ignore", crop=False, style=style)
2022-07-28T10:21:26,511     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_vendor/rich/console.py", line 1752, in print
2022-07-28T10:21:26,512       extend(render(renderable, render_options))
2022-07-28T10:21:26,512     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_vendor/rich/console.py", line 1390, in render
2022-07-28T10:21:26,512       for render_output in iter_render:
2022-07-28T10:21:26,513     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/utils/logging.py", line 134, in __rich_console__
2022-07-28T10:21:26,513       for line in lines:
2022-07-28T10:21:26,513     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_vendor/rich/segment.py", line 245, in split_lines
2022-07-28T10:21:26,514       for segment in segments:
2022-07-28T10:21:26,514     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_vendor/rich/console.py", line 1368, in render
2022-07-28T10:21:26,514       renderable = rich_cast(renderable)
2022-07-28T10:21:26,514     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_vendor/rich/protocol.py", line 36, in rich_cast
2022-07-28T10:21:26,515       renderable = cast_method()
2022-07-28T10:21:26,515     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 130, in __rich__
2022-07-28T10:21:26,516       pip_cmd = get_best_invocation_for_this_pip()
2022-07-28T10:21:26,516     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/utils/entrypoints.py", line 58, in get_best_invocation_for_this_pip
2022-07-28T10:21:26,516       if found_executable and os.path.samefile(
2022-07-28T10:21:26,517     File "/usr/lib/python3.9/genericpath.py", line 101, in samefile
2022-07-28T10:21:26,517       s2 = os.stat(f2)
2022-07-28T10:21:26,517   FileNotFoundError: [Errno 2] No such file or directory: '/usr/bin/pip3.9'
2022-07-28T10:21:26,518   Call stack:
2022-07-28T10:21:26,521     File "/usr/lib/python3.9/runpy.py", line 197, in _run_module_as_main
2022-07-28T10:21:26,522       return _run_code(code, main_globals, None,
2022-07-28T10:21:26,522     File "/usr/lib/python3.9/runpy.py", line 87, in _run_code
2022-07-28T10:21:26,522       exec(code, run_globals)
2022-07-28T10:21:26,523     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/__main__.py", line 31, in <module>
2022-07-28T10:21:26,523       sys.exit(_main())
2022-07-28T10:21:26,523     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/cli/main.py", line 70, in main
2022-07-28T10:21:26,523       return command.main(cmd_args)
2022-07-28T10:21:26,524     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/cli/base_command.py", line 101, in main
2022-07-28T10:21:26,524       return self._main(args)
2022-07-28T10:21:26,525     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/cli/base_command.py", line 223, in _main
2022-07-28T10:21:26,525       self.handle_pip_version_check(options)
2022-07-28T10:21:26,525     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/cli/req_command.py", line 148, in handle_pip_version_check
2022-07-28T10:21:26,526       pip_self_version_check(session, options)
2022-07-28T10:21:26,526     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/self_outdated_check.py", line 237, in pip_self_version_check
2022-07-28T10:21:26,526       logger.info("[present-rich] %s", upgrade_prompt)
2022-07-28T10:21:26,527     File "/usr/lib/python3.9/logging/__init__.py", line 1442, in info
2022-07-28T10:21:26,527       self._log(INFO, msg, args, **kwargs)
2022-07-28T10:21:26,527     File "/usr/lib/python3.9/logging/__init__.py", line 1585, in _log
2022-07-28T10:21:26,528       self.handle(record)
2022-07-28T10:21:26,528     File "/usr/lib/python3.9/logging/__init__.py", line 1595, in handle
2022-07-28T10:21:26,528       self.callHandlers(record)
2022-07-28T10:21:26,528     File "/usr/lib/python3.9/logging/__init__.py", line 1657, in callHandlers
2022-07-28T10:21:26,529       hdlr.handle(record)
2022-07-28T10:21:26,529     File "/usr/lib/python3.9/logging/__init__.py", line 948, in handle
2022-07-28T10:21:26,529       self.emit(record)
2022-07-28T10:21:26,530     File "/tmp/pip-standalone-pip-jbat0ldu/__env_pip__.zip/pip/_internal/utils/logging.py", line 179, in emit
2022-07-28T10:21:26,530       self.handleError(record)
2022-07-28T10:21:26,530   Message: '[present-rich] %s'
2022-07-28T10:21:26,530   Arguments: (UpgradePrompt(old='22.1.2', new='22.2'),)
2022-07-28T10:21:26,964   Installing backend dependencies: finished with status 'done'
2022-07-28T10:21:26,988   Created temporary directory: /tmp/pip-modern-metadata-lj9stv1j
2022-07-28T10:21:26,995   Preparing metadata (pyproject.toml): started
2022-07-28T10:21:26,997   Running command Preparing metadata (pyproject.toml)
2022-07-28T10:21:29,235   Popen(['git', 'version'], cwd=/tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:29,261   Popen(['git', 'version'], cwd=/tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:31,144   /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:31,144     warnings.warn(msg, _ExperimentalProjectMetadata)
2022-07-28T10:21:33,163   running dist_info
2022-07-28T10:21:33,772   creating /tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info
2022-07-28T10:21:34,367   writing /tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/PKG-INFO
2022-07-28T10:21:34,932   writing dependency_links to /tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/dependency_links.txt
2022-07-28T10:21:37,733   writing requirements to /tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/requires.txt
2022-07-28T10:21:38,294   writing top-level names to /tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/top_level.txt
2022-07-28T10:21:38,297   writing manifest file '/tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:38,988   reading manifest file '/tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:38,991   reading manifest template 'MANIFEST.in'
2022-07-28T10:21:38,994   adding license file 'COPYRIGHT'
2022-07-28T10:21:38,995   adding license file 'LICENSE'
2022-07-28T10:21:39,002   writing manifest file '/tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:39,004   creating '/tmp/pip-modern-metadata-lj9stv1j/lsst_ctrl_bps-24.0.0a20223100.dist-info'
2022-07-28T10:21:39,177   adding license file "LICENSE" (matched pattern "LICEN[CS]E*")
2022-07-28T10:21:39,547   Preparing metadata (pyproject.toml): finished with status 'done'
2022-07-28T10:21:39,562   Source in /tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db has version 24.0.0a20223100, which satisfies requirement lsst-ctrl-bps==24.0.0a20223100 from https://files.pythonhosted.org/packages/ec/7d/b88595bfbadcf01c894de681c163dc24162e0b1caaaa39982a2979c3baaa/lsst-ctrl-bps-24.0.0a20223100.tar.gz#sha256=e5269f2d215761cc7a7e7b725ce6e52a0ff4f371cdaf35cad1216ef9bc7401aa
2022-07-28T10:21:39,564   Removed lsst-ctrl-bps==24.0.0a20223100 from https://files.pythonhosted.org/packages/ec/7d/b88595bfbadcf01c894de681c163dc24162e0b1caaaa39982a2979c3baaa/lsst-ctrl-bps-24.0.0a20223100.tar.gz#sha256=e5269f2d215761cc7a7e7b725ce6e52a0ff4f371cdaf35cad1216ef9bc7401aa from build tracker '/tmp/pip-build-tracker-nt3z24r3'
2022-07-28T10:21:39,573 Created temporary directory: /tmp/pip-unpack-uljssg5y
2022-07-28T10:21:39,574 Building wheels for collected packages: lsst-ctrl-bps
2022-07-28T10:21:39,584   Created temporary directory: /tmp/pip-wheel-53jgqekx
2022-07-28T10:21:39,585   Destination directory: /tmp/pip-wheel-53jgqekx
2022-07-28T10:21:39,590   Building wheel for lsst-ctrl-bps (pyproject.toml): started
2022-07-28T10:21:39,592   Running command Building wheel for lsst-ctrl-bps (pyproject.toml)
2022-07-28T10:21:41,759   Popen(['git', 'version'], cwd=/tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:41,783   Popen(['git', 'version'], cwd=/tmp/pip-wheel-0l11gp4u/lsst-ctrl-bps_d06da1f013194d2e91193714033a56db, universal_newlines=False, shell=None, istream=None)
2022-07-28T10:21:43,666   /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:43,667     warnings.warn(msg, _ExperimentalProjectMetadata)
2022-07-28T10:21:45,140   running bdist_wheel
2022-07-28T10:21:45,843   running build
2022-07-28T10:21:45,844   running build_py
2022-07-28T10:21:46,445   creating build
2022-07-28T10:21:46,446   creating build/lib
2022-07-28T10:21:46,447   creating build/lib/lsst
2022-07-28T10:21:46,449   copying python/lsst/__init__.py -> build/lib/lsst
2022-07-28T10:21:46,454   creating build/lib/lsst/ctrl
2022-07-28T10:21:46,455   copying python/lsst/ctrl/__init__.py -> build/lib/lsst/ctrl
2022-07-28T10:21:46,462   creating build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,463   copying python/lsst/ctrl/bps/clustered_quantum_graph.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,468   copying python/lsst/ctrl/bps/cancel.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,472   copying python/lsst/ctrl/bps/restart.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,475   copying python/lsst/ctrl/bps/bps_draw.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,478   copying python/lsst/ctrl/bps/wms_service.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,482   copying python/lsst/ctrl/bps/transform.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,487   copying python/lsst/ctrl/bps/constants.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,491   copying python/lsst/ctrl/bps/report.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,495   copying python/lsst/ctrl/bps/bps_utils.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,499   copying python/lsst/ctrl/bps/submit.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,503   copying python/lsst/ctrl/bps/quantum_clustering_funcs.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,507   copying python/lsst/ctrl/bps/pre_transform.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,512   copying python/lsst/ctrl/bps/generic_workflow.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,518   copying python/lsst/ctrl/bps/version.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,520   copying python/lsst/ctrl/bps/bps_config.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,525   copying python/lsst/ctrl/bps/prepare.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,528   copying python/lsst/ctrl/bps/ping.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,532   copying python/lsst/ctrl/bps/__init__.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,535   copying python/lsst/ctrl/bps/drivers.py -> build/lib/lsst/ctrl/bps
2022-07-28T10:21:46,541   creating build/lib/lsst/ctrl/bps/cli
2022-07-28T10:21:46,543   copying python/lsst/ctrl/bps/cli/bps.py -> build/lib/lsst/ctrl/bps/cli
2022-07-28T10:21:46,547   copying python/lsst/ctrl/bps/cli/__init__.py -> build/lib/lsst/ctrl/bps/cli
2022-07-28T10:21:46,550   creating build/lib/lsst/ctrl/bps/etc
2022-07-28T10:21:46,552   copying python/lsst/ctrl/bps/etc/__init__.py -> build/lib/lsst/ctrl/bps/etc
2022-07-28T10:21:46,556   creating build/lib/lsst/ctrl/bps/cli/cmd
2022-07-28T10:21:46,557   copying python/lsst/ctrl/bps/cli/cmd/commands.py -> build/lib/lsst/ctrl/bps/cli/cmd
2022-07-28T10:21:46,562   copying python/lsst/ctrl/bps/cli/cmd/__init__.py -> build/lib/lsst/ctrl/bps/cli/cmd
2022-07-28T10:21:46,567   creating build/lib/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:46,568   copying python/lsst/ctrl/bps/cli/opt/option_groups.py -> build/lib/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:46,572   copying python/lsst/ctrl/bps/cli/opt/options.py -> build/lib/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:46,575   copying python/lsst/ctrl/bps/cli/opt/arguments.py -> build/lib/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:46,579   copying python/lsst/ctrl/bps/cli/opt/__init__.py -> build/lib/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:46,582   running egg_info
2022-07-28T10:21:47,781   writing python/lsst_ctrl_bps.egg-info/PKG-INFO
2022-07-28T10:21:48,344   writing dependency_links to python/lsst_ctrl_bps.egg-info/dependency_links.txt
2022-07-28T10:21:51,151   writing requirements to python/lsst_ctrl_bps.egg-info/requires.txt
2022-07-28T10:21:51,714   writing top-level names to python/lsst_ctrl_bps.egg-info/top_level.txt
2022-07-28T10:21:51,767   reading manifest file 'python/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:51,773   reading manifest template 'MANIFEST.in'
2022-07-28T10:21:51,776   adding license file 'COPYRIGHT'
2022-07-28T10:21:51,776   adding license file 'LICENSE'
2022-07-28T10:21:51,785   writing manifest file 'python/lsst_ctrl_bps.egg-info/SOURCES.txt'
2022-07-28T10:21:51,792   copying python/lsst/ctrl/bps/etc/bps_defaults.yaml -> build/lib/lsst/ctrl/bps/etc
2022-07-28T10:21:52,999   installing to build/bdist.linux-armv7l/wheel
2022-07-28T10:21:52,999   running install
2022-07-28T10:21:53,061   running install_lib
2022-07-28T10:21:53,657   creating build/bdist.linux-armv7l
2022-07-28T10:21:53,658   creating build/bdist.linux-armv7l/wheel
2022-07-28T10:21:53,661   creating build/bdist.linux-armv7l/wheel/lsst
2022-07-28T10:21:53,664   creating build/bdist.linux-armv7l/wheel/lsst/ctrl
2022-07-28T10:21:53,668   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,669   copying build/lib/lsst/ctrl/bps/clustered_quantum_graph.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,675   copying build/lib/lsst/ctrl/bps/cancel.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,679   copying build/lib/lsst/ctrl/bps/restart.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,683   copying build/lib/lsst/ctrl/bps/bps_draw.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,687   copying build/lib/lsst/ctrl/bps/wms_service.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,692   copying build/lib/lsst/ctrl/bps/transform.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,699   copying build/lib/lsst/ctrl/bps/constants.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,702   copying build/lib/lsst/ctrl/bps/report.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,707   copying build/lib/lsst/ctrl/bps/bps_utils.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,714   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli
2022-07-28T10:21:53,717   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/cmd
2022-07-28T10:21:53,718   copying build/lib/lsst/ctrl/bps/cli/cmd/commands.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/cmd
2022-07-28T10:21:53,723   copying build/lib/lsst/ctrl/bps/cli/cmd/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/cmd
2022-07-28T10:21:53,727   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:53,729   copying build/lib/lsst/ctrl/bps/cli/opt/option_groups.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:53,733   copying build/lib/lsst/ctrl/bps/cli/opt/options.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:53,736   copying build/lib/lsst/ctrl/bps/cli/opt/arguments.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:53,740   copying build/lib/lsst/ctrl/bps/cli/opt/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli/opt
2022-07-28T10:21:53,743   copying build/lib/lsst/ctrl/bps/cli/bps.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli
2022-07-28T10:21:53,747   copying build/lib/lsst/ctrl/bps/cli/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/cli
2022-07-28T10:21:53,750   copying build/lib/lsst/ctrl/bps/submit.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,753   copying build/lib/lsst/ctrl/bps/quantum_clustering_funcs.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,758   copying build/lib/lsst/ctrl/bps/pre_transform.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,763   creating build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/etc
2022-07-28T10:21:53,765   copying build/lib/lsst/ctrl/bps/etc/bps_defaults.yaml -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/etc
2022-07-28T10:21:53,990   copying build/lib/lsst/ctrl/bps/etc/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps/etc
2022-07-28T10:21:53,993   copying build/lib/lsst/ctrl/bps/generic_workflow.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:53,999   copying build/lib/lsst/ctrl/bps/version.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:54,002   copying build/lib/lsst/ctrl/bps/bps_config.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:54,007   copying build/lib/lsst/ctrl/bps/prepare.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:54,010   copying build/lib/lsst/ctrl/bps/ping.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:54,013   copying build/lib/lsst/ctrl/bps/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:54,017   copying build/lib/lsst/ctrl/bps/drivers.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl/bps
2022-07-28T10:21:54,022   copying build/lib/lsst/ctrl/__init__.py -> build/bdist.linux-armv7l/wheel/lsst/ctrl
2022-07-28T10:21:54,025   copying build/lib/lsst/__init__.py -> build/bdist.linux-armv7l/wheel/lsst
2022-07-28T10:21:54,028   running install_egg_info
2022-07-28T10:21:54,625   Copying python/lsst_ctrl_bps.egg-info to build/bdist.linux-armv7l/wheel/lsst_ctrl_bps-24.0.0a20223100-py3.9.egg-info
2022-07-28T10:21:54,649   running install_scripts
2022-07-28T10:21:54,723   adding license file "LICENSE" (matched pattern "LICEN[CS]E*")
2022-07-28T10:21:54,735   creating build/bdist.linux-armv7l/wheel/lsst_ctrl_bps-24.0.0a20223100.dist-info/WHEEL
2022-07-28T10:21:54,740   creating '/tmp/pip-wheel-53jgqekx/tmp2c6xjze1/lsst_ctrl_bps-24.0.0a20223100-py3-none-any.whl' and adding 'build/bdist.linux-armv7l/wheel' to it
2022-07-28T10:21:54,744   adding 'lsst/__init__.py'
2022-07-28T10:21:54,748   adding 'lsst/ctrl/__init__.py'
2022-07-28T10:21:54,752   adding 'lsst/ctrl/bps/__init__.py'
2022-07-28T10:21:54,756   adding 'lsst/ctrl/bps/bps_config.py'
2022-07-28T10:21:54,759   adding 'lsst/ctrl/bps/bps_draw.py'
2022-07-28T10:21:54,762   adding 'lsst/ctrl/bps/bps_utils.py'
2022-07-28T10:21:54,765   adding 'lsst/ctrl/bps/cancel.py'
2022-07-28T10:21:54,770   adding 'lsst/ctrl/bps/clustered_quantum_graph.py'
2022-07-28T10:21:54,773   adding 'lsst/ctrl/bps/constants.py'
2022-07-28T10:21:54,778   adding 'lsst/ctrl/bps/drivers.py'
2022-07-28T10:21:54,786   adding 'lsst/ctrl/bps/generic_workflow.py'
2022-07-28T10:21:54,790   adding 'lsst/ctrl/bps/ping.py'
2022-07-28T10:21:54,793   adding 'lsst/ctrl/bps/pre_transform.py'
2022-07-28T10:21:54,796   adding 'lsst/ctrl/bps/prepare.py'
2022-07-28T10:21:54,800   adding 'lsst/ctrl/bps/quantum_clustering_funcs.py'
2022-07-28T10:21:54,804   adding 'lsst/ctrl/bps/report.py'
2022-07-28T10:21:54,807   adding 'lsst/ctrl/bps/restart.py'
2022-07-28T10:21:54,810   adding 'lsst/ctrl/bps/submit.py'
2022-07-28T10:21:54,819   adding 'lsst/ctrl/bps/transform.py'
2022-07-28T10:21:54,822   adding 'lsst/ctrl/bps/version.py'
2022-07-28T10:21:54,826   adding 'lsst/ctrl/bps/wms_service.py'
2022-07-28T10:21:54,830   adding 'lsst/ctrl/bps/cli/__init__.py'
2022-07-28T10:21:54,832   adding 'lsst/ctrl/bps/cli/bps.py'
2022-07-28T10:21:54,836   adding 'lsst/ctrl/bps/cli/cmd/__init__.py'
2022-07-28T10:21:54,838   adding 'lsst/ctrl/bps/cli/cmd/commands.py'
2022-07-28T10:21:54,842   adding 'lsst/ctrl/bps/cli/opt/__init__.py'
2022-07-28T10:21:54,844   adding 'lsst/ctrl/bps/cli/opt/arguments.py'
2022-07-28T10:21:54,847   adding 'lsst/ctrl/bps/cli/opt/option_groups.py'
2022-07-28T10:21:54,850   adding 'lsst/ctrl/bps/cli/opt/options.py'
2022-07-28T10:21:54,853   adding 'lsst/ctrl/bps/etc/__init__.py'
2022-07-28T10:21:54,855   adding 'lsst/ctrl/bps/etc/bps_defaults.yaml'
2022-07-28T10:21:54,872   adding 'lsst_ctrl_bps-24.0.0a20223100.dist-info/LICENSE'
2022-07-28T10:21:54,875   adding 'lsst_ctrl_bps-24.0.0a20223100.dist-info/METADATA'
2022-07-28T10:21:54,877   adding 'lsst_ctrl_bps-24.0.0a20223100.dist-info/WHEEL'
2022-07-28T10:21:54,878   adding 'lsst_ctrl_bps-24.0.0a20223100.dist-info/top_level.txt'
2022-07-28T10:21:54,880   adding 'lsst_ctrl_bps-24.0.0a20223100.dist-info/zip-safe'
2022-07-28T10:21:54,882   adding 'lsst_ctrl_bps-24.0.0a20223100.dist-info/RECORD'
2022-07-28T10:21:54,888   removing build/bdist.linux-armv7l/wheel
2022-07-28T10:21:55,274   Building wheel for lsst-ctrl-bps (pyproject.toml): finished with status 'done'
2022-07-28T10:21:55,291   Created wheel for lsst-ctrl-bps: filename=lsst_ctrl_bps-24.0.0a20223100-py3-none-any.whl size=79249 sha256=f830d78e43c63a717dfeffb27a4b0caf7234f5efeef3c56dc595d5cb8692bb03
2022-07-28T10:21:55,293   Stored in directory: /tmp/pip-ephem-wheel-cache-8euh5ch7/wheels/7f/3d/43/e54cad8baf529ddf82f36fad1ef631cb9b306b1b14c2800142
2022-07-28T10:21:55,320 Successfully built lsst-ctrl-bps
2022-07-28T10:21:55,333 Removed build tracker: '/tmp/pip-build-tracker-nt3z24r3'