You are not logged in.

#1 2022-04-01 15:39:38

loqs
Member
Registered: 2014-03-06
Posts: 14,701

libevent failing check

I encountered this issue rebuilding libevent.  Reverting https://github.com/libevent/libevent/co … 14a01a2cf9 but would like to understand what has triggered the failure and what the test is doing.
It seems to be sending a 1000 DNS requests for foobar.bazquux.example.com and errors out when it received a 1000 responses.  It then repeats the failure 10 times.

libevent-2.1.12-1-x86_64-check.log

Running tests...
Test project /build/libevent/src/libevent-2.1.12-stable/build
      Start  1: test-changelist__timerfd_EPOLL
 1/54 Test  #1: test-changelist__timerfd_EPOLL ..............   Passed    1.50 sec
      Start  2: test-eof__timerfd_EPOLL
 2/54 Test  #2: test-eof__timerfd_EPOLL .....................   Passed    0.00 sec
      Start  3: test-closed__timerfd_EPOLL
 3/54 Test  #3: test-closed__timerfd_EPOLL ..................   Passed    0.00 sec
      Start  4: test-fdleak__timerfd_EPOLL
 4/54 Test  #4: test-fdleak__timerfd_EPOLL ..................   Passed    0.04 sec
      Start  5: test-init__timerfd_EPOLL
 5/54 Test  #5: test-init__timerfd_EPOLL ....................   Passed    0.00 sec
      Start  6: test-time__timerfd_EPOLL
 6/54 Test  #6: test-time__timerfd_EPOLL ....................   Passed    1.00 sec
      Start  7: test-weof__timerfd_EPOLL
 7/54 Test  #7: test-weof__timerfd_EPOLL ....................   Passed    0.00 sec
      Start  8: test-dumpevents__timerfd_EPOLL
 8/54 Test  #8: test-dumpevents__timerfd_EPOLL ..............   Passed    0.00 sec
      Start  9: regress__timerfd_EPOLL
 9/54 Test  #9: regress__timerfd_EPOLL ......................***Failed   90.40 sec
      Start 10: regress__timerfd_EPOLL_debug
10/54 Test #10: regress__timerfd_EPOLL_debug ................***Failed   85.41 sec
      Start 11: test-changelist__changelist_EPOLL
11/54 Test #11: test-changelist__changelist_EPOLL ...........   Passed    1.50 sec
      Start 12: test-eof__changelist_EPOLL
12/54 Test #12: test-eof__changelist_EPOLL ..................   Passed    0.00 sec
      Start 13: test-closed__changelist_EPOLL
13/54 Test #13: test-closed__changelist_EPOLL ...............   Passed    0.00 sec
      Start 14: test-fdleak__changelist_EPOLL
14/54 Test #14: test-fdleak__changelist_EPOLL ...............   Passed    0.04 sec
      Start 15: test-init__changelist_EPOLL
15/54 Test #15: test-init__changelist_EPOLL .................   Passed    0.00 sec
      Start 16: test-time__changelist_EPOLL
16/54 Test #16: test-time__changelist_EPOLL .................   Passed    0.12 sec
      Start 17: test-weof__changelist_EPOLL
17/54 Test #17: test-weof__changelist_EPOLL .................   Passed    0.00 sec
      Start 18: test-dumpevents__changelist_EPOLL
18/54 Test #18: test-dumpevents__changelist_EPOLL ...........   Passed    0.00 sec
      Start 19: regress__changelist_EPOLL
19/54 Test #19: regress__changelist_EPOLL ...................***Failed   85.48 sec
      Start 20: regress__changelist_EPOLL_debug
20/54 Test #20: regress__changelist_EPOLL_debug .............***Failed   85.47 sec
      Start 21: test-changelist__timerfd_changelist_EPOLL
21/54 Test #21: test-changelist__timerfd_changelist_EPOLL ...   Passed    1.50 sec
      Start 22: test-eof__timerfd_changelist_EPOLL
22/54 Test #22: test-eof__timerfd_changelist_EPOLL ..........   Passed    0.00 sec
      Start 23: test-closed__timerfd_changelist_EPOLL
23/54 Test #23: test-closed__timerfd_changelist_EPOLL .......   Passed    0.00 sec
      Start 24: test-fdleak__timerfd_changelist_EPOLL
24/54 Test #24: test-fdleak__timerfd_changelist_EPOLL .......   Passed    0.04 sec
      Start 25: test-init__timerfd_changelist_EPOLL
25/54 Test #25: test-init__timerfd_changelist_EPOLL .........   Passed    0.00 sec
      Start 26: test-time__timerfd_changelist_EPOLL
26/54 Test #26: test-time__timerfd_changelist_EPOLL .........   Passed    1.00 sec
      Start 27: test-weof__timerfd_changelist_EPOLL
27/54 Test #27: test-weof__timerfd_changelist_EPOLL .........   Passed    0.00 sec
      Start 28: test-dumpevents__timerfd_changelist_EPOLL
28/54 Test #28: test-dumpevents__timerfd_changelist_EPOLL ...   Passed    0.00 sec
      Start 29: regress__timerfd_changelist_EPOLL
29/54 Test #29: regress__timerfd_changelist_EPOLL ...........***Failed   85.38 sec
      Start 30: regress__timerfd_changelist_EPOLL_debug
30/54 Test #30: regress__timerfd_changelist_EPOLL_debug .....***Failed   85.42 sec
      Start 31: test-changelist__SELECT
31/54 Test #31: test-changelist__SELECT .....................   Passed    1.50 sec
      Start 32: test-eof__SELECT
32/54 Test #32: test-eof__SELECT ............................   Passed    0.00 sec
      Start 33: test-closed__SELECT
33/54 Test #33: test-closed__SELECT .........................   Passed    0.00 sec
      Start 34: test-fdleak__SELECT
34/54 Test #34: test-fdleak__SELECT .........................   Passed    0.04 sec
      Start 35: test-init__SELECT
