]0;/Users/bertin/Library/xgraph/xg_init.xg: 0% (0 of 3503): line 0.0
Current xgraph info:
 xgraph -nodetach examples/PythonBench.xg
 XGraph build version 20061013; gcc4.0.1[Oct 25 2006 18:35:43,"Darwin portia.local 8.8.0 Power Macintosh ; CC='gccopt -DXGRAPH ' ACC='gccopt -DXGRAPH '"]
 Executed d.d. Wed Oct 25 19:13:03 2006
 Working directory: /Volumes/Debian/Users/bertin/work/src/xgraph
 Last opened on display :0.0/-1/-1 (visual 0x22, 24 planes TrueColor) by bertin


]0;examples/PythonBench.xg: 0% (0 of 5451): line 0.0Python/Python.c::initDyMod(): Initialising Python.so loaded from ./Python.so (build: XGraph v20061013 'Python/Python.c'-[Oct 25 2006,18:35:47]), call 1
Python/Python.c calling Python 2.5 (r25:51908, Sep 28 2006, 13:40:40) 
[GCC 4.0.1 (Apple Computer, Inc. build 5363)]
100000 1000000
#1: 	verbose[ Python-Eval[ "ExportVariable('NoOp',NoOp, as_PObj=1 )" ] ] *BUFLEN*
#2 Create_AutoVariable("ExportVariable('NoOp',NoOp, as_PObj=1 )" ): removed trailing whitespace
#2 string parameter ""ExportVariable('NoOp',NoOp, as_PObj=1 )"" => creating temporary string variable with "DCL["Xx9e11c67d4",0]"
#3 	""Xx9e11c67d4"" new variable (hash=-2077815028)
#3 	DCL[0,0]== 0	->
#		`"ExportVariable('NoOp',NoOp, as_PObj=1 )"={0x6713b0:0x22345600|0x4}==0x22345604:6713b0
#		`"ExportVariable('NoOp',NoOp, as_PObj=1 )"={0x6713b0:0x22345600|0x4}==0x22345604:6713b0
#2	: "ExportVariable('NoOp',NoOp, as_PObj=1 )"== ExportVariable('NoOp',NoOp, as_PObj=1 )
#2 	Python-Eval[`"ExportVariable('NoOp',NoOp, as_PObj=1 )"]==  (0x22345604:6713b0=={0x6713b0:0x22345604}==`"ExportVariable('NoOp',NoOp, as_PObj=1 )":"ExportVariable('NoOp',NoOp, as_PObj=1 )")== #0 unknown symbol "&NoOp" => compiler would create variable with "DCL[&NoOp]"
#1 	"NoOp" new label, value=0(id) (hash=49764)
#1 	DCL[0]== 0	  ,
#		&NoOp={0x66f120:0x12345600|0x9}==0x12345609:66f120
0	->
#1 	verbose[0]== 0	  ,
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, add[ 1, 2, 3, 4, 5] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0005601229970204539s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, call[&add, 1, 2, 3, 4, 5] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0004423365744583263s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): call(a_add, (1,2,3,4,5));" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003300841063417707s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): callr(a_add, (1,2,3,4,5));" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002324803411693767s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): call(a_addP, (1,2,3,4,5));" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003176055816115592s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): callr(a_addP, (1,2,3,4,5));" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002261325698935734s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ Python-Eval[ "print a_addP.reentrant()"] ] *EVAL*
#ac3 string parameter ""print a_addP.reentrant()"" => creating temporary string variable with "DCL["Xx199239ebb2",0]"
#4 	""Xx199239ebb2"" new variable (hash=-1074560848)
#sc:4 	DCL[0,0]== 0	->
#		`"print a_addP.reentrant()"={0x67b860:0x22345600|0x4}==0x22345604:67b860
#		`"print a_addP.reentrant()"={0x67b860:0x22345600|0x4}==0x22345604:67b860
 (0x22345604:67b860=={0x67b860:0x22345604}==`"print a_addP.reentrant()":"print a_addP.reentrant()")== #ac3	: "print a_addP.reentrant()"== print a_addP.reentrant()
#ac:3 	Python-Eval[`"print a_addP.reentrant()"]== 0	->
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): a_addP(1,2,3,4,5);" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002824486945455717s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ Python-Eval[ "print a_addP.reentrant(0)"] ] *EVAL*
#ac3 string parameter ""print a_addP.reentrant(0)"" => creating temporary string variable with "DCL["Xx2124094944",0]"
#4 	""Xx2124094944"" new variable (hash=1290891971)
#sc:4 	DCL[0,0]== 0	->
#		`"print a_addP.reentrant(0)"={0x67bf80:0x22345600|0x4}==0x22345604:67bf80
#		`"print a_addP.reentrant(0)"={0x67bf80:0x22345600|0x4}==0x22345604:67bf80
 (0x22345604:67bf80=={0x67bf80:0x22345604}==`"print a_addP.reentrant(0)":"print a_addP.reentrant(0)")== #ac3	: "print a_addP.reentrant(0)"== print a_addP.reentrant(0)
#ac:3 	Python-Eval[`"print a_addP.reentrant(0)"]== 0	->
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): a_addP(1,2,3,4,5);" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.01681003768188942s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): call(a_call, (a_add, 1,2,3,4,5));" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0004528076843406342s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): call(a_call, a_args);" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003787503527895959s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): callr(a_call, a_args);" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002510896193539966s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "call(a_call, a_args, N2)" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002259698065275272s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "callr(a_call, a_args, N2)" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002276516946433383s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-EvalValue[ "for i in xrange(N): ascanf.ReturnValue= ImportVariable('$loop');" ] ] ], ?Python-EvalValue ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002509268559879503s (0% CPU)
#ac:2 	verbose[0,NaN]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "ascanf.ReturnValue= ImportVariable('$loop')" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0004948006327805637s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-EvalValue[ "ascanf.ReturnValue= ImportVariable('$loop')" ] ] ] ], ?Python-EvalValue ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0005495433782274484s (0% CPU)
#ac:2 	verbose[0,NaN]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ DCL[cexpr,Python-Compile[ "ascanf.ReturnValue= ImportVariable('$loop')" ]], for-toMAX[0, N, Python-EvalValueCompiled[cexpr]  ] ] ], ?Python-EvalValueCompiled ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.001241504701745355s (0% CPU)
#ac:2 	verbose[0,NaN]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N): kk= int(ImportVariable('$loop'));" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003591102399533499s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "kk= int(ImportVariable('$loop'))" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0.01s, system 0s, total 0.0006910932522323268s (1446.982728842832% CPU)
#ac:2 	verbose[0.01]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N): ImportVariable('$loop');" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0.01s, total 0.0003752780676472762s (2664.690761890993% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "ImportVariable('$loop')" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003754950854686712s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ DCL[cexpr, Python-Compile[ "ImportVariable('$loop')" ]], for-toMAX[0, N, Python-EvalCompiled[cexpr] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0008962836023612842s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp(string='$loop',int=kk)" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0007531603491512922s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp(kk,'$loop')" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0004023510408663005s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp(1,'$loop')" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003373542033585027s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ DCL[cexpr2, Python-Compile[ "NoOp(1,'$loop')" ]], for-toMAX[0, N, Python-EvalCompiled[cexpr2] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0010708201852182s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp(string='$loop')" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003609006369798585s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp(int=1)" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003460891706696508s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp(1)" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002762636866358147s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[ "NoOp()" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003809747854588946s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ DCL[cexpr3, Python-Compile[ "NoOp()" ]], for-toMAX[0, N, Python-EvalCompiled[cexpr3] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.001256750203698353s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Call[ &NoOp, 1,"$loop" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0004243783497378914s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Call[ &NoOp, 1 ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0001985713065764104s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, Python-Call[ &NoOp ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0001439370700402232s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, NoOp[ 1, "$loop" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0006466045988463551s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, NoOp[ 1 ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0001950990214340907s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N, NoOp[,] ] ] ] ] *EVAL*
#ac:6 	NoOp called with empty argument list while compiling
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0001507731314141652s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N): NoOp(int=kk,string='$loop');" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002767519767339534s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N): NoOp(kk,'$loop');" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0.01s, system 0s, total 0.0005269735248023701s (1897.628539071347% CPU)
#ac:2 	verbose[0.01]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N): NoOp(1);" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0004003436260183968s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N): NoOp();" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.000245230138176332s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, Python-Eval[ "kk" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0003332851192073468s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, Python-Eval[ "1" ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0007098110393276441s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, Python-Eval[ 0 ] ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002163125134754504s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, $loop ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002117551392261557s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ Python-Eval[ "for i in xrange(N2): i;" ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002883624301785851s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2, 0 ] ] ] ] *EVAL*
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0001510986581462577s (0% CPU)
#ac:2 	verbose[0]== 0	->
#ac2: 	verbose[ system.time[ no-verbose[ for-toMAX[0, N2 ] ] ] ] *EVAL*
#ac5 	 for-toMAX[0, N2 ]  :
#ac5 	"for-toMAX[0, N2 ]" warning: function call with only 2 parameters
#ac:3 	system.time[0]== <delayed!>	->
## Compilation time: user 0s, system 0s, total 0.0002641106886376956s (0% CPU)
#ac:2 	verbose[0]== 0	->
## Compilation time: user 0.02s, system 0.01s, total 0.2435224249397749s (12.31919401567197% CPU)
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ 
#C3: 	    for-toMAX[0, N2, add[<C>1, 2, 3, 4, 5]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 1.06s, system 0s, total 1.161915585926959s (91.22865833272631% CPU)
#C2 	verbose[1.06]== 1.06	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ 
#C3: 	    for-toMAX[0, N2, call[<C>&add, 1, 2, 3, 4, 5]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 1.26s, system 0s, total 1.299689349839564s (96.94624335850227% CPU)
#C2 	verbose[1.26]== 1.26	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): call(a_add, (1,2,3,4,5));"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 2.7s, system 0.01s, total 2.759593842466546s (98.20285718487402% CPU)
#C2 	verbose[2.7]== 2.7	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): callr(a_add, (1,2,3,4,5));"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.52s, system 0.01s, total 3.613882000682955s (97.6788948652141% CPU)
#C2 	verbose[3.52]== 3.52	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): call(a_addP, (1,2,3,4,5));"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 2.43s, system 0.01s, total 2.514154878891577s (97.05050474359518% CPU)
#C2 	verbose[2.43]== 2.43	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): callr(a_addP, (1,2,3,4,5));"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.23s, system 0s, total 3.321423847415638s (97.24745014139721% CPU)
#C2 	verbose[3.23]== 3.23	->
#C2: 	verbose[
#C3: 	    Python-Eval[<C>`"print a_addP.reentrant()"]
#C2: 	] *EVAL*
#		`"print a_addP.reentrant()"={0x67b860:0x22345600|0x4}==0x22345604:67b860
#Cecal4: 	`"print a_addP.reentrant()"==`"print a_addP.reentrant()"
#C3 	Python-Eval[<C>`"print a_addP.reentrant()"]==  (0x22345604:67b860=={0x67b860:0x22345604}==`"print a_addP.reentrant()":"print a_addP.reentrant()")== 0	->
#C2 	verbose[0]== 0	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): a_addP(1,2,3,4,5);"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 2.72s, system 0s, total 2.810903637251239s (96.76603509111634% CPU)
#C2 	verbose[2.72]== 2.72	->
#C2: 	verbose[
#C3: 	    Python-Eval[<C>`"print a_addP.reentrant(0)"]
#C2: 	] *EVAL*
#		`"print a_addP.reentrant(0)"={0x67bf80:0x22345600|0x4}==0x22345604:67bf80
#Cecal4: 	`"print a_addP.reentrant(0)"==`"print a_addP.reentrant(0)"
#C3 	Python-Eval[<C>`"print a_addP.reentrant(0)"]==  (0x22345604:67bf80=={0x67bf80:0x22345604}==`"print a_addP.reentrant(0)":"print a_addP.reentrant(0)")== 0	->
#C2 	verbose[0]== 0	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): a_addP(1,2,3,4,5);"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 1.95s, system 0.01s, total 1.999877710457644s (98.00599255398878% CPU)
#C2 	verbose[1.95]== 1.95	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): call(a_call, (a_add, 1,2,3,4,5));"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.17s, system 0.01s, total 3.268886220051948s (97.28084081034379% CPU)
#C2 	verbose[3.17]== 3.17	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): call(a_call, a_args);"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 2.9s, system 0s, total 3.014369889298124s (96.2058442228948% CPU)
#C2 	verbose[2.9]== 2.9	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): callr(a_call, a_args);"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.8s, system 0.01s, total 3.871233235237661s (98.41824990857461% CPU)
#C2 	verbose[3.8]== 3.8	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"call(a_call, a_args, N2)"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.53s, system 0.06s, total 0.6473252065222971s (91.14429564232913% CPU)
#C2 	verbose[0.53]== 0.53	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"callr(a_call, a_args, N2)"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.57s, system 0.02s, total 0.6464713499040186s (91.26467864161299% CPU)
#C2 	verbose[0.57]== 0.57	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-EvalValue[<C>
#C3: 	    `"for i in xrange(N): ascanf.ReturnValue= ImportVariable('$loop');"] ] ], 
#C3: 	    ?Python-EvalValue
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.2s, system 0s, total 0.2525504748187169s (79.19209027168208% CPU)
#C2 	verbose[0.2,0]== return[0]== 0	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>
#C3: 	        `"ascanf.ReturnValue= ImportVariable('$loop')"]
#C3: 	    ] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 6.28s, system 0.02s, total 6.382492879374008s (98.70751317810951% CPU)
#C2 	verbose[6.28]== 6.28	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-EvalValue[<C>
#C3: 	        `"ascanf.ReturnValue= ImportVariable('$loop')"]
#C3: 	    ] ] ], ?Python-EvalValue
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 6.24s, system 0.01s, total 6.354673853139484s (98.35280526493472% CPU)
#C2 	verbose[6.24,99999]== return[0]== 0	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ DCL[cexpr, Python-Compile[<C>`"ascanf.ReturnValue= ImportVariable('$loop')"]], 
#C3: 	    for-toMAX[0, N, Python-EvalValueCompiled[<C>cexpr]] ] ], ?Python-EvalValueCompiled
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.43s, system 0s, total 0.4758033809857449s (90.37346458302758% CPU)
#C2 	verbose[0.43,99999]== return[0]== 0	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N): kk= int(ImportVariable('$loop'));"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.24s, system 0s, total 0.3036622408413868s (79.03518044752894% CPU)
#C2 	verbose[0.24]== 0.24	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"kk= int(ImportVariable('$loop'))"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 6.81s, system 0.02s, total 6.948804465098273s (98.29028913254082% CPU)
#C2 	verbose[6.81]== 6.81	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N): ImportVariable('$loop');"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.14s, system 0s, total 0.1680383639764306s (83.31430792770436% CPU)
#C2 	verbose[0.14]== 0.14	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"ImportVariable('$loop')"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 4.86s, system 0.01s, total 5.00338363336228s (97.33413139714321% CPU)
#C2 	verbose[4.86]== 4.86	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ DCL[cexpr, Python-Compile[<C>`"ImportVariable('$loop')"]], for-toMAX[0, N, 
#C3: 	        Python-EvalCompiled[<C>cexpr]
#C3: 	    ] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.28s, system 0s, total 0.3297659039611504s (84.90871755892228% CPU)
#C2 	verbose[0.28]== 0.28	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp(string='$loop',int=kk)"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 7.08s, system 0.02s, total 7.233455293731994s (98.15502704706508% CPU)
#C2 	verbose[7.08]== 7.08	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp(kk,'$loop')"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 5.49s, system 0.01s, total 5.608466429458955s (98.06602338048731% CPU)
#C2 	verbose[5.49]== 5.49	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp(1,'$loop')"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 5.36s, system 0.02s, total 5.468711998144932s (98.37782647586809% CPU)
#C2 	verbose[5.36]== 5.36	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ DCL[cexpr2, Python-Compile[<C>`"NoOp(1,'$loop')"]], for-toMAX[0, N, 
#C3: 	        Python-EvalCompiled[<C>cexpr2]
#C3: 	    ] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.24s, system 0s, total 0.2630984090097987s (91.22062003463562% CPU)
#C2 	verbose[0.24]== 0.24	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp(string='$loop')"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 5.48s, system 0.02s, total 5.605689957706482s (98.11459501856353% CPU)
#C2 	verbose[5.48]== 5.48	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp(int=1)"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 5.45s, system 0.01s, total 5.575807634535045s (97.92303389704904% CPU)
#C2 	verbose[5.45]== 5.45	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp(1)"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 4.66s, system 0.02s, total 4.768088245522678s (98.15254582157965% CPU)
#C2 	verbose[4.66]== 4.66	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Eval[<C>`"NoOp()"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.93s, system 0.01s, total 4.0124292074303s (98.1948788704814% CPU)
#C2 	verbose[3.93]== 3.93	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ DCL[cexpr3, Python-Compile[<C>`"NoOp()"]], for-toMAX[0, N, 
#C3: 	        Python-EvalCompiled[<C>cexpr3]
#C3: 	    ] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.2s, system 0s, total 0.2482655121897283s (80.55891381609096% CPU)
#C2 	verbose[0.2]== 0.2	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ 
#C3: 	    for-toMAX[0, N, Python-Call[<C>&NoOp, 1, `"$loop"]] ] ]
#C2: 	] *EVAL*
sys:1: DeprecationWarning: integer argument expected, got float
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.19s, system 0.05s, total 3.327718340816289s (97.36400945535638% CPU)
#C2 	verbose[3.19]== 3.19	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Call[<C>&NoOp, 1]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.03s, system 0.03s, total 3.116978899248717s (98.17198315771559% CPU)
#C2 	verbose[3.03]== 3.03	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, Python-Call[<C>&NoOp]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.21s, system 0s, total 0.2341286457773269s (89.69427867435111% CPU)
#C2 	verbose[0.21]== 0.21	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, NoOp[<C>1, `"$loop"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 3.15s, system 0.05s, total 3.296275496745655s (97.07926425322439% CPU)
#C2 	verbose[3.15]== 3.15	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, NoOp[<C>1]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 2.98s, system 0.03s, total 3.066045576129689s (98.17205665284219% CPU)
#C2 	verbose[2.98]== 2.98	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N, NoOp] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.15s, system 0s, total 0.1953221700089401s (76.79619778601393% CPU)
#C2 	verbose[0.15]== 0.15	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N): NoOp(int=kk,string='$loop');"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.25s, system 0s, total 0.3064794034353704s (81.57155006102045% CPU)
#C2 	verbose[0.25]== 0.25	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N): NoOp(kk,'$loop');"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.11s, system 0s, total 0.1656652198450211s (66.39897022616117% CPU)
#C2 	verbose[0.11]== 0.11	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N): NoOp(1);"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.09s, system 0s, total 0.1394800665333237s (64.5253492035708% CPU)
#C2 	verbose[0.09]== 0.09	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N): NoOp();"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.07000000000000001s, system 0s, total 0.1325669638327865s (52.80350245351821% CPU)
#C2 	verbose[0.07000000000000001]== 0.07000000000000001	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N2, Python-Eval[<C>`"kk"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 35.77s, system 0.1s, total 36.37877384496876s (98.60145411404754% CPU)
#C2 	verbose[35.77]== 35.77	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N2, Python-Eval[<C>`"1"]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 31.04s, system 0.08s, total 31.54329793085444s (98.65804161701054% CPU)
#C2 	verbose[31.04]== 31.04	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[0, N2, Python-Eval[<C>0]] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.91s, system 0s, total 0.9704312133260228s (93.77274633212767% CPU)
#C2 	verbose[0.91]== 0.91	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[<C>0, N2, $loop] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.28s, system 0.01s, total 0.3354412455173613s (86.45329215634295% CPU)
#C2 	verbose[0.28]== 0.28	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ Python-Eval[<C>`"for i in xrange(N2): i;"] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.33s, system 0s, total 0.3875231354561221s (85.15620612214126% CPU)
#C2 	verbose[0.33]== 0.33	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[<C>0, N2, 0] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0.27s, system 0s, total 0.3239264426775095s (83.35225669390724% CPU)
#C2 	verbose[0.27]== 0.27	->
#C2: 	verbose[
#C3: 	    system.time[ no-verbose[ for-toMAX[<C>0, N2] ] ]
#C2: 	] *EVAL*
#C3 	system.time[0]== <delayed!>	->
#C# Evaluation time 'no-verbose': user 0s, system 0s, total 0.0001687856105899488s (0% CPU)
#C2 	verbose[0]== 0	->
#C# Evaluation time 'verbose': user 175.05s, system 0.6899999999999999s, total 180.6216454877166s (97.29730870597753% CPU)
]0;examples/PythonBench.xg: 100% (5451 of 5451): line 0.72Only (4) command(s) in "examples/PythonBench.xg"
xgraph: maxitems=0, setNumber=0; no data found
Python/Python.c::closeDyMod(1): Closing Python.so loaded from ./Python.so, call 1 (ignoring attempt to close a file opened elsewhere!) <function PyAscanfObject "add">
1
0


