Go to:
Gentoo Home
Documentation
Forums
Lists
Bugs
Planet
Store
Wiki
Get Gentoo!
Gentoo's Bugzilla – Attachment 825309 Details for
Bug 878221
app-admin/rsyslog-8.2210.0-r1 fails tests: FAIL: daqueue-dirty-shutdown.sh
Home
|
New
–
[Ex]
|
Browse
|
Search
|
Privacy Policy
|
[?]
|
Reports
|
Requests
|
Help
|
New Account
|
Log In
[x]
|
Forgot Password
Login:
[x]
1-test-suite.log
1-test-suite.log (text/plain), 15.18 KB, created by
Agostino Sarubbo
on 2022-10-25 06:44:49 UTC
(
hide
)
Description:
1-test-suite.log
Filename:
MIME Type:
Creator:
Agostino Sarubbo
Created:
2022-10-25 06:44:49 UTC
Size:
15.18 KB
patch
obsolete
>============================================ > rsyslog 8.2210.0: tests/test-suite.log >============================================ > ># TOTAL: 557 ># PASS: 546 ># SKIP: 10 ># XFAIL: 0 ># FAIL: 1 ># XPASS: 0 ># ERROR: 0 > >.. contents:: :depth: 2 > >SKIP: allowed-sender-tcp-hostname-fail >====================================== > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:45:42[0] Test: ./allowed-sender-tcp-hostname-fail.sh >------------------------------------------------------------ >HTTP endpoint 'http://testbench.rsyslog.com/testbench/echo-get.php' is not reachable. Skipping test ... >SKIP allowed-sender-tcp-hostname-fail.sh (exit status: 77) > >SKIP: daqueue-persist >===================== > >=============================================================================== >[daqueue-persist.sh]: test data persisting at shutdown >TEST is currently DISABLE because it is unstable >SKIP daqueue-persist.sh (exit status: 77) > >FAIL: daqueue-dirty-shutdown >============================ > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:46:03[0] Test: ./daqueue-dirty-shutdown.sh >------------------------------------------------------------ >config rstb_777097_8113cd96lxNQ_.conf is: > 1 module(load="../plugins/imdiag/.libs/imdiag") > 2 global(inputs.timeout.shutdown="60000" > 3 default.action.queue.timeoutshutdown="20000" > 4 default.action.queue.timeoutEnqueue="20000") > 5 # use legacy-style for the following settings so that we can override if needed > 6 $MainmsgQueueTimeoutEnqueue 20000 > 7 $MainmsgQueueTimeoutShutdown 10000 > 8 $IMDiagListenPortFileName rstb_777097_8113cd96lxNQ.imdiag.port > 9 $IMDiagServerRun 0 > 10 $IMDiagAbortTimeout 580 > 11 > 12 :syslogtag, contains, "rsyslogd" ./rstb_777097_8113cd96lxNQ.started > 13 ###### end of testbench instrumentation part, test conf follows: > 14 > 15 module(load="../plugins/omtesting/.libs/omtesting") > 16 > 17 # set spool locations and switch queue to disk-only mode > 18 global(workDirectory="rstb_777097_8113cd96lxNQ.spool") > 19 main_queue(queue.filename="mainq" queue.saveonshutdown="on" > 20 queue.timeoutshutdown="1" queue.maxfilesize="1m" > 21 queue.timeoutworkerthreadshutdown="500" queue.size="200000" > 22 ) > 23 > 24 :msg, contains, "msgnum:" :omtesting:sleep 10 0 >rsyslogd: NOTE: RSYSLOG_DEBUG_TIMEOUTS_TO_STDERR activated >main Q:Reg: worker start requested, num workers currently 0 >main Q:Reg: wrkr start initiated with state 0, num workers now 1 >rsyslog debug: main Q:Reg: worker 0x55f07f7032c0 started >rsyslog debug: main Q:Reg: started with state 3, num workers now 1 >17:46:03[0] rstb_777097_8113cd96lxNQ:.pid found, pid 14008 >17:46:04[1] rsyslogd startup msg seen, pid 14008 >waiting for file rstb_777097_8113cd96lxNQ.imdiag.port >imdiag port: 38799 >injecting 210000 messages >main Q:DAwpool: worker start requested, num workers currently 0 >main Q:DAwpool: wrkr start initiated with state 0, num workers now 1 >rsyslog debug: main Q:DAwpool: worker 0x55f07f7035a0 started >rsyslog debug: main Q:DAwpool: started with state 3, num workers now 1 >main Q[DA]:Reg: worker start requested, num workers currently 0 >main Q[DA]:Reg: wrkr start initiated with state 0, num workers now 1 >rsyslog debug: main Q[DA]:Reg: worker 0x55f07f715000 started >rsyslog debug: main Q[DA]:Reg: started with state 3, num workers now 1 >rsyslog debug: main Q:DAwpool/w0: enter WrkrExecCleanup >rsyslog debug: 0x55f07f7036e0: worker exiting >imdiag[38799]: 210000 messages injected >spool files immediately before shutdown: >mainq.00000001 >mainq.00000002 >mainq.00000003 >mainq.00000004 >mainq.00000005 >mainq.00000006 >mainq.00000007 >mainq.00000008 >mainq.00000009 >mainq.00000010 >mainq.00000011 >mainq.00000012 >mainq.00000013 >mainq.00000014 >mainq.00000015 >mainq.00000016 >mainq.00000017 >mainq.qi >rsyslogd debug: info: trying to cooperatively stop input ../plugins/imdiag/.libs/imdiag, timeout 60000 ms >main Q:DAwpool: worker start requested, num workers currently 0 >rsyslog debug: main Q:DAwpool: worker start requested during shutdown - ignored >rsyslog internal message (6,-2041): main Q: regular queue shutdown timed out on primary queue (this is OK, timeout was 1) [v8.2210.0 try https://www.rsyslog.com/e/2041 ] >rsyslog internal message (6,-2041): main Q: regular queue shutdown timed out on DA queue (this is OK, timeout was 1) [v8.2210.0 try https://www.rsyslog.com/e/2041 ] >rsyslog debug: main Q:Reg/w0: enter WrkrExecCleanup >rsyslog debug: 0x55f07f703400: worker exiting >spool files immediately after shutdown (but before kill): >total 17692 >-rw------- 1 portage portage 1048917 Oct 24 17:46 mainq.00000017 >-rw------- 1 portage portage 1048683 Oct 24 17:46 mainq.00000018 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000019 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000020 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000021 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000022 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000023 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000024 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000025 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000026 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000027 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000028 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000029 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000030 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000031 >-rw------- 1 portage portage 1048621 Oct 24 17:46 mainq.00000032 >-rw------- 1 portage portage 1048757 Oct 24 17:46 mainq.00000033 >-rw------- 1 portage portage 216204 Oct 24 17:46 mainq.00000034 >-rw------- 1 portage portage 570 Oct 24 17:46 mainq.qi >17:46:05[2] wait on shutdown of 14008 >./diag.sh: line 528: 14008 Killed LD_PRELOAD= ../tools/rsyslogd -C -n -irstb_777097_8113cd96lxNQ:.pid -M../runtime/.libs:../.libs -frstb_777097_8113cd96lxNQ_.conf >spool files after kill: >total 17336 >-rw------- 1 portage portage 1048683 Oct 24 17:46 mainq.00000018 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000019 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000020 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000021 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000022 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000023 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000024 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000025 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000026 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000027 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000028 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000029 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000030 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000031 >-rw------- 1 portage portage 1048621 Oct 24 17:46 mainq.00000032 >-rw------- 1 portage portage 1048757 Oct 24 17:46 mainq.00000033 >-rw------- 1 portage portage 904450 Oct 24 17:46 mainq.00000034 >-rw------- 1 portage portage 570 Oct 24 17:46 mainq.qi >.qi file contents: ><OPB:1:qqueue:1: >+iQueueSize:2:5:41983: >+tVars.disk.sizeOnDisk:2:8:17850581: >>End >. ><Obj:1:strm:1: >+iCurrFNum:2:2:34: >+pszFName:1:5:mainq: >+iMaxFiles:2:8:10000000: >+bDeleteOnClose:2:1:0: >+sType:2:1:1: >+tOperationsMode:2:1:2: >+tOpenMode:2:3:384: >+iCurrOffs:2:5:21788: >+inode:2:1:0: >+strtOffs:2:1:0: >+bPrevWasNL:2:1:0: >>End >. ><Obj:1:strm:1: >+iCurrFNum:2:2:17: >+pszFName:1:5:mainq: >+iMaxFiles:2:8:10000000: >+bDeleteOnClose:2:1:1: >+sType:2:1:1: >+tOperationsMode:2:1:1: >+tOpenMode:2:3:384: >+iCurrOffs:2:6:307546: >+inode:2:1:0: >+strtOffs:2:6:307546: >+bPrevWasNL:2:1:0: >>End >. >RSYSLOG RESTART > >mkdir: cannot create directory ârstb_777097_8113cd96lxNQ.spoolâ: File exists >config rstb_777097_8113cd96lxNQ_.conf is: > 1 module(load="../plugins/imdiag/.libs/imdiag") > 2 global(inputs.timeout.shutdown="60000" > 3 default.action.queue.timeoutshutdown="20000" > 4 default.action.queue.timeoutEnqueue="20000") > 5 # use legacy-style for the following settings so that we can override if needed > 6 $MainmsgQueueTimeoutEnqueue 20000 > 7 $MainmsgQueueTimeoutShutdown 10000 > 8 $IMDiagListenPortFileName rstb_777097_8113cd96lxNQ.imdiag.port > 9 $IMDiagServerRun 0 > 10 $IMDiagAbortTimeout 580 > 11 > 12 :syslogtag, contains, "rsyslogd" ./rstb_777097_8113cd96lxNQ.started > 13 ###### end of testbench instrumentation part, test conf follows: > 14 > 15 module(load="../plugins/omtesting/.libs/omtesting") > 16 > 17 global(workDirectory="rstb_777097_8113cd96lxNQ.spool") > 18 main_queue(queue.filename="mainq" queue.saveonshutdown="on" > 19 queue.maxfilesize="1m" # note: now regular shutdown timeout! > 20 queue.timeoutworkerthreadshutdown="500" queue.size="200000" > 21 ) > 22 > 23 $template outfmt,"%msg:F,58:2%\n" > 24 action(type="omfile" file="rstb_777097_8113cd96lxNQ.out.log" template="outfmt") >rsyslogd: NOTE: RSYSLOG_DEBUG_TIMEOUTS_TO_STDERR activated >rsyslogd: file 'rstb_777097_8113cd96lxNQ.spool/mainq.00000017': open error: No such file or directory [v8.2210.0 try https://www.rsyslog.com/e/2040 ] >main Q[DA]:Reg: worker start requested, num workers currently 0 >main Q[DA]:Reg: wrkr start initiated with state 0, num workers now 1 >rsyslog debug: main Q[DA]:Reg: worker 0x5567bb4fe8e0 started >rsyslog debug: main Q[DA]:Reg: started with state 3, num workers now 1 >rsyslogd: main Q[DA]: lost 41983 messages from diskqueue (invalid .qi file) [v8.2210.0] >rsyslog debug: main Q[DA]:Reg/w0: enter WrkrExecCleanup >rsyslog debug: 0x5567bb4fea60: worker exiting >main Q:Reg: worker start requested, num workers currently 0 >main Q:Reg: wrkr start initiated with state 0, num workers now 1 >rsyslog debug: main Q:Reg: worker 0x5567bb4e9110 started >rsyslog debug: main Q:Reg: started with state 3, num workers now 1 >17:46:06[3] rstb_777097_8113cd96lxNQ:.pid found, pid 14064 >17:46:06[3] rsyslogd startup msg seen, pid 14064 >waiting for file rstb_777097_8113cd96lxNQ.imdiag.port >imdiag port: 37121 >Shutting down instance 1 >imdiag: wait q_empty: qsize 0 nempty 1 >imdiag[37121]: mainqueue empty >rsyslogd debug: info: trying to cooperatively stop input ../plugins/imdiag/.libs/imdiag, timeout 60000 ms >main Q:DAwpool: worker start requested, num workers currently 0 >rsyslog debug: main Q:DAwpool: worker start requested during shutdown - ignored >main Q[DA]:Reg: worker start requested, num workers currently 0 >rsyslog debug: main Q[DA]:Reg: worker start requested during shutdown - ignored >rsyslog debug: main Q:Reg/w0: enter WrkrExecCleanup >rsyslog debug: 0x5567bb4e9250: worker exiting >rsyslog debug: main Q[DA]:Reg/w0: thread joined >rsyslog debug: main Q:Reg/w0: thread joined >17:46:06[3] wait on shutdown of 14064 >FAIL: spool directory is not empty! >total 17336 >-rw------- 1 portage portage 1048683 Oct 24 17:46 mainq.00000018 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000019 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000020 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000021 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000022 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000023 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000024 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000025 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000026 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000027 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000028 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000029 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000030 >-rw------- 1 portage portage 1048755 Oct 24 17:46 mainq.00000031 >-rw------- 1 portage portage 1048621 Oct 24 17:46 mainq.00000032 >-rw------- 1 portage portage 1048757 Oct 24 17:46 mainq.00000033 >-rw------- 1 portage portage 904450 Oct 24 17:46 mainq.00000034 >-rw------- 1 portage portage 570 Oct 24 17:46 mainq.qi >not reporting failure as RSYSLOG_STATSURL is not set >17:46:07[4] FAIL: Test ./daqueue-dirty-shutdown.sh (took 4 seconds) >FAIL daqueue-dirty-shutdown.sh (exit status: 1) > >SKIP: imuxsock_logger_ratelimit >=============================== > >[imuxsock_logger_ratelimit.sh]: test rate limiting with imuxsock >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:51:46[0] Test: ./imuxsock_logger_ratelimit.sh >------------------------------------------------------------ >liblogging-stdlog not available - skipping test >SKIP imuxsock_logger_ratelimit.sh (exit status: 77) > >SKIP: imuxsock_traillf >====================== > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:51:52[0] Test: ./imuxsock_traillf.sh >------------------------------------------------------------ >liblogging-stdlog not available - skipping test >SKIP imuxsock_traillf.sh (exit status: 77) > >SKIP: imuxsock_ccmiddle >======================= > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:51:52[0] Test: ./imuxsock_ccmiddle.sh >------------------------------------------------------------ >liblogging-stdlog not available - skipping test >SKIP imuxsock_ccmiddle.sh (exit status: 77) > >SKIP: imuxsock_traillf_syssock >============================== > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:51:54[0] Test: ./imuxsock_traillf_syssock.sh >------------------------------------------------------------ >liblogging-stdlog not available - skipping test >SKIP imuxsock_traillf_syssock.sh (exit status: 77) > >SKIP: imuxsock_ccmiddle_syssock >=============================== > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:51:54[0] Test: ./imuxsock_ccmiddle_syssock.sh >------------------------------------------------------------ >Linux >liblogging-stdlog not available - skipping test >SKIP imuxsock_ccmiddle_syssock.sh (exit status: 77) > >SKIP: omprog-restart-terminated >=============================== > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:58:54[0] Test: ./omprog-restart-terminated.sh >------------------------------------------------------------ >Testbench requires unavailable command: lsof >SKIP omprog-restart-terminated.sh (exit status: 77) > >SKIP: omprog-restart-terminated-outfile >======================================= > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >17:58:54[0] Test: ./omprog-restart-terminated-outfile.sh >------------------------------------------------------------ >Testbench requires unavailable command: lsof >SKIP omprog-restart-terminated-outfile.sh (exit status: 77) > >SKIP: omfwd_fast_imuxsock >========================= > >testbench: TZ env var not set, setting it to UTC >------------------------------------------------------------ >18:01:24[0] Test: ./omfwd_fast_imuxsock.sh >------------------------------------------------------------ >liblogging-stdlog not available - skipping test >SKIP omfwd_fast_imuxsock.sh (exit status: 77) >
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=825309">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 878221
:
825307
| 825309
