0s autopkgtest [00:49:48]: starting date and time: 2026-01-30 00:49:48+0000 0s autopkgtest [00:49:48]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [00:49:48]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.nw6ftxd8/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:rsyslog,src:sphinx-rtd-theme,src:systemd --apt-upgrade stdsyslog --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=rsyslog/8.2512.0-1ubuntu3 sphinx-rtd-theme/3.1.0+dfsg-1 systemd/259-1ubuntu3' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-s390x-3.secgroup --name adt-resolute-s390x-stdsyslog-20260130-004948-juju-7f2275-prod-proposed-migration-environment-20-f7e38b76-6561-4c0d-ad49-c0844dfe5a02 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-s390x-stdsyslog-20260130-004948-juju-7f2275-prod-proposed-migration-environment-20-f7e38b76-6561-4c0d-ad49-c0844dfe5a02 from image adt/ubuntu-resolute-s390x-server-20260129.img (UUID 0fdc3f45-c7c7-4a2c-aa6d-edc1dbbf6141)... 132s autopkgtest [00:52:00]: testbed dpkg architecture: s390x 132s autopkgtest [00:52:00]: testbed apt version: 3.1.14 133s autopkgtest [00:52:01]: @@@@@@@@@@@@@@@@@@@@ test bed setup 134s autopkgtest [00:52:02]: testbed release detected to be: None 136s autopkgtest [00:52:04]: updating testbed package index (apt update) 136s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 137s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 137s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 137s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 137s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1043 kB] 137s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [103 kB] 137s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 137s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [25.4 kB] 138s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [115 kB] 138s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [766 kB] 138s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [6428 B] 140s Fetched 2186 kB in 3s (705 kB/s) 144s Reading package lists... 146s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 146s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 146s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 146s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 151s Reading package lists... 153s Reading package lists... 153s Building dependency tree... 153s Reading state information... 153s Calculating upgrade... 154s The following packages will be upgraded: 154s gcc-15-base libatomic1 libgcc-s1 libnss-systemd libpam-systemd 154s libsgutils2-1.48 libstdc++6 libsystemd-shared libsystemd0 libudev1 rsyslog 154s sg3-utils sg3-utils-udev systemd systemd-cryptsetup systemd-resolved 154s systemd-sysv systemd-userdbd udev 155s 19 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 155s Need to get 11.7 MB of archives. 155s After this operation, 2048 B disk space will be freed. 155s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x systemd-userdbd s390x 259-1ubuntu3 [52.3 kB] 155s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libnss-systemd s390x 259-1ubuntu3 [294 kB] 155s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libsystemd0 s390x 259-1ubuntu3 [551 kB] 155s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x systemd-sysv s390x 259-1ubuntu3 [9248 B] 155s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x systemd-resolved s390x 259-1ubuntu3 [297 kB] 155s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libpam-systemd s390x 259-1ubuntu3 [277 kB] 155s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libsystemd-shared s390x 259-1ubuntu3 [2525 kB] 155s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x systemd s390x 259-1ubuntu3 [3119 kB] 155s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x systemd-cryptsetup s390x 259-1ubuntu3 [127 kB] 155s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x udev s390x 259-1ubuntu3 [1485 kB] 156s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libudev1 s390x 259-1ubuntu3 [215 kB] 156s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libatomic1 s390x 15.2.0-12ubuntu1 [9486 B] 156s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++6 s390x 15.2.0-12ubuntu1 [907 kB] 156s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-base s390x 15.2.0-12ubuntu1 [59.6 kB] 156s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-s1 s390x 15.2.0-12ubuntu1 [35.7 kB] 156s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x rsyslog s390x 8.2512.0-1ubuntu3 [553 kB] 156s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x libsgutils2-1.48 s390x 1.48-3ubuntu2 [120 kB] 156s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x sg3-utils s390x 1.48-3ubuntu2 [1025 kB] 156s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x sg3-utils-udev all 1.48-3ubuntu2 [6610 B] 161s dpkg-preconfigure: unable to re-open stdin: No such file or directory 161s Fetched 11.7 MB in 2s (7215 kB/s) 166s (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 ... 87720 files and directories currently installed.) 170s Preparing to unpack .../systemd-userdbd_259-1ubuntu3_s390x.deb ... 170s Unpacking systemd-userdbd (259-1ubuntu3) over (259-1ubuntu2) ... 170s Preparing to unpack .../libnss-systemd_259-1ubuntu3_s390x.deb ... 170s Unpacking libnss-systemd:s390x (259-1ubuntu3) over (259-1ubuntu2) ... 170s Preparing to unpack .../libsystemd0_259-1ubuntu3_s390x.deb ... 170s Unpacking libsystemd0:s390x (259-1ubuntu3) over (259-1ubuntu2) ... 170s Setting up libsystemd0:s390x (259-1ubuntu3) ... 170s (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 ... 87720 files and directories currently installed.) 170s Preparing to unpack .../systemd-sysv_259-1ubuntu3_s390x.deb ... 171s Unpacking systemd-sysv (259-1ubuntu3) over (259-1ubuntu2) ... 171s Preparing to unpack .../systemd-resolved_259-1ubuntu3_s390x.deb ... 171s Unpacking systemd-resolved (259-1ubuntu3) over (259-1ubuntu2) ... 173s Preparing to unpack .../libpam-systemd_259-1ubuntu3_s390x.deb ... 173s Unpacking libpam-systemd:s390x (259-1ubuntu3) over (259-1ubuntu2) ... 174s Preparing to unpack .../libsystemd-shared_259-1ubuntu3_s390x.deb ... 174s Unpacking libsystemd-shared:s390x (259-1ubuntu3) over (259-1ubuntu2) ... 176s Setting up libsystemd-shared:s390x (259-1ubuntu3) ... 178s (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 ... 87720 files and directories currently installed.) 178s Preparing to unpack .../systemd_259-1ubuntu3_s390x.deb ... 178s Unpacking systemd (259-1ubuntu3) over (259-1ubuntu2) ... 183s Preparing to unpack .../systemd-cryptsetup_259-1ubuntu3_s390x.deb ... 183s Unpacking systemd-cryptsetup (259-1ubuntu3) over (259-1ubuntu2) ... 184s Preparing to unpack .../udev_259-1ubuntu3_s390x.deb ... 184s Unpacking udev (259-1ubuntu3) over (259-1ubuntu2) ... 187s Preparing to unpack .../libudev1_259-1ubuntu3_s390x.deb ... 187s Unpacking libudev1:s390x (259-1ubuntu3) over (259-1ubuntu2) ... 187s Setting up libudev1:s390x (259-1ubuntu3) ... 188s (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 ... 87720 files and directories currently installed.) 188s Preparing to unpack .../libatomic1_15.2.0-12ubuntu1_s390x.deb ... 189s Unpacking libatomic1:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 189s Preparing to unpack .../libstdc++6_15.2.0-12ubuntu1_s390x.deb ... 192s Unpacking libstdc++6:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 194s Preparing to unpack .../gcc-15-base_15.2.0-12ubuntu1_s390x.deb ... 194s Unpacking gcc-15-base:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 195s Setting up gcc-15-base:s390x (15.2.0-12ubuntu1) ... 197s (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 ... 87720 files and directories currently installed.) 197s Preparing to unpack .../libgcc-s1_15.2.0-12ubuntu1_s390x.deb ... 197s Unpacking libgcc-s1:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 199s Setting up libgcc-s1:s390x (15.2.0-12ubuntu1) ... 201s (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 ... 87720 files and directories currently installed.) 201s Preparing to unpack .../rsyslog_8.2512.0-1ubuntu3_s390x.deb ... 203s Unpacking rsyslog (8.2512.0-1ubuntu3) over (8.2512.0-1ubuntu2) ... 206s Preparing to unpack .../libsgutils2-1.48_1.48-3ubuntu2_s390x.deb ... 206s Unpacking libsgutils2-1.48:s390x (1.48-3ubuntu2) over (1.48-3ubuntu1) ... 208s Preparing to unpack .../sg3-utils_1.48-3ubuntu2_s390x.deb ... 209s Unpacking sg3-utils (1.48-3ubuntu2) over (1.48-3ubuntu1) ... 212s Preparing to unpack .../sg3-utils-udev_1.48-3ubuntu2_all.deb ... 212s Unpacking sg3-utils-udev (1.48-3ubuntu2) over (1.48-3ubuntu1) ... 214s Setting up rsyslog (8.2512.0-1ubuntu3) ... 217s warn: The user `syslog' is already a member of `adm'. 225s Setting up libsgutils2-1.48:s390x (1.48-3ubuntu2) ... 226s Setting up libnss-systemd:s390x (259-1ubuntu3) ... 226s Setting up systemd (259-1ubuntu3) ... 228s /usr/lib/tmpfiles.d/legacy.conf:14: Duplicate line for path "/run/lock", ignoring. 228s /usr/lib/tmpfiles.d/legacy.conf:14: Duplicate line for path "/run/lock", ignoring. 232s Setting up systemd-cryptsetup (259-1ubuntu3) ... 233s Setting up libatomic1:s390x (15.2.0-12ubuntu1) ... 233s Setting up udev (259-1ubuntu3) ... 242s Setting up libstdc++6:s390x (15.2.0-12ubuntu1) ... 242s Setting up systemd-resolved (259-1ubuntu3) ... 249s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 249s Setting up sg3-utils (1.48-3ubuntu2) ... 250s Setting up systemd-userdbd (259-1ubuntu3) ... 253s Setting up systemd-sysv (259-1ubuntu3) ... 254s Setting up sg3-utils-udev (1.48-3ubuntu2) ... 255s update-initramfs: deferring update (trigger activated) 256s Setting up libpam-systemd:s390x (259-1ubuntu3) ... 259s Processing triggers for libc-bin (2.42-2ubuntu4) ... 262s Processing triggers for man-db (2.13.1-1) ... 317s Processing triggers for dbus (1.16.2-2ubuntu2) ... 319s Processing triggers for shared-mime-info (2.4-5build2) ... 465s Processing triggers for procps (2:4.0.4-9ubuntu1) ... 466s Processing triggers for initramfs-tools (0.150ubuntu7) ... 466s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 520s Using config file '/etc/zipl.conf' 520s Building bootmap in '/boot' 520s Adding IPL section 'ubuntu' (default) 521s Preparing boot device: vda. 521s Done. 523s autopkgtest [00:58:31]: upgrading testbed (apt dist-upgrade and autopurge) 523s Reading package lists... 523s Building dependency tree... 523s Reading state information... 524s Calculating upgrade... 525s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 526s Reading package lists... 526s Building dependency tree... 526s Reading state information... 526s Solving dependencies... 527s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 528s autopkgtest [00:58:36]: rebooting testbed after setup commands that affected boot 615s autopkgtest [01:00:03]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP Mon Jan 12 15:39:23 UTC 2026 620s autopkgtest [01:00:08]: @@@@@@@@@@@@@@@@@@@@ apt-source stdsyslog 626s Get:1 http://ftpmaster.internal/ubuntu resolute/universe stdsyslog 0.03.4-1 (dsc) [2413 B] 626s Get:2 http://ftpmaster.internal/ubuntu resolute/universe stdsyslog 0.03.4-1 (tar) [31.2 kB] 626s Get:3 http://ftpmaster.internal/ubuntu resolute/universe stdsyslog 0.03.4-1 (asc) [833 B] 626s Get:4 http://ftpmaster.internal/ubuntu resolute/universe stdsyslog 0.03.4-1 (diff) [6816 B] 628s gpgv: Signature made Tue Aug 27 11:04:03 2024 UTC 628s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 628s gpgv: issuer "roam@debian.org" 628s gpgv: Can't check signature: No public key 628s dpkg-source: warning: cannot verify inline signature for ./stdsyslog_0.03.4-1.dsc: no acceptable signature found 629s autopkgtest [01:00:17]: testing package stdsyslog version 0.03.4-1 629s autopkgtest [01:00:17]: build not needed 630s autopkgtest [01:00:18]: test upstream-stdtestlog: preparing testbed 631s Reading package lists... 632s Building dependency tree... 632s Reading state information... 632s Solving dependencies... 633s The following NEW packages will be installed: 633s python3-click stdsyslog 634s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 634s Need to get 92.4 kB of archives. 634s After this operation, 420 kB of additional disk space will be used. 634s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 634s Get:2 http://ftpmaster.internal/ubuntu resolute/universe s390x stdsyslog s390x 0.03.4-1 [12.2 kB] 637s Fetched 92.4 kB in 0s (300 kB/s) 637s Selecting previously unselected package python3-click. 640s (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 ... 87720 files and directories currently installed.) 640s Preparing to unpack .../python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 640s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 640s Selecting previously unselected package stdsyslog. 640s Preparing to unpack .../stdsyslog_0.03.4-1_s390x.deb ... 640s Unpacking stdsyslog (0.03.4-1) ... 640s Setting up stdsyslog (0.03.4-1) ... 640s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 641s Processing triggers for man-db (2.13.1-1) ... 658s autopkgtest [01:00:46]: test upstream-stdtestlog: env PYTHONPATH="$(pwd)/python/src" python3 -B -u -m stdtestlog run 658s autopkgtest [01:00:46]: test upstream-stdtestlog: [----------------------- 659s Starting `rsyslogd -n -iNONE -f /tmp/stdtestlog.6sswey1x/rsyslog-stdtestlog.conf` 659s Got rsyslog pid 1564 659s Waiting for a second 659s rsyslogd: could not open config file '/tmp/stdtestlog.6sswey1x/rsyslog-stdtestlog.conf': Permission denied [v8.2512.0 try https://www.rsyslog.com/e/2104 ] 659s rsyslogd: run failed with error -2104 (see rsyslog.h or try https://www.rsyslog.com/e/2104 to learn what that number means) 660s Making sure rsyslog is still alive 660s Waiting for the rsyslogd process to go away 660s Traceback (most recent call last): 660s File "/tmp/autopkgtest.KQalQS/build.xZt/src/python/src/stdtestlog/__main__.py", line 297, in cmd_run 660s raise RuntimeError(repr(rsysproc.wait())) 660s RuntimeError: 1 660s 660s During handling of the above exception, another exception occurred: 660s 660s Traceback (most recent call last): 660s File "", line 198, in _run_module_as_main 660s File "", line 88, in _run_code 660s File "/tmp/autopkgtest.KQalQS/build.xZt/src/python/src/stdtestlog/__main__.py", line 336, in 660s main() 660s ~~~~^^ 660s File "/usr/lib/python3/dist-packages/click/core.py", line 1161, in __call__ 660s return self.main(*args, **kwargs) 660s ~~~~~~~~~^^^^^^^^^^^^^^^^^ 660s File "/usr/lib/python3/dist-packages/click/core.py", line 1082, in main 660s rv = self.invoke(ctx) 660s File "/usr/lib/python3/dist-packages/click/core.py", line 1697, in invoke 660s return _process_result(sub_ctx.command.invoke(sub_ctx)) 660s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 660s File "/usr/lib/python3/dist-packages/click/core.py", line 1443, in invoke 660s return ctx.invoke(self.callback, **ctx.params) 660s ~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 660s File "/usr/lib/python3/dist-packages/click/core.py", line 788, in invoke 660s return __callback(*args, **kwargs) 660s File "/tmp/autopkgtest.KQalQS/build.xZt/src/python/src/stdtestlog/__main__.py", line 323, in cmd_run 660s raise RuntimeError(repr(rsysres)) 660s RuntimeError: 1 661s autopkgtest [01:00:49]: test upstream-stdtestlog: -----------------------] 661s autopkgtest [01:00:49]: test upstream-stdtestlog: - - - - - - - - - - results - - - - - - - - - - 661s upstream-stdtestlog FAIL non-zero exit status 1 662s autopkgtest [01:00:50]: @@@@@@@@@@@@@@@@@@@@ summary 662s upstream-stdtestlog FAIL non-zero exit status 1