rlib/test/test_rthread.py::TestUsingBoehm::()::test_acquire_timed_1sec
self = <rpython.rlib.test.test_rthread.TestUsingBoehm object at 0x000002099a301600>
def test_acquire_timed_1sec(self):
import time
def f():
l = allocate_lock()
l.acquire(True)
t1 = time.time()
ok = l.acquire_timed(1000001)
t2 = time.time()
delay = t2 - t1
if ok == 0: # RPY_LOCK_FAILURE
return -delay
elif ok == 2: # RPY_LOCK_INTR
return delay
else: # RPY_LOCK_ACQUIRED
return 0.0
fn = self.getcompiled(f, [])
res = fn()
> assert res < -1.0
E assert -1.0 < -1.0
rlib\test\test_rthread.py:195: AssertionError
---------- Captured stdout call ----------
THE RESULT IS: -1.0 ;
MALLOC COUNTERS: 0 0
---------- Captured stderr call ----------
[platform:msg] Updated environment with vsver 160, using x64 True
[flowgraph] (rpython.translator.c.test.test_genc:66)entry_point
[translation:info] Annotating&simplifying...
[translation:info] with policy: rpython.annotator.policy.AnnotatorPolicy
[flowgraph] (rpython.rlib.test.test_rthread:180)f
[flowgraph] (rpython.rlib.rthread:92)allocate_lock
[flowgraph] (rpython.rlib.rthread:249)allocate_ll_lock
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)RPyThreadLockInit
[flowgraph] (rpython.rtyper.lltypesystem.rffi:2)ccall_RPyThreadLockInit
[flowgraph] (rpython.rlib.rthread:203)Lock.__del__
[flowgraph] (rpython.rlib.rthread:166)Lock.__init__
[flowgraph] (rpython.rlib.rthread:260)free_ll_lock
[flowgraph] (rpython.rlib.rthread:266)acquire_NOAUTO
[flowgraph] (rpython.rlib.rthread:272)release_NOAUTO
[flowgraph] (rpython.rlib.rthread:169)Lock.acquire
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)RPyThreadAcquireLock
[flowgraph] (rpython.rtyper.lltypesystem.rffi:3)ccall_RPyThreadAcquireLock
[flowgraph] (rpython.rlib.rgil:160)release
[flowgraph] (rpython.rlib.rgil:171)acquire
[flowgraph] (rpython.rlib.rthread:282)gc_thread_run
[flowgraph] (rpython.rlib.rtime:146)time
[flowgraph] (rpython.rlib.rthread:192)Lock.acquire_timed
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)RPyThreadAcquireLockTimed
[flowgraph] (rpython.rtyper.lltypesystem.rffi:3)ccall_RPyThreadAcquireLockTimed
[flowgraph] (rpython.flowspace.specialcase:76)rpython_print_item
[flowgraph] (rpython.translator.c.test.test_genc:39)llrepr_out
[flowgraph] (?:2)formatd
[flowgraph] (rpython.rlib.rfloat:105)formatd
[flowgraph] (rpython.rlib.rdtoa:290)dtoa_formatd
[flowgraph] (rpython.rlib.rdtoa:248)dtoa
[flowgraph] (rpython.rlib.objectmodel:397)revdb_flag_io_disabled
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)_PyPy_dg_dtoa
[flowgraph] (rpython.rtyper.lltypesystem.rffi:2)ccall__PyPy_dg_dtoa
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)_PyPy_dg_freedtoa
[flowgraph] (rpython.rtyper.lltypesystem.rffi:2)ccall__PyPy_dg_freedtoa
[flowgraph] (rpython.rlib.rdtoa:82)format_nonfinite
[flowgraph] (rpython.rlib.rdtoa:97)format_number
[flowgraph] (rpython.flowspace.specialcase:95)rpython_print_newline
[flowgraph] (rpython.flowspace.specialcase:85)rpython_print_end
[flowgraph] (rpython.rlib.rposix:500)write
[flowgraph] (rpython.rtyper.lltypesystem.rffi:1450)scoped_nonmovingbuffer.__init__
[flowgraph] (rpython.rtyper.lltypesystem.rffi:1454)scoped_nonmovingbuffer.__enter__
[flowgraph] (rpython.rtyper.lltypesystem.rffi:883)get_nonmovingbuffer_ll
[flowgraph] (rpython.rlib.objectmodel:315)we_are_translated_to_c
[flowgraph] (rpython.rtyper.lltypesystem.rstr:116)copy_string_to_raw
[flowgraph] (rpython.rtyper.lltypesystem.rstr:63)_get_raw_buf
[flowgraph] (rpython.rtyper.lltypesystem.rstr:57)_str_ofs
[flowgraph] (rpython.rtyper.lltypesystem.llmemory:411)sizeof
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)wrap_write
[flowgraph] (rpython.rtyper.lltypesystem.rffi:1456)scoped_nonmovingbuffer.__exit__
[flowgraph] (rpython.rtyper.lltypesystem.rffi:947)free_nonmovingbuffer_ll
[flowgraph] (rpython.rtyper.lltypesystem.rffi:3)ccall_wrap_write
[flowgraph] (rpython.rlib.rposix:151)_errno_before
[flowgraph] (rpython.rlib.rthread:349)getraw
[flowgraph] (rpython.rlib.rthread:349)getraw
[flowgraph] (rpython.rlib.rthread:349)getraw
[flowgraph] (rpython.rlib.rthread:349)getraw
[flowgraph] (rpython.rlib.rposix:170)_errno_after
[flowgraph] (rpython.rlib.rthread:364)setraw
[flowgraph] (rpython.rlib.rthread:364)setraw
[flowgraph] (rpython.rlib.rthread:364)setraw
[flowgraph] (rpython.rlib.rthread:364)setraw
[flowgraph] (rpython.rlib.rposix:430)handle_posix_error
[flowgraph] (rpython.rlib.rarithmetic:139)widen
[flowgraph] (rpython.rlib.rposix:113)get_saved_errno
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo_offsetof_0
[flowgraph] (?:1)memo_itemoffsetof_0
[flowgraph] (?:1)memo__sizeof_none_0
[flowgraph] (?:1)memo_itemoffsetof_0
[flowgraph] (?:1)memo__isfunctype_0
[flowgraph] (?:1)memo__should_widen_type_0
[translation:info] usession directory: c:\users\runner~1\appdata\local\temp\usession-main-130
[translation:info] already done: Annotating&simplifying
[translation:info] RTyping...
[flowgraph] (rpython.rtyper.rclass:1170)ll_runtime_type_info
[flowgraph] (rpython.rtyper.rstr:426)ll_str
[flowgraph] (?:1)memo_ll_constant_0
[flowgraph] (rpython.rtyper.lltypesystem.rstr:1293)ll_striter
[flowgraph] (rpython.rtyper.lltypesystem.rstr:1306)ll_strnext
[flowgraph] (rpython.rtyper.rlist:590)ll_append
[flowgraph] (rpython.rtyper.lltypesystem.rlist:365)ll_length
[flowgraph] (rpython.rtyper.lltypesystem.rlist:280)_ll_list_resize_ge
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rlib.jit:1300)conditional_call
[flowgraph] (?:11)_ll_list_resize_hint_really_look_inside_iff
[flowgraph] (rpython.rtyper.lltypesystem.rlist:198)<lambda>
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.lltypesystem.rlist:198)_ll_list_resize_hint_really
[flowgraph] (rpython.rtyper.lltypesystem.rlist:351)_ll_new_empty_item_array
[flowgraph] (?:2)ll_arraycopy
[flowgraph] (rpython.rlib.rgc:362)ll_arraycopy
[flowgraph] (rpython.rlib.rgc:342)copy_item
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (?:2)_ll_list_resize_hint_really_trampoline
[flowgraph] (rpython.rtyper.lltypesystem.rlist:198)_ll_list_resize_hint_really
[flowgraph] (rpython.rtyper.lltypesystem.rlist:377)ll_setitem_fast
[flowgraph] (rpython.rtyper.lltypesystem.rlist:369)ll_items
[flowgraph] (?:1)memo__ll_prebuilt_empty_array_0
[flowgraph] (?:1)memo__contains_gcptr_0
[flowgraph] (?:1)memo_itemoffsetof_0
[flowgraph] (rpython.rtyper.lltypesystem.rstr:1046)ll_contains
[flowgraph] (rpython.rtyper.rstr:931)ll_lower_char
[rtyper] specializing: 100 / 513 blocks (19%)
[flowgraph] (rpython.rtyper.rstr:891)ll_char_isdigit
[flowgraph] (rpython.rtyper.rlist:690)ll_getitem_nonneg
[flowgraph] (rpython.rtyper.rlist:723)ll_getitem_foldable_nonneg
[flowgraph] (rpython.rtyper.lltypesystem.rlist:402)ll_fixed_getitem_fast
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:445)ll_new
[flowgraph] (?:2)mallocstr
[flowgraph] (rpython.rtyper.lltypesystem.rstr:36)mallocstr
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:178)ll_append_char
[flowgraph] (rpython.rlib.jit:1300)conditional_call
[flowgraph] (?:2)ll_grow_by
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:94)ll_grow_by
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:275)ll_append_multiple_char
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:299)ll_jit_try_append_multiple_char
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:282)_ll_append_multiple_char
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:328)ll_append_charpsize
[flowgraph] (rpython.rtyper.lltypesystem.rstr:145)copy_raw_to_string
[rtyper] specializing: 200 / 588 blocks (34%)
[flowgraph] (?:11)ll_build_look_inside_iff
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:355)<lambda>
[flowgraph] (rpython.rlib.jit:281)isvirtual
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:355)ll_build
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:374)ll_fold_pieces
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:346)ll_getlength
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:365)ll_shrink_final
[flowgraph] (?:2)ll_shrink_array
[flowgraph] (rpython.rlib.rgc:468)ll_shrink_array
[flowgraph] (rpython.rtyper.lltypesystem.rstr:78)copy_string_contents
[flowgraph] (?:2)ll_build_trampoline
[flowgraph] (rpython.rtyper.lltypesystem.rbuilder:355)ll_build
[flowgraph] (rpython.rtyper.lltypesystem.rstr:350)ll_strlen
[flowgraph] (rpython.rtyper.lltypesystem.rstr:354)ll_stritem_nonneg
[flowgraph] (rpython.rtyper.lltypesystem.rrange:70)ll_newrange
[flowgraph] (rpython.rtyper.lltypesystem.rrange:91)ll_rangeiter
[flowgraph] (rpython.rtyper.rrange:172)ll_rangenext_up
[flowgraph] (rpython.rtyper.lltypesystem.rstr:856)ll_stringslice_startonly
[flowgraph] (rpython.rtyper.lltypesystem.rstr:840)_ll_stringslice
[flowgraph] (?:1)memo_emptystrfun_0
[flowgraph] (rpython.rtyper.rint:149)ll_str
[flowgraph] (rpython.rtyper.lltypesystem.ll_str:13)ll_int2dec
[flowgraph] (rpython.rtyper.lltypesystem.ll_str:7)ll_unsigned
[flowgraph] (rpython.rtyper.lltypesystem.rstr:362)ll_chr2str
[flowgraph] (rpython.rtyper.lltypesystem.rstr:425)ll_strconcat
[flowgraph] (rpython.rtyper.lltypesystem.rstr:416)ll_length
[flowgraph] (rpython.rtyper.lltypesystem.rstr:860)ll_stringslice_startstop
[flowgraph] (?:11)ll_join_chars_look_inside_iff
[flowgraph] (rpython.rtyper.lltypesystem.rstr:821)<lambda>
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.lltypesystem.rstr:820)ll_join_chars
[flowgraph] (rpython.rlib.jit:281)isvirtual
[flowgraph] (?:2)ll_join_chars_trampoline
[flowgraph] (rpython.rtyper.lltypesystem.rstr:820)ll_join_chars
[flowgraph] (?:11)ll_listdelslice_startonly_look_inside_iff
[flowgraph] (rpython.rtyper.rlist:914)<lambda>
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.rlist:914)ll_listdelslice_startonly
[flowgraph] (rpython.rtyper.rlist:541)ll_null_item
[flowgraph] (rpython.rlib.jit:281)isvirtual
[flowgraph] (rpython.rtyper.lltypesystem.rlist:295)_ll_list_resize_le
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (?:2)ll_listdelslice_startonly_trampoline
[flowgraph] (rpython.rtyper.rlist:914)ll_listdelslice_startonly
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rlib.jit:1300)conditional_call
[rtyper] specializing: 300 / 760 blocks (39%)
[flowgraph] (?:11)ll_join_strs_look_inside_iff
[flowgraph] (rpython.rtyper.lltypesystem.rstr:785)<lambda>
[flowgraph] (rpython.rlib.jit:294)loop_unrolling_heuristic
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.lltypesystem.rstr:783)ll_join_strs
[flowgraph] (rpython.rtyper.lltypesystem.llmemory:1037)cast_any_ptr
[flowgraph] (rpython.rlib.jit:281)isvirtual
[flowgraph] (?:2)ll_join_strs_trampoline
[flowgraph] (rpython.rtyper.lltypesystem.rstr:783)ll_join_strs
[rtyper] specializing: 400 / 808 blocks (49%)
[rtyper] specializing: 500 / 808 blocks (61%)
[flowgraph] (rpython.rtyper.rbuiltin:240)ll_min
[rtyper] specializing: 600 / 810 blocks (74%)
[flowgraph] (rpython.rtyper.rint:434)ll_uint_py_div
[flowgraph] (rpython.rtyper.rint:525)ll_uint_py_mod
[rtyper] specializing: 700 / 814 blocks (85%)
[rtyper] specializing: 800 / 814 blocks (98%)
[rtyper] -=- specialized 814 blocks -=-
[flowgraph] (rpython.rtyper.rclass:1133)ll_issubclass
[flowgraph] (rpython.rtyper.rclass:1130)ll_type
[rtyper] -=- specialized 4 more blocks -=-
[translation:info] usession directory: c:\users\runner~1\appdata\local\temp\usession-main-130
[translation:info] already done: Annotating&simplifying
[translation:info] already done: RTyping
[translation:info] lltype back-end optimisations...
[backendopt:removecasts] removed 1 cast_pointers in allocate_ll_lock
[backendopt:removecasts] removed 1 cast_pointers in Lock.acquire
[backendopt:removecasts] removed 2 cast_pointers in time
[backendopt:removecasts] removed 2 cast_pointers in dtoa_formatd
[backendopt:removecasts] removed 3 cast_pointers in dtoa
[backendopt:removecasts] removed 1 cast_pointers in format_nonfinite
[backendopt:removecasts] removed 4 cast_pointers in format_number
[backendopt:removecasts] removed 1 cast_pointers in _get_raw_buf__rpy_string
[backendopt:removecasts] removed 3 cast_pointers in handle_posix_error__write
[backendopt:removecasts] removed 1 cast_pointers in ll_strnext__stringiterPtr
[backendopt:removecasts] removed 1 cast_pointers in ll_grow_by__stringbuilderPtr_Signed
[backendopt:removecasts] removed 1 cast_pointers in copy_raw_to_string__arrayPtr_rpy_stringPtr_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in copy_string_contents__rpy_stringPtr_rpy_stringPtr_Signed_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in ll_rangenext_up__rangePtr_Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_stringslice__rpy_stringPtr_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in ll_join_strs__v727___simple_call__function_l
[backendopt:removecasts] removed 1 cast_pointers in ll_join_strs__v730___simple_call__function_l
[backendopt:inlining] phase with threshold factor: 32.4
[backendopt:inlining] heuristic: rpython.translator.backendopt.inline.inlining_heuristic
[backendopt:inlining] 0.00 ll_getitem_nonneg__dum_nocheckConst_ll_getitem_foldable_nonnegCons_arrayPtr_Signed
[backendopt:inlining] 0.00 cast_any_ptr__Ptr_GcStruct_rpy_stringLlT_rpy_stringPtr
[backendopt:inlining] 0.00 isconstant__v722___simple_call__function_i
[backendopt:inlining] 0.00 memo__sizeof_none_0
[backendopt:inlining] 0.00 conditional_call__v723___simple_call__function_c_star_3
[backendopt:inlining] 0.00 RPyThreadAcquireLock__struct_RPyOpaque_ThreadLockPtr_Signed_star_2
[backendopt:inlining] 0.00 scoped_nonmovingbuffer.__init__
[backendopt:inlining] 0.00 scoped_nonmovingbuffer.__enter__
[backendopt:inlining] 0.00 wrap_write__Signed_arrayPtr_Signed_star_3
[backendopt:inlining] 0.00 memo_emptystrfun_0
[backendopt:inlining] 0.00 _PyPy_dg_dtoa__Float_Signed_Signed_arrayPtr_arrayPtr_arrayPtr_star_6
[backendopt:inlining] 0.00 isconstant__v709___simple_call__function_i
[backendopt:inlining] 0.00 isvirtual__v711___simple_call__function_i
[backendopt:inlining] 0.00 isvirtual__v717___simple_call__function_i
[backendopt:inlining] 0.00 __exit____star_3
[backendopt:inlining] 0.00 _get_raw_buf__rpy_string
[backendopt:inlining] 0.00 get_nonmovingbuffer_ll
[backendopt:inlining] 0.00 copy_string_to_raw
[backendopt:inlining] 0.00 isconstant__v715___simple_call__function_i
[backendopt:inlining] 0.00 _PyPy_dg_freedtoa__arrayPtr_star_1
[backendopt:inlining] 0.00 isconstant__v726___simple_call__function_i
[backendopt:inlining] 0.00 isconstant__v719___simple_call__function_i
[backendopt:inlining] 0.00 ll_getlength__stringbuilderPtr
[backendopt:inlining] 0.00 copy_string_contents__rpy_stringPtr_rpy_stringPtr_Signed_Signed_Signed
[backendopt:inlining] 0.00 isvirtual__v704___simple_call__function_i
[backendopt:inlining] 0.00 isconstant__v693___simple_call__function_i
[backendopt:inlining] 0.00 memo__ll_prebuilt_empty_array_0
[backendopt:inlining] 0.00 isconstant__v691___simple_call__function_i
[backendopt:inlining] 0.00 conditional_call__v688___simple_call__function_c_star_3
[backendopt:inlining] 0.00 isconstant__v687___simple_call__function_i
[backendopt:inlining] 0.00 isconstant__v686___simple_call__function_i
[backendopt:inlining] 0.00 RPyThreadAcquireLockTimed__struct_RPyOpaque_ThreadLockPtr_Signed_Signed_star_3
[backendopt:inlining] 0.00 ll_append_multiple_char__stringbuilderPtr_Char_Signed
[backendopt:inlining] 0.00 memo_ll_constant_0
[backendopt:inlining] 0.00 widen__int
[backendopt:inlining] 0.00 gc_thread_run
[backendopt:inlining] 0.00 ll_append_char__stringbuilderPtr_Char
[backendopt:inlining] 0.00 isconstant__v698___simple_call__function_i
[backendopt:inlining] 0.00 isconstant__v700___simple_call__function_i
[backendopt:inlining] 0.00 isconstant__v702___simple_call__function_i
[backendopt:inlining] 0.00 copy_raw_to_string__arrayPtr_rpy_stringPtr_Signed_Signed
[backendopt:inlining] 0.00 conditional_call__v697___simple_call__function_c_star_2
[backendopt:inlining] 0.00 RPyThreadLockInit__struct_RPyOpaque_ThreadLockPtr_star_1
[backendopt:inlining] 0.00 isvirtual__v728___simple_call__function_i
[backendopt:inlining] 2.00 ll_length__listPtr
[backendopt:inlining] 2.00 ll_strlen__rpy_stringPtr
[backendopt:inlining] 2.00 ll_items__listPtr
[backendopt:inlining] 2.00 getraw
[backendopt:inlining] 2.00 ll_unsigned__Signed
[backendopt:inlining] 2.00 setraw
[backendopt:inlining] 2.00 setraw
[backendopt:inlining] 2.00 setraw
[backendopt:inlining] 2.00 setraw
[backendopt:inlining] 2.00 getraw
[backendopt:inlining] 2.00 getraw
[backendopt:inlining] 2.00 getraw
[backendopt:inlining] 2.00 ll_length__rpy_stringPtr
[backendopt:inlining] 2.00 Lock.__init__
[backendopt:inlining] 2.00 ll_uint_py_div__Unsigned_Unsigned
[backendopt:inlining] 2.00 ll_uint_py_mod__Unsigned_Unsigned
[backendopt:inlining] 4.00 ll_fixed_getitem_fast__arrayPtr_Signed
[backendopt:inlining] 4.00 copy_item__arrayPtr_arrayPtr_Signed_Signed
[backendopt:inlining] 4.00 ll_stritem_nonneg__rpy_stringPtr_Signed
[backendopt:inlining] 4.00 ll_min__Unsigned_Unsigned
[backendopt:inlining] 5.00 release
[backendopt:inlining] 6.00 _str_ofs__rpy_string
[backendopt:inlining] 7.00 mallocstr__Signed
[backendopt:inlining] 4.00 ll_getitem_foldable_nonneg__arrayPtr_Signed
[backendopt:inlining] 4.00 get_saved_errno
[backendopt:inlining] 7.00 ll_shrink_array__rpy_stringPtr_Signed
[backendopt:inlining] 7.00 rpython_print_newline
[backendopt:inlining] 7.00 ll_str__IntegerR_SignedConst_Signed
[backendopt:inlining] 7.00 ll_listdelslice_startonly_look_inside_iff__listPtr_Signed
[backendopt:inlining] 7.00 ccall__PyPy_dg_freedtoa
[backendopt:inlining] 0.00 _PyPy_dg_freedtoa__arrayPtr_star_1
[backendopt:inlining] 7.00 ll_build_look_inside_iff__stringbuilderPtr
[backendopt:inlining] 7.00 release_NOAUTO
[backendopt:inlining] 7.00 ccall_RPyThreadLockInit
[backendopt:inlining] 7.50 mallocstr__Signed
[backendopt:inlining] 7.50 ll_char_isdigit__Char
[backendopt:inlining] 8.00 ll_newrange__Ptr_GcStruct_rangeLlT_Signed_Signed
[backendopt:inlining] 9.00 _ll_list_resize_hint_really_look_inside_iff__listPtr_Signed_Bool
[backendopt:inlining] 9.00 ll_join_chars_look_inside_iff__Signed_arrayPtr_Ptr_GcStruct_rpy_stringLlT
[backendopt:inlining] 9.00 ll_join_strs_look_inside_iff__Signed_arrayPtr_Ptr_GcStruct_rpy_stringLlT
[backendopt:inlining] 9.00 formatd
[backendopt:inlining] 9.00 llrepr_out__float
[backendopt:inlining] 9.00 formatd
[backendopt:inlining] 4.00 ll_str__StringR_Ptr_GcStruct_rpy_strin_rpy_stringPtr
[backendopt:inlining] 9.50 ll_chr2str__Char
[backendopt:inlining] 7.00 acquire
[backendopt:inlining] 10.75 ll_lower_char__Char
[backendopt:inlining] 11.00 ll_arraycopy__arrayPtr_arrayPtr_Signed_Signed_Signed
[backendopt:inlining] 6.00 ll_setitem_fast__listPtr_Signed_Char
[backendopt:inlining] 11.00 ll_stringslice_startonly__rpy_stringPtr_Signed
[backendopt:inlining] 11.00 ccall__PyPy_dg_dtoa
[backendopt:inlining] 0.00 _PyPy_dg_dtoa__Float_Signed_Signed_arrayPtr_arrayPtr_arrayPtr_star_6
[backendopt:inlining] 12.00 ll_striter__rpy_stringPtr
[backendopt:inlining] 12.00 ll_rangeiter__Ptr_GcStruct_rangeLlT_rangePtr
[backendopt:inlining] 12.50 ll_rangenext_up__rangePtr_Signed
[backendopt:inlining] 13.00 acquire_NOAUTO
[backendopt:inlining] 13.00 free_nonmovingbuffer_ll
[backendopt:inlining] 9.00 ll_listdelslice_startonly__v721___simple_call__function_l
[backendopt:inlining] 9.00 ll_listdelslice_startonly__v716___simple_call__function_l
[backendopt:inlining] 15.50 ll_strnext__stringiterPtr
[backendopt:inlining] 17.00 ll_contains__rpy_stringPtr_Char
[backendopt:inlining] 4.00 loop_unrolling_heuristic__v725___simple_call__function_l
[backendopt:inlining] 18.12 ll_stringslice_startstop__rpy_stringPtr_Signed_Signed
[backendopt:inlining] 19.00 ll_shrink_final__stringbuilderPtr
[backendopt:inlining] 17.00 allocate_lock
[backendopt:inlining] 22.00 ll_shrink_array__rpy_stringPtr_Signed
[backendopt:inlining] 17.00 ll_append__listPtr_Char
[backendopt:inlining] 26.16 ll_join_chars__v710___simple_call__function_l
[backendopt:inlining] 26.16 ll_join_chars__v713___simple_call__function_l
[backendopt:inlining] 26.50 time
[backendopt:inlining] 29.00 free_ll_lock
[backendopt:inlining] 27.50 ll_new__Signed
[backendopt:inlining] 27.50 ll_arraycopy__arrayPtr_arrayPtr_Signed_Signed_Signed
[backendopt:inlining] 31.75 handle_posix_error__write
[backendopt:inlining] inlined 246 callsites.
[backendopt:malloc] starting malloc removal
[backendopt:malloc] 3 simple mallocs removed in 'allocate_ll_lock'
[backendopt:malloc] 3 simple mallocs removed in 'RPyThreadLockInit__struct_RPyOpaque_ThreadLockPtr_star_1'
[backendopt:malloc] 5 simple mallocs removed in 'Lock.acquire'
[backendopt:malloc] 5 simple mallocs removed in 'RPyThreadAcquireLock__struct_RPyOpaque_ThreadLockPtr_Signed_star_2'
[backendopt:malloc] 6 simple mallocs removed in 'Lock.acquire_timed'
[backendopt:malloc] 6 simple mallocs removed in 'RPyThreadAcquireLockTimed__struct_RPyOpaque_ThreadLockPtr_Signed_Signed_star_3'
[backendopt:malloc] 1 simple mallocs removed in 'rpython_print_item'
[backendopt:malloc] 13 simple mallocs removed in '_PyPy_dg_dtoa__Float_Signed_Signed_arrayPtr_arrayPtr_arrayPtr_star_6'
[backendopt:malloc] 3 simple mallocs removed in '_PyPy_dg_freedtoa__arrayPtr_star_1'
[backendopt:malloc] 7 simple mallocs removed in 'format_number'
[backendopt:malloc] 1 simple mallocs removed in 'rpython_print_end'
[backendopt:malloc] 3 simple mallocs removed in 'write'
[backendopt:malloc] 2 simple mallocs removed in 'scoped_nonmovingbuffer.__init__'
[backendopt:malloc] 7 simple mallocs removed in 'wrap_write__Signed_arrayPtr_Signed_star_3'
[backendopt:malloc] 1 simple mallocs removed in '_ll_list_resize_ge__listPtr_Signed'
[backendopt:malloc] 1 simple mallocs removed in 'conditional_call__v688___simple_call__function_c_star_3'
[backendopt:malloc] 1 simple mallocs removed in 'll_append_char__stringbuilderPtr_Char'
[backendopt:malloc] 1 simple mallocs removed in 'conditional_call__v697___simple_call__function_c_star_2'
[backendopt:malloc] 4 simple mallocs removed in '_ll_append_multiple_char__stringbuilderPtr_Char_Signed'
[backendopt:malloc] 1 simple mallocs removed in '_ll_list_resize_le__listPtr_Signed'
[backendopt:malloc] 1 simple mallocs removed in 'conditional_call__v723___simple_call__function_c_star_3'
[backendopt:malloc] removed 75 simple mallocs in total
[backendopt:mergeifblocks] starting to merge if blocks
[backendopt] merging blocks in f
[backendopt] merging blocks in dtoa_formatd
[backendopt] merging blocks in format_number
[translation:info] usession directory: c:\users\runner~1\appdata\local\temp\usession-main-130
[translation:info] already done: Annotating&simplifying
[translation:info] already done: RTyping
[translation:info] already done: lltype back-end optimisations
[translation:info] inserting stack checks...
[flowgraph] (rpython.rlib.rstack:42)stack_check
[flowgraph] (rpython.rlib.rstack:67)stack_check_slowpath
[rtyper] -=- specialized 8 more blocks -=-
[translation:info] inserted 0 stack checks.
[translation:info] Creating database for generating c source...
[flowgraph] (rpython.translator.exceptiontransform:100)rpyexc_occurred
[flowgraph] (rpython.translator.exceptiontransform:104)rpyexc_fetch_type
[flowgraph] (rpython.translator.exceptiontransform:107)rpyexc_fetch_value
[flowgraph] (rpython.translator.exceptiontransform:110)rpyexc_clear
[flowgraph] (rpython.translator.exceptiontransform:114)rpyexc_raise
[flowgraph] (rpython.translator.exceptiontransform:128)rpyexc_reraise
[flowgraph] (rpython.translator.exceptiontransform:133)rpyexc_fetch_exception
[flowgraph] (rpython.translator.exceptiontransform:138)rpyexc_restore_exception
[flowgraph] (rpython.translator.exceptiontransform:539)rpyexc_get_exception_addr
[flowgraph] (rpython.translator.exceptiontransform:543)rpyexc_get_exc_value_addr
[flowgraph] (rpython.rtyper.rclass:1173)ll_inst_type
[rtyper] -=- specialized 25 more blocks -=-
[flowgraph] (rpython.memory.gctransform.transform:455)_ll_malloc_fixedsize
[flowgraph] (rpython.memory.gctransform.transform:462)_ll_malloc_fixedsize_zero
[flowgraph] (rpython.memory.gctransform.transform:478)_ll_malloc_varsize_no_length
[flowgraph] (rpython.memory.gctransform.transform:495)ll_malloc_varsize
[flowgraph] (rpython.memory.gctransform.transform:501)_ll_malloc_varsize_no_length_zero
[flowgraph] (rpython.memory.gctransform.transform:455)_ll_malloc_fixedsize
[flowgraph] (rpython.memory.gctransform.transform:455)_ll_malloc_fixedsize
[flowgraph] (rpython.memory.gctransform.transform:478)_ll_malloc_varsize_no_length
[flowgraph] (rpython.memory.gctransform.transform:495)ll_malloc_varsize
[flowgraph] (rpython.memory.gctransform.boehm:249)ll_weakref_create
[flowgraph] (rpython.memory.gctransform.boehm:263)ll_weakref_deref
[flowgraph] (rpython.memory.gctransform.boehm:58)ll_identityhash
[flowgraph] (rpython.memory.gctransform.transform:469)_ll_compute_size
[flowgraph] (rpython.memory.gctransform.boehm:20)<lambda>
[flowgraph] (rpython.memory.gctransform.boehm:24)<lambda>
[flowgraph] (rpython.memory.gctransform.transform:469)_ll_compute_size
[rtyper] specializing: 900 / 915 blocks (98%)
[rtyper] -=- specialized 64 more blocks -=-
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_fixedsize__Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_fixedsize_zero__Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_varsize_no_length__Signed_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_varsize_no_length_zero__Signed_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_fixedsize__Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_fixedsize__Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_malloc_varsize_no_length__Signed_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in ll_weakref_create__GCREFPtr
[backendopt:removecasts] removed 1 cast_pointers in _ll_compute_size__Signed_Signed_Signed
[backendopt:removecasts] removed 1 cast_pointers in _ll_compute_size__Signed_Signed_Signed
[backendopt:inlining] phase with threshold factor: 32.4
[backendopt:inlining] heuristic: rpython.translator.backendopt.inline.inlining_heuristic
[backendopt:inlining] 0.00 _ll_compute_size__Signed_Signed_Signed
[backendopt:inlining] 0.00 _ll_compute_size__Signed_Signed_Signed
[backendopt:inlining] 2.00 _lambda_
[backendopt:inlining] 2.00 _lambda_
[backendopt:inlining] 16.50 _ll_malloc_varsize_no_length__Signed_Signed_Signed
[backendopt:inlining] 16.50 _ll_malloc_varsize_no_length__Signed_Signed_Signed
[backendopt:inlining] inlined 8 callsites.
[backendopt:malloc] starting malloc removal
[backendopt:malloc] removed 0 simple mallocs in total
[backendopt:mergeifblocks] starting to merge if blocks
[flowgraph] (rpython.translator.c.genc:261)entrypoint_wrapper
[flowgraph] (rpython.rtyper.lltypesystem.rffi:864)charp2str
[flowgraph] (rpython.rtyper.lltypesystem.rffi:1053)charpsize2str
[flowgraph] (rpython.rtyper.rlist:487)ll_alloc_and_set
[flowgraph] (rpython.rlib.rarithmetic:741)int_force_ge_zero
[flowgraph] (rpython.rtyper.rlist:510)_ll_alloc_and_set_jit
[flowgraph] (rpython.rtyper.rlist:472)_ll_zero_or_null
[flowgraph] (rpython.rtyper.rlist:494)_ll_alloc_and_set_nojit
[flowgraph] (rpython.rtyper.lltypesystem.rlist:324)ll_newlist
[flowgraph] (rpython.rtyper.rlist:522)_ll_alloc_and_clear
[flowgraph] (rpython.rtyper.lltypesystem.rlist:369)ll_items
[flowgraph] (?:11)_ll_alloc_and_set_nonnull_look_inside_iff
[flowgraph] (rpython.rtyper.rlist:530)<lambda>
[flowgraph] (rpython.rlib.jit:269)isconstant
[flowgraph] (rpython.rtyper.rlist:530)_ll_alloc_and_set_nonnull
[flowgraph] (rpython.rtyper.lltypesystem.rlist:377)ll_setitem_fast
[flowgraph] (rpython.rlib.rgc:538)ll_arrayfill
[flowgraph] (rpython.rlib.rgc:508)ll_arrayclear
[flowgraph] (rpython.rtyper.lltypesystem.llmemory:411)sizeof
[flowgraph] (?:2)_ll_alloc_and_set_nonnull_trampoline
[flowgraph] (rpython.rtyper.rlist:530)_ll_alloc_and_set_nonnull
[flowgraph] (?:1)memo_itemoffsetof_0
[flowgraph] (?:1)memo__sizeof_none_0
[flowgraph] (rpython.rtyper.rlist:728)ll_setitem_nonneg
[rtyper] -=- specialized 84 more blocks -=-
[rtyper] -=- specialized 0 more blocks -=-
[flowgraph] (rpython.memory.gctransform.boehm:128)ll_finalizer
[flowgraph] (rpython.memory.gctransform.support:81)ll_call_destructor
[flowgraph] (rpython.memory.gctransform.support:77)write
[flowgraph] (rpython.rtyper.lltypesystem.rffi:278)wrap_write
[flowgraph] (rpython.rtyper.lltypesystem.rffi:936)get_nonmovingbuffer_ll_final_null
[rtyper] specializing: 1000 / 1031 blocks (96%)
[flowgraph] (rpython.rtyper.rclass:870)ll_str
[flowgraph] (rpython.rtyper.lltypesystem.ll_str:47)ll_int2hex
[flowgraph] (rpython.rtyper.lltypesystem.ll_str:7)ll_unsigned
[flowgraph] (?:1)memo_conststr_0
[flowgraph] (?:1)memo_conststr_0
[flowgraph] (?:1)memo_conststr_0
[flowgraph] (?:1)memo_conststr_0
[rtyper] -=- specialized 64 more blocks -=-
[backendopt:inlining] phase with threshold factor: 32.4
[backendopt:inlining] heuristic: rpython.translator.backendopt.inline.inlining_heuristic
[backendopt:inlining] 0.00 memo_conststr_0
[backendopt:inlining] 0.00 ll_unsigned__Unsigned
[backendopt:inlining] 0.00 get_nonmovingbuffer_ll_final_null
[backendopt:inlining] 0.00 wrap_write__Signed_SomeString_Signed_star_3
[backendopt:inlining] 0.00 get_nonmovingbuffer_ll
[backendopt:inlining] 2.00 ll_strlen__rpy_stringPtr
[backendopt:inlining] 13.00 free_nonmovingbuffer_ll
[backendopt:inlining] inlined 14 callsites.
[backendopt:malloc] starting malloc removal
[backendopt:malloc] 10 simple mallocs removed in 'write'
[backendopt:malloc] 10 simple mallocs removed in 'wrap_write__Signed_SomeString_Signed_star_3'
[backendopt:malloc] removed 20 simple mallocs in total
[backendopt:mergeifblocks] starting to merge if blocks
[c:database] GC transformer: finished helpers
[c:database] GC transformer: finished tables
[c:database] Inlining GC helpers and postprocessing
[c] 380 nodes [ array: 52 boehm rtti: 19 func: 96 struct: 213 ]
[c:database] Completed
[translation:info] database for generating C source was created
[translation:info] Generating c source...
[c:writing] structdef.h
[c:writing] forwarddecl.h
[c:writing] preimpl.h
[c:writing] data_rpython_flowspace.c
[c:writing] data_rpython_memory_gctransform.c
[c:writing] data_rpython_rlib.c
[c:writing] data_rpython_rtyper.c
[c:writing] data_rpython_rtyper_lltypesystem.c
[c:writing] data_rpython_translator_c.c
[c:writing] data_rpython_translator_c_test.c
[c:writing] nonfuncnodes.c
[c:writing] data_rpython_rlib_1.c
[c:writing] data_rpython_rlib_test.c
[c:writing] data_rpython_rtyper_lltypesystem_1.c
[c:writing] implement.c
[c:writing] rpython_flowspace.c
[c:writing] rpython_memory_gctransform.c
[c:writing] rpython_rlib.c
[c:writing] rpython_rlib_test.c
[c:writing] rpython_rtyper.c
[c:writing] rpython_rtyper_lltypesystem.c
[c:writing] rpython_translator.c
[c:writing] rpython_translator_c.c
[c:writing] rpython_translator_c_test.c
[translation:info] written: c:\users\runner~1\appdata\local\temp\usession-main-130\testing_2\testing_2.c
[translation:info] Compiling c source...
[platform:execute] nmake in c:\users\runner~1\appdata\local\temp\usession-main-130\testing_2
[translation:info] created: c:\users\runner~1\appdata\local\temp\usession-main-130\testing_2\testing_2.exe
[translation:info] usession directory: c:\users\runner~1\appdata\local\temp\usession-main-130
[c:cmdexec] c:\users\runner~1\appdata\local\temp\usession-main-130\testing_2\testing_2.exe
builder: rpython-win-x86-64 build #771*
test: rlib/test/test_rthread.py::TestUsingBoehm::()::test_acquire_timed_1sec