35/54 Test #35: test-init__SELECT ...........................   Passed    0.00 sec
      Start 36: test-time__SELECT
36/54 Test #36: test-time__SELECT ...........................   Passed    1.10 sec
      Start 37: test-weof__SELECT
37/54 Test #37: test-weof__SELECT ...........................   Passed    0.00 sec
      Start 38: test-dumpevents__SELECT
38/54 Test #38: test-dumpevents__SELECT .....................   Passed    0.00 sec
      Start 39: regress__SELECT
39/54 Test #39: regress__SELECT .............................***Failed   85.47 sec
      Start 40: regress__SELECT_debug
40/54 Test #40: regress__SELECT_debug .......................***Failed   85.47 sec
      Start 41: test-changelist__POLL
41/54 Test #41: test-changelist__POLL .......................   Passed    1.50 sec
      Start 42: test-eof__POLL
42/54 Test #42: test-eof__POLL ..............................   Passed    0.00 sec
      Start 43: test-closed__POLL
43/54 Test #43: test-closed__POLL ...........................   Passed    0.00 sec
      Start 44: test-fdleak__POLL
44/54 Test #44: test-fdleak__POLL ...........................   Passed    0.04 sec
      Start 45: test-init__POLL
45/54 Test #45: test-init__POLL .............................   Passed    0.00 sec
      Start 46: test-time__POLL
46/54 Test #46: test-time__POLL .............................   Passed    1.15 sec
      Start 47: test-weof__POLL
47/54 Test #47: test-weof__POLL .............................   Passed    0.00 sec
      Start 48: test-dumpevents__POLL
48/54 Test #48: test-dumpevents__POLL .......................   Passed    0.00 sec
      Start 49: regress__POLL
49/54 Test #49: regress__POLL ...............................***Failed   85.47 sec
      Start 50: regress__POLL_debug
50/54 Test #50: regress__POLL_debug .........................***Failed   85.49 sec
      Start 51: test-ratelim__group_lim
51/54 Test #51: test-ratelim__group_lim .....................   Passed    5.30 sec
      Start 52: test-ratelim__con_lim
52/54 Test #52: test-ratelim__con_lim .......................   Passed    5.30 sec
      Start 53: test-ratelim__group_con_lim
53/54 Test #53: test-ratelim__group_con_lim .................   Passed    5.30 sec
      Start 54: test-ratelim__group_con_lim_drain
54/54 Test #54: test-ratelim__group_con_lim_drain ...........   Passed    5.30 sec

81% tests passed, 10 tests failed out of 54

Total Test time (real) = 892.77 sec

The following tests FAILED:
	  9 - regress__timerfd_EPOLL (Failed)
	 10 - regress__timerfd_EPOLL_debug (Failed)
	 19 - regress__changelist_EPOLL (Failed)
	 20 - regress__changelist_EPOLL_debug (Failed)
	 29 - regress__timerfd_changelist_EPOLL (Failed)
	 30 - regress__timerfd_changelist_EPOLL_debug (Failed)
	 39 - regress__SELECT (Failed)
	 40 - regress__SELECT_debug (Failed)
	 49 - regress__POLL (Failed)
	 50 - regress__POLL_debug (Failed)
Errors while running CTest
Output from these tests are in: /build/libevent/src/libevent-2.1.12-stable/build/Testing/Temporary/LastTest.log
Use "--rerun-failed --output-on-failure" to re-run the failed cases verbosely.
make: *** [Makefile:71: test] Error 8

LastTest.log

Start testing: Apr 01 15:07 UTC
----------------------------------------------------------
1/54 Testing: test-changelist__timerfd_EPOLL
1/54 Test: test-changelist__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-changelist"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-changelist__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
write callback. should only see this once
timeout fired, time to end test
usec used=39, usec passed=1500038, cpu usage=0.00%
<end of output>
Test time =   1.50 sec
----------------------------------------------------------
Test Passed.
"test-changelist__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-changelist__timerfd_EPOLL" time elapsed: 00:00:01
----------------------------------------------------------

2/54 Testing: test-eof__timerfd_EPOLL
2/54 Test: test-eof__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-eof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-eof__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
read_cb: read 12
read_cb: read 0 - means EOF
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-eof__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-eof__timerfd_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

3/54 Testing: test-closed__timerfd_EPOLL
3/54 Test: test-closed__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-closed"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-closed__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
closed_cb: detected socket close with success
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-closed__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-closed__timerfd_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

4/54 Testing: test-fdleak__timerfd_EPOLL
4/54 Test: test-fdleak__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-fdleak"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-fdleak__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.04 sec
----------------------------------------------------------
Test Passed.
"test-fdleak__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-fdleak__timerfd_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

5/54 Testing: test-init__timerfd_EPOLL
5/54 Test: test-init__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-init"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-init__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-init__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-init__timerfd_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

6/54 Testing: test-time__timerfd_EPOLL
6/54 Test: test-time__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-time"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-time__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
event_base_dispatch=1, called=209032, EVENT=20000
<end of output>
Test time =   1.00 sec
----------------------------------------------------------
Test Passed.
"test-time__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-time__timerfd_EPOLL" time elapsed: 00:00:01
----------------------------------------------------------

7/54 Testing: test-weof__timerfd_EPOLL
7/54 Test: test-weof__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-weof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-weof__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
write_cb: write 12
write_cb: write -1
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-weof__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-weof__timerfd_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

8/54 Testing: test-dumpevents__timerfd_EPOLL
8/54 Test: test-dumpevents__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-dumpevents" "|" "/usr/bin/python3" "/build/libevent/src/libevent-2.1.12-stable/test/check-dumpevents.py"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-dumpevents__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------
=====expected
Now= 1648825658.421619
Inserted:
  0x557b7061a940 [fd  4] Write
  0x557b7061a9d0 [fd  5] Read Persist Timeout=T+1
  0x557b7061aa60 [fd  6] Write Persist Timeout=T+1
  0x557b7061aaf0 [fd  7] Read Timeout=T+2.5
  0x557b7061ab80 [fd  -1] Timeout=T+1
  0x557b7061ac10 [fd  -1] Timeout=T+1
  0x557b7061aca0 [fd  -1] Timeout=T+1
  0x557b7061ad30 [fd  -1] Persist Timeout=T+2.5
  0x557b7061adc0 [fd  -1] Persist Timeout=T+1
  0x557b7061ae50 [fd  -1] Persist Timeout=T+2.5
  0x557b7061b000 [sig 2] Signal Persist
