剖析 Python cProfile 与 unix 时间

Question

我正在分析 python 代码；为什么它在用户空间花费更多时间？

 



     user@terminal$ time python main.py 

     1964 function calls in 0.003 CPU seconds


排序方式：标准名称

ncalls tottime percall cumtime percall 文件名：lineno(function)
        1 0.000 0.000 0.003 0.003 :1()
        1 0.000 0.000 0.000 0.000 ConfigParser.py:218(init)
        1 0.000 0.000 0.001 0.001 ConfigParser.py:266（读取）
       30 0.000 0.000 0.000 0.000 ConfigParser.py:354(选项xform)
        1 0.000 0.000 0.000 0.000 ConfigParser.py:434(_read)
       15 0.000 0.000 0.000 0.000 ConfigParser.py:515(获取)
       15 0.000 0.000 0.000 0.000 ConfigParser.py:611(_interpolate)
       15 0.000 0.000 0.000 0.000 ConfigParser.py:619(_interpolate_some)
        1 0.000 0.000 0.000 0.000 配置.py:32(读取配置数据)
        1 0.000 0.000 0.001 0.001 config.py:9(init)
        6 0.000 0.000 0.000 0.000 实体.py:108(add_to_filter)
        1 0.000 0.000 0.002 0.002 实体.py:24(init)
        1 0.001 0.001 0.002 0.002 实体.py:39(create_inverted_index)
      493 0.000 0.000 0.001 0.000 实体.py:80(美化)
        1 0.000 0.000 0.000 0.000 实体.py:84(create_bucket_lookup)
        1 0.000 0.000 0.000 0.000 main.py:15()
        2 0.000 0.000 0.000 0.000 main.py:18()
        1 0.000 0.000 0.003 0.003 main.py:23(主)
        1 0.000 0.000 0.000 0.000 main.py:9(get_bag_of_words)
       19 0.000 0.000 0.000 0.000 {内置方法组}
       34 0.000 0.000 0.000 0.000 {内置方法匹配}
        1 0.000 0.000 0.000 0.000 {是实例}
        2 0.000 0.000 0.000 0.000 {长度}
       28 0.000 0.000 0.000 0.000 {“列表”对象的“追加”方法}
        1 0.000 0.000 0.000 0.000 {“文件”对象的“关闭”方法}
       15 0.000 0.000 0.000 0.000 {“dict”对象的“复制”方法}
        1 0.000 0.000 0.000 0.000 {“_lsprof.Profiler”对象的“禁用”方法}
       15 0.000 0.000 0.000 0.000 {'str'对象的'find'方法}
       19 0.000 0.000 0.000 0.000 {“str”对象的“isspace”方法}
       24 0.000 0.000 0.000 0.000 {“str”对象的“join”方法}
       49 0.000 0.000 0.000 0.000 {“str”对象的“lower”方法}
       20 0.000 0.000 0.000 0.000 {“文件”对象的“readline”方法}
        6 0.000 0.000 0.000 0.000 {“str”对象的“替换”方法}
       24 0.000 0.000 0.000 0.000 {“str”对象的“rstrip”方法}
       47 0.000 0.000 0.000 0.000 {“str”对象的“split”方法}
        9 0.000 0.000 0.000 0.000 {“str”对象的“startswith”方法}
     1030 0.000 0.000 0.000 0.000 {“str”对象的“strip”方法}
       15 0.000 0.000 0.000 0.000 {'dict'对象的'update'方法}
        2 0.000 0.000 0.000 0.000 {“文件”对象的“写入”方法}
       10 0.000 0.000 0.000 0.000 {打开}
        2 0.000 0.000 0.000 0.000 {范围}
        3 0.000 0.000 0.000 0.000 {reduce}

实际完成

0m0.063s
用户0m0.050s
sys 0m0.010s

虽然 cProfile 说只花了 0.003 秒，但为什么 unix (sys) time 说它运行时间为 0.01 秒？

Answer 1

time(1)测量的是整个过程的执行时间，而profiler不包括Python解释器启动时间、字节码编译时间等。