0s autopkgtest [22:44:59]: starting date and time: 2024-03-23 22:44:59+0000
  0s autopkgtest [22:44:59]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps
  0s autopkgtest [22:44:59]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.supt8dcw/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-ppc64el-17.secgroup --name adt-noble-ppc64el-userv-20240323-224459-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-ppc64el-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/
149s autopkgtest [22:47:28]: testbed dpkg architecture: ppc64el
150s autopkgtest [22:47:29]: testbed apt version: 2.7.12
150s autopkgtest [22:47:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup
151s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
151s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
151s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB]
151s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB]
151s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3966 kB]
151s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [661 kB]
151s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B]
151s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B]
151s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B]
151s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4254 kB]
152s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B]
152s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [60.8 kB]
152s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B]
155s Fetched 9629 kB in 3s (3801 kB/s)
156s Reading package lists...
159s Reading package lists...
159s Building dependency tree...
159s Reading state information...
159s Calculating upgrade...
159s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
159s Reading package lists...
159s Building dependency tree...
159s Reading state information...
160s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
160s sh: Attempting to set up Debian/Ubuntu apt sources automatically
160s sh: Distribution appears to be Ubuntu
161s Reading package lists...
161s Building dependency tree...
161s Reading state information...
161s eatmydata is already the newest version (131-1).
161s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
161s Reading package lists...
161s Building dependency tree...
161s Reading state information...
162s dbus is already the newest version (1.14.10-4ubuntu1).
162s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
162s Reading package lists...
162s Building dependency tree...
162s Reading state information...
162s rng-tools-debian is already the newest version (2.4).
162s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
162s Reading package lists...
162s Building dependency tree...
162s Reading state information...
162s The following packages will be REMOVED:
162s   cloud-init* python3-configobj* python3-debconf*
163s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
163s After this operation, 3256 kB disk space will be freed.
163s (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 ... 70156 files and directories currently installed.)
163s Removing cloud-init (24.1.2-0ubuntu1) ...
163s Removing python3-configobj (5.0.8-3) ...
163s Removing python3-debconf (1.5.86) ...
164s Processing triggers for man-db (2.12.0-3) ...
164s (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 ... 69767 files and directories currently installed.)
164s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ...
165s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
165s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
165s invoke-rc.d: policy-rc.d denied execution of try-restart.
165s Reading package lists...
165s Building dependency tree...
165s Reading state information...
165s linux-generic is already the newest version (6.8.0-11.11+1).
165s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
166s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
166s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
166s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
169s Reading package lists...
169s Reading package lists...
169s Building dependency tree...
169s Reading state information...
169s Calculating upgrade...
169s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
169s Reading package lists...
169s Building dependency tree...
169s Reading state information...
169s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
170s autopkgtest [22:47:49]: rebooting testbed after setup commands that affected boot
336s autopkgtest [22:50:35]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024
339s autopkgtest [22:50:38]: @@@@@@@@@@@@@@@@@@@@ apt-source userv
341s Get:1 http://ftpmaster.internal/ubuntu noble/universe userv 1.2.1~beta4 (dsc) [1311 B]
341s Get:2 http://ftpmaster.internal/ubuntu noble/universe userv 1.2.1~beta4 (tar) [199 kB]
341s gpgv: Signature made Sat Jan  1 01:16:54 2022 UTC
341s gpgv:                using RSA key 559AE46C2D6B6D3265E7CBA1E3E3392348B50D39
341s gpgv: Can't check signature: No public key
341s dpkg-source: warning: cannot verify inline signature for ./userv_1.2.1~beta4.dsc: no acceptable signature found
341s autopkgtest [22:50:40]: testing package userv version 1.2.1~beta4
341s autopkgtest [22:50:40]: build not needed
342s autopkgtest [22:50:41]: test t-basic: preparing testbed
349s Reading package lists...
349s Building dependency tree...
349s Reading state information...
349s Starting pkgProblemResolver with broken count: 0
349s Starting 2 pkgProblemResolver with broken count: 0
349s Done
349s The following additional packages will be installed:
349s   userv
349s The following NEW packages will be installed:
349s   autopkgtest-satdep userv
349s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
349s Need to get 293 kB/293 kB of archives.
349s After this operation, 557 kB of additional disk space will be used.
349s Get:1 /tmp/autopkgtest.iQe8mE/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [712 B]
349s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el userv ppc64el 1.2.1~beta4 [293 kB]
350s Fetched 293 kB in 0s (715 kB/s)
350s Selecting previously unselected package userv.
350s (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 ... 69712 files and directories currently installed.)
350s Preparing to unpack .../userv_1.2.1~beta4_ppc64el.deb ...
350s Unpacking userv (1.2.1~beta4) ...
350s Selecting previously unselected package autopkgtest-satdep.
350s Preparing to unpack .../1-autopkgtest-satdep.deb ...
350s Unpacking autopkgtest-satdep (0) ...
350s Setting up userv (1.2.1~beta4) ...
351s Setting up autopkgtest-satdep (0) ...
351s Processing triggers for man-db (2.12.0-3) ...
353s (Reading database ... 69742 files and directories currently installed.)
353s Removing autopkgtest-satdep (0) ...
354s autopkgtest [22:50:53]: test t-basic: [-----------------------
354s + . tests/lib
354s + expect_output 0 games userv --override 'execute whoami' games spong
354s + local e_status=0
354s + shift
354s + local e_stdout=games
354s + shift
354s + set +e
354s + local g_stdout
354s ++ userv --override 'execute whoami' games spong
354s ok.
354s + g_stdout=games
354s + local g_status=0
354s + set -e
354s + test 0 = 0
354s + test games = games
354s + echo ok.
354s autopkgtest [22:50:53]: test t-basic: -----------------------]
355s t-basic              PASS
355s autopkgtest [22:50:54]: test t-basic:  - - - - - - - - - - results - - - - - - - - - -
355s autopkgtest [22:50:54]: test t-config: preparing testbed
371s Reading package lists...
372s Building dependency tree...
372s Reading state information...
372s Starting pkgProblemResolver with broken count: 0
372s Starting 2 pkgProblemResolver with broken count: 0
372s Done
372s The following additional packages will be installed:
372s   chiark-really
372s The following NEW packages will be installed:
372s   autopkgtest-satdep chiark-really
372s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
372s Need to get 11.6 kB/12.4 kB of archives.
372s After this operation, 100 kB of additional disk space will be used.
372s Get:1 /tmp/autopkgtest.iQe8mE/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [724 B]
372s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el chiark-really ppc64el 7.0.0 [11.6 kB]
372s Fetched 11.6 kB in 0s (78.7 kB/s)
372s Selecting previously unselected package chiark-really.
372s (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 ... 69742 files and directories currently installed.)
372s Preparing to unpack .../chiark-really_7.0.0_ppc64el.deb ...
372s Unpacking chiark-really (7.0.0) ...
372s Selecting previously unselected package autopkgtest-satdep.
372s Preparing to unpack .../2-autopkgtest-satdep.deb ...
372s Unpacking autopkgtest-satdep (0) ...
373s Setting up chiark-really (7.0.0) ...
373s Setting up autopkgtest-satdep (0) ...
373s Processing triggers for man-db (2.12.0-3) ...
375s (Reading database ... 69748 files and directories currently installed.)
375s Removing autopkgtest-satdep (0) ...
375s autopkgtest [22:51:14]: test t-config: [-----------------------
375s + . tests/lib
375s + prep_tmp
375s + tmp=/tmp/autopkgtest.iQe8mE/t-config-artifacts
375s + '[' x/tmp/autopkgtest.iQe8mE/t-config-artifacts = x ']'
375s + prep_cuser
375s + cuser=bin
375s ++ id -u bin
375s + cuser_uid=2
375s ++ id -g bin
375s + cuser_gid=2
375s + prep_config_t_env games /etc/userv
375s + local suser=games
375s + local cfgdir=/etc/userv
375s + mkdir -p /etc/userv/services.d
375s + cat
375s + : '---------- test service invocation ----------'
375s + really -u bin userv games userv-t-env
375s + check_expected_env /tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s + local got_env=/tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s + 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"
375s + egrep '^USERV_SERVICE=userv-t-env$' /tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s USERV_SERVICE=userv-t-env
375s + 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"
375s + egrep '^USERV_USER=bin$' /tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s USERV_USER=bin
375s + 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"
375s + egrep '^USERV_GROUP=bin bin$' /tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s USERV_GROUP=bin bin
375s + 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"
375s + egrep '^USERV_UID=2$' /tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s USERV_UID=2
375s USERV_GID=2 2
375s ok.
375s + 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"
375s + egrep '^USERV_GID=2 2$' /tmp/autopkgtest.iQe8mE/t-config-artifacts/env
375s + : '---------- test rejection (wrong calling user) ----------'
375s + expect_output 255 '' really -u daemon userv games userv-t-env
375s + local e_status=255
375s + shift
375s + local e_stdout=
375s + shift
375s + set +e
375s + local g_stdout
375s ++ really -u daemon userv games userv-t-env
375s uservd: request rejected
375s userv: uservd reports that service failed
375s + g_stdout=
375s + local g_status=255
375s + set -e
375s + test 255 = 255
375s + test '' = ''
375s + echo ok.
376s autopkgtest [22:51:15]: test t-config: -----------------------]
377s autopkgtest [22:51:16]: test t-config:  - - - - - - - - - - results - - - - - - - - - -
377s t-config             PASS
377s autopkgtest [22:51:16]: test t-userconfig: preparing testbed
571s autopkgtest [22:54:30]: testbed dpkg architecture: ppc64el
571s autopkgtest [22:54:30]: testbed apt version: 2.7.12
571s autopkgtest [22:54:30]: @@@@@@@@@@@@@@@@@@@@ test bed setup
572s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
572s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
572s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB]
572s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB]
573s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3966 kB]
576s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [661 kB]
576s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B]
576s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B]
576s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B]
576s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4254 kB]
578s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B]
578s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [60.8 kB]
578s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B]
581s Fetched 9629 kB in 8s (1283 kB/s)
581s Reading package lists...
584s Reading package lists...
584s Building dependency tree...
584s Reading state information...
584s Calculating upgrade...
584s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
584s Reading package lists...
584s Building dependency tree...
584s Reading state information...
585s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
585s sh: Attempting to set up Debian/Ubuntu apt sources automatically
585s sh: Distribution appears to be Ubuntu
586s Reading package lists...
586s Building dependency tree...
586s Reading state information...
586s eatmydata is already the newest version (131-1).
586s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
586s Reading package lists...
586s Building dependency tree...
586s Reading state information...
587s dbus is already the newest version (1.14.10-4ubuntu1).
587s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
587s Reading package lists...
587s Building dependency tree...
587s Reading state information...
587s rng-tools-debian is already the newest version (2.4).
587s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
587s Reading package lists...
587s Building dependency tree...
587s Reading state information...
587s The following packages will be REMOVED:
587s   cloud-init* python3-configobj* python3-debconf*
587s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
587s After this operation, 3256 kB disk space will be freed.
588s (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 ... 70156 files and directories currently installed.)
588s Removing cloud-init (24.1.2-0ubuntu1) ...
588s Removing python3-configobj (5.0.8-3) ...
588s Removing python3-debconf (1.5.86) ...
588s Processing triggers for man-db (2.12.0-3) ...
589s (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 ... 69767 files and directories currently installed.)
589s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ...
589s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
589s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
589s invoke-rc.d: policy-rc.d denied execution of try-restart.
589s Reading package lists...
589s Building dependency tree...
589s Reading state information...
590s linux-generic is already the newest version (6.8.0-11.11+1).
590s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
590s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
590s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
590s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
592s Reading package lists...
592s Reading package lists...
593s Building dependency tree...
593s Reading state information...
593s Calculating upgrade...
593s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
593s Reading package lists...
593s Building dependency tree...
593s Reading state information...
593s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
593s autopkgtest [22:54:52]: rebooting testbed after setup commands that affected boot
631s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
646s Reading package lists...
646s Building dependency tree...
646s Reading state information...
646s Starting pkgProblemResolver with broken count: 0
646s Starting 2 pkgProblemResolver with broken count: 0
646s Done
646s The following additional packages will be installed:
646s   chiark-really userv
646s The following NEW packages will be installed:
646s   autopkgtest-satdep chiark-really userv
646s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded.
646s Need to get 304 kB/305 kB of archives.
646s After this operation, 657 kB of additional disk space will be used.
646s Get:1 /tmp/autopkgtest.iQe8mE/3-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B]
646s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el userv ppc64el 1.2.1~beta4 [293 kB]
647s Get:3 http://ftpmaster.internal/ubuntu noble/universe ppc64el chiark-really ppc64el 7.0.0 [11.6 kB]
647s Fetched 304 kB in 1s (535 kB/s)
647s Selecting previously unselected package userv.
647s (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 ... 69712 files and directories currently installed.)
647s Preparing to unpack .../userv_1.2.1~beta4_ppc64el.deb ...
647s Unpacking userv (1.2.1~beta4) ...
647s Selecting previously unselected package chiark-really.
647s Preparing to unpack .../chiark-really_7.0.0_ppc64el.deb ...
647s Unpacking chiark-really (7.0.0) ...
647s Selecting previously unselected package autopkgtest-satdep.
647s Preparing to unpack .../3-autopkgtest-satdep.deb ...
647s Unpacking autopkgtest-satdep (0) ...
647s Setting up userv (1.2.1~beta4) ...
648s Setting up chiark-really (7.0.0) ...
648s Setting up autopkgtest-satdep (0) ...
648s Processing triggers for man-db (2.12.0-3) ...
651s (Reading database ... 69748 files and directories currently installed.)
651s Removing autopkgtest-satdep (0) ...
654s autopkgtest [22:55:53]: test t-userconfig: [-----------------------
654s + . tests/lib
654s + prep_tmp
654s + tmp=/tmp/autopkgtest.iQe8mE/t-userconfig-artifacts
654s + '[' x/tmp/autopkgtest.iQe8mE/t-userconfig-artifacts = x ']'
654s + prep_cuser
654s + cuser=bin
654s ++ id -u bin
654s + cuser_uid=2
654s ++ id -g bin
654s + cuser_gid=2
654s + mkdir /root/.userv
654s + cat
654s + prep_config_t_env root /root/.userv
654s + local suser=root
654s + local cfgdir=/root/.userv
654s USERV_SERVICE=userv-t-env
654s USERV_USER=bin
654s USERV_GROUP=bin bin
654s USERV_UID=2
654s USERV_GID=2 2
654s + mkdir -p /root/.userv/services.d
654s + cat
654s + : '---------- test service invocation ----------'
654s + really -u bin userv root userv-t-env
654s + check_expected_env /tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + local got_env=/tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + 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"
654s + egrep '^USERV_SERVICE=userv-t-env$' /tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + 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"
654s + egrep '^USERV_USER=bin$' /tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + 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"
654s + egrep '^USERV_GROUP=bin bin$' /tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + 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"
654s + egrep '^USERV_UID=2$' /tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + 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"
654s + egrep '^USERV_GID=2 2$' /tmp/autopkgtest.iQe8mE/t-userconfig-artifacts/env
654s + : '---------- test rejection (wrong calling user) ----------'
654s + expect_output 255 '' really -u daemon userv root userv-t-env
654s + local e_status=255
654s + shift
654s + local e_stdout=
654s + shift
654s + set +e
654s + local g_stdout
654s ++ really -u daemon userv root userv-t-env
654s uservd: request rejected
654s userv: uservd reports that service failed
654s + g_stdout=
654s + local g_status=255
654s + set -e
654s + test 255 = 255
654s + test '' = ''
654s + echo ok.
654s ok.
654s autopkgtest [22:55:53]: test t-userconfig: -----------------------]
655s autopkgtest [22:55:54]: test t-userconfig:  - - - - - - - - - - results - - - - - - - - - -
655s t-userconfig         PASS
655s autopkgtest [22:55:54]: test t-persist: preparing testbed
753s autopkgtest [22:57:32]: testbed dpkg architecture: ppc64el
753s autopkgtest [22:57:32]: testbed apt version: 2.7.12
753s autopkgtest [22:57:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup
754s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB]
754s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B]
754s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3966 kB]
754s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB]
754s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB]
755s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [661 kB]
755s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B]
755s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B]
755s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B]
755s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4254 kB]
755s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B]
755s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [60.8 kB]
755s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B]
758s Fetched 9629 kB in 2s (4213 kB/s)
758s Reading package lists...
761s Reading package lists...
761s Building dependency tree...
761s Reading state information...
761s Calculating upgrade...
761s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
761s Reading package lists...
762s Building dependency tree...
762s Reading state information...
762s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
762s sh: Attempting to set up Debian/Ubuntu apt sources automatically
762s sh: Distribution appears to be Ubuntu
763s Reading package lists...
763s Building dependency tree...
763s Reading state information...
763s eatmydata is already the newest version (131-1).
763s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
763s Reading package lists...
764s Building dependency tree...
764s Reading state information...
764s dbus is already the newest version (1.14.10-4ubuntu1).
764s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
764s Reading package lists...
764s Building dependency tree...
764s Reading state information...
764s rng-tools-debian is already the newest version (2.4).
764s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
764s Reading package lists...
764s Building dependency tree...
764s Reading state information...
764s The following packages will be REMOVED:
764s   cloud-init* python3-configobj* python3-debconf*
765s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded.
765s After this operation, 3256 kB disk space will be freed.
765s (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 ... 70156 files and directories currently installed.)
765s Removing cloud-init (24.1.2-0ubuntu1) ...
765s Removing python3-configobj (5.0.8-3) ...
765s Removing python3-debconf (1.5.86) ...
765s Processing triggers for man-db (2.12.0-3) ...
766s (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 ... 69767 files and directories currently installed.)
766s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ...
767s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed
767s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ...
767s invoke-rc.d: policy-rc.d denied execution of try-restart.
767s Reading package lists...
767s Building dependency tree...
767s Reading state information...
767s linux-generic is already the newest version (6.8.0-11.11+1).
767s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
767s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease
768s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease
768s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease
770s Reading package lists...
770s Reading package lists...
770s Building dependency tree...
770s Reading state information...
770s Calculating upgrade...
770s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
770s Reading package lists...
771s Building dependency tree...
771s Reading state information...
771s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
771s autopkgtest [22:57:50]: rebooting testbed after setup commands that affected boot
812s Reading package lists...
812s Building dependency tree...
812s Reading state information...
812s Starting pkgProblemResolver with broken count: 0
812s Starting 2 pkgProblemResolver with broken count: 0
812s Done
812s The following additional packages will be installed:
812s   userv
812s The following NEW packages will be installed:
812s   autopkgtest-satdep userv
812s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
812s Need to get 293 kB/293 kB of archives.
812s After this operation, 557 kB of additional disk space will be used.
812s Get:1 /tmp/autopkgtest.iQe8mE/4-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [712 B]
813s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppc64el userv ppc64el 1.2.1~beta4 [293 kB]
813s Fetched 293 kB in 0s (714 kB/s)
813s Selecting previously unselected package userv.
813s (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 ... 69712 files and directories currently installed.)
813s Preparing to unpack .../userv_1.2.1~beta4_ppc64el.deb ...
813s Unpacking userv (1.2.1~beta4) ...
813s Selecting previously unselected package autopkgtest-satdep.
813s Preparing to unpack .../4-autopkgtest-satdep.deb ...
813s Unpacking autopkgtest-satdep (0) ...
813s Setting up userv (1.2.1~beta4) ...
814s Setting up autopkgtest-satdep (0) ...
814s Processing triggers for man-db (2.12.0-3) ...
817s (Reading database ... 69742 files and directories currently installed.)
817s Removing autopkgtest-satdep (0) ...
819s autopkgtest [22:58:38]: test t-persist: [-----------------------
819s + . tests/lib
819s + userv --override 'execute cat -vet' games spong
819s + stdout=63
819s + stdin=60
819s + print_expect_reply hi
819s + local m=hi
819s + local got
819s + echo hi
819s + read got
819s ++ printf '%s$\n' hi
819s + local 'exp=hi$'
819s + test 'hi$' = 'hi$'
819s + service userv restart
819s + print_expect_reply ho
819s ok.
819s + local m=ho
819s + local got
819s + echo ho
819s + read got
819s ++ printf '%s$\n' ho
819s + local 'exp=ho$'
819s + test 'ho$' = 'ho$'
819s + echo ok.
820s autopkgtest [22:58:39]: test t-persist: -----------------------]
821s autopkgtest [22:58:40]: test t-persist:  - - - - - - - - - - results - - - - - - - - - -
821s t-persist            PASS
821s autopkgtest [22:58:40]: @@@@@@@@@@@@@@@@@@@@ summary
821s t-basic              PASS
821s t-config             PASS
821s t-userconfig         PASS
821s t-persist            PASS
833s Creating nova instance adt-noble-ppc64el-userv-20240323-224459-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-ppc64el-server-20240323.img (UUID ff8abf95-5243-4ea5-b7f5-3bf690534a1d)...
833s Creating nova instance adt-noble-ppc64el-userv-20240323-224459-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-ppc64el-server-20240323.img (UUID ff8abf95-5243-4ea5-b7f5-3bf690534a1d)...
833s Creating nova instance adt-noble-ppc64el-userv-20240323-224459-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-ppc64el-server-20240323.img (UUID ff8abf95-5243-4ea5-b7f5-3bf690534a1d)...