Active:
  0x557b7061aee0 [fd  -1, priority=0] Read active
  0x557b7061af70 [fd  -1, priority=0] Read Write Timeout active
  0x557b7061a9d0 [fd  5, priority=0] Read active
======received
Inserted events:
  0x557b7061a940 [fd  4] Write
  0x557b7061a9d0 [fd  5] Read Persist Timeout=1648825659.421616
  0x557b7061aa60 [fd  6] Write Persist Timeout=1648825659.421617
  0x557b7061aaf0 [fd  7] Read Timeout=1648825660.921618
  0x557b7061a308 [fd  10] Read Persist Internal
  0x557b7061b000 [sig 2] Signal Persist
  0x557b7061a800 [fd  -1] Internal Timeout=1648825659.421617
  0x557b7061a8b0 [fd  -1] Internal Timeout=1648825660.921618
  0x557b7061aca0 [fd  -1] Timeout=1648825659.421618
  0x557b7061ae50 [fd  -1] Persist Timeout=1648825660.921618
  0x557b7061ab80 [fd  -1] Timeout=1648825659.421618
  0x557b7061ac10 [fd  -1] Timeout=1648825659.421618
  0x557b7061adc0 [fd  -1] Persist Timeout=1648825659.421618
  0x557b7061ad30 [fd  -1] Persist Timeout=1648825660.921618
Active events:
  0x557b7061a9d0 [fd  5, priority=0] Read active
  0x557b7061aee0 [fd  -1, priority=0] Read active
  0x557b7061af70 [fd  -1, priority=0] Read Write Timeout active
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-dumpevents__timerfd_EPOLL" end time: Apr 01 15:07 UTC
"test-dumpevents__timerfd_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

9/54 Testing: regress__timerfd_EPOLL
9/54 Test: regress__timerfd_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__timerfd_EPOLL" start time: Apr 01 15:07 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/353 TESTS FAILED. (37 skipped)
<end of output>
Test time =  90.40 sec
----------------------------------------------------------
Test Failed.
"regress__timerfd_EPOLL" end time: Apr 01 15:09 UTC
"regress__timerfd_EPOLL" time elapsed: 00:01:30
----------------------------------------------------------

10/54 Testing: regress__timerfd_EPOLL_debug
10/54 Test: regress__timerfd_EPOLL_debug
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__timerfd_EPOLL_debug" start time: Apr 01 15:09 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/353 TESTS FAILED. (37 skipped)
<end of output>
Test time =  85.41 sec
----------------------------------------------------------
Test Failed.
"regress__timerfd_EPOLL_debug" end time: Apr 01 15:10 UTC
"regress__timerfd_EPOLL_debug" time elapsed: 00:01:25
----------------------------------------------------------

11/54 Testing: test-changelist__changelist_EPOLL
11/54 Test: test-changelist__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-changelist"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-changelist__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
write callback. should only see this once
timeout fired, time to end test
usec used=53, usec passed=1501539, cpu usage=0.00%
<end of output>
Test time =   1.50 sec
----------------------------------------------------------
Test Passed.
"test-changelist__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-changelist__changelist_EPOLL" time elapsed: 00:00:01
----------------------------------------------------------

12/54 Testing: test-eof__changelist_EPOLL
12/54 Test: test-eof__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-eof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-eof__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
read_cb: read 12
read_cb: read 0 - means EOF
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-eof__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-eof__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

13/54 Testing: test-closed__changelist_EPOLL
13/54 Test: test-closed__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-closed"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-closed__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
closed_cb: detected socket close with success
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-closed__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-closed__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

14/54 Testing: test-fdleak__changelist_EPOLL
14/54 Test: test-fdleak__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-fdleak"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-fdleak__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.04 sec
----------------------------------------------------------
Test Passed.
"test-fdleak__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-fdleak__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

15/54 Testing: test-init__changelist_EPOLL
15/54 Test: test-init__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-init"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-init__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-init__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-init__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

16/54 Testing: test-time__changelist_EPOLL
16/54 Test: test-time__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-time"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-time__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
event_base_dispatch=1, called=22289, EVENT=20000
<end of output>
Test time =   0.12 sec
----------------------------------------------------------
Test Passed.
"test-time__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-time__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

17/54 Testing: test-weof__changelist_EPOLL
17/54 Test: test-weof__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-weof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-weof__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
write_cb: write 12
write_cb: write -1
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-weof__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-weof__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

18/54 Testing: test-dumpevents__changelist_EPOLL
18/54 Test: test-dumpevents__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-dumpevents" "|" "/usr/bin/python3" "/build/libevent/src/libevent-2.1.12-stable/test/check-dumpevents.py"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-dumpevents__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------
=====expected
Now= 1648825835.890084
Inserted:
  0x556cd2cc1940 [fd  4] Write
  0x556cd2cc19d0 [fd  5] Read Persist Timeout=T+1
  0x556cd2cc1a60 [fd  6] Write Persist Timeout=T+1
  0x556cd2cc1af0 [fd  7] Read Timeout=T+2.5
  0x556cd2cc1b80 [fd  -1] Timeout=T+1
  0x556cd2cc1c10 [fd  -1] Timeout=T+1
  0x556cd2cc1ca0 [fd  -1] Timeout=T+1
  0x556cd2cc1d30 [fd  -1] Persist Timeout=T+2.5
  0x556cd2cc1dc0 [fd  -1] Persist Timeout=T+1
  0x556cd2cc1e50 [fd  -1] Persist Timeout=T+2.5
  0x556cd2cc2000 [sig 2] Signal Persist
