需要帮助调试缓慢的 scons 运行时
Need help debugging slow scons runtime
我正在做两个使用scons构建的项目。一个效果很好,而另一个(更复杂的)项目非常令人沮丧。这是我在 运行ning scons 为第二个项目时看到的内容:
S time scons
scons: Reading SConscript files ...
<<< Generates some code, output seen in console >>>
scons: done reading SConscript files.
scons: Building targets ...
<<< 30+ second delay, with no output >>>
<<< Building occurs, with output, only 2-3 seconds >>>
scons: done building targets.
<<< 15-20 second delay, no output to console >>>
real 0m54.600s
user 0m2.612s
sys 0m9.236s
$
所以它似乎足够快地读取 SConscript 文件,但是当它完成时,有一个巨大的 30 秒以上的延迟,它似乎什么也没做。如果我在这里按 Ctrl-C,延迟仍然存在;在延迟结束之前,它不会处理 Ctrl-C 并终止 运行。同样,在完成所有操作后还有另一个神秘的延迟;此延迟也不可中断。
我不知道这是怎么回事,但是等了 54 秒,应该是 4 秒左右,真是令人沮丧!我一直无法找到让 scons 告诉我在这些延迟期间它在做什么的方法。有谁知道如何获得更多关于它在做什么的信息?
Update: 我运行 scons 使用 profile= 选项获取分析信息,然后打印出一个排序列表;这是前几项:
2016 年 3 月 13 日星期日 17:46:04scons.profile.data
1253379 function calls (1208007 primitive calls) in 61.303 seconds
排序依据:内部时间、函数名
ncalls tottime percall cumtime percall filename:lineno(function)
41 32.778 0.799 32.783 0.800 {cPickle.loads}
1 17.691 17.691 17.691 17.691 {cPickle.dump}
456 5.337 0.012 5.337 0.012 {method 'read' of 'file' objects}
24 2.844 0.118 2.844 0.118 {posix.waitpid}
5 0.245 0.049 0.245 0.049 {built-in method poll}
. . .
230 0.016 0.000 0.029 0.000 {cPickle.dumps}
剩余的 1000 多个条目总时长不到 0.1 秒。请注意,前两个条目与两个神秘延迟相匹配! (为了完整起见,我还展示了另一个 cPickle 调用,尽管它非常小。)是什么导致 cPickle 使用这么多时间?
更新2:我今天又运行了,得到的时间如下:
real 1m42.446s
user 0m4.104s
sys 0m11.648s
请注意,这只是编译两个小的 .c 文件!然后,我再次重命名了 .sconsign.dblite 和 运行 ;当然,它重建了一切,但已经快得多了:
real 0m34.229s
user 0m28.392s
sys 0m2.932s
好多了!所以现在,我 运行 再次使用相同的两个需要编译的文件,我得到了这个:
real 0m3.494s
user 0m2.556s
sys 0m0.460s
现在,这很好!我决定查看 .sconsign.dblite 和旧版本,发现以下内容:
$ ls -la .scons*
-rw-rw-r-- 1 rsg rsg 189513 Mar 14 20:31 .sconsign.dblite
-rw-rw-r-- 1 rsg rsg 1440507770 Mar 14 20:23 .sconsign.dblite.save
相差7600倍!!!所以现在,这似乎已经解决了,尽管很高兴知道 .sconsign.dblite 发生了什么,以及如何防止它再次发生。
环境:
$ scons --version
SCons by Steven Knight et al.:
script: v2.1.0.r5357[MODIFIED], 2011/09/09 21:31:03, by bdeegan on ubuntu
engine: v2.1.0.r5357[MODIFIED], 2011/09/09 21:31:03, by bdeegan on ubuntu
engine path: ['/usr/lib/scons/SCons']
Copyright (c) 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011 The SCons Foundation
$ lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 12.04.5 LTS
Release: 12.04
Codename: precise
$ python --version
Python 2.7.3
此外,Ubuntu 运行 在虚拟机中,在 VirtualBox v5.0.16 下,在一台像样的 Windows 7 机器上。
由于 unpickling 花费了大量时间,.sconsign 文件可能已损坏或由于某种原因因数据过多而爆炸,重命名它并查看是否能解决您的问题可能是明智之举。
mv .sconsign.dblite .sconsign.dblist.save
如果是,您可以使用
检查旧文件的内容
sconsign .sconsign.dblite.save
这种情况偶尔(实际上很少)发生,我们还无法为开发人员重现并修复它。
我正在做两个使用scons构建的项目。一个效果很好,而另一个(更复杂的)项目非常令人沮丧。这是我在 运行ning scons 为第二个项目时看到的内容:
S time scons
scons: Reading SConscript files ...
<<< Generates some code, output seen in console >>>
scons: done reading SConscript files.
scons: Building targets ...
<<< 30+ second delay, with no output >>>
<<< Building occurs, with output, only 2-3 seconds >>>
scons: done building targets.
<<< 15-20 second delay, no output to console >>>
real 0m54.600s
user 0m2.612s
sys 0m9.236s
$
所以它似乎足够快地读取 SConscript 文件,但是当它完成时,有一个巨大的 30 秒以上的延迟,它似乎什么也没做。如果我在这里按 Ctrl-C,延迟仍然存在;在延迟结束之前,它不会处理 Ctrl-C 并终止 运行。同样,在完成所有操作后还有另一个神秘的延迟;此延迟也不可中断。
我不知道这是怎么回事,但是等了 54 秒,应该是 4 秒左右,真是令人沮丧!我一直无法找到让 scons 告诉我在这些延迟期间它在做什么的方法。有谁知道如何获得更多关于它在做什么的信息?
Update: 我运行 scons 使用 profile= 选项获取分析信息,然后打印出一个排序列表;这是前几项:
2016 年 3 月 13 日星期日 17:46:04scons.profile.data
1253379 function calls (1208007 primitive calls) in 61.303 seconds
排序依据:内部时间、函数名
ncalls tottime percall cumtime percall filename:lineno(function)
41 32.778 0.799 32.783 0.800 {cPickle.loads}
1 17.691 17.691 17.691 17.691 {cPickle.dump}
456 5.337 0.012 5.337 0.012 {method 'read' of 'file' objects}
24 2.844 0.118 2.844 0.118 {posix.waitpid}
5 0.245 0.049 0.245 0.049 {built-in method poll}
. . .
230 0.016 0.000 0.029 0.000 {cPickle.dumps}
剩余的 1000 多个条目总时长不到 0.1 秒。请注意,前两个条目与两个神秘延迟相匹配! (为了完整起见,我还展示了另一个 cPickle 调用,尽管它非常小。)是什么导致 cPickle 使用这么多时间?
更新2:我今天又运行了,得到的时间如下:
real 1m42.446s
user 0m4.104s
sys 0m11.648s
请注意,这只是编译两个小的 .c 文件!然后,我再次重命名了 .sconsign.dblite 和 运行 ;当然,它重建了一切,但已经快得多了:
real 0m34.229s
user 0m28.392s
sys 0m2.932s
好多了!所以现在,我 运行 再次使用相同的两个需要编译的文件,我得到了这个:
real 0m3.494s
user 0m2.556s
sys 0m0.460s
现在,这很好!我决定查看 .sconsign.dblite 和旧版本,发现以下内容:
$ ls -la .scons*
-rw-rw-r-- 1 rsg rsg 189513 Mar 14 20:31 .sconsign.dblite
-rw-rw-r-- 1 rsg rsg 1440507770 Mar 14 20:23 .sconsign.dblite.save
相差7600倍!!!所以现在,这似乎已经解决了,尽管很高兴知道 .sconsign.dblite 发生了什么,以及如何防止它再次发生。
环境:
$ scons --version
SCons by Steven Knight et al.:
script: v2.1.0.r5357[MODIFIED], 2011/09/09 21:31:03, by bdeegan on ubuntu
engine: v2.1.0.r5357[MODIFIED], 2011/09/09 21:31:03, by bdeegan on ubuntu
engine path: ['/usr/lib/scons/SCons']
Copyright (c) 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011 The SCons Foundation
$ lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 12.04.5 LTS
Release: 12.04
Codename: precise
$ python --version
Python 2.7.3
此外,Ubuntu 运行 在虚拟机中,在 VirtualBox v5.0.16 下,在一台像样的 Windows 7 机器上。
由于 unpickling 花费了大量时间,.sconsign 文件可能已损坏或由于某种原因因数据过多而爆炸,重命名它并查看是否能解决您的问题可能是明智之举。
mv .sconsign.dblite .sconsign.dblist.save
如果是,您可以使用
检查旧文件的内容sconsign .sconsign.dblite.save
这种情况偶尔(实际上很少)发生,我们还无法为开发人员重现并修复它。