Bug 50372 - 40_mail/02virus00basic failed
40_mail/02virus00basic failed
Status: NEW
Product: UCS Test
Classification: Unclassified
Component: Mail
unspecified
Other Linux
: P5 normal (vote)
: ---
Assigned To: Mail maintainers
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2019-10-16 11:00 CEST by Florian Best
Modified: 2019-10-16 11:00 CEST (History)
0 users

See Also:
What kind of report is it?: ---
What type of bug is this?: ---
Who will be affected by this bug?: ---
How will those affected feel about the bug?: ---
User Pain:
Enterprise Customer affected?:
School Customer affected?:
ISV affected?:
Waiting Support:
Flags outvoted (downgraded) after PO Review:
Ticket number:
Bug group (optional):
Max CVSS v3 score:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Florian Best univentionstaff 2019-10-16 11:00:07 CEST
https://jenkins.knut.univention.de:8181/job/UCS-4.4/job/UCS-4.4-2/job/AutotestJoin/32/SambaVersion=samba-pdc,Systemrolle=slave/testReport/40_mail/02virus00basic/slave098/

[2019-10-16 04:18:57.312437] Setting mail/alias/root
[2019-10-16 04:18:57.419421] File: /etc/aliases
[2019-10-16 04:18:58.446202] Reloading amavis configuration (via systemctl): amavis.service.
[2019-10-16 04:18:58.542581] Reloading postfix configuration (via systemctl): postfix.service.
[2019-10-16 04:18:58.551798] Creating users/user object with /usr/sbin/udm-test users/user create --position cn=users,dc=autotest098,dc=local --set mailHomeServer=slave098.autotest098.local --set mailPrimaryAddress=virusvqhvas6ny1test@autotest098.local --set username=sqh3kvysxw --set firstname=s2odvbbfgx --set lastname=x7pfsi2gyd --set password=univention
[2019-10-16 04:19:00.720322] *** Sending mail: recipients=['virusvqhvas6ny1test@autotest098.local'] sender='tarpit@example.com' subject='Filter on' idstring='no id string' gtube=False server='localhost' port=25 tls=False username=None password=None HELO/EHLO='ucstest.28012.example.com'
[2019-10-16 04:19:00.720358] *** Warning: A body with only one word will be rated with BODY_SINGLE_WORD=2.499 and probably lead to the message being identified as spam.
(2019-10-16 04:19:00.742098) send: 'ehlo ucstest.28012.example.com\r\n'
(2019-10-16 04:19:00.742306) reply: '250-slave098.autotest098.local\r\n'
(2019-10-16 04:19:00.742384) reply: '250-PIPELINING\r\n'
(2019-10-16 04:19:00.742445) reply: '250-SIZE 10240000\r\n'
(2019-10-16 04:19:00.742503) reply: '250-VRFY\r\n'
(2019-10-16 04:19:00.742578) reply: '250-ETRN\r\n'
(2019-10-16 04:19:00.742637) reply: '250-STARTTLS\r\n'
(2019-10-16 04:19:00.742696) reply: '250-ENHANCEDSTATUSCODES\r\n'
(2019-10-16 04:19:00.742755) reply: '250-8BITMIME\r\n'
(2019-10-16 04:19:00.742813) reply: '250 DSN\r\n'
(2019-10-16 04:19:00.742841) reply: retcode (250); Msg: slave098.autotest098.local
(2019-10-16 04:19:00.742847) PIPELINING
(2019-10-16 04:19:00.742852) SIZE 10240000
(2019-10-16 04:19:00.742856) VRFY
(2019-10-16 04:19:00.742860) ETRN
(2019-10-16 04:19:00.742864) STARTTLS
(2019-10-16 04:19:00.742868) ENHANCEDSTATUSCODES
(2019-10-16 04:19:00.742873) 8BITMIME
(2019-10-16 04:19:00.742891) DSN
(2019-10-16 04:19:00.745536) send: 'mail FROM:<tarpit@example.com> size=717\r\n'
(2019-10-16 04:19:00.758790) reply: '250 2.1.0 Ok\r\n'
(2019-10-16 04:19:00.758839) reply: retcode (250); Msg: 2.1.0 Ok
(2019-10-16 04:19:00.758960) send: 'rcpt TO:<virusvqhvas6ny1test@autotest098.local>\r\n'
(2019-10-16 04:19:00.773761) reply: '250 2.1.5 Ok\r\n'
(2019-10-16 04:19:00.773808) reply: retcode (250); Msg: 2.1.5 Ok
(2019-10-16 04:19:00.773868) send: 'data\r\n'
(2019-10-16 04:19:00.773986) reply: '354 End data with <CR><LF>.<CR><LF>\r\n'
(2019-10-16 04:19:00.774027) reply: retcode (354); Msg: End data with <CR><LF>.<CR><LF>
(2019-10-16 04:19:00.774462) data: (354, 'End data with <CR><LF>.<CR><LF>')
(2019-10-16 04:19:00.774736) send: 'Content-Type: multipart/mixed; boundary="===============7859238598441425491=="\r\nMIME-Version: 1.0\r\nFrom: tarpit@example.com\r\nTo: virusvqhvas6ny1test@autotest098.local\r\nDate: Wed, 16 Oct 2019 04:19:00 +0000\r\nSubject: Filter on\r\nUCS-TEST: no id string\r\nMessage-Id: 50edd244-efbb-11e9-aca9-525400d52fa1.c0bptly7tj@autotest098.local\r\n\r\n--===============7859238598441425491==\r\nContent-Type: text/plain; charset="us-ascii"\r\nMIME-Version: 1.0\r\nContent-Transfer-Encoding: 7bit\r\n\r\n1571192340.72\r\n--===============7859238598441425491==\r\nContent-Type: text/plain; charset="us-ascii"\r\nMIME-Version: 1.0\r\nContent-Transfer-Encoding: 7bit\r\n\r\nX5O!P%@AP[4\\PZX54(P^)7CC)7}$EICAR-STANDARD-ANTIVIRUS-TEST-FILE!$H+H*\r\n--===============7859238598441425491==--\r\n.\r\n'
(2019-10-16 04:19:00.785529) reply: '250 2.0.0 Ok: queued as BCC6E401023\r\n'
(2019-10-16 04:19:00.785564) reply: retcode (250); Msg: 2.0.0 Ok: queued as BCC6E401023
(2019-10-16 04:19:00.785572) data: (250, '2.0.0 Ok: queued as BCC6E401023')
(2019-10-16 04:19:00.785581) send: 'quit\r\n'
(2019-10-16 04:19:00.785671) reply: '221 2.0.0 Bye\r\n'
(2019-10-16 04:19:00.785711) reply: retcode (221); Msg: 2.0.0 Bye
[2019-10-16 04:19:05.799192] Setting mail/antivir/spam
[2019-10-16 04:19:05.908119] Multifile: /etc/postfix/main.cf
[2019-10-16 04:19:06.347088] File: /etc/amavis/conf.d/60-univention
[2019-10-16 04:19:06.615087] File: /etc/amavis/conf.d/15-content_filter_mode
[2019-10-16 04:19:06.662916] Multifile: /etc/postfix/master.cf
[2019-10-16 04:19:09.188706] Reloading amavis configuration (via systemctl): amavis.service.
[2019-10-16 04:19:09.274079] Reloading postfix configuration (via systemctl): postfix.service.
[2019-10-16 04:19:09.275095] *** Sending mail: recipients=['virusvqhvas6ny1test@autotest098.local'] sender='tarpit@example.com' subject='Filter off' idstring='no id string' gtube=False server='localhost' port=25 tls=False username=None password=None HELO/EHLO='ucstest.28012.example.com'
[2019-10-16 04:19:09.275118] *** Warning: A body with only one word will be rated with BODY_SINGLE_WORD=2.499 and probably lead to the message being identified as spam.
(2019-10-16 04:19:09.313146) send: 'ehlo ucstest.28012.example.com\r\n'
(2019-10-16 04:19:09.313349) reply: '250-slave098.autotest098.local\r\n'
(2019-10-16 04:19:09.313477) reply: '250-PIPELINING\r\n'
(2019-10-16 04:19:09.313544) reply: '250-SIZE 10240000\r\n'
(2019-10-16 04:19:09.313605) reply: '250-VRFY\r\n'
(2019-10-16 04:19:09.313664) reply: '250-ETRN\r\n'
(2019-10-16 04:19:09.313723) reply: '250-STARTTLS\r\n'
(2019-10-16 04:19:09.313783) reply: '250-ENHANCEDSTATUSCODES\r\n'
(2019-10-16 04:19:09.313841) reply: '250-8BITMIME\r\n'
(2019-10-16 04:19:09.313899) reply: '250 DSN\r\n'
(2019-10-16 04:19:09.313928) reply: retcode (250); Msg: slave098.autotest098.local
(2019-10-16 04:19:09.313936) PIPELINING
(2019-10-16 04:19:09.313941) SIZE 10240000
(2019-10-16 04:19:09.313945) VRFY
(2019-10-16 04:19:09.313949) ETRN
(2019-10-16 04:19:09.313953) STARTTLS
(2019-10-16 04:19:09.313957) ENHANCEDSTATUSCODES
(2019-10-16 04:19:09.313961) 8BITMIME
(2019-10-16 04:19:09.313977) DSN
(2019-10-16 04:19:09.314359) send: 'mail FROM:<tarpit@example.com> size=718\r\n'
(2019-10-16 04:19:09.329461) reply: '250 2.1.0 Ok\r\n'
(2019-10-16 04:19:09.329514) reply: retcode (250); Msg: 2.1.0 Ok
(2019-10-16 04:19:09.329647) send: 'rcpt TO:<virusvqhvas6ny1test@autotest098.local>\r\n'
(2019-10-16 04:19:09.344364) reply: '250 2.1.5 Ok\r\n'
(2019-10-16 04:19:09.344413) reply: retcode (250); Msg: 2.1.5 Ok
(2019-10-16 04:19:09.344473) send: 'data\r\n'
(2019-10-16 04:19:09.344754) reply: '354 End data with <CR><LF>.<CR><LF>\r\n'
(2019-10-16 04:19:09.344777) reply: retcode (354); Msg: End data with <CR><LF>.<CR><LF>
(2019-10-16 04:19:09.344783) data: (354, 'End data with <CR><LF>.<CR><LF>')
(2019-10-16 04:19:09.344982) send: 'Content-Type: multipart/mixed; boundary="===============2083117090301651233=="\r\nMIME-Version: 1.0\r\nFrom: tarpit@example.com\r\nTo: virusvqhvas6ny1test@autotest098.local\r\nDate: Wed, 16 Oct 2019 04:19:09 +0000\r\nSubject: Filter off\r\nUCS-TEST: no id string\r\nMessage-Id: 56072b7c-efbb-11e9-aca9-525400d52fa1.v3d8hzneub@autotest098.local\r\n\r\n--===============2083117090301651233==\r\nContent-Type: text/plain; charset="us-ascii"\r\nMIME-Version: 1.0\r\nContent-Transfer-Encoding: 7bit\r\n\r\n1571192349.27\r\n--===============2083117090301651233==\r\nContent-Type: text/plain; charset="us-ascii"\r\nMIME-Version: 1.0\r\nContent-Transfer-Encoding: 7bit\r\n\r\nX5O!P%@AP[4\\PZX54(P^)7CC)7}$EICAR-STANDARD-ANTIVIRUS-TEST-FILE!$H+H*\r\n--===============2083117090301651233==--\r\n.\r\n'
(2019-10-16 04:19:09.354973) reply: '250 2.0.0 Ok: queued as 53F5E401031\r\n'
(2019-10-16 04:19:09.355021) reply: retcode (250); Msg: 2.0.0 Ok: queued as 53F5E401031
(2019-10-16 04:19:09.355472) data: (250, '2.0.0 Ok: queued as 53F5E401031')
(2019-10-16 04:19:09.355533) send: 'quit\r\n'
(2019-10-16 04:19:09.355931) reply: '221 2.0.0 Bye\r\n'
(2019-10-16 04:19:09.355974) reply: retcode (221); Msg: 2.0.0 Bye
[2019-10-16 04:19:09.356057] Waiting up to 60 seconds
[2019-10-16 04:19:10.357816] Waiting up to 59 seconds
[2019-10-16 04:19:11.359616] Waiting up to 58 seconds
[2019-10-16 04:19:12.361437] Waiting up to 57 seconds
[2019-10-16 04:19:13.363249] Waiting up to 56 seconds
[2019-10-16 04:19:14.364965] Waiting up to 55 seconds
[2019-10-16 04:19:15.366502] Waiting up to 54 seconds
[2019-10-16 04:19:16.368176] Waiting up to 53 seconds
[2019-10-16 04:19:17.370039] Waiting up to 52 seconds
[2019-10-16 04:19:18.371879] Waiting up to 51 seconds
[2019-10-16 04:19:19.373675] Waiting up to 50 seconds
[2019-10-16 04:19:20.375476] Waiting up to 49 seconds
[2019-10-16 04:19:21.377238] Waiting up to 48 seconds
[2019-10-16 04:19:22.378950] Waiting up to 47 seconds
[2019-10-16 04:19:23.380725] Waiting up to 46 seconds
[2019-10-16 04:19:24.382424] Waiting up to 45 seconds
[2019-10-16 04:19:25.384151] Waiting up to 44 seconds
[2019-10-16 04:19:26.384868] Waiting up to 43 seconds
[2019-10-16 04:19:27.386608] Waiting up to 42 seconds
[2019-10-16 04:19:28.387482] Waiting up to 41 seconds
[2019-10-16 04:19:29.389204] Waiting up to 40 seconds
[2019-10-16 04:19:30.390991] Waiting up to 39 seconds
[2019-10-16 04:19:31.392853] Waiting up to 38 seconds
[2019-10-16 04:19:32.394684] Waiting up to 37 seconds
[2019-10-16 04:19:33.396620] Waiting up to 36 seconds
[2019-10-16 04:19:34.398396] Waiting up to 35 seconds
[2019-10-16 04:19:35.400134] Waiting up to 34 seconds
[2019-10-16 04:19:36.401847] Waiting up to 33 seconds
[2019-10-16 04:19:37.403538] Waiting up to 32 seconds
[2019-10-16 04:19:38.405112] Waiting up to 31 seconds
[2019-10-16 04:19:39.406856] Waiting up to 30 seconds
[2019-10-16 04:19:40.408610] Waiting up to 29 seconds
[2019-10-16 04:19:41.410310] Waiting up to 28 seconds
[2019-10-16 04:19:42.411987] Waiting up to 27 seconds
[2019-10-16 04:19:43.413662] Waiting up to 26 seconds
[2019-10-16 04:19:44.415326] Waiting up to 25 seconds
[2019-10-16 04:19:45.416939] Waiting up to 24 seconds
[2019-10-16 04:19:46.418721] Waiting up to 23 seconds
[2019-10-16 04:19:47.420610] Waiting up to 22 seconds
[2019-10-16 04:19:48.421916] Waiting up to 21 seconds
[2019-10-16 04:19:49.423626] Waiting up to 20 seconds
[2019-10-16 04:19:50.425470] Waiting up to 19 seconds
[2019-10-16 04:19:51.427331] Waiting up to 18 seconds
[2019-10-16 04:19:52.429043] Waiting up to 17 seconds
[2019-10-16 04:19:53.430788] Waiting up to 16 seconds
[2019-10-16 04:19:54.432552] Waiting up to 15 seconds
[2019-10-16 04:19:55.434254] Waiting up to 14 seconds
[2019-10-16 04:19:56.436019] Waiting up to 13 seconds
[2019-10-16 04:19:57.437843] Waiting up to 12 seconds
[2019-10-16 04:19:58.439681] Waiting up to 11 seconds
[2019-10-16 04:19:59.441407] Waiting up to 10 seconds
[2019-10-16 04:20:00.443095] Waiting up to 9 seconds
[2019-10-16 04:20:01.443830] Waiting up to 8 seconds
[2019-10-16 04:20:02.445347] Waiting up to 7 seconds
[2019-10-16 04:20:03.446837] Waiting up to 6 seconds
[2019-10-16 04:20:04.448634] Waiting up to 5 seconds
[2019-10-16 04:20:05.450313] Waiting up to 4 seconds
[2019-10-16 04:20:06.452087] Waiting up to 3 seconds
[2019-10-16 04:20:07.453818] Waiting up to 2 seconds
[2019-10-16 04:20:08.455267] Waiting up to 1 seconds
[2019-10-16 04:20:09.456137] ### FAIL ###
[2019-10-16 04:20:09.456267] Virus sent with token = '1571192340.72' was not delivered correctly with a warning
[2019-10-16 04:20:09.456359] ###      ###
[2019-10-16 04:20:09.471346] Setting mail/antivir/spam
[2019-10-16 04:20:09.471444] Setting mail/alias/root
[2019-10-16 04:20:09.580596] Multifile: /etc/postfix/main.cf
[2019-10-16 04:20:10.031081] File: /etc/amavis/conf.d/60-univention
[2019-10-16 04:20:10.306385] File: /etc/aliases
[2019-10-16 04:20:12.459128] File: /etc/amavis/conf.d/15-content_filter_mode
[2019-10-16 04:20:12.551046] Multifile: /etc/postfix/master.cf
[2019-10-16 04:20:13.328750] Reloading amavis configuration (via systemctl): amavis.service.
[2019-10-16 04:20:13.425348] Reloading postfix configuration (via systemctl): postfix.service.
[2019-10-16 04:20:13.430042] Cleanup after exception: <type 'exceptions.SystemExit'> 1
[2019-10-16 04:20:13.430061] Performing UCSTestUDM cleanup...
[2019-10-16 04:20:13.430068] removing DN: uid=sqh3kvysxw,cn=users,dc=autotest098,dc=local
[2019-10-16 04:20:13.763263] Cleanup: wait for replication and drs removal
[2019-10-16 04:20:14.851127] OpenLDAP object to check against S4-Connector match_filter doesn't exist: uid=sqh3kvysxw,cn=users,dc=autotest098,dc=local
[2019-10-16 04:20:14.851173] DRS wait not required, S4-Connector match_filter did not match the OpenLDAP object: uid=sqh3kvysxw,cn=users,dc=autotest098,dc=local
[2019-10-16 04:20:14.851193] trying to restart UDM CLI server
[2019-10-16 04:20:14.863490] sending signal 15 to process 28084 (['/usr/bin/python2.7', '/usr/share/univention-directory-manager-tools/univention-cli-server'])
[2019-10-16 04:20:15.869157] process already terminated
[2019-10-16 04:20:15.869355] UCSTestUDM cleanup done