Active:
  0x556cd2cc1ee0 [fd  -1, priority=0] Read active
  0x556cd2cc1f70 [fd  -1, priority=0] Read Write Timeout active
  0x556cd2cc19d0 [fd  5, priority=0] Read active
======received
Inserted events:
  0x556cd2cc1940 [fd  4] Write
  0x556cd2cc19d0 [fd  5] Read Persist Timeout=1648825836.890073
  0x556cd2cc1a60 [fd  6] Write Persist Timeout=1648825836.890073
  0x556cd2cc1af0 [fd  7] Read Timeout=1648825838.390073
  0x556cd2cc1308 [fd  9] Read Persist Internal
  0x556cd2cc2000 [sig 2] Signal Persist
  0x556cd2cc1800 [fd  -1] Internal Timeout=1648825836.890073
  0x556cd2cc18b0 [fd  -1] Internal Timeout=1648825838.390073
  0x556cd2cc1ca0 [fd  -1] Timeout=1648825836.890073
  0x556cd2cc1e50 [fd  -1] Persist Timeout=1648825838.390073
  0x556cd2cc1b80 [fd  -1] Timeout=1648825836.890073
  0x556cd2cc1c10 [fd  -1] Timeout=1648825836.890073
  0x556cd2cc1dc0 [fd  -1] Persist Timeout=1648825836.890073
  0x556cd2cc1d30 [fd  -1] Persist Timeout=1648825838.390073
Active events:
  0x556cd2cc19d0 [fd  5, priority=0] Read active
  0x556cd2cc1ee0 [fd  -1, priority=0] Read active
  0x556cd2cc1f70 [fd  -1, priority=0] Read Write Timeout active
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-dumpevents__changelist_EPOLL" end time: Apr 01 15:10 UTC
"test-dumpevents__changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

19/54 Testing: regress__changelist_EPOLL
19/54 Test: regress__changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__changelist_EPOLL" start time: Apr 01 15:10 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/353 TESTS FAILED. (37 skipped)
<end of output>
Test time =  85.48 sec
----------------------------------------------------------
Test Failed.
"regress__changelist_EPOLL" end time: Apr 01 15:12 UTC
"regress__changelist_EPOLL" time elapsed: 00:01:25
----------------------------------------------------------

20/54 Testing: regress__changelist_EPOLL_debug
20/54 Test: regress__changelist_EPOLL_debug
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__changelist_EPOLL_debug" start time: Apr 01 15:12 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/353 TESTS FAILED. (37 skipped)
<end of output>
Test time =  85.47 sec
----------------------------------------------------------
Test Failed.
"regress__changelist_EPOLL_debug" end time: Apr 01 15:13 UTC
"regress__changelist_EPOLL_debug" time elapsed: 00:01:25
----------------------------------------------------------

21/54 Testing: test-changelist__timerfd_changelist_EPOLL
21/54 Test: test-changelist__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-changelist"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-changelist__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
write callback. should only see this once
timeout fired, time to end test
usec used=65, usec passed=1500203, cpu usage=0.00%
<end of output>
Test time =   1.50 sec
----------------------------------------------------------
Test Passed.
"test-changelist__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-changelist__timerfd_changelist_EPOLL" time elapsed: 00:00:01
----------------------------------------------------------

22/54 Testing: test-eof__timerfd_changelist_EPOLL
22/54 Test: test-eof__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-eof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-eof__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
read_cb: read 12
read_cb: read 0 - means EOF
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-eof__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-eof__timerfd_changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

23/54 Testing: test-closed__timerfd_changelist_EPOLL
23/54 Test: test-closed__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-closed"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-closed__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
closed_cb: detected socket close with success
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-closed__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-closed__timerfd_changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

24/54 Testing: test-fdleak__timerfd_changelist_EPOLL
24/54 Test: test-fdleak__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-fdleak"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-fdleak__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.04 sec
----------------------------------------------------------
Test Passed.
"test-fdleak__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-fdleak__timerfd_changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

25/54 Testing: test-init__timerfd_changelist_EPOLL
25/54 Test: test-init__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-init"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-init__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-init__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-init__timerfd_changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

26/54 Testing: test-time__timerfd_changelist_EPOLL
26/54 Test: test-time__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-time"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-time__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
event_base_dispatch=1, called=209006, EVENT=20000
<end of output>
Test time =   1.00 sec
----------------------------------------------------------
Test Passed.
"test-time__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-time__timerfd_changelist_EPOLL" time elapsed: 00:00:01
----------------------------------------------------------

27/54 Testing: test-weof__timerfd_changelist_EPOLL
27/54 Test: test-weof__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-weof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-weof__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
write_cb: write 12
write_cb: write -1
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-weof__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-weof__timerfd_changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

28/54 Testing: test-dumpevents__timerfd_changelist_EPOLL
28/54 Test: test-dumpevents__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-dumpevents" "|" "/usr/bin/python3" "/build/libevent/src/libevent-2.1.12-stable/test/check-dumpevents.py"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-dumpevents__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------
=====expected
Now= 1648826009.397702
Inserted:
  0x560f122db940 [fd  4] Write
  0x560f122db9d0 [fd  5] Read Persist Timeout=T+1
  0x560f122dba60 [fd  6] Write Persist Timeout=T+1
  0x560f122dbaf0 [fd  7] Read Timeout=T+2.5
  0x560f122dbb80 [fd  -1] Timeout=T+1
  0x560f122dbc10 [fd  -1] Timeout=T+1
  0x560f122dbca0 [fd  -1] Timeout=T+1
  0x560f122dbd30 [fd  -1] Persist Timeout=T+2.5
  0x560f122dbdc0 [fd  -1] Persist Timeout=T+1
  0x560f122dbe50 [fd  -1] Persist Timeout=T+2.5
  0x560f122dc000 [sig 2] Signal Persist
