|  | #!/usr/bin/env python | 
|  |  | 
|  | r""" | 
|  | This module provides many valuable print functions such as sprint_var, | 
|  | sprint_time, sprint_error, sprint_call_stack. | 
|  | """ | 
|  |  | 
|  | import sys | 
|  | import os | 
|  | import time | 
|  | import inspect | 
|  | import re | 
|  | import grp | 
|  | import socket | 
|  | import argparse | 
|  | import __builtin__ | 
|  | import logging | 
|  | import collections | 
|  |  | 
|  | running_from_robot = 1 | 
|  | try: | 
|  | from robot.utils import DotDict | 
|  | except ImportError: | 
|  | running_from_robot = 0 | 
|  |  | 
|  | import gen_arg as ga | 
|  |  | 
|  | # Setting these variables for use both inside this module and by programs | 
|  | # importing this module. | 
|  | pgm_dir_path = sys.argv[0] | 
|  | pgm_name = os.path.basename(pgm_dir_path) | 
|  | pgm_dir_name = re.sub("/" + pgm_name, "", pgm_dir_path) + "/" | 
|  |  | 
|  |  | 
|  | # Some functions (e.g. sprint_pgm_header) have need of a program name value | 
|  | # that looks more like a valid variable name.  Therefore, we'll swap odd | 
|  | # characters like "." out for underscores. | 
|  | pgm_name_var_name = pgm_name.replace(".", "_") | 
|  |  | 
|  | # Initialize global values used as defaults by print_time, print_var, etc. | 
|  | col1_indent = 0 | 
|  |  | 
|  | # Calculate default column width for print_var functions based on environment | 
|  | # variable settings.  The objective is to make the variable values line up | 
|  | # nicely with the time stamps. | 
|  | col1_width = 29 | 
|  | if 'NANOSECONDS' in os.environ: | 
|  | NANOSECONDS = os.environ['NANOSECONDS'] | 
|  | else: | 
|  | NANOSECONDS = 0 | 
|  |  | 
|  | if NANOSECONDS == "1": | 
|  | col1_width = col1_width + 7 | 
|  |  | 
|  | if 'SHOW_ELAPSED_TIME' in os.environ: | 
|  | SHOW_ELAPSED_TIME = os.environ['SHOW_ELAPSED_TIME'] | 
|  | else: | 
|  | SHOW_ELAPSED_TIME = 0 | 
|  |  | 
|  | if SHOW_ELAPSED_TIME == "1": | 
|  | if NANOSECONDS == "1": | 
|  | col1_width = col1_width + 14 | 
|  | else: | 
|  | col1_width = col1_width + 7 | 
|  |  | 
|  | # Initialize some time variables used in module functions. | 
|  | start_time = time.time() | 
|  | sprint_time_last_seconds = start_time | 
|  |  | 
|  | try: | 
|  | # The user can set environment variable "GEN_PRINT_DEBUG" to get debug | 
|  | # output from this module. | 
|  | gen_print_debug = os.environ['GEN_PRINT_DEBUG'] | 
|  | except KeyError: | 
|  | gen_print_debug = 0 | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_func_name(stack_frame_ix=None): | 
|  |  | 
|  | r""" | 
|  | Return the function name associated with the indicated stack frame. | 
|  |  | 
|  | Description of arguments: | 
|  | stack_frame_ix                  The index of the stack frame whose | 
|  | function name should be returned.  If the | 
|  | caller does not specifiy a value, this | 
|  | function will set the value to 1 which is | 
|  | the index of the caller's stack frame.  If | 
|  | the caller is the wrapper function | 
|  | "print_func_name", this function will bump | 
|  | it up by 1. | 
|  | """ | 
|  |  | 
|  | # If user specified no stack_frame_ix, we'll set it to a proper default | 
|  | # value. | 
|  | if stack_frame_ix is None: | 
|  | func_name = sys._getframe().f_code.co_name | 
|  | caller_func_name = sys._getframe(1).f_code.co_name | 
|  | if func_name[1:] == caller_func_name: | 
|  | stack_frame_ix = 2 | 
|  | else: | 
|  | stack_frame_ix = 1 | 
|  |  | 
|  | func_name = sys._getframe(stack_frame_ix).f_code.co_name | 
|  |  | 
|  | return func_name | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | # get_arg_name is not a print function per se.  I have included it in this | 
|  | # module because it is used by sprint_var which is found in this module. | 
|  | ############################################################################### | 
|  | def get_arg_name(var, | 
|  | arg_num=1, | 
|  | stack_frame_ix=1): | 
|  |  | 
|  | r""" | 
|  | Return the "name" of an argument passed to a function.  This could be a | 
|  | literal or a variable name. | 
|  |  | 
|  | Description of arguements: | 
|  | var                             The variable whose name you want returned. | 
|  | arg_num                         The arg number (1 through n) whose name | 
|  | you wish to have returned.  This value | 
|  | should not exceed the number of arguments | 
|  | allowed by the target function. | 
|  | stack_frame_ix                  The stack frame index of the target | 
|  | function.  This value must be 1 or | 
|  | greater.  1 would indicate get_arg_name's | 
|  | stack frame.  2 would be the caller of | 
|  | get_arg_name's stack frame, etc. | 
|  |  | 
|  | Example 1: | 
|  |  | 
|  | my_var = "mike" | 
|  | var_name = get_arg_name(my_var) | 
|  |  | 
|  | In this example, var_name will receive the value "my_var". | 
|  |  | 
|  | Example 2: | 
|  |  | 
|  | def test1(var): | 
|  | # Getting the var name of the first arg to this function, test1. | 
|  | # Note, in this case, it doesn't matter what you pass as the first arg | 
|  | # to get_arg_name since it is the caller's variable name that matters. | 
|  | dummy = 1 | 
|  | arg_num = 1 | 
|  | stack_frame = 2 | 
|  | var_name = get_arg_name(dummy, arg_num, stack_frame) | 
|  |  | 
|  | # Mainline... | 
|  |  | 
|  | another_var = "whatever" | 
|  | test1(another_var) | 
|  |  | 
|  | In this example, var_name will be set to "another_var". | 
|  |  | 
|  | """ | 
|  |  | 
|  | # Note: I wish to avoid recursion so I refrain from calling any function | 
|  | # that calls this function (i.e. sprint_var, valid_value, etc.). | 
|  |  | 
|  | try: | 
|  | # The user can set environment variable "GET_ARG_NAME_DEBUG" to get | 
|  | # debug output from this function. | 
|  | local_debug = os.environ['GET_ARG_NAME_DEBUG'] | 
|  | except KeyError: | 
|  | local_debug = 0 | 
|  |  | 
|  | if arg_num < 1: | 
|  | print_error("Programmer error - Variable \"arg_num\" has an invalid" + | 
|  | " value of \"" + str(arg_num) + "\".  The value must be" + | 
|  | " an integer that is greater than 0.\n") | 
|  | # What is the best way to handle errors?  Raise exception?  I'll | 
|  | # revisit later. | 
|  | return | 
|  | if stack_frame_ix < 1: | 
|  | print_error("Programmer error - Variable \"stack_frame_ix\" has an" + | 
|  | " invalid value of \"" + str(stack_frame_ix) + "\".  The" + | 
|  | " value must be an integer that is greater than or equal" + | 
|  | " to 1.\n") | 
|  | return | 
|  |  | 
|  | if local_debug: | 
|  | debug_indent = 2 | 
|  | print(sprint_func_name() + "() parms:") | 
|  | print_varx("var", var, 0, debug_indent) | 
|  | print_varx("arg_num", arg_num, 0, debug_indent) | 
|  | print_varx("stack_frame_ix", stack_frame_ix, 0, debug_indent) | 
|  |  | 
|  | try: | 
|  | frame, filename, cur_line_no, function_name, lines, index = \ | 
|  | inspect.stack()[stack_frame_ix] | 
|  | except IndexError: | 
|  | print_error("Programmer error - The caller has asked for information" + | 
|  | " about the stack frame at index \"" + | 
|  | str(stack_frame_ix) + "\".  However, the stack only" + | 
|  | " contains " + str(len(inspect.stack())) + " entries." + | 
|  | "  Therefore the stack frame index is out of range.\n") | 
|  | return | 
|  |  | 
|  | if local_debug: | 
|  | print("\nVariables retrieved from inspect.stack() function:") | 
|  | print_varx("frame", frame, 0, debug_indent) | 
|  | print_varx("filename", filename, 0, debug_indent) | 
|  | print_varx("cur_line_no", cur_line_no, 0, debug_indent) | 
|  | print_varx("function_name", function_name, 0, debug_indent) | 
|  | print_varx("lines", lines, 0, debug_indent) | 
|  | print_varx("index", index, 0, debug_indent) | 
|  |  | 
|  | composite_line = lines[0].strip() | 
|  |  | 
|  | called_func_name = sprint_func_name(stack_frame_ix) | 
|  | # Needed to add a right anchor to func_regex for cases like this where | 
|  | # there is an arg whose name is a substring of the function name.  So the | 
|  | # function name needs to be bounded on the right by zero or more spaces | 
|  | # and a left parenthesis. | 
|  | # if not valid_value(whatever, valid_values=["one", "two"]): | 
|  | func_regex = ".*" + called_func_name + "[ ]*\(" | 
|  | # if not re.match(r".*" + called_func_name, composite_line): | 
|  | if not re.match(func_regex, composite_line): | 
|  | # The called function name was not found in the composite line.  The | 
|  | # caller may be using a function alias. | 
|  | # I added code to handle pvar, qpvar, dpvar, etc. aliases. | 
|  | # pvar is an alias for print_var.  However, when it is used, | 
|  | # sprint_func_name() returns the non-alias version, i.e. "print_var". | 
|  | # Adjusting for that here. | 
|  | alias = re.sub("print_var", "pvar", called_func_name) | 
|  | if local_debug: | 
|  | print_varx("alias", alias, 0, debug_indent) | 
|  | called_func_name = alias | 
|  | func_regex = ".*" + called_func_name + "[ ]*\(" | 
|  |  | 
|  | # arg_list_etc = re.sub(".*" + called_func_name, "", composite_line) | 
|  | arg_list_etc = "(" + re.sub(func_regex, "", composite_line) | 
|  | if local_debug: | 
|  | print_varx("func_regex", func_regex, 0, debug_indent) | 
|  | print_varx("called_func_name", called_func_name, 0, debug_indent) | 
|  | print_varx("composite_line", composite_line, 0, debug_indent) | 
|  | print_varx("arg_list_etc", arg_list_etc, 0, debug_indent) | 
|  |  | 
|  | # Parse arg list... | 
|  | # Initialize... | 
|  | nest_level = -1 | 
|  | arg_ix = 0 | 
|  | args_list = [""] | 
|  | for ix in range(0, len(arg_list_etc)): | 
|  | char = arg_list_etc[ix] | 
|  | # Set the nest_level based on whether we've encounted a parenthesis. | 
|  | if char == "(": | 
|  | nest_level += 1 | 
|  | if nest_level == 0: | 
|  | continue | 
|  | elif char == ")": | 
|  | nest_level -= 1 | 
|  | if nest_level < 0: | 
|  | break | 
|  |  | 
|  | # If we reach a comma at base nest level, we are done processing an | 
|  | # argument so we increment arg_ix and initialize a new args_list entry. | 
|  | if char == "," and nest_level == 0: | 
|  | arg_ix += 1 | 
|  | args_list.append("") | 
|  | continue | 
|  |  | 
|  | # For any other character, we append it it to the current arg list | 
|  | # entry. | 
|  | args_list[arg_ix] += char | 
|  |  | 
|  | # Trim whitespace from each list entry. | 
|  | args_list = [arg.strip() for arg in args_list] | 
|  |  | 
|  | if arg_num > len(args_list): | 
|  | print_error("Programmer error - The caller has asked for the name of" + | 
|  | " argument number \"" + str(arg_num) + "\" but there " + | 
|  | "were only \"" + str(len(args_list)) + "\" args used:\n" + | 
|  | sprint_varx("args_list", args_list)) | 
|  | return | 
|  |  | 
|  | argument = args_list[arg_num - 1] | 
|  |  | 
|  | if local_debug: | 
|  | print_varx("args_list", args_list, 0, debug_indent) | 
|  | print_varx("argument", argument, 0, debug_indent) | 
|  |  | 
|  | return argument | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_time(buffer=""): | 
|  |  | 
|  | r""" | 
|  | Return the time in the following format. | 
|  |  | 
|  | Example: | 
|  |  | 
|  | The following python code... | 
|  |  | 
|  | sys.stdout.write(sprint_time()) | 
|  | sys.stdout.write("Hi.\n") | 
|  |  | 
|  | Will result in the following type of output: | 
|  |  | 
|  | #(CDT) 2016/07/08 15:25:35 - Hi. | 
|  |  | 
|  | Example: | 
|  |  | 
|  | The following python code... | 
|  |  | 
|  | sys.stdout.write(sprint_time("Hi.\n")) | 
|  |  | 
|  | Will result in the following type of output: | 
|  |  | 
|  | #(CDT) 2016/08/03 17:12:05 - Hi. | 
|  |  | 
|  | The following environment variables will affect the formatting as | 
|  | described: | 
|  | NANOSECONDS                     This will cause the time stamps to be | 
|  | precise to the microsecond (Yes, it | 
|  | probably should have been named | 
|  | MICROSECONDS but the convention was set | 
|  | long ago so we're sticking with it). | 
|  | Example of the output when environment | 
|  | variable NANOSECONDS=1. | 
|  |  | 
|  | #(CDT) 2016/08/03 17:16:25.510469 - Hi. | 
|  |  | 
|  | SHOW_ELAPSED_TIME               This will cause the elapsed time to be | 
|  | included in the output.  This is the | 
|  | amount of time that has elapsed since the | 
|  | last time this function was called.  The | 
|  | precision of the elapsed time field is | 
|  | also affected by the value of the | 
|  | NANOSECONDS environment variable.  Example | 
|  | of the output when environment variable | 
|  | NANOSECONDS=0 and SHOW_ELAPSED_TIME=1. | 
|  |  | 
|  | #(CDT) 2016/08/03 17:17:40 -    0 - Hi. | 
|  |  | 
|  | Example of the output when environment variable NANOSECONDS=1 and | 
|  | SHOW_ELAPSED_TIME=1. | 
|  |  | 
|  | #(CDT) 2016/08/03 17:18:47.317339 -    0.000046 - Hi. | 
|  |  | 
|  | Description of arguments. | 
|  | buffer                          This will be appended to the formatted | 
|  | time string. | 
|  | """ | 
|  |  | 
|  | global NANOSECONDS | 
|  | global SHOW_ELAPSED_TIME | 
|  | global sprint_time_last_seconds | 
|  |  | 
|  | seconds = time.time() | 
|  | loc_time = time.localtime(seconds) | 
|  | nanoseconds = "%0.6f" % seconds | 
|  | pos = nanoseconds.find(".") | 
|  | nanoseconds = nanoseconds[pos:] | 
|  |  | 
|  | time_string = time.strftime("#(%Z) %Y/%m/%d %H:%M:%S", loc_time) | 
|  | if NANOSECONDS == "1": | 
|  | time_string = time_string + nanoseconds | 
|  |  | 
|  | if SHOW_ELAPSED_TIME == "1": | 
|  | cur_time_seconds = seconds | 
|  | math_string = "%9.9f" % cur_time_seconds + " - " + "%9.9f" % \ | 
|  | sprint_time_last_seconds | 
|  | elapsed_seconds = eval(math_string) | 
|  | if NANOSECONDS == "1": | 
|  | elapsed_seconds = "%11.6f" % elapsed_seconds | 
|  | else: | 
|  | elapsed_seconds = "%4i" % elapsed_seconds | 
|  | sprint_time_last_seconds = cur_time_seconds | 
|  | time_string = time_string + " - " + elapsed_seconds | 
|  |  | 
|  | return time_string + " - " + buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_timen(buffer=""): | 
|  |  | 
|  | r""" | 
|  | Append a line feed to the buffer, pass it to sprint_time and return the | 
|  | result. | 
|  | """ | 
|  |  | 
|  | return sprint_time(buffer + "\n") | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_error(buffer=""): | 
|  |  | 
|  | r""" | 
|  | Return a standardized error string.  This includes: | 
|  | - A time stamp | 
|  | - The "**ERROR**" string | 
|  | - The caller's buffer string. | 
|  |  | 
|  | Example: | 
|  |  | 
|  | The following python code... | 
|  |  | 
|  | print(sprint_error("Oops.\n")) | 
|  |  | 
|  | Will result in the following type of output: | 
|  |  | 
|  | #(CDT) 2016/08/03 17:12:05 - **ERROR** Oops. | 
|  |  | 
|  | Description of arguments. | 
|  | buffer                          This will be appended to the formatted | 
|  | error string. | 
|  | """ | 
|  |  | 
|  | return sprint_time() + "**ERROR** " + buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_varx(var_name, | 
|  | var_value, | 
|  | hex=0, | 
|  | loc_col1_indent=col1_indent, | 
|  | loc_col1_width=col1_width, | 
|  | trailing_char="\n"): | 
|  |  | 
|  | r""" | 
|  | Print the var name/value passed to it.  If the caller lets loc_col1_width | 
|  | default, the printing lines up nicely with output generated by the | 
|  | print_time functions. | 
|  |  | 
|  | Note that the sprint_var function (defined below) can be used to call this | 
|  | function so that the programmer does not need to pass the var_name. | 
|  | sprint_var will figure out the var_name.  The sprint_var function is the | 
|  | one that would normally be used by the general user. | 
|  |  | 
|  | For example, the following python code: | 
|  |  | 
|  | first_name = "Mike" | 
|  | print_time("Doing this...\n") | 
|  | print_varx("first_name", first_name) | 
|  | print_time("Doing that...\n") | 
|  |  | 
|  | Will generate output like this: | 
|  |  | 
|  | #(CDT) 2016/08/10 17:34:42.847374 -    0.001285 - Doing this... | 
|  | first_name:                                       Mike | 
|  | #(CDT) 2016/08/10 17:34:42.847510 -    0.000136 - Doing that... | 
|  |  | 
|  | This function recognizes several complex types of data such as dict, list | 
|  | or tuple. | 
|  |  | 
|  | For example, the following python code: | 
|  |  | 
|  | my_dict = dict(one=1, two=2, three=3) | 
|  | print_var(my_dict) | 
|  |  | 
|  | Will generate the following output: | 
|  |  | 
|  | my_dict: | 
|  | my_dict[three]:                                 3 | 
|  | my_dict[two]:                                   2 | 
|  | my_dict[one]:                                   1 | 
|  |  | 
|  | Description of arguments. | 
|  | var_name                        The name of the variable to be printed. | 
|  | var_value                       The value of the variable to be printed. | 
|  | hex                             This indicates that the value should be | 
|  | printed in hex format.  It is the user's | 
|  | responsibility to ensure that a var_value | 
|  | contains a valid hex number.  For string | 
|  | var_values, this will be interpreted as | 
|  | show_blanks which means that blank values | 
|  | will be printed as "<blank>". | 
|  | loc_col1_indent                 The number of spaces to indent the output. | 
|  | loc_col1_width                  The width of the output column containing | 
|  | the variable name.  The default value of | 
|  | this is adjusted so that the var_value | 
|  | lines up with text printed via the | 
|  | print_time function. | 
|  | trailing_char                   The character to be used at the end of the | 
|  | returned string.  The default value is a | 
|  | line feed. | 
|  | """ | 
|  |  | 
|  | # Determine the type | 
|  | if type(var_value) in (int, float, bool, str, unicode) \ | 
|  | or var_value is None: | 
|  | # The data type is simple in the sense that it has no subordinate | 
|  | # parts. | 
|  | # Adjust loc_col1_width. | 
|  | loc_col1_width = loc_col1_width - loc_col1_indent | 
|  | # See if the user wants the output in hex format. | 
|  | if hex: | 
|  | if type(var_value) in (str, unicode): | 
|  | value_format = "%s" | 
|  | if var_value is "": | 
|  | var_value = "<blank>" | 
|  | else: | 
|  | value_format = "0x%08x" | 
|  | else: | 
|  | value_format = "%s" | 
|  | format_string = "%" + str(loc_col1_indent) + "s%-" \ | 
|  | + str(loc_col1_width) + "s" + value_format + trailing_char | 
|  | return format_string % ("", var_name + ":", var_value) | 
|  | else: | 
|  | # The data type is complex in the sense that it has subordinate parts. | 
|  | format_string = "%" + str(loc_col1_indent) + "s%s\n" | 
|  | buffer = format_string % ("", var_name + ":") | 
|  | loc_col1_indent += 2 | 
|  | try: | 
|  | length = len(var_value) | 
|  | except TypeError: | 
|  | pass | 
|  | ix = 0 | 
|  | loc_trailing_char = "\n" | 
|  | type_is_dict = 0 | 
|  | try: | 
|  | if type(var_value) in (dict, collections.OrderedDict): | 
|  | type_is_dict = 1 | 
|  | except AttributeError: | 
|  | type_is_dict = 0 | 
|  | if running_from_robot: | 
|  | if type(var_value) is DotDict: | 
|  | type_is_dict = 1 | 
|  | if type_is_dict: | 
|  | for key, value in var_value.iteritems(): | 
|  | ix += 1 | 
|  | if ix == length: | 
|  | loc_trailing_char = trailing_char | 
|  | buffer += sprint_varx(var_name + "[" + key + "]", value, hex, | 
|  | loc_col1_indent, loc_col1_width, | 
|  | loc_trailing_char) | 
|  | elif type(var_value) in (list, tuple, set): | 
|  | for key, value in enumerate(var_value): | 
|  | ix += 1 | 
|  | if ix == length: | 
|  | loc_trailing_char = trailing_char | 
|  | buffer += sprint_varx(var_name + "[" + str(key) + "]", value, | 
|  | hex, loc_col1_indent, loc_col1_width, | 
|  | loc_trailing_char) | 
|  | elif type(var_value) is argparse.Namespace: | 
|  | for key in var_value.__dict__: | 
|  | ix += 1 | 
|  | if ix == length: | 
|  | loc_trailing_char = trailing_char | 
|  | cmd_buf = "buffer += sprint_varx(var_name + \".\" + str(key)" \ | 
|  | + ", var_value." + key + ", hex, loc_col1_indent," \ | 
|  | + " loc_col1_width, loc_trailing_char)" | 
|  | exec(cmd_buf) | 
|  | else: | 
|  | var_type = type(var_value).__name__ | 
|  | func_name = sys._getframe().f_code.co_name | 
|  | var_value = "<" + var_type + " type not supported by " + \ | 
|  | func_name + "()>" | 
|  | value_format = "%s" | 
|  | loc_col1_indent -= 2 | 
|  | # Adjust loc_col1_width. | 
|  | loc_col1_width = loc_col1_width - loc_col1_indent | 
|  | format_string = "%" + str(loc_col1_indent) + "s%-" \ | 
|  | + str(loc_col1_width) + "s" + value_format + trailing_char | 
|  | return format_string % ("", var_name + ":", var_value) | 
|  | return buffer | 
|  |  | 
|  | return "" | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_var(*args): | 
|  |  | 
|  | r""" | 
|  | Figure out the name of the first argument for you and then call | 
|  | sprint_varx with it.  Therefore, the following 2 calls are equivalent: | 
|  | sprint_varx("var1", var1) | 
|  | sprint_var(var1) | 
|  | """ | 
|  |  | 
|  | # Get the name of the first variable passed to this function. | 
|  | stack_frame = 2 | 
|  | caller_func_name = sprint_func_name(2) | 
|  | if caller_func_name.endswith("print_var"): | 
|  | stack_frame += 1 | 
|  | var_name = get_arg_name(None, 1, stack_frame) | 
|  | return sprint_varx(var_name, *args) | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def lprint_varx(var_name, | 
|  | var_value, | 
|  | hex=0, | 
|  | loc_col1_indent=col1_indent, | 
|  | loc_col1_width=col1_width, | 
|  | log_level=getattr(logging, 'INFO')): | 
|  |  | 
|  | r""" | 
|  | Send sprint_varx output to logging. | 
|  | """ | 
|  |  | 
|  | logging.log(log_level, sprint_varx(var_name, var_value, hex, | 
|  | loc_col1_indent, loc_col1_width, "")) | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def lprint_var(*args): | 
|  |  | 
|  | r""" | 
|  | Figure out the name of the first argument for you and then call | 
|  | lprint_varx with it.  Therefore, the following 2 calls are equivalent: | 
|  | lprint_varx("var1", var1) | 
|  | lprint_var(var1) | 
|  | """ | 
|  |  | 
|  | # Get the name of the first variable passed to this function. | 
|  | stack_frame = 2 | 
|  | caller_func_name = sprint_func_name(2) | 
|  | if caller_func_name.endswith("print_var"): | 
|  | stack_frame += 1 | 
|  | var_name = get_arg_name(None, 1, stack_frame) | 
|  | lprint_varx(var_name, *args) | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_dashes(indent=col1_indent, | 
|  | width=80, | 
|  | line_feed=1, | 
|  | char="-"): | 
|  |  | 
|  | r""" | 
|  | Return a string of dashes to the caller. | 
|  |  | 
|  | Description of arguements: | 
|  | indent                          The number of characters to indent the | 
|  | output. | 
|  | width                           The width of the string of dashes. | 
|  | line_feed                       Indicates whether the output should end | 
|  | with a line feed. | 
|  | char                            The character to be repeated in the output | 
|  | string. | 
|  | """ | 
|  |  | 
|  | width = int(width) | 
|  | buffer = " "*int(indent) + char*width | 
|  | if line_feed: | 
|  | buffer += "\n" | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sindent(text="", | 
|  | indent=0): | 
|  |  | 
|  | r""" | 
|  | Pre-pend the specified number of characters to the text string (i.e. | 
|  | indent it) and return it. | 
|  |  | 
|  | Description of arguments: | 
|  | text                            The string to be indented. | 
|  | indent                          The number of characters to indent the | 
|  | string. | 
|  | """ | 
|  |  | 
|  | format_string = "%" + str(indent) + "s%s" | 
|  | buffer = format_string % ("", text) | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_call_stack(indent=0, | 
|  | stack_frame_ix=0): | 
|  |  | 
|  | r""" | 
|  | Return a call stack report for the given point in the program with line | 
|  | numbers, function names and function parameters and arguments. | 
|  |  | 
|  | Sample output: | 
|  |  | 
|  | ------------------------------------------------------------------------- | 
|  | Python function call stack | 
|  |  | 
|  | Line # Function name and arguments | 
|  | ------ ------------------------------------------------------------------ | 
|  | 424 sprint_call_stack () | 
|  | 4 print_call_stack () | 
|  | 31 func1 (last_name = 'walsh', first_name = 'mikey') | 
|  | 59 /tmp/scr5.py | 
|  | ------------------------------------------------------------------------- | 
|  |  | 
|  | Description of arguments: | 
|  | indent                          The number of characters to indent each | 
|  | line of output. | 
|  | stack_frame_ix                  The index of the first stack frame which | 
|  | is to be returned. | 
|  | """ | 
|  |  | 
|  | buffer = "" | 
|  | buffer += sprint_dashes(indent) | 
|  | buffer += sindent("Python function call stack\n\n", indent) | 
|  | buffer += sindent("Line # Function name and arguments\n", indent) | 
|  | buffer += sprint_dashes(indent, 6, 0) + " " + sprint_dashes(0, 73) | 
|  |  | 
|  | # Grab the current program stack. | 
|  | current_stack = inspect.stack() | 
|  |  | 
|  | # Process each frame in turn. | 
|  | format_string = "%6s %s\n" | 
|  | ix = 0 | 
|  | for stack_frame in current_stack: | 
|  | if ix < stack_frame_ix: | 
|  | ix += 1 | 
|  | continue | 
|  | lineno = str(stack_frame[2]) | 
|  | func_name = str(stack_frame[3]) | 
|  | if func_name == "?": | 
|  | # "?" is the name used when code is not in a function. | 
|  | func_name = "(none)" | 
|  |  | 
|  | if func_name == "<module>": | 
|  | # If the func_name is the "main" program, we simply get the | 
|  | # command line call string. | 
|  | func_and_args = ' '.join(sys.argv) | 
|  | else: | 
|  | # Get the program arguments. | 
|  | arg_vals = inspect.getargvalues(stack_frame[0]) | 
|  | function_parms = arg_vals[0] | 
|  | frame_locals = arg_vals[3] | 
|  |  | 
|  | args_list = [] | 
|  | for arg_name in function_parms: | 
|  | # Get the arg value from frame locals. | 
|  | arg_value = frame_locals[arg_name] | 
|  | args_list.append(arg_name + " = " + repr(arg_value)) | 
|  | args_str = "(" + ', '.join(map(str, args_list)) + ")" | 
|  |  | 
|  | # Now we need to print this in a nicely-wrapped way. | 
|  | func_and_args = func_name + " " + args_str | 
|  |  | 
|  | buffer += sindent(format_string % (lineno, func_and_args), indent) | 
|  | ix += 1 | 
|  |  | 
|  | buffer += sprint_dashes(indent) | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_executing(stack_frame_ix=None): | 
|  |  | 
|  | r""" | 
|  | Print a line indicating what function is executing and with what parameter | 
|  | values.  This is useful for debugging. | 
|  |  | 
|  | Sample output: | 
|  |  | 
|  | #(CDT) 2016/08/25 17:54:27 - Executing: func1 (x = 1) | 
|  |  | 
|  | Description of arguments: | 
|  | stack_frame_ix                  The index of the stack frame whose | 
|  | function info should be returned.  If the | 
|  | caller does not specifiy a value, this | 
|  | function will set the value to 1 which is | 
|  | the index of the caller's stack frame.  If | 
|  | the caller is the wrapper function | 
|  | "print_executing", this function will bump | 
|  | it up by 1. | 
|  | """ | 
|  |  | 
|  | # If user wants default stack_frame_ix. | 
|  | if stack_frame_ix is None: | 
|  | func_name = sys._getframe().f_code.co_name | 
|  | caller_func_name = sys._getframe(1).f_code.co_name | 
|  | if caller_func_name.endswith(func_name[1:]): | 
|  | stack_frame_ix = 2 | 
|  | else: | 
|  | stack_frame_ix = 1 | 
|  |  | 
|  | stack_frame = inspect.stack()[stack_frame_ix] | 
|  |  | 
|  | func_name = str(stack_frame[3]) | 
|  | if func_name == "?": | 
|  | # "?" is the name used when code is not in a function. | 
|  | func_name = "(none)" | 
|  |  | 
|  | if func_name == "<module>": | 
|  | # If the func_name is the "main" program, we simply get the command | 
|  | # line call string. | 
|  | func_and_args = ' '.join(sys.argv) | 
|  | else: | 
|  | # Get the program arguments. | 
|  | arg_vals = inspect.getargvalues(stack_frame[0]) | 
|  | function_parms = arg_vals[0] | 
|  | frame_locals = arg_vals[3] | 
|  |  | 
|  | args_list = [] | 
|  | for arg_name in function_parms: | 
|  | # Get the arg value from frame locals. | 
|  | arg_value = frame_locals[arg_name] | 
|  | args_list.append(arg_name + " = " + repr(arg_value)) | 
|  | args_str = "(" + ', '.join(map(str, args_list)) + ")" | 
|  |  | 
|  | # Now we need to print this in a nicely-wrapped way. | 
|  | func_and_args = func_name + " " + args_str | 
|  |  | 
|  | return sprint_time() + "Executing: " + func_and_args + "\n" | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_pgm_header(indent=0, | 
|  | linefeed=1): | 
|  |  | 
|  | r""" | 
|  | Return a standardized header that programs should print at the beginning | 
|  | of the run.  It includes useful information like command line, pid, | 
|  | userid, program parameters, etc. | 
|  |  | 
|  | Description of arguments: | 
|  | indent                          The number of characters to indent each | 
|  | line of output. | 
|  | linefeed                        Indicates whether a line feed be included | 
|  | at the beginning and end of the report. | 
|  | """ | 
|  |  | 
|  | loc_col1_width = col1_width + indent | 
|  |  | 
|  | buffer = "" | 
|  | if linefeed: | 
|  | buffer = "\n" | 
|  |  | 
|  | buffer += sindent(sprint_time() + "Running " + pgm_name + ".\n", indent) | 
|  | buffer += sindent(sprint_time() + "Program parameter values, etc.:\n\n", | 
|  | indent) | 
|  | buffer += sprint_varx("command_line", ' '.join(sys.argv), 0, indent, | 
|  | loc_col1_width) | 
|  | # We want the output to show a customized name for the pid and pgid but | 
|  | # we want it to look like a valid variable name.  Therefore, we'll use | 
|  | # pgm_name_var_name which was set when this module was imported. | 
|  | buffer += sprint_varx(pgm_name_var_name + "_pid", os.getpid(), 0, indent, | 
|  | loc_col1_width) | 
|  | buffer += sprint_varx(pgm_name_var_name + "_pgid", os.getpgrp(), 0, indent, | 
|  | loc_col1_width) | 
|  | buffer += sprint_varx("uid", str(os.geteuid()) + " (" + os.getlogin() + | 
|  | ")", 0, indent, loc_col1_width) | 
|  | buffer += sprint_varx("gid", str(os.getgid()) + " (" + | 
|  | str(grp.getgrgid(os.getgid()).gr_name) + ")", 0, | 
|  | indent, loc_col1_width) | 
|  | buffer += sprint_varx("host_name", socket.gethostname(), 0, indent, | 
|  | loc_col1_width) | 
|  | buffer += sprint_varx("DISPLAY", os.environ['DISPLAY'], 0, indent, | 
|  | loc_col1_width) | 
|  | # I want to add code to print caller's parms. | 
|  |  | 
|  | # __builtin__.arg_obj is created by the get_arg module function, | 
|  | # gen_get_options. | 
|  | try: | 
|  | buffer += ga.sprint_args(__builtin__.arg_obj, indent) | 
|  | except AttributeError: | 
|  | pass | 
|  |  | 
|  | if linefeed: | 
|  | buffer += "\n" | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_error_report(error_text="\n", | 
|  | indent=2): | 
|  |  | 
|  | r""" | 
|  | Return a string with a standardized report which includes the caller's | 
|  | error text, the call stack and the program header. | 
|  |  | 
|  | Description of args: | 
|  | error_text                      The error text to be included in the | 
|  | report.  The caller should include any | 
|  | needed linefeeds. | 
|  | indent                          The number of characters to indent each | 
|  | line of output. | 
|  | """ | 
|  |  | 
|  | buffer = "" | 
|  | buffer += sprint_dashes(width=120, char="=") | 
|  | buffer += sprint_error(error_text) | 
|  | buffer += "\n" | 
|  | # Calling sprint_call_stack with stack_frame_ix of 0 causes it to show | 
|  | # itself and this function in the call stack.  This is not helpful to a | 
|  | # debugger and is therefore clutter.  We will adjust the stack_frame_ix to | 
|  | # hide that information. | 
|  | stack_frame_ix = 2 | 
|  | caller_func_name = sprint_func_name(2) | 
|  | if caller_func_name.endswith("print_error_report"): | 
|  | stack_frame_ix += 1 | 
|  | buffer += sprint_call_stack(indent, stack_frame_ix) | 
|  | buffer += sprint_pgm_header(indent) | 
|  | buffer += sprint_dashes(width=120, char="=") | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sissuing(cmd_buf, | 
|  | test_mode=0): | 
|  |  | 
|  | r""" | 
|  | Return a line indicating a command that the program is about to execute. | 
|  |  | 
|  | Sample output for a cmd_buf of "ls" | 
|  |  | 
|  | #(CDT) 2016/08/25 17:57:36 - Issuing: ls | 
|  |  | 
|  | Description of args: | 
|  | cmd_buf                         The command to be executed by caller. | 
|  | test_mode                       With test_mode set, your output will look | 
|  | like this: | 
|  |  | 
|  | #(CDT) 2016/08/25 17:57:36 - (test_mode) Issuing: ls | 
|  |  | 
|  | """ | 
|  |  | 
|  | buffer = sprint_time() | 
|  | if test_mode: | 
|  | buffer += "(test_mode) " | 
|  | buffer += "Issuing: " + cmd_buf + "\n" | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint_pgm_footer(): | 
|  |  | 
|  | r""" | 
|  | Return a standardized footer that programs should print at the end of the | 
|  | program run.  It includes useful information like total run time, etc. | 
|  | """ | 
|  |  | 
|  | buffer = "\n" + sprint_time() + "Finished running " + pgm_name + ".\n\n" | 
|  |  | 
|  | total_time = time.time() - start_time | 
|  | total_time_string = "%0.6f" % total_time | 
|  |  | 
|  | buffer += sprint_varx(pgm_name_var_name + "_runtime", total_time_string) | 
|  | buffer += "\n" | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprint(buffer=""): | 
|  |  | 
|  | r""" | 
|  | Simply return the user's buffer.  This function is used by the qprint and | 
|  | dprint functions defined dynamically below, i.e. it would not normally be | 
|  | called for general use. | 
|  |  | 
|  | Description of arguments. | 
|  | buffer                          This will be returned to the caller. | 
|  | """ | 
|  |  | 
|  | return str(buffer) | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | def sprintn(buffer=""): | 
|  |  | 
|  | r""" | 
|  | Simply return the user's buffer with a line feed.  This function is used | 
|  | by the qprint and dprint functions defined dynamically below, i.e. it | 
|  | would not normally be called for general use. | 
|  |  | 
|  | Description of arguments. | 
|  | buffer                          This will be returned to the caller. | 
|  | """ | 
|  |  | 
|  | buffer = str(buffer) + "\n" | 
|  |  | 
|  | return buffer | 
|  |  | 
|  | ############################################################################### | 
|  |  | 
|  |  | 
|  | ############################################################################### | 
|  | # In the following section of code, we will dynamically create print versions | 
|  | # for each of the sprint functions defined above.  So, for example, where we | 
|  | # have an sprint_time() function defined above that returns the time to the | 
|  | # caller in a string, we will create a corresponding print_time() function | 
|  | # that will print that string directly to stdout. | 
|  |  | 
|  | # It can be complicated to follow what's being creaed by the exec statement | 
|  | # below.  Here is an example of the print_time() function that will be created: | 
|  |  | 
|  | # def print_time(*args): | 
|  | #   s_funcname = "s" + sys._getframe().f_code.co_name | 
|  | #   s_func = getattr(sys.modules[__name__], s_funcname) | 
|  | #   sys.stdout.write(s_func(*args)) | 
|  |  | 
|  | # Here are comments describing the 3 lines in the body of the created function. | 
|  | # Calculate the "s" version of this function name (e.g. if this function name | 
|  | # is print_time, we want s_funcname to be "sprint_time". | 
|  | # Put a reference to the "s" version of this function in s_func. | 
|  | # Call the "s" version of this function passing it all of our arguments. | 
|  | # Write the result to stdout. | 
|  |  | 
|  | # func_names contains a list of all print functions which should be created | 
|  | # from their sprint counterparts. | 
|  | func_names = ['print_time', 'print_timen', 'print_error', 'print_varx', | 
|  | 'print_var', 'print_dashes', 'indent', 'print_call_stack', | 
|  | 'print_func_name', 'print_executing', 'print_pgm_header', | 
|  | 'issuing', 'print_pgm_footer', 'print_error_report', 'print', | 
|  | 'printn'] | 
|  |  | 
|  | for func_name in func_names: | 
|  | if func_name == "print": | 
|  | continue | 
|  | # Create abbreviated aliases (e.g. spvar is an alias for sprint_var). | 
|  | alias = re.sub("print_", "p", func_name) | 
|  | pgm_definition_string = "s" + alias + " = s" + func_name | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | for func_name in func_names: | 
|  | if func_name == "print_error" or func_name == "print_error_report": | 
|  | output_stream = "stderr" | 
|  | else: | 
|  | output_stream = "stdout" | 
|  | func_def = \ | 
|  | [ | 
|  | "def " + func_name + "(*args):", | 
|  | "  s_func_name = \"s\" + sys._getframe().f_code.co_name", | 
|  | "  s_func = getattr(sys.modules[__name__], s_func_name)", | 
|  | "  sys." + output_stream + ".write(s_func(*args))", | 
|  | "  sys." + output_stream + ".flush()" | 
|  | ] | 
|  | if func_name != "print": | 
|  | pgm_definition_string = '\n'.join(func_def) | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | # Now define "q" versions of each print function. | 
|  | func_def = \ | 
|  | [ | 
|  | "def q" + func_name + "(*args):", | 
|  | "  if __builtin__.quiet: return", | 
|  | "  s_func_name = \"s" + func_name + "\"", | 
|  | "  s_func = getattr(sys.modules[__name__], s_func_name)", | 
|  | "  sys." + output_stream + ".write(s_func(*args))", | 
|  | "  sys." + output_stream + ".flush()" | 
|  | ] | 
|  |  | 
|  | pgm_definition_string = '\n'.join(func_def) | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | # Now define "d" versions of each print function. | 
|  | func_def = \ | 
|  | [ | 
|  | "def d" + func_name + "(*args):", | 
|  | "  if not __builtin__.debug: return", | 
|  | "  s_func_name = \"s" + func_name + "\"", | 
|  | "  s_func = getattr(sys.modules[__name__], s_func_name)", | 
|  | "  sys." + output_stream + ".write(s_func(*args))", | 
|  | "  sys." + output_stream + ".flush()" | 
|  | ] | 
|  |  | 
|  | pgm_definition_string = '\n'.join(func_def) | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | # Now define "l" versions of each print function. | 
|  | func_def = \ | 
|  | [ | 
|  | "def l" + func_name + "(*args):", | 
|  | "  s_func_name = \"s" + func_name + "\"", | 
|  | "  s_func = getattr(sys.modules[__name__], s_func_name)", | 
|  | "  logging.log(getattr(logging, 'INFO'), s_func(*args))", | 
|  | ] | 
|  |  | 
|  | if func_name != "print_varx" and func_name != "print_var": | 
|  | pgm_definition_string = '\n'.join(func_def) | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | if func_name == "print": | 
|  | continue | 
|  |  | 
|  | # Create abbreviated aliases (e.g. pvar is an alias for print_var). | 
|  | alias = re.sub("print_", "p", func_name) | 
|  | pgm_definition_string = alias + " = " + func_name | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | # Create abbreviated aliases (e.g. qpvar is an alias for qprint_var). | 
|  | alias = re.sub("print_", "p", func_name) | 
|  | pgm_definition_string = "q" + alias + " = q" + func_name | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | # Create abbreviated aliases (e.g. dpvar is an alias for dprint_var). | 
|  | alias = re.sub("print_", "p", func_name) | 
|  | pgm_definition_string = "d" + alias + " = d" + func_name | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | # Create abbreviated aliases (e.g. lpvar is an alias for lprint_var). | 
|  | alias = re.sub("print_", "p", func_name) | 
|  | pgm_definition_string = "l" + alias + " = l" + func_name | 
|  | if gen_print_debug: | 
|  | print(pgm_definition_string) | 
|  | exec(pgm_definition_string) | 
|  |  | 
|  | ############################################################################### |