0s autopkgtest [13:13:24]: starting date and time: 2025-03-15 13:13:24+0000 0s autopkgtest [13:13:24]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [13:13:24]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.inye5fnb/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:glibc --apt-upgrade postfix --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- lxd -r lxd-armhf-10.145.243.188 lxd-armhf-10.145.243.188:autopkgtest/ubuntu/plucky/armhf 22s autopkgtest [13:13:46]: testbed dpkg architecture: armhf 24s autopkgtest [13:13:48]: testbed apt version: 2.9.33 27s autopkgtest [13:13:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 29s autopkgtest [13:13:53]: testbed release detected to be: None 38s autopkgtest [13:14:02]: updating testbed package index (apt update) 40s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 40s Get:2 http://ftpmaster.internal/ubuntu plucky InRelease [257 kB] 41s Get:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease [126 kB] 41s Get:4 http://ftpmaster.internal/ubuntu plucky-security InRelease [126 kB] 41s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 41s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [404 kB] 41s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [101 kB] 42s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [81.0 kB] 42s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf c-n-f Metadata [1944 B] 42s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted armhf c-n-f Metadata [116 B] 42s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [326 kB] 42s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf c-n-f Metadata [12.1 kB] 42s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [3472 B] 42s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf c-n-f Metadata [332 B] 42s Get:15 http://ftpmaster.internal/ubuntu plucky/main Sources [1400 kB] 43s Get:16 http://ftpmaster.internal/ubuntu plucky/universe Sources [21.0 MB] 63s Get:17 http://ftpmaster.internal/ubuntu plucky/multiverse Sources [299 kB] 63s Get:18 http://ftpmaster.internal/ubuntu plucky/main armhf Packages [1378 kB] 64s Get:19 http://ftpmaster.internal/ubuntu plucky/main armhf c-n-f Metadata [29.4 kB] 64s Get:20 http://ftpmaster.internal/ubuntu plucky/restricted armhf c-n-f Metadata [108 B] 64s Get:21 http://ftpmaster.internal/ubuntu plucky/universe armhf Packages [15.1 MB] 77s Get:22 http://ftpmaster.internal/ubuntu plucky/multiverse armhf Packages [172 kB] 79s Fetched 41.0 MB in 39s (1047 kB/s) 80s Reading package lists... 91s autopkgtest [13:14:55]: upgrading testbed (apt dist-upgrade and autopurge) 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 94s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 94s Starting 2 pkgProblemResolver with broken count: 0 94s Done 95s Entering ResolveByKeep 95s 95s Calculating upgrade... 96s The following packages will be upgraded: 96s libc-bin libc6 locales python3-jinja2 sos strace 96s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 96s Need to get 8642 kB of archives. 96s After this operation, 23.6 kB of additional disk space will be used. 96s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc6 armhf 2.41-1ubuntu2 [2932 kB] 99s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc-bin armhf 2.41-1ubuntu2 [545 kB] 99s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf locales all 2.41-1ubuntu2 [4246 kB] 103s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf strace armhf 6.13+ds-1ubuntu1 [445 kB] 104s Get:5 http://ftpmaster.internal/ubuntu plucky/main armhf python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 104s Get:6 http://ftpmaster.internal/ubuntu plucky/main armhf sos all 4.9.0-5 [365 kB] 104s Preconfiguring packages ... 105s Fetched 8642 kB in 8s (1062 kB/s) 105s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64655 files and directories currently installed.) 105s Preparing to unpack .../libc6_2.41-1ubuntu2_armhf.deb ... 105s Unpacking libc6:armhf (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 105s Setting up libc6:armhf (2.41-1ubuntu2) ... 105s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64655 files and directories currently installed.) 105s Preparing to unpack .../libc-bin_2.41-1ubuntu2_armhf.deb ... 105s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 105s Setting up libc-bin (2.41-1ubuntu2) ... 106s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64655 files and directories currently installed.) 106s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 106s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 106s Preparing to unpack .../strace_6.13+ds-1ubuntu1_armhf.deb ... 106s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 106s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 106s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 106s Preparing to unpack .../archives/sos_4.9.0-5_all.deb ... 106s Unpacking sos (4.9.0-5) over (4.9.0-4) ... 106s Setting up sos (4.9.0-5) ... 107s Setting up locales (2.41-1ubuntu2) ... 108s Generating locales (this might take a while)... 110s en_US.UTF-8... done 110s Generation complete. 110s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 110s Setting up strace (6.13+ds-1ubuntu1) ... 110s Processing triggers for man-db (2.13.0-1) ... 111s Processing triggers for systemd (257.3-1ubuntu3) ... 113s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Starting pkgProblemResolver with broken count: 0 114s Starting 2 pkgProblemResolver with broken count: 0 114s Done 115s Solving dependencies... 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s autopkgtest [13:15:21]: rebooting testbed after setup commands that affected boot 159s autopkgtest [13:16:03]: testbed running kernel: Linux 6.8.0-52-generic #53~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Wed Jan 15 18:10:51 UTC 2 185s autopkgtest [13:16:29]: @@@@@@@@@@@@@@@@@@@@ apt-source postfix 236s Get:1 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (dsc) [3002 B] 236s Get:2 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (tar) [4954 kB] 236s Get:3 http://ftpmaster.internal/ubuntu plucky/main postfix 3.9.1-10ubuntu1 (diff) [202 kB] 236s gpgv: Signature made Fri Jan 31 12:46:11 2025 UTC 236s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 236s gpgv: Can't check signature: No public key 236s dpkg-source: warning: cannot verify inline signature for ./postfix_3.9.1-10ubuntu1.dsc: no acceptable signature found 236s autopkgtest [13:17:20]: testing package postfix version 3.9.1-10ubuntu1 239s autopkgtest [13:17:23]: build not needed 243s autopkgtest [13:17:27]: test postfix: preparing testbed 244s Reading package lists... 245s Building dependency tree... 245s Reading state information... 245s Starting pkgProblemResolver with broken count: 0 245s Starting 2 pkgProblemResolver with broken count: 0 245s Done 246s The following NEW packages will be installed: 246s db-util db5.3-util procmail sasl2-bin ssl-cert 246s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 246s Need to get 325 kB of archives. 246s After this operation, 1268 kB of additional disk space will be used. 246s Get:1 http://ftpmaster.internal/ubuntu plucky/main armhf db5.3-util armhf 5.3.28+dfsg2-9 [70.8 kB] 247s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf db-util all 1:5.3.21ubuntu2 [2412 B] 247s Get:3 http://ftpmaster.internal/ubuntu plucky/main armhf sasl2-bin armhf 2.1.28+dfsg1-9 [95.5 kB] 247s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf procmail armhf 3.24-1ubuntu3 [138 kB] 247s Get:5 http://ftpmaster.internal/ubuntu plucky/main armhf ssl-cert all 1.1.3ubuntu1 [18.7 kB] 247s Preconfiguring packages ... 247s Fetched 325 kB in 1s (409 kB/s) 247s Selecting previously unselected package db5.3-util. 247s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64655 files and directories currently installed.) 247s Preparing to unpack .../db5.3-util_5.3.28+dfsg2-9_armhf.deb ... 247s Unpacking db5.3-util (5.3.28+dfsg2-9) ... 247s Selecting previously unselected package db-util. 247s Preparing to unpack .../db-util_1%3a5.3.21ubuntu2_all.deb ... 247s Unpacking db-util (1:5.3.21ubuntu2) ... 248s Selecting previously unselected package sasl2-bin. 248s Preparing to unpack .../sasl2-bin_2.1.28+dfsg1-9_armhf.deb ... 248s Unpacking sasl2-bin (2.1.28+dfsg1-9) ... 248s Selecting previously unselected package procmail. 248s Preparing to unpack .../procmail_3.24-1ubuntu3_armhf.deb ... 248s Unpacking procmail (3.24-1ubuntu3) ... 248s Selecting previously unselected package ssl-cert. 248s Preparing to unpack .../ssl-cert_1.1.3ubuntu1_all.deb ... 248s Unpacking ssl-cert (1.1.3ubuntu1) ... 248s Setting up ssl-cert (1.1.3ubuntu1) ... 248s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 249s Setting up db5.3-util (5.3.28+dfsg2-9) ... 249s Setting up procmail (3.24-1ubuntu3) ... 249s Setting up db-util (1:5.3.21ubuntu2) ... 249s Setting up sasl2-bin (2.1.28+dfsg1-9) ... 249s saslauthd.service is a disabled or a static unit, not starting it. 249s Processing triggers for man-db (2.13.0-1) ... 256s autopkgtest [13:17:40]: test postfix: [----------------------- 258s Reading package lists... 258s Building dependency tree... 258s Reading state information... 259s Solving dependencies... 259s Suggested packages: 259s mail-reader postfix-cdb postfix-doc postfix-ldap postfix-lmdb 259s postfix-mta-sts-resolver postfix-mongodb postfix-mysql postfix-pcre 259s postfix-pgsql postfix-sqlite 259s The following NEW packages will be installed: 259s postfix 260s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 260s Need to get 1228 kB of archives. 260s After this operation, 5416 kB of additional disk space will be used. 260s Get:1 http://ftpmaster.internal/ubuntu plucky/main armhf postfix armhf 3.9.1-10ubuntu1 [1228 kB] 261s Preconfiguring packages ... 262s Fetched 1228 kB in 2s (712 kB/s) 262s Selecting previously unselected package postfix. 262s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64781 files and directories currently installed.) 262s Preparing to unpack .../postfix_3.9.1-10ubuntu1_armhf.deb ... 262s Unpacking postfix (3.9.1-10ubuntu1) ... 262s Setting up postfix (3.9.1-10ubuntu1) ... 262s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 263s info: Selecting UID from range 100 to 999 ... 263s 263s info: Selecting GID from range 100 to 999 ... 263s info: Adding system user `postfix' (UID 107) ... 263s info: Adding new group `postfix' (GID 111) ... 263s info: Adding new user `postfix' (UID 107) with group `postfix' ... 263s info: Not creating home directory `/var/spool/postfix'. 263s info: Selecting GID from range 100 to 999 ... 263s info: Adding group `postdrop' (GID 112) ... 263s Creating /etc/postfix/dynamicmaps.cf 263s setting myhostname: localhost 263s setting alias maps 263s setting alias database 263s mailname is not a fully qualified domain name. Not changing /etc/mailname. 263s setting destinations: $myhostname, localhost, localhost.localdomain, localhost 263s setting relayhost: 263s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 263s setting mailbox_command 263s setting mailbox_size_limit: 0 263s setting recipient_delimiter: + 265s setting inet_interfaces: all 267s setting inet_protocols: all 267s /etc/aliases does not exist, creating it. 267s WARNING: /etc/aliases exists, but does not have a root alias. 267s 267s Postfix (main.cf) is now set up with a default configuration. If you need to 267s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 267s Postfix configuration values, see postconf(1). 267s 267s After modifying main.cf, be sure to run 'systemctl reload postfix'. 267s 269s Processing triggers for ufw (0.36.2-9) ... 269s Processing triggers for man-db (2.13.0-1) ... 270s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ... 270s Processing triggers for postfix (3.9.1-10ubuntu1) ... 270s Restarting postfix 274s postfix/postlog: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 341s ............... 342s ---------------------------------------------------------------------- 342s Ran 15 tests in 66.764s 342s 342s OK 342s ===== postfix.log contents: 342s Mar 15 13:17:57 localhost postfix/master[1850]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:17:58 localhost postfix/postfix-script[1945]: warning: not owned by root: /var/spool/postfix/etc/resolv.conf 342s Mar 15 13:18:01 localhost postfix/postfix-script[2404]: stopping the Postfix mail system 342s Mar 15 13:18:01 localhost postfix/master[1850]: terminating on signal 15 342s Mar 15 13:18:02 localhost postfix/master[2530]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:18:04 localhost postfix/smtpd[2535]: initializing the server-side TLS engine 342s Mar 15 13:18:04 localhost postfix/smtpd[2535]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: initializing the server-side TLS engine 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:18:04 localhost postfix/smtpd[2535]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: NOQUEUE: reject: VRFY from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:04 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] ehlo=1 vrfy=2/3 quit=1 help=1 commands=5/6 342s Mar 15 13:18:04 localhost postfix/smtpd[2535]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2535]: 7C0D613D5B: client=localhost[127.0.0.1] 342s Mar 15 13:18:04 localhost postfix/smtpd[2535]: 7C0D613D5B: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:04 localhost postfix/cleanup[2580]: 7C0D613D5B: message-id=<20250315131804.7C0D613D5B@localhost> 342s Mar 15 13:18:04 localhost postfix/qmgr[2532]: 7C0D613D5B: from=, size=380, nrcpt=1 (queue active) 342s Mar 15 13:18:05 localhost postfix/local[2581]: 7C0D613D5B: to=, orig_to=, relay=local, delay=1, delays=0.01/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:05 localhost postfix/qmgr[2532]: 7C0D613D5B: removed 342s Mar 15 13:18:07 localhost postfix/smtpd[2535]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 342s Mar 15 13:18:07 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:09 localhost postfix/smtpd[2538]: warning: SASL authentication failure: Password verification failed 342s Mar 15 13:18:09 localhost postfix/smtpd[2538]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 342s Mar 15 13:18:11 localhost postfix/smtpd[2538]: warning: SASL authentication failure: Password verification failed 342s Mar 15 13:18:11 localhost postfix/smtpd[2538]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=todltnhe 342s Mar 15 13:18:11 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 quit=1 commands=3/5 342s Mar 15 13:18:11 localhost postfix/smtpd[2535]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:11 localhost postfix/smtpd[2535]: AAAF413D9E: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=txcugzhy 342s Mar 15 13:18:11 localhost postfix/smtpd[2535]: AAAF413D9E: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:11 localhost postfix/cleanup[2580]: AAAF413D9E: message-id=<20250315131811.AAAF413D9E@localhost> 342s Mar 15 13:18:11 localhost postfix/qmgr[2532]: AAAF413D9E: from=, size=416, nrcpt=1 (queue active) 342s Mar 15 13:18:12 localhost postfix/local[2581]: AAAF413D9E: to=, orig_to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:12 localhost postfix/qmgr[2532]: AAAF413D9E: removed 342s Mar 15 13:18:14 localhost postfix/smtpd[2535]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1/2 data=1 quit=1 commands=6/7 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: setting up TLS connection from localhost[127.0.0.1] 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: localhost[127.0.0.1]: TLS cipher list "aNULL:-aNULL:HIGH:MEDIUM:!SEED:!IDEA:!3DES:!RC2:!RC4:!RC5:!kDH:!kECDH:!aDSS:!MD5:+RC4:@STRENGTH" 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:before SSL initialization 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:before SSL initialization 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS read client hello 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS write server hello 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS write change cipher spec 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:TLSv1.3 write encrypted extensions 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS write certificate 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:TLSv1.3 write server certificate verify 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS write finished 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:TLSv1.3 early data 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:TLSv1.3 early data 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS read finished 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: localhost[127.0.0.1]: Issuing session ticket, key expiration: 1742046493 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: SSL_accept:SSLv3/TLS write session ticket 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: Anonymous TLS connection established from localhost[127.0.0.1]: TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (2048 bits) server-digest SHA256 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: D887613DC0: client=localhost[127.0.0.1] 342s Mar 15 13:18:14 localhost postfix/smtpd[2538]: D887613DC0: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:14 localhost postfix/cleanup[2580]: D887613DC0: message-id=<20250315131814.D887613DC0@localhost> 342s Mar 15 13:18:14 localhost postfix/qmgr[2532]: D887613DC0: from=, size=381, nrcpt=1 (queue active) 342s Mar 15 13:18:15 localhost postfix/local[2581]: D887613DC0: to=, orig_to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:15 localhost postfix/qmgr[2532]: D887613DC0: removed 342s Mar 15 13:18:17 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] ehlo=2 starttls=1 mail=1 rcpt=1/2 data=1 quit=1 commands=7/8 342s Mar 15 13:18:18 localhost postfix/smtpd[2535]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:18 localhost postfix/smtpd[2535]: 2C83613DF7: client=localhost[127.0.0.1] 342s Mar 15 13:18:18 localhost postfix/smtpd[2535]: 2C83613DF7: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:18 localhost postfix/cleanup[2580]: 2C83613DF7: message-id=<20250315131818.2C83613DF7@localhost> 342s Mar 15 13:18:18 localhost postfix/qmgr[2532]: 2C83613DF7: from=, size=380, nrcpt=1 (queue active) 342s Mar 15 13:18:18 localhost postfix/cleanup[2580]: 2E2DA13DF8: message-id=<20250315131818.2C83613DF7@localhost> 342s Mar 15 13:18:18 localhost postfix/local[2581]: 2C83613DF7: to=, orig_to=, relay=local, delay=0.01, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (forwarded as 2E2DA13DF8) 342s Mar 15 13:18:18 localhost postfix/qmgr[2532]: 2E2DA13DF8: from=, size=503, nrcpt=1 (queue active) 342s Mar 15 13:18:18 localhost postfix/qmgr[2532]: 2C83613DF7: removed 342s Mar 15 13:18:19 localhost postfix/local[2581]: 2E2DA13DF8: to=, orig_to=, relay=local, delay=1, delays=0/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:19 localhost postfix/qmgr[2532]: 2E2DA13DF8: removed 342s Mar 15 13:18:21 localhost postfix/smtpd[2535]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1/2 data=1 quit=1 commands=5/6 342s Mar 15 13:18:21 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:21 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s Mar 15 13:18:21 localhost postfix/smtpd[2535]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:21 localhost postfix/smtpd[2535]: 97FEB13E4C: client=localhost[127.0.0.1] 342s Mar 15 13:18:21 localhost postfix/cleanup[2580]: 97FEB13E4C: message-id=<20250315131821.97FEB13E4C@localhost> 342s Mar 15 13:18:21 localhost postfix/qmgr[2532]: 97FEB13E4C: from=, size=389, nrcpt=1 (queue active) 342s Mar 15 13:18:23 localhost postfix/smtpd[2535]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5 342s Mar 15 13:18:23 localhost postfix/smtpd[2538]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:23 localhost postfix/smtpd[2538]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s Mar 15 13:18:27 localhost postfix/postfix-script[2772]: stopping the Postfix mail system 342s Mar 15 13:18:27 localhost postfix/master[2530]: terminating on signal 15 342s Mar 15 13:18:28 localhost postfix/master[2899]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:18:28 localhost postfix/qmgr[2901]: 97FEB13E4C: from=, size=389, nrcpt=1 (queue active) 342s Mar 15 13:18:28 localhost postfix/local[2905]: 97FEB13E4C: to=, orig_to=, relay=local, delay=6.7, delays=6.6/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "tcdwnluv") 342s Mar 15 13:18:28 localhost postfix/cleanup[2907]: 44C3E13E91: message-id=<20250315131828.44C3E13E91@localhost> 342s Mar 15 13:18:28 localhost postfix/bounce[2906]: 97FEB13E4C: sender non-delivery notification: 44C3E13E91 342s Mar 15 13:18:28 localhost postfix/qmgr[2901]: 44C3E13E91: from=<>, size=2190, nrcpt=1 (queue active) 342s Mar 15 13:18:28 localhost postfix/qmgr[2901]: 97FEB13E4C: removed 342s Mar 15 13:18:29 localhost postfix/local[2905]: 44C3E13E91: to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:29 localhost postfix/qmgr[2901]: 44C3E13E91: removed 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: initializing the server-side TLS engine 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:30 localhost postfix/smtpd[2913]: initializing the server-side TLS engine 342s Mar 15 13:18:30 localhost postfix/smtpd[2913]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:30 localhost postfix/smtpd[2913]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:18:30 localhost postfix/smtpd[2913]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=root 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: warning: SASL authentication failure: incorrect digest response 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication failed: authentication failure, sasl_username=tsljiclx 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: 622F113E93: client=localhost[127.0.0.1], sasl_method=CRAM-MD5, sasl_username=tsljiclx 342s Mar 15 13:18:30 localhost postfix/smtpd[2910]: 622F113E93: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:30 localhost postfix/cleanup[2907]: 622F113E93: message-id=<20250315131830.622F113E93@localhost> 342s Mar 15 13:18:30 localhost postfix/qmgr[2901]: 622F113E93: from=, size=416, nrcpt=1 (queue active) 342s Mar 15 13:18:31 localhost postfix/local[2905]: 622F113E93: to=, orig_to=, relay=local, delay=1, delays=0.01/0/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:31 localhost postfix/qmgr[2901]: 622F113E93: removed 342s Mar 15 13:18:33 localhost postfix/smtpd[2910]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 342s Mar 15 13:18:33 localhost postfix/smtpd[2913]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:33 localhost postfix/smtpd[2913]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s Mar 15 13:18:37 localhost postfix/postfix-script[2953]: stopping the Postfix mail system 342s Mar 15 13:18:37 localhost postfix/master[2899]: terminating on signal 15 342s Mar 15 13:18:38 localhost postfix/master[3080]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:18:40 localhost postfix/smtpd[3085]: initializing the server-side TLS engine 342s Mar 15 13:18:40 localhost postfix/smtpd[3085]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:40 localhost postfix/smtpd[3088]: initializing the server-side TLS engine 342s Mar 15 13:18:40 localhost postfix/smtpd[3088]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:40 localhost postfix/smtpd[3088]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:18:40 localhost postfix/smtpd[3088]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:18:40 localhost postfix/smtpd[3085]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 342s Mar 15 13:18:40 localhost postfix/smtpd[3088]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:40 localhost postfix/smtpd[3088]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s Mar 15 13:18:44 localhost postfix/postfix-script[3126]: stopping the Postfix mail system 342s Mar 15 13:18:44 localhost postfix/master[3080]: terminating on signal 15 342s Mar 15 13:18:45 localhost postfix/master[3253]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:18:47 localhost postfix/smtpd[3258]: initializing the server-side TLS engine 342s Mar 15 13:18:47 localhost postfix/smtpd[3258]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:47 localhost postfix/smtpd[3261]: initializing the server-side TLS engine 342s Mar 15 13:18:47 localhost postfix/smtpd[3261]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:47 localhost postfix/smtpd[3261]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:18:47 localhost postfix/smtpd[3261]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:18:47 localhost postfix/smtpd[3258]: disconnect from localhost[127.0.0.1] ehlo=1 quit=1 commands=2 342s Mar 15 13:18:47 localhost postfix/smtpd[3261]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:47 localhost postfix/smtpd[3261]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s Mar 15 13:18:51 localhost postfix/postfix-script[3299]: stopping the Postfix mail system 342s Mar 15 13:18:51 localhost postfix/master[3253]: terminating on signal 15 342s Mar 15 13:18:52 localhost postfix/master[3426]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: initializing the server-side TLS engine 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:54 localhost postfix/smtpd[3434]: initializing the server-side TLS engine 342s Mar 15 13:18:54 localhost postfix/smtpd[3434]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:54 localhost postfix/smtpd[3434]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:18:54 localhost postfix/smtpd[3434]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: warning: SASL authentication failure: Password verification failed 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=root 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: warning: SASL authentication failure: Password verification failed 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: warning: localhost[127.0.0.1]: SASL PLAIN authentication failed: authentication failure, sasl_username=trjsgrdy 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: 7F7B313F5B: client=localhost[127.0.0.1], sasl_method=PLAIN, sasl_username=trjsgrdy 342s Mar 15 13:18:54 localhost postfix/smtpd[3431]: 7F7B313F5B: reject: RCPT from localhost[127.0.0.1]: 550 5.1.1 : Recipient address rejected: User unknown in local recipient table; from= to= proto=ESMTP helo=<[127.0.0.1]> 342s Mar 15 13:18:54 localhost postfix/cleanup[3436]: 7F7B313F5B: message-id=<20250315131854.7F7B313F5B@localhost> 342s Mar 15 13:18:54 localhost postfix/qmgr[3428]: 7F7B313F5B: from=, size=416, nrcpt=1 (queue active) 342s Mar 15 13:18:55 localhost postfix/local[3437]: 7F7B313F5B: to=, orig_to=, relay=local, delay=1, delays=0.01/0.01/0/1, dsn=2.0.0, status=sent (delivered to command: procmail -a "$EXTENSION") 342s Mar 15 13:18:55 localhost postfix/qmgr[3428]: 7F7B313F5B: removed 342s Mar 15 13:18:57 localhost postfix/smtpd[3431]: disconnect from localhost[127.0.0.1] ehlo=1 auth=1/3 mail=1 rcpt=1/2 data=1 quit=1 commands=6/9 342s Mar 15 13:18:57 localhost postfix/smtpd[3434]: connect from localhost[127.0.0.1] 342s Mar 15 13:18:57 localhost postfix/smtpd[3434]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s Mar 15 13:19:01 localhost postfix/postfix-script[3477]: stopping the Postfix mail system 342s Mar 15 13:19:01 localhost postfix/master[3426]: terminating on signal 15 342s Mar 15 13:19:02 localhost postfix/master[3604]: daemon started -- version 3.9.1, configuration /etc/postfix 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: initializing the server-side TLS engine 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: connect from localhost[127.0.0.1] 342s Mar 15 13:19:04 localhost postfix/smtpd[3614]: initializing the server-side TLS engine 342s Mar 15 13:19:04 localhost postfix/smtpd[3614]: connect from localhost[127.0.0.1] 342s Mar 15 13:19:04 localhost postfix/smtpd[3614]: lost connection after CONNECT from localhost[127.0.0.1] 342s Mar 15 13:19:04 localhost postfix/smtpd[3614]: disconnect from localhost[127.0.0.1] commands=0/0 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: warning: localhost[127.0.0.1]: SASL CRAM-MD5 authentication aborted 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: warning: SASL authentication failure: Parse error 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: warning: localhost[127.0.0.1]: SASL DIGEST-MD5 authentication failed: authentication failure, sasl_username=(unavailable) 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: lost connection after AUTH from localhost[127.0.0.1] 342s Mar 15 13:19:04 localhost postfix/smtpd[3611]: disconnect from localhost[127.0.0.1] ehlo=1 auth=0/2 commands=1/3 342s Mar 15 13:19:04 localhost postfix/smtpd[3614]: connect from localhost[127.0.0.1] 342s Mar 15 13:19:05 localhost postfix/smtpd[3614]: disconnect from localhost[127.0.0.1] quit=1 commands=1 342s autopkgtest [13:19:06]: test postfix: -----------------------] 346s postfix PASS 346s autopkgtest [13:19:10]: test postfix: - - - - - - - - - - results - - - - - - - - - - 350s autopkgtest [13:19:14]: @@@@@@@@@@@@@@@@@@@@ summary 350s postfix PASS