Active:
  0x560f122dbee0 [fd  -1, priority=0] Read active
  0x560f122dbf70 [fd  -1, priority=0] Read Write Timeout active
  0x560f122db9d0 [fd  5, priority=0] Read active
======received
Inserted events:
  0x560f122db940 [fd  4] Write
  0x560f122db9d0 [fd  5] Read Persist Timeout=1648826010.397700
  0x560f122dba60 [fd  6] Write Persist Timeout=1648826010.397700
  0x560f122dbaf0 [fd  7] Read Timeout=1648826011.897700
  0x560f122db308 [fd  10] Read Persist Internal
  0x560f122dc000 [sig 2] Signal Persist
  0x560f122db800 [fd  -1] Internal Timeout=1648826010.397700
  0x560f122db8b0 [fd  -1] Internal Timeout=1648826011.897700
  0x560f122dbca0 [fd  -1] Timeout=1648826010.397701
  0x560f122dbe50 [fd  -1] Persist Timeout=1648826011.897701
  0x560f122dbb80 [fd  -1] Timeout=1648826010.397700
  0x560f122dbc10 [fd  -1] Timeout=1648826010.397701
  0x560f122dbdc0 [fd  -1] Persist Timeout=1648826010.397701
  0x560f122dbd30 [fd  -1] Persist Timeout=1648826011.897701
Active events:
  0x560f122db9d0 [fd  5, priority=0] Read active
  0x560f122dbee0 [fd  -1, priority=0] Read active
  0x560f122dbf70 [fd  -1, priority=0] Read Write Timeout active
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-dumpevents__timerfd_changelist_EPOLL" end time: Apr 01 15:13 UTC
"test-dumpevents__timerfd_changelist_EPOLL" time elapsed: 00:00:00
----------------------------------------------------------

29/54 Testing: regress__timerfd_changelist_EPOLL
29/54 Test: regress__timerfd_changelist_EPOLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__timerfd_changelist_EPOLL" start time: Apr 01 15:13 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/353 TESTS FAILED. (37 skipped)
<end of output>
Test time =  85.38 sec
----------------------------------------------------------
Test Failed.
"regress__timerfd_changelist_EPOLL" end time: Apr 01 15:14 UTC
"regress__timerfd_changelist_EPOLL" time elapsed: 00:01:25
----------------------------------------------------------

30/54 Testing: regress__timerfd_changelist_EPOLL_debug
30/54 Test: regress__timerfd_changelist_EPOLL_debug
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__timerfd_changelist_EPOLL_debug" start time: Apr 01 15:14 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/353 TESTS FAILED. (37 skipped)
<end of output>
Test time =  85.42 sec
----------------------------------------------------------
Test Failed.
"regress__timerfd_changelist_EPOLL_debug" end time: Apr 01 15:16 UTC
"regress__timerfd_changelist_EPOLL_debug" time elapsed: 00:01:25
----------------------------------------------------------

31/54 Testing: test-changelist__SELECT
31/54 Test: test-changelist__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-changelist"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-changelist__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
write callback. should only see this once
timeout fired, time to end test
usec used=55, usec passed=1501596, cpu usage=0.00%
<end of output>
Test time =   1.50 sec
----------------------------------------------------------
Test Passed.
"test-changelist__SELECT" end time: Apr 01 15:16 UTC
"test-changelist__SELECT" time elapsed: 00:00:01
----------------------------------------------------------

32/54 Testing: test-eof__SELECT
32/54 Test: test-eof__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-eof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-eof__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
read_cb: read 12
read_cb: read 0 - means EOF
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-eof__SELECT" end time: Apr 01 15:16 UTC
"test-eof__SELECT" time elapsed: 00:00:00
----------------------------------------------------------

33/54 Testing: test-closed__SELECT
33/54 Test: test-closed__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-closed"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-closed__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
[warn] event_base_new_with_config: no event mechanism available
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-closed__SELECT" end time: Apr 01 15:16 UTC
"test-closed__SELECT" time elapsed: 00:00:00
----------------------------------------------------------

34/54 Testing: test-fdleak__SELECT
34/54 Test: test-fdleak__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-fdleak"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-fdleak__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.04 sec
----------------------------------------------------------
Test Passed.
"test-fdleak__SELECT" end time: Apr 01 15:16 UTC
"test-fdleak__SELECT" time elapsed: 00:00:00
----------------------------------------------------------

35/54 Testing: test-init__SELECT
35/54 Test: test-init__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-init"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-init__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-init__SELECT" end time: Apr 01 15:16 UTC
"test-init__SELECT" time elapsed: 00:00:00
----------------------------------------------------------

36/54 Testing: test-time__SELECT
36/54 Test: test-time__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-time"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-time__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
event_base_dispatch=1, called=208980, EVENT=20000
<end of output>
Test time =   1.10 sec
----------------------------------------------------------
Test Passed.
"test-time__SELECT" end time: Apr 01 15:16 UTC
"test-time__SELECT" time elapsed: 00:00:01
----------------------------------------------------------

37/54 Testing: test-weof__SELECT
37/54 Test: test-weof__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-weof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-weof__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
write_cb: write 12
write_cb: write -1
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-weof__SELECT" end time: Apr 01 15:16 UTC
"test-weof__SELECT" time elapsed: 00:00:00
----------------------------------------------------------

38/54 Testing: test-dumpevents__SELECT
38/54 Test: test-dumpevents__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-dumpevents" "|" "/usr/bin/python3" "/build/libevent/src/libevent-2.1.12-stable/test/check-dumpevents.py"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-dumpevents__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------
=====expected
Now= 1648826182.835686
Inserted:
  0x55d985b9c810 [fd  4] Write
  0x55d985b9c8a0 [fd  5] Read Persist Timeout=T+1
  0x55d985b9c930 [fd  6] Write Persist Timeout=T+1
  0x55d985b9c9c0 [fd  7] Read Timeout=T+2.5
  0x55d985b9ca50 [fd  -1] Timeout=T+1
  0x55d985b9cae0 [fd  -1] Timeout=T+1
  0x55d985b9cb70 [fd  -1] Timeout=T+1
  0x55d985b9cc00 [fd  -1] Persist Timeout=T+2.5
  0x55d985b9cc90 [fd  -1] Persist Timeout=T+1
  0x55d985b9cd20 [fd  -1] Persist Timeout=T+2.5
  0x55d985b9ced0 [sig 2] Signal Persist
