|
import logging |
|
import os |
|
import shlex |
|
import subprocess |
|
from typing import ( |
|
TYPE_CHECKING, |
|
Any, |
|
Callable, |
|
Iterable, |
|
List, |
|
Mapping, |
|
Optional, |
|
Union, |
|
) |
|
|
|
from pip._internal.cli.spinners import SpinnerInterface, open_spinner |
|
from pip._internal.exceptions import InstallationSubprocessError |
|
from pip._internal.utils.logging import VERBOSE, subprocess_logger |
|
from pip._internal.utils.misc import HiddenText |
|
|
|
if TYPE_CHECKING: |
|
|
|
|
|
|
|
from typing import Literal |
|
|
|
CommandArgs = List[Union[str, HiddenText]] |
|
|
|
|
|
LOG_DIVIDER = "----------------------------------------" |
|
|
|
|
|
def make_command(*args: Union[str, HiddenText, CommandArgs]) -> CommandArgs: |
|
""" |
|
Create a CommandArgs object. |
|
""" |
|
command_args: CommandArgs = [] |
|
for arg in args: |
|
|
|
|
|
if isinstance(arg, list): |
|
command_args.extend(arg) |
|
else: |
|
|
|
command_args.append(arg) |
|
|
|
return command_args |
|
|
|
|
|
def format_command_args(args: Union[List[str], CommandArgs]) -> str: |
|
""" |
|
Format command arguments for display. |
|
""" |
|
|
|
|
|
|
|
|
|
|
|
return " ".join( |
|
shlex.quote(str(arg)) if isinstance(arg, HiddenText) else shlex.quote(arg) |
|
for arg in args |
|
) |
|
|
|
|
|
def reveal_command_args(args: Union[List[str], CommandArgs]) -> List[str]: |
|
""" |
|
Return the arguments in their raw, unredacted form. |
|
""" |
|
return [arg.secret if isinstance(arg, HiddenText) else arg for arg in args] |
|
|
|
|
|
def make_subprocess_output_error( |
|
cmd_args: Union[List[str], CommandArgs], |
|
cwd: Optional[str], |
|
lines: List[str], |
|
exit_status: int, |
|
) -> str: |
|
""" |
|
Create and return the error message to use to log a subprocess error |
|
with command output. |
|
|
|
:param lines: A list of lines, each ending with a newline. |
|
""" |
|
command = format_command_args(cmd_args) |
|
|
|
|
|
output = "".join(lines) |
|
msg = ( |
|
|
|
|
|
|
|
"Command errored out with exit status {exit_status}:\n" |
|
" command: {command_display}\n" |
|
" cwd: {cwd_display}\n" |
|
"Complete output ({line_count} lines):\n{output}{divider}" |
|
).format( |
|
exit_status=exit_status, |
|
command_display=command, |
|
cwd_display=cwd, |
|
line_count=len(lines), |
|
output=output, |
|
divider=LOG_DIVIDER, |
|
) |
|
return msg |
|
|
|
|
|
def call_subprocess( |
|
cmd: Union[List[str], CommandArgs], |
|
show_stdout: bool = False, |
|
cwd: Optional[str] = None, |
|
on_returncode: 'Literal["raise", "warn", "ignore"]' = "raise", |
|
extra_ok_returncodes: Optional[Iterable[int]] = None, |
|
command_desc: Optional[str] = None, |
|
extra_environ: Optional[Mapping[str, Any]] = None, |
|
unset_environ: Optional[Iterable[str]] = None, |
|
spinner: Optional[SpinnerInterface] = None, |
|
log_failed_cmd: Optional[bool] = True, |
|
stdout_only: Optional[bool] = False, |
|
) -> str: |
|
""" |
|
Args: |
|
show_stdout: if true, use INFO to log the subprocess's stderr and |
|
stdout streams. Otherwise, use DEBUG. Defaults to False. |
|
extra_ok_returncodes: an iterable of integer return codes that are |
|
acceptable, in addition to 0. Defaults to None, which means []. |
|
unset_environ: an iterable of environment variable names to unset |
|
prior to calling subprocess.Popen(). |
|
log_failed_cmd: if false, failed commands are not logged, only raised. |
|
stdout_only: if true, return only stdout, else return both. When true, |
|
logging of both stdout and stderr occurs when the subprocess has |
|
terminated, else logging occurs as subprocess output is produced. |
|
""" |
|
if extra_ok_returncodes is None: |
|
extra_ok_returncodes = [] |
|
if unset_environ is None: |
|
unset_environ = [] |
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
|
if show_stdout: |
|
|
|
log_subprocess = subprocess_logger.info |
|
used_level = logging.INFO |
|
else: |
|
|
|
|
|
log_subprocess = subprocess_logger.verbose |
|
used_level = VERBOSE |
|
|
|
|
|
showing_subprocess = subprocess_logger.getEffectiveLevel() <= used_level |
|
|
|
|
|
|
|
use_spinner = not showing_subprocess and spinner is not None |
|
|
|
if command_desc is None: |
|
command_desc = format_command_args(cmd) |
|
|
|
log_subprocess("Running command %s", command_desc) |
|
env = os.environ.copy() |
|
if extra_environ: |
|
env.update(extra_environ) |
|
for name in unset_environ: |
|
env.pop(name, None) |
|
try: |
|
proc = subprocess.Popen( |
|
|
|
reveal_command_args(cmd), |
|
stdin=subprocess.PIPE, |
|
stdout=subprocess.PIPE, |
|
stderr=subprocess.STDOUT if not stdout_only else subprocess.PIPE, |
|
cwd=cwd, |
|
env=env, |
|
errors="backslashreplace", |
|
) |
|
except Exception as exc: |
|
if log_failed_cmd: |
|
subprocess_logger.critical( |
|
"Error %s while executing command %s", |
|
exc, |
|
command_desc, |
|
) |
|
raise |
|
all_output = [] |
|
if not stdout_only: |
|
assert proc.stdout |
|
assert proc.stdin |
|
proc.stdin.close() |
|
|
|
while True: |
|
line: str = proc.stdout.readline() |
|
if not line: |
|
break |
|
line = line.rstrip() |
|
all_output.append(line + "\n") |
|
|
|
|
|
log_subprocess(line) |
|
|
|
if use_spinner: |
|
assert spinner |
|
spinner.spin() |
|
try: |
|
proc.wait() |
|
finally: |
|
if proc.stdout: |
|
proc.stdout.close() |
|
output = "".join(all_output) |
|
else: |
|
|
|
|
|
out, err = proc.communicate() |
|
|
|
for out_line in out.splitlines(): |
|
log_subprocess(out_line) |
|
all_output.append(out) |
|
for err_line in err.splitlines(): |
|
log_subprocess(err_line) |
|
all_output.append(err) |
|
output = out |
|
|
|
proc_had_error = proc.returncode and proc.returncode not in extra_ok_returncodes |
|
if use_spinner: |
|
assert spinner |
|
if proc_had_error: |
|
spinner.finish("error") |
|
else: |
|
spinner.finish("done") |
|
if proc_had_error: |
|
if on_returncode == "raise": |
|
if not showing_subprocess and log_failed_cmd: |
|
|
|
|
|
msg = make_subprocess_output_error( |
|
cmd_args=cmd, |
|
cwd=cwd, |
|
lines=all_output, |
|
exit_status=proc.returncode, |
|
) |
|
subprocess_logger.error(msg) |
|
raise InstallationSubprocessError(proc.returncode, command_desc) |
|
elif on_returncode == "warn": |
|
subprocess_logger.warning( |
|
'Command "%s" had error code %s in %s', |
|
command_desc, |
|
proc.returncode, |
|
cwd, |
|
) |
|
elif on_returncode == "ignore": |
|
pass |
|
else: |
|
raise ValueError(f"Invalid value: on_returncode={on_returncode!r}") |
|
return output |
|
|
|
|
|
def runner_with_spinner_message(message: str) -> Callable[..., None]: |
|
"""Provide a subprocess_runner that shows a spinner message. |
|
|
|
Intended for use with for pep517's Pep517HookCaller. Thus, the runner has |
|
an API that matches what's expected by Pep517HookCaller.subprocess_runner. |
|
""" |
|
|
|
def runner( |
|
cmd: List[str], |
|
cwd: Optional[str] = None, |
|
extra_environ: Optional[Mapping[str, Any]] = None, |
|
) -> None: |
|
with open_spinner(message) as spinner: |
|
call_subprocess( |
|
cmd, |
|
cwd=cwd, |
|
extra_environ=extra_environ, |
|
spinner=spinner, |
|
) |
|
|
|
return runner |
|
|