0s autopkgtest [21:38:16]: starting date and time: 2024-03-23 21:38:16+0000
  0s autopkgtest [21:38:16]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps
  0s autopkgtest [21:38:16]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.u5uhso07/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:chiark-utils --apt-upgrade userv --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=chiark-utils/7.0.0build1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-s390x-8.secgroup --name adt-noble-s390x-userv-20240323-213812-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/
 91s autopkgtest [21:39:47]: testbed dpkg architecture: s390x
 92s autopkgtest [21:39:48]: testbed apt version: 2.7.12
 92s autopkgtest [21:39:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 92s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
 93s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4028 kB]
 94s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB]
 94s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB]
 94s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
 94s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [654 kB]
 94s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B]
 94s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B]
 94s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B]
 94s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4150 kB]
 95s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B]
 95s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [46.8 kB]
 95s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B]
 98s Fetched 9565 kB in 3s (2869 kB/s)
 98s Reading package lists...
107s Reading package lists...
107s Unknown architecture, assuming PC-style ttyS0
107s Building dependency tree...
107s Reading state information...
107s Calculating upgrade...
107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s eatmydata is already the newest version (131-1).
107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s dbus is already the newest version (1.14.10-4ubuntu1).
107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s rng-tools-debian is already the newest version (2.4).
107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s The following packages will be REMOVED:
107s   cloud-init* python3-configobj* python3-debconf*
107s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
107s After this operation, 3256 kB disk space will be freed.
107s (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 ... 52170 files and directories currently installed.)
107s Removing cloud-init (24.1.2-0ubuntu1) ...
107s Removing python3-configobj (5.0.8-3) ...
107s Removing python3-debconf (1.5.86) ...
107s Processing triggers for man-db (2.12.0-3) ...
107s (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 ... 51781 files and directories currently installed.)
107s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ...
107s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
107s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
107s invoke-rc.d: policy-rc.d denied execution of try-restart.
107s Reading package lists...
107s Building dependency tree...
107s Reading state information...
107s linux-generic is already the newest version (6.8.0-11.11+1).
107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
107s sh: Attempting to set up Debian/Ubuntu apt sources automatically
107s sh: Distribution appears to be Ubuntu
107s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
107s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
108s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
112s Reading package lists...
112s Reading package lists...autopkgtest [21:40:06]: rebooting testbed after setup commands that affected boot
112s 
112s Building dependency tree...
112s Reading state information...
112s Calculating upgrade...
112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
112s Reading package lists...
112s Building dependency tree...
112s Reading state information...
112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
128s autopkgtest [21:40:24]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024
132s autopkgtest [21:40:28]: @@@@@@@@@@@@@@@@@@@@ apt-source userv
134s Get:1 http://ftpmaster.internal/ubuntu noble/universe userv 1.2.1~beta4 (dsc) [1311 B]
134s Get:2 http://ftpmaster.internal/ubuntu noble/universe userv 1.2.1~beta4 (tar) [199 kB]
134s gpgv: Signature made Sat Jan  1 01:16:54 2022 UTC
134s gpgv:                using RSA key 559AE46C2D6B6D3265E7CBA1E3E3392348B50D39
134s gpgv: Can't check signature: No public key
134s dpkg-source: warning: cannot verify inline signature for ./userv_1.2.1~beta4.dsc: no acceptable signature found
134s autopkgtest [21:40:30]: testing package userv version 1.2.1~beta4
134s autopkgtest [21:40:30]: build not needed
135s autopkgtest [21:40:31]: test t-basic: preparing testbed
145s Reading package lists...
145s Building dependency tree...
145s Reading state information...
145s Starting pkgProblemResolver with broken count: 0
145s Starting 2 pkgProblemResolver with broken count: 0
145s Done
145s The following additional packages will be installed:
145s   userv
145s The following NEW packages will be installed:
145s   autopkgtest-satdep userv
145s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
145s Need to get 284 kB/285 kB of archives.
145s After this operation, 487 kB of additional disk space will be used.
145s Get:1 /tmp/autopkgtest.Q9tbVB/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [704 B]
146s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x userv s390x 1.2.1~beta4 [284 kB]
146s Fetched 284 kB in 0s (675 kB/s)
146s Selecting previously unselected package userv.
146s (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 ... 51726 files and directories currently installed.)
146s Preparing to unpack .../userv_1.2.1~beta4_s390x.deb ...
146s Unpacking userv (1.2.1~beta4) ...
146s Selecting previously unselected package autopkgtest-satdep.
146s Preparing to unpack .../1-autopkgtest-satdep.deb ...
146s Unpacking autopkgtest-satdep (0) ...
146s Setting up userv (1.2.1~beta4) ...
147s Setting up autopkgtest-satdep (0) ...
147s Processing triggers for man-db (2.12.0-3) ...
150s (Reading database ... 51756 files and directories currently installed.)
150s Removing autopkgtest-satdep (0) ...
151s autopkgtest [21:40:47]: test t-basic: [-----------------------
151s + . tests/lib
151s + expect_output 0 games userv --override 'execute whoami' games spong
151s + local e_status=0
151s + shift
151s + local e_stdout=games
151s + shift
151s + set +e
151s + local g_stdout
151s ++ userv --override 'execute whoami' games spong
151s + g_stdout=games
151s + local g_status=0
151s + set -e
151s + test 0 = 0
151s + test games = games
151s + echo ok.
151s ok.
151s autopkgtest [21:40:47]: test t-basic: -----------------------]
152s autopkgtest [21:40:48]: test t-basic:  - - - - - - - - - - results - - - - - - - - - -
152s t-basic              PASS
152s autopkgtest [21:40:48]: test t-config: preparing testbed
160s Reading package lists...
160s Building dependency tree...
160s Reading state information...
161s Starting pkgProblemResolver with broken count: 0
161s Starting 2 pkgProblemResolver with broken count: 0
161s Done
161s The following additional packages will be installed:
161s   chiark-really
161s The following NEW packages will be installed:
161s   autopkgtest-satdep chiark-really
161s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
161s Need to get 11.1 kB/11.8 kB of archives.
161s After this operation, 47.1 kB of additional disk space will be used.
161s Get:1 /tmp/autopkgtest.Q9tbVB/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [720 B]
161s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x chiark-really s390x 7.0.0 [11.1 kB]
161s Fetched 11.1 kB in 0s (57.2 kB/s)
161s Selecting previously unselected package chiark-really.
161s (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 ... 51756 files and directories currently installed.)
161s Preparing to unpack .../chiark-really_7.0.0_s390x.deb ...
161s Unpacking chiark-really (7.0.0) ...
161s Selecting previously unselected package autopkgtest-satdep.
161s Preparing to unpack .../2-autopkgtest-satdep.deb ...
161s Unpacking autopkgtest-satdep (0) ...
161s Setting up chiark-really (7.0.0) ...
161s Setting up autopkgtest-satdep (0) ...
161s Processing triggers for man-db (2.12.0-3) ...
164s (Reading database ... 51762 files and directories currently installed.)
164s Removing autopkgtest-satdep (0) ...
167s autopkgtest [21:41:03]: test t-config: [-----------------------
167s + . tests/lib
167s + prep_tmp
167s + tmp=/tmp/autopkgtest.Q9tbVB/t-config-artifacts
167s + '[' x/tmp/autopkgtest.Q9tbVB/t-config-artifacts = x ']'
167s + prep_cuser
167s + cuser=bin
167s ++ id -u bin
167s + cuser_uid=2
167s ++ id -g bin
167s + cuser_gid=2
167s + prep_config_t_env games /etc/userv
167s + local suser=games
167s + local cfgdir=/etc/userv
167s + mkdir -p /etc/userv/services.d
167s + cat
167s + : '---------- test service invocation ----------'
167s + really -u bin userv games userv-t-env
167s + check_expected_env /tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + local got_env=/tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
167s + egrep '^USERV_SERVICE=userv-t-env$' /tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
167s USERV_SERVICE=userv-t-env
167s + egrep '^USERV_USER=bin$' /tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
167s USERV_USER=bin
167s + egrep '^USERV_GROUP=bin bin$' /tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
167s + egrep '^USERV_UID=2$' /tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
167s + egrep '^USERV_GID=2 2$' /tmp/autopkgtest.Q9tbVB/t-config-artifacts/env
167s + : '---------- test rejection (wrong calling user) ----------'
167s + expect_output 255 '' really -u daemon userv games userv-t-env
167s + local e_status=255
167s + shift
167s + local e_stdout=
167s + shift
167s + set +e
167s + local g_stdout
167s ++ really -u daemon userv games userv-t-env
167s uservd: request rejected
167s userv: uservd reports that service failed
167s + g_stdout=
167s + local g_status=255
167s + set -e
167s + test 255 = 255
167s + test '' = ''
167s + echo ok.
167s USERV_GROUP=bin bin
167s USERV_UID=2
167s USERV_GID=2 2
167s ok.
167s autopkgtest [21:41:03]: test t-config: -----------------------]
168s autopkgtest [21:41:04]: test t-config:  - - - - - - - - - - results - - - - - - - - - -
168s t-config             PASS
169s autopkgtest [21:41:05]: test t-userconfig: preparing testbed
266s autopkgtest [21:42:39]: testbed dpkg architecture: s390x
266s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
266s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
266s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB]
266s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4028 kB]
266s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB]
266s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [654 kB]
266s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B]
266s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B]
266s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B]
266s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4150 kB]
266s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B]
266s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [46.8 kB]
266s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B]
266s autopkgtest [21:42:39]: testbed apt version: 2.7.12
266s autopkgtest [21:42:39]: @@@@@@@@@@@@@@@@@@@@ test bed setup
267s Fetched 9565 kB in 2s (3978 kB/s)
267s Reading package lists...
269s Reading package lists...
269s Building dependency tree...
269s Reading state information...
270s Calculating upgrade...
270s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
270s Reading package lists...
270s Building dependency tree...
270s Reading state information...
270s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
270s Unknown architecture, assuming PC-style ttyS0
270s sh: Attempting to set up Debian/Ubuntu apt sources automatically
270s sh: Distribution appears to be Ubuntu
271s Reading package lists...
271s Building dependency tree...
271s Reading state information...
271s eatmydata is already the newest version (131-1).
271s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
271s Reading package lists...
272s Building dependency tree...
272s Reading state information...
272s dbus is already the newest version (1.14.10-4ubuntu1).
272s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
272s Reading package lists...
272s Building dependency tree...
272s Reading state information...
272s rng-tools-debian is already the newest version (2.4).
272s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
272s Reading package lists...
272s Building dependency tree...
272s Reading state information...
272s The following packages will be REMOVED:
272s   cloud-init* python3-configobj* python3-debconf*
273s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
273s After this operation, 3256 kB disk space will be freed.
273s (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 ... 52170 files and directories currently installed.)
273s Removing cloud-init (24.1.2-0ubuntu1) ...
273s Removing python3-configobj (5.0.8-3) ...
273s Removing python3-debconf (1.5.86) ...
273s Processing triggers for man-db (2.12.0-3) ...
274s (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 ... 51781 files and directories currently installed.)
274s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ...
274s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
274s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
274s invoke-rc.d: policy-rc.d denied execution of try-restart.
274s Reading package lists...
274s Building dependency tree...
274s Reading state information...
275s linux-generic is already the newest version (6.8.0-11.11+1).
275s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
275s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
275s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
275s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
278s Reading package lists...Reading package lists...
278s autopkgtest [21:42:54]: rebooting testbed after setup commands that affected boot
278s 
278s Building dependency tree...
278s Reading state information...
278s Calculating upgrade...
278s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
278s Reading package lists...
278s Building dependency tree...
278s Reading state information...
278s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
300s Reading package lists...
300s Building dependency tree...
300s Reading state information...
300s Starting pkgProblemResolver with broken count: 0
300s Starting 2 pkgProblemResolver with broken count: 0
300s Done
300s The following additional packages will be installed:
300s   chiark-really userv
300s The following NEW packages will be installed:
300s   autopkgtest-satdep chiark-really userv
300s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded.
300s Need to get 295 kB/296 kB of archives.
300s After this operation, 535 kB of additional disk space will be used.
300s Get:1 /tmp/autopkgtest.Q9tbVB/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [720 B]
301s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x userv s390x 1.2.1~beta4 [284 kB]
301s Get:3 http://ftpmaster.internal/ubuntu noble/universe s390x chiark-really s390x 7.0.0 [11.1 kB]
301s Fetched 295 kB in 1s (411 kB/s)
301s Selecting previously unselected package userv.
301s (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 ... 51726 files and directories currently installed.)
301s Preparing to unpack .../userv_1.2.1~beta4_s390x.deb ...
301s Unpacking userv (1.2.1~beta4) ...
301s Selecting previously unselected package chiark-really.
301s Preparing to unpack .../chiark-really_7.0.0_s390x.deb ...
301s Unpacking chiark-really (7.0.0) ...
301s Selecting previously unselected package autopkgtest-satdep.
301s Preparing to unpack .../3-autopkgtest-satdep.deb ...
301s Unpacking autopkgtest-satdep (0) ...
301s Setting up userv (1.2.1~beta4) ...
302s Setting up chiark-really (7.0.0) ...
302s Setting up autopkgtest-satdep (0) ...
302s Processing triggers for man-db (2.12.0-3) ...
304s (Reading database ... 51762 files and directories currently installed.)
304s Removing autopkgtest-satdep (0) ...
308s autopkgtest [21:43:24]: test t-userconfig: [-----------------------
308s + . tests/lib
308s + prep_tmp
308s + tmp=/tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts
308s + '[' x/tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts = x ']'
308s + prep_cuser
308s + cuser=bin
308s ++ id -u bin
308s + cuser_uid=2
308s ++ id -g bin
308s + cuser_gid=2
308s + mkdir /root/.userv
308s + cat
308s + prep_config_t_env root /root/.userv
308s + local suser=root
308s + local cfgdir=/root/.userv
308s + mkdir -p /root/.userv/services.d
308s + cat
308s + : '---------- test service invocation ----------'
308s + really -u bin userv root userv-t-env
308s + check_expected_env /tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + local got_env=/tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
308s + egrep '^USERV_SERVICE=userv-t-env$' /tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
308s + egrep '^USERV_USER=bin$' /tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
308s + egrep '^USERV_GROUP=bin bin$' /tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
308s + egrep '^USERV_UID=2$' /tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + for expect in "USERV_SERVICE=userv-t-env" "USERV_USER=$cuser" "USERV_GROUP=$cuser $cuser" "USERV_UID=$cuser_uid" "USERV_GID=$cuser_gid $cuser_gid"
308s + egrep '^USERV_GID=2 2$' /tmp/autopkgtest.Q9tbVB/t-userconfig-artifacts/env
308s + : '---------- test rejection (wrong calling user) ----------'
308s + expect_output 255 '' really -u daemon userv root userv-t-env
308s + local e_status=255
308s + shift
308s + local e_stdout=
308s + shift
308s + set +e
308s + local g_stdout
308s USERV_SERVICE=userv-t-env
308s USERV_USER=bin
308s USERV_GROUP=bin bin
308s USERV_UID=2
308s USERV_GID=2 2
308s ++ really -u daemon userv root userv-t-env
308s uservd: request rejected
308s userv: uservd reports that service failed
308s + g_stdout=
308s + local g_status=255
308s + set -e
308s + test 255 = 255
308s + test '' = ''
308s + echo ok.
308s ok.
309s autopkgtest [21:43:24]: test t-userconfig: -----------------------]
310s autopkgtest [21:43:26]: test t-userconfig:  - - - - - - - - - - results - - - - - - - - - -
310s t-userconfig         PASS
310s autopkgtest [21:43:26]: test t-persist: preparing testbed
425s autopkgtest [21:45:18]: testbed dpkg architecture: s390x
425s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
425s autopkgtest [21:45:18]: testbed apt version: 2.7.12
425s autopkgtest [21:45:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup
425s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB]
425s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
425s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4028 kB]
425s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB]
425s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [654 kB]
426s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B]
426s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B]
426s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B]
426s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4150 kB]
431s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B]
431s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [46.8 kB]
431s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B]
431s Fetched 9565 kB in 5s (2112 kB/s)
431s Reading package lists...
434s Reading package lists...
434s Building dependency tree...
434s Reading state information...
434s Calculating upgrade...
434s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
434s Reading package lists...
435s Building dependency tree...
435s Reading state information...
435s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
435s Unknown architecture, assuming PC-style ttyS0
435s sh: Attempting to set up Debian/Ubuntu apt sources automatically
435s sh: Distribution appears to be Ubuntu
436s Reading package lists...
436s Building dependency tree...
436s Reading state information...
436s eatmydata is already the newest version (131-1).
436s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
436s Reading package lists...
437s Building dependency tree...
437s Reading state information...
437s dbus is already the newest version (1.14.10-4ubuntu1).
437s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
437s Reading package lists...
437s Building dependency tree...
437s Reading state information...
438s rng-tools-debian is already the newest version (2.4).
438s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
438s Reading package lists...
438s Building dependency tree...
438s Reading state information...
438s The following packages will be REMOVED:
438s   cloud-init* python3-configobj* python3-debconf*
442s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
442s After this operation, 3256 kB disk space will be freed.
442s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
442s (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 ... 52170 files and directories currently installed.)
442s Removing cloud-init (24.1.2-0ubuntu1) ...
442s Removing python3-configobj (5.0.8-3) ...
442s Removing python3-debconf (1.5.86) ...
442s Processing triggers for man-db (2.12.0-3) ...
442s (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 ... 51781 files and directories currently installed.)
442s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ...
442s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
442s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
442s invoke-rc.d: policy-rc.d denied execution of try-restart.
442s Reading package lists...
442s Building dependency tree...
442s Reading state information...
442s linux-generic is already the newest version (6.8.0-11.11+1).
442s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
442s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
442s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
443s Reading package lists...
443s Reading package lists...
443s Building dependency tree...
443s Reading state information...
443s Calculating upgrade...
443s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
443s Reading package lists...
444s Building dependency tree...
444s Reading state information...
444s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
444s autopkgtest [21:45:40]: rebooting testbed after setup commands that affected boot
470s Reading package lists...
470s Building dependency tree...
470s Reading state information...
470s Starting pkgProblemResolver with broken count: 0
470s Starting 2 pkgProblemResolver with broken count: 0
470s Done
470s The following additional packages will be installed:
470s   userv
470s The following NEW packages will be installed:
470s   autopkgtest-satdep userv
470s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
470s Need to get 284 kB/285 kB of archives.
470s After this operation, 487 kB of additional disk space will be used.
470s Get:1 /tmp/autopkgtest.Q9tbVB/4-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [708 B]
470s Get:2 http://ftpmaster.internal/ubuntu noble/universe s390x userv s390x 1.2.1~beta4 [284 kB]
471s Fetched 284 kB in 0s (663 kB/s)
471s Selecting previously unselected package userv.
471s (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 ... 51726 files and directories currently installed.)
471s Preparing to unpack .../userv_1.2.1~beta4_s390x.deb ...
471s Unpacking userv (1.2.1~beta4) ...
471s Selecting previously unselected package autopkgtest-satdep.
471s Preparing to unpack .../4-autopkgtest-satdep.deb ...
471s Unpacking autopkgtest-satdep (0) ...
471s Setting up userv (1.2.1~beta4) ...
471s Setting up autopkgtest-satdep (0) ...
471s Processing triggers for man-db (2.12.0-3) ...
475s (Reading database ... 51756 files and directories currently installed.)
475s Removing autopkgtest-satdep (0) ...
476s autopkgtest [21:46:12]: test t-persist: [-----------------------
476s + . tests/lib
476s + userv --override 'execute cat -vet' games spong
476s + stdout=63
476s + stdin=60
476s + print_expect_reply hi
476s + local m=hi
476s + local got
476s + echo hi
476s + read got
476s ++ printf '%s$\n' hi
476s + local 'exp=hi$'
476s + test 'hi$' = 'hi$'
476s + service userv restart
477s + print_expect_reply ho
477s + local m=ho
477s + local got
477s + echo ho
477s + read got
477s ++ printf '%s$\n' ho
477s + local 'exp=ho$'
477s + test 'ho$' = 'ho$'
477s + echo ok.
477s ok.
477s autopkgtest [21:46:13]: test t-persist: -----------------------]
477s t-persist            PASS
477s autopkgtest [21:46:13]: test t-persist:  - - - - - - - - - - results - - - - - - - - - -
480s autopkgtest [21:46:14]: @@@@@@@@@@@@@@@@@@@@ summary
480s t-basic              PASS
480s t-config             PASS
480s t-userconfig         PASS
480s t-persist            PASS
492s Creating nova instance adt-noble-s390x-userv-20240323-213812-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240323.img (UUID 15bd59e1-c29b-45d7-aba1-73ff15182fb5)...
492s Creating nova instance adt-noble-s390x-userv-20240323-213812-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240323.img (UUID 15bd59e1-c29b-45d7-aba1-73ff15182fb5)...
492s Creating nova instance adt-noble-s390x-userv-20240323-213812-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240323.img (UUID 15bd59e1-c29b-45d7-aba1-73ff15182fb5)...