Active:
  0x55d985b9cdb0 [fd  -1, priority=0] Read active
  0x55d985b9ce40 [fd  -1, priority=0] Read Write Timeout active
  0x55d985b9c8a0 [fd  5, priority=0] Read active
======received
Inserted events:
  0x55d985b9c810 [fd  4] Write
  0x55d985b9c8a0 [fd  5] Read Persist Timeout=1648826183.835672
  0x55d985b9c930 [fd  6] Write Persist Timeout=1648826183.835672
  0x55d985b9c9c0 [fd  7] Read Timeout=1648826185.335672
  0x55d985b9c308 [fd  8] Read Persist Internal
  0x55d985b9ced0 [sig 2] Signal Persist
  0x55d985b9c6d0 [fd  -1] Internal Timeout=1648826183.835672
  0x55d985b9c780 [fd  -1] Internal Timeout=1648826185.335672
  0x55d985b9cb70 [fd  -1] Timeout=1648826183.835672
  0x55d985b9cd20 [fd  -1] Persist Timeout=1648826185.335672
  0x55d985b9ca50 [fd  -1] Timeout=1648826183.835672
  0x55d985b9cae0 [fd  -1] Timeout=1648826183.835672
  0x55d985b9cc90 [fd  -1] Persist Timeout=1648826183.835672
  0x55d985b9cc00 [fd  -1] Persist Timeout=1648826185.335672
Active events:
  0x55d985b9c8a0 [fd  5, priority=0] Read active
  0x55d985b9cdb0 [fd  -1, priority=0] Read active
  0x55d985b9ce40 [fd  -1, priority=0] Read Write Timeout active
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-dumpevents__SELECT" end time: Apr 01 15:16 UTC
"test-dumpevents__SELECT" time elapsed: 00:00:00
----------------------------------------------------------

39/54 Testing: regress__SELECT
39/54 Test: regress__SELECT
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__SELECT" start time: Apr 01 15:16 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/344 TESTS FAILED. (46 skipped)
<end of output>
Test time =  85.47 sec
----------------------------------------------------------
Test Failed.
"regress__SELECT" end time: Apr 01 15:17 UTC
"regress__SELECT" time elapsed: 00:01:25
----------------------------------------------------------

40/54 Testing: regress__SELECT_debug
40/54 Test: regress__SELECT_debug
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__SELECT_debug" start time: Apr 01 15:17 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/344 TESTS FAILED. (46 skipped)
<end of output>
Test time =  85.47 sec
----------------------------------------------------------
Test Failed.
"regress__SELECT_debug" end time: Apr 01 15:19 UTC
"regress__SELECT_debug" time elapsed: 00:01:25
----------------------------------------------------------

41/54 Testing: test-changelist__POLL
41/54 Test: test-changelist__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-changelist"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-changelist__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
write callback. should only see this once
timeout fired, time to end test
usec used=56, usec passed=1501787, cpu usage=0.00%
<end of output>
Test time =   1.50 sec
----------------------------------------------------------
Test Passed.
"test-changelist__POLL" end time: Apr 01 15:19 UTC
"test-changelist__POLL" time elapsed: 00:00:01
----------------------------------------------------------

42/54 Testing: test-eof__POLL
42/54 Test: test-eof__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-eof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-eof__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
read_cb: read 12
read_cb: read 0 - means EOF
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-eof__POLL" end time: Apr 01 15:19 UTC
"test-eof__POLL" time elapsed: 00:00:00
----------------------------------------------------------

43/54 Testing: test-closed__POLL
43/54 Test: test-closed__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-closed"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-closed__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
closed_cb: detected socket close with success
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-closed__POLL" end time: Apr 01 15:19 UTC
"test-closed__POLL" time elapsed: 00:00:00
----------------------------------------------------------

44/54 Testing: test-fdleak__POLL
44/54 Test: test-fdleak__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-fdleak"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-fdleak__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.04 sec
----------------------------------------------------------
Test Passed.
"test-fdleak__POLL" end time: Apr 01 15:19 UTC
"test-fdleak__POLL" time elapsed: 00:00:00
----------------------------------------------------------

45/54 Testing: test-init__POLL
45/54 Test: test-init__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-init"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-init__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-init__POLL" end time: Apr 01 15:19 UTC
"test-init__POLL" time elapsed: 00:00:00
----------------------------------------------------------

46/54 Testing: test-time__POLL
46/54 Test: test-time__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-time"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-time__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
event_base_dispatch=1, called=209020, EVENT=20000
<end of output>
Test time =   1.15 sec
----------------------------------------------------------
Test Passed.
"test-time__POLL" end time: Apr 01 15:19 UTC
"test-time__POLL" time elapsed: 00:00:01
----------------------------------------------------------

47/54 Testing: test-weof__POLL
47/54 Test: test-weof__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-weof"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-weof__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
write_cb: write 12
write_cb: write -1
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-weof__POLL" end time: Apr 01 15:19 UTC
"test-weof__POLL" time elapsed: 00:00:00
----------------------------------------------------------

48/54 Testing: test-dumpevents__POLL
48/54 Test: test-dumpevents__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-dumpevents" "|" "/usr/bin/python3" "/build/libevent/src/libevent-2.1.12-stable/test/check-dumpevents.py"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-dumpevents__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------
=====expected
Now= 1648826356.476418
Inserted:
  0x560d4238b7c0 [fd  4] Write
  0x560d4238b850 [fd  5] Read Persist Timeout=T+1
  0x560d4238b8e0 [fd  6] Write Persist Timeout=T+1
  0x560d4238b970 [fd  7] Read Timeout=T+2.5
  0x560d4238ba00 [fd  -1] Timeout=T+1
  0x560d4238ba90 [fd  -1] Timeout=T+1
  0x560d4238bb20 [fd  -1] Timeout=T+1
  0x560d4238bbb0 [fd  -1] Persist Timeout=T+2.5
  0x560d4238bc40 [fd  -1] Persist Timeout=T+1
  0x560d4238bcd0 [fd  -1] Persist Timeout=T+2.5
  0x560d4238be80 [sig 2] Signal Persist
Active:
  0x560d4238bd60 [fd  -1, priority=0] Read active
  0x560d4238bdf0 [fd  -1, priority=0] Read Write Timeout active
  0x560d4238b850 [fd  5, priority=0] Read active
======received
Inserted events:
  0x560d4238b7c0 [fd  4] Write
  0x560d4238b850 [fd  5] Read Persist Timeout=1648826357.476402
  0x560d4238b8e0 [fd  6] Write Persist Timeout=1648826357.476402
  0x560d4238b970 [fd  7] Read Timeout=1648826358.976402
  0x560d4238b308 [fd  8] Read Persist Internal
  0x560d4238be80 [sig 2] Signal Persist
  0x560d4238b680 [fd  -1] Internal Timeout=1648826357.476402
  0x560d4238b730 [fd  -1] Internal Timeout=1648826358.976402
  0x560d4238bb20 [fd  -1] Timeout=1648826357.476402
  0x560d4238bcd0 [fd  -1] Persist Timeout=1648826358.976402
  0x560d4238ba00 [fd  -1] Timeout=1648826357.476402
  0x560d4238ba90 [fd  -1] Timeout=1648826357.476402
  0x560d4238bc40 [fd  -1] Persist Timeout=1648826357.476402
  0x560d4238bbb0 [fd  -1] Persist Timeout=1648826358.976402
Active events:
  0x560d4238b850 [fd  5, priority=0] Read active
  0x560d4238bd60 [fd  -1, priority=0] Read active
  0x560d4238bdf0 [fd  -1, priority=0] Read Write Timeout active
<end of output>
Test time =   0.00 sec
----------------------------------------------------------
Test Passed.
"test-dumpevents__POLL" end time: Apr 01 15:19 UTC
"test-dumpevents__POLL" time elapsed: 00:00:00
----------------------------------------------------------

49/54 Testing: regress__POLL
49/54 Test: regress__POLL
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__POLL" start time: Apr 01 15:19 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/349 TESTS FAILED. (41 skipped)
<end of output>
Test time =  85.47 sec
----------------------------------------------------------
Test Failed.
"regress__POLL" end time: Apr 01 15:20 UTC
"regress__POLL" time elapsed: 00:01:25
----------------------------------------------------------

50/54 Testing: regress__POLL_debug
50/54 Test: regress__POLL_debug
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/regress" "--quiet"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"regress__POLL_debug" start time: Apr 01 15:20 UTC
Output:
----------------------------------------------------------

  FAIL /build/libevent/src/libevent-2.1.12-stable/test/regress_dns.c:2105: assert(gaic_freed != 1000): 1000 vs 1000dns/getaddrinfo_cancel_stress: 
  [getaddrinfo_cancel_stress FAILED]
1/349 TESTS FAILED. (41 skipped)
<end of output>
Test time =  85.49 sec
----------------------------------------------------------
Test Failed.
"regress__POLL_debug" end time: Apr 01 15:22 UTC
"regress__POLL_debug" time elapsed: 00:01:25
----------------------------------------------------------

51/54 Testing: test-ratelim__group_lim
51/54 Test: test-ratelim__group_lim
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-ratelim" "-g" "30000" "-n" "30" "-t" "100" "--check-grouplimit" "1000" "--check-stddev" "100"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-ratelim__group_lim" start time: Apr 01 15:22 UTC
Output:
----------------------------------------------------------
CPU is fast enough, timers bias is 0.396000
waiting for 30 conns
Average group read bucket level: 2958.591837
Average group write bucket level: 2958.591837
1: 1032.800000 per second
2: 1025.200000 per second
3: 1009.800000 per second
4: 1025.800000 per second
5: 1031.200000 per second
6: 1061.600000 per second
7: 1074.800000 per second
8: 1073.800000 per second
9: 1074.000000 per second
10: 978.000000 per second
11: 968.800000 per second
12: 930.600000 per second
13: 904.600000 per second
14: 977.400000 per second
15: 967.800000 per second
15: 1017.800000 per second
17: 982.400000 per second
18: 974.000000 per second
19: 971.800000 per second
20: 988.400000 per second
21: 959.600000 per second
22: 963.600000 per second
23: 967.400000 per second
24: 970.600000 per second
25: 1007.800000 per second
26: 1036.600000 per second
27: 1022.800000 per second
28: 1025.800000 per second
29: 991.000000 per second
30: 988.600000 per second
   total: 30004.400000 per second
  [Off by -4.400000]
 average: 1000.146667 per second
  [Off by -0.146667]
  stddev: 41.066383 per second
<end of output>
Test time =   5.30 sec
----------------------------------------------------------
Test Passed.
"test-ratelim__group_lim" end time: Apr 01 15:22 UTC
"test-ratelim__group_lim" time elapsed: 00:00:05
----------------------------------------------------------

52/54 Testing: test-ratelim__con_lim
52/54 Test: test-ratelim__con_lim
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-ratelim" "-c" "1000" "-n" "30" "-t" "100" "--check-connlimit" "50" "--check-stddev" "50"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-ratelim__con_lim" start time: Apr 01 15:22 UTC
Output:
----------------------------------------------------------
CPU is fast enough, timers bias is 0.464000
waiting for 30 conns
Average read bucket level: 100.000000
Average write bucket level: 100.000000
Highest read bucket level: 100.000000
Highest write bucket level: 100.000000
Average max-to-read: 100.000000
Average max-to-write: 100.000000
Average group read bucket level: 0.000000
Average group write bucket level: 0.000000
1: 1000.000000 per second
2: 1000.000000 per second
3: 1000.000000 per second
4: 1000.000000 per second
5: 1000.000000 per second
6: 1000.000000 per second
7: 1000.000000 per second
8: 1000.000000 per second
9: 1000.000000 per second
10: 1000.000000 per second
11: 1000.000000 per second
12: 1000.000000 per second
13: 1000.000000 per second
14: 1000.000000 per second
15: 1000.000000 per second
15: 1000.000000 per second
17: 1000.000000 per second
18: 1000.000000 per second
19: 1000.000000 per second
20: 1000.000000 per second
21: 1000.000000 per second
22: 1000.000000 per second
23: 1000.000000 per second
24: 1000.000000 per second
25: 1000.000000 per second
26: 1000.000000 per second
27: 1000.000000 per second
28: 1000.000000 per second
29: 1000.000000 per second
30: 1000.000000 per second
   total: 30000.000000 per second
 average: 1000.000000 per second
  [Off by 0.000000]
  stddev: 0.000000 per second
<end of output>
Test time =   5.30 sec
----------------------------------------------------------
Test Passed.
"test-ratelim__con_lim" end time: Apr 01 15:22 UTC
"test-ratelim__con_lim" time elapsed: 00:00:05
----------------------------------------------------------

53/54 Testing: test-ratelim__group_con_lim
53/54 Test: test-ratelim__group_con_lim
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-ratelim" "-c" "1000" "-g" "30000" "-n" "30" "-t" "100" "--check-grouplimit" "1000" "--check-connlimit" "50" "--check-stddev" "50"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-ratelim__group_con_lim" start time: Apr 01 15:22 UTC
Output:
----------------------------------------------------------
CPU is fast enough, timers bias is 0.377000
waiting for 30 conns
Average read bucket level: 100.000000
Average write bucket level: 100.000000
Highest read bucket level: 100.000000
Highest write bucket level: 100.000000
Average max-to-read: 100.000000
Average max-to-write: 100.000000
Average group read bucket level: 3000.000000
Average group write bucket level: 3000.000000
1: 1000.000000 per second
2: 1000.000000 per second
3: 1000.000000 per second
4: 1000.000000 per second
5: 1000.000000 per second
6: 1000.000000 per second
7: 1000.000000 per second
8: 1000.000000 per second
9: 1000.000000 per second
10: 1000.000000 per second
11: 1000.000000 per second
12: 1000.000000 per second
13: 1000.000000 per second
14: 1000.000000 per second
15: 1000.000000 per second
15: 1000.000000 per second
17: 1000.000000 per second
18: 1000.000000 per second
19: 1000.000000 per second
20: 1000.000000 per second
21: 1000.000000 per second
22: 1000.000000 per second
23: 1000.000000 per second
24: 1000.000000 per second
25: 1000.000000 per second
26: 1000.000000 per second
27: 1000.000000 per second
28: 1000.000000 per second
29: 1000.000000 per second
30: 1000.000000 per second
   total: 30000.000000 per second
  [Off by 0.000000]
 average: 1000.000000 per second
  [Off by 0.000000]
  stddev: 0.000000 per second
<end of output>
Test time =   5.30 sec
----------------------------------------------------------
Test Passed.
"test-ratelim__group_con_lim" end time: Apr 01 15:22 UTC
"test-ratelim__group_con_lim" time elapsed: 00:00:05
----------------------------------------------------------

54/54 Testing: test-ratelim__group_con_lim_drain
54/54 Test: test-ratelim__group_con_lim_drain
Command: "/build/libevent/src/libevent-2.1.12-stable/build/bin/test-ratelim" "-c" "1000" "-g" "35000" "-n" "30" "-t" "100" "-G" "500" "--check-grouplimit" "1000" "--check-connlimit" "50" "--check-stddev" "50"
Directory: /build/libevent/src/libevent-2.1.12-stable/build
"test-ratelim__group_con_lim_drain" start time: Apr 01 15:22 UTC
Output:
----------------------------------------------------------
CPU is fast enough, timers bias is 0.438000
waiting for 30 conns
Average read bucket level: 100.000000
Average write bucket level: 100.000000
Highest read bucket level: 100.000000
Highest write bucket level: 100.000000
Average max-to-read: 100.000000
Average max-to-write: 100.000000
Average group read bucket level: 3500.000000
Average group write bucket level: 3500.000000
1: 1000.000000 per second
2: 1000.000000 per second
3: 1000.000000 per second
4: 1000.000000 per second
5: 1000.000000 per second
6: 1000.000000 per second
7: 1000.000000 per second
8: 1000.000000 per second
9: 1000.000000 per second
10: 1000.000000 per second
11: 1000.000000 per second
12: 1000.000000 per second
13: 1000.000000 per second
14: 1000.000000 per second
15: 1000.000000 per second
15: 1000.000000 per second
17: 1000.000000 per second
18: 1000.000000 per second
19: 1000.000000 per second
20: 1000.000000 per second
21: 1000.000000 per second
22: 1000.000000 per second
23: 1000.000000 per second
24: 1000.000000 per second
25: 1000.000000 per second
26: 1000.000000 per second
27: 1000.000000 per second
28: 1000.000000 per second
29: 1000.000000 per second
30: 1000.000000 per second
   total: 30000.000000 per second
 average: 1000.000000 per second
  [Off by 0.000000]
  stddev: 0.000000 per second
<end of output>
Test time =   5.30 sec
----------------------------------------------------------
Test Passed.
"test-ratelim__group_con_lim_drain" end time: Apr 01 15:22 UTC
"test-ratelim__group_con_lim_drain" time elapsed: 00:00:05
----------------------------------------------------------

End testing: Apr 01 15:22 UTC

Edit:
https://github.com/libevent/libevent/issues/1271

Last edited by loqs (2022-04-24 19:29:33)

Offline

Board footer

Powered by FluxBB