0s autopkgtest [14:54:26]: starting date and time: 2025-03-26 14:54:26+0000 0s autopkgtest [14:54:26]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:54:26]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.h823s2m8/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:postgresql-12 --apt-upgrade pg-cron --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=postgresql-12/12.22-0ubuntu0.20.04.3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-32.secgroup --name adt-focal-s390x-pg-cron-20250326-145423-juju-7f2275-prod-proposed-migration-environment-2-87474f58-343e-4d6a-87d2-5f4c80629de6 --image adt/ubuntu-focal-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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,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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 60s autopkgtest [14:55:26]: testbed dpkg architecture: s390x 61s autopkgtest [14:55:27]: testbed apt version: 2.0.10 61s autopkgtest [14:55:27]: @@@@@@@@@@@@@@@@@@@@ test bed setup 61s autopkgtest [14:55:27]: testbed release detected to be: None 62s autopkgtest [14:55:28]: updating testbed package index (apt update) 62s Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 62s Hit:2 http://ftpmaster.internal/ubuntu focal-updates InRelease 62s Hit:3 http://ftpmaster.internal/ubuntu focal-security InRelease 62s Get:4 http://ftpmaster.internal/ubuntu focal-proposed InRelease [277 kB] 63s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/universe Sources [8816 B] 63s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/main Sources [59.1 kB] 63s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/restricted Sources [15.7 kB] 63s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/main s390x Packages [145 kB] 63s Get:9 http://ftpmaster.internal/ubuntu focal-proposed/main s390x c-n-f Metadata [1568 B] 63s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/restricted s390x Packages [1524 B] 63s Get:11 http://ftpmaster.internal/ubuntu focal-proposed/restricted s390x c-n-f Metadata [116 B] 63s Get:12 http://ftpmaster.internal/ubuntu focal-proposed/universe s390x Packages [40.5 kB] 63s Get:13 http://ftpmaster.internal/ubuntu focal-proposed/universe s390x c-n-f Metadata [1776 B] 63s Get:14 http://ftpmaster.internal/ubuntu focal-proposed/multiverse s390x Packages [760 B] 63s Get:15 http://ftpmaster.internal/ubuntu focal-proposed/multiverse s390x c-n-f Metadata [116 B] 64s Fetched 551 kB in 1s (650 kB/s) 65s Reading package lists... 65s autopkgtest [14:55:31]: upgrading testbed (apt dist-upgrade and autopurge) 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 66s Starting 2 pkgProblemResolver with broken count: 0 66s Done 66s 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Starting pkgProblemResolver with broken count: 0 66s Starting 2 pkgProblemResolver with broken count: 0 66s Done 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 69s autopkgtest [14:55:35]: testbed running kernel: Linux 5.4.0-211-generic #231-Ubuntu SMP Tue Mar 11 17:23:30 UTC 2025 69s autopkgtest [14:55:35]: @@@@@@@@@@@@@@@@@@@@ apt-source pg-cron 71s Get:1 http://ftpmaster.internal/ubuntu focal/universe pg-cron 1.2.0-1 (dsc) [2019 B] 71s Get:2 http://ftpmaster.internal/ubuntu focal/universe pg-cron 1.2.0-1 (tar) [34.1 kB] 71s Get:3 http://ftpmaster.internal/ubuntu focal/universe pg-cron 1.2.0-1 (diff) [3732 B] 71s gpgv: Signature made Tue Oct 29 12:10:09 2019 UTC 71s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 71s gpgv: Can't check signature: No public key 71s dpkg-source: warning: failed to verify signature on ./pg-cron_1.2.0-1.dsc 71s autopkgtest [14:55:37]: testing package pg-cron version 1.2.0-1 71s autopkgtest [14:55:37]: build not needed 72s autopkgtest [14:55:38]: test cron: preparing testbed 72s Reading package lists... 72s Building dependency tree... 72s Reading state information... 72s Starting pkgProblemResolver with broken count: 0 72s Starting 2 pkgProblemResolver with broken count: 0 72s Done 72s The following NEW packages will be installed: 72s libpq-dev libpq5 libxslt1.1 postgresql-12 postgresql-12-cron 72s postgresql-client-12 postgresql-client-common postgresql-common 72s postgresql-server-dev-12 postgresql-server-dev-all ssl-cert 72s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 72s Need to get 6427 kB of archives. 72s After this operation, 29.5 MB of additional disk space will be used. 72s Get:1 http://ftpmaster.internal/ubuntu focal-proposed/main s390x libpq5 s390x 12.22-0ubuntu0.20.04.3 [109 kB] 73s Get:2 http://ftpmaster.internal/ubuntu focal-proposed/main s390x libpq-dev s390x 12.22-0ubuntu0.20.04.3 [133 kB] 73s Get:3 http://ftpmaster.internal/ubuntu focal-updates/main s390x libxslt1.1 s390x 1.1.34-4ubuntu0.20.04.3 [140 kB] 73s Get:4 http://ftpmaster.internal/ubuntu focal-updates/main s390x postgresql-client-common all 214ubuntu0.1 [28.2 kB] 73s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/main s390x postgresql-client-12 s390x 12.22-0ubuntu0.20.04.3 [1029 kB] 73s Get:6 http://ftpmaster.internal/ubuntu focal/main s390x ssl-cert all 1.0.39 [17.0 kB] 73s Get:7 http://ftpmaster.internal/ubuntu focal-updates/main s390x postgresql-common all 214ubuntu0.1 [169 kB] 73s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/main s390x postgresql-12 s390x 12.22-0ubuntu0.20.04.3 [3745 kB] 73s Get:9 http://ftpmaster.internal/ubuntu focal/universe s390x postgresql-12-cron s390x 1.2.0-1 [59.9 kB] 73s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/universe s390x postgresql-server-dev-12 s390x 12.22-0ubuntu0.20.04.3 [989 kB] 73s Get:11 http://ftpmaster.internal/ubuntu focal-updates/universe s390x postgresql-server-dev-all all 214ubuntu0.1 [7652 B] 73s Preconfiguring packages ... 73s Fetched 6427 kB in 1s (8456 kB/s) 73s Selecting previously unselected package libpq5:s390x. 73s (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 ... 48771 files and directories currently installed.) 73s Preparing to unpack .../00-libpq5_12.22-0ubuntu0.20.04.3_s390x.deb ... 73s Unpacking libpq5:s390x (12.22-0ubuntu0.20.04.3) ... 73s Selecting previously unselected package libpq-dev. 73s Preparing to unpack .../01-libpq-dev_12.22-0ubuntu0.20.04.3_s390x.deb ... 73s Unpacking libpq-dev (12.22-0ubuntu0.20.04.3) ... 73s Selecting previously unselected package libxslt1.1:s390x. 73s Preparing to unpack .../02-libxslt1.1_1.1.34-4ubuntu0.20.04.3_s390x.deb ... 73s Unpacking libxslt1.1:s390x (1.1.34-4ubuntu0.20.04.3) ... 73s Selecting previously unselected package postgresql-client-common. 73s Preparing to unpack .../03-postgresql-client-common_214ubuntu0.1_all.deb ... 73s Unpacking postgresql-client-common (214ubuntu0.1) ... 73s Selecting previously unselected package postgresql-client-12. 73s Preparing to unpack .../04-postgresql-client-12_12.22-0ubuntu0.20.04.3_s390x.deb ... 73s Unpacking postgresql-client-12 (12.22-0ubuntu0.20.04.3) ... 73s Selecting previously unselected package ssl-cert. 73s Preparing to unpack .../05-ssl-cert_1.0.39_all.deb ... 73s Unpacking ssl-cert (1.0.39) ... 73s Selecting previously unselected package postgresql-common. 73s Preparing to unpack .../06-postgresql-common_214ubuntu0.1_all.deb ... 74s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 74s Unpacking postgresql-common (214ubuntu0.1) ... 74s Selecting previously unselected package postgresql-12. 74s Preparing to unpack .../07-postgresql-12_12.22-0ubuntu0.20.04.3_s390x.deb ... 74s Unpacking postgresql-12 (12.22-0ubuntu0.20.04.3) ... 74s Selecting previously unselected package postgresql-12-cron. 74s Preparing to unpack .../08-postgresql-12-cron_1.2.0-1_s390x.deb ... 74s Unpacking postgresql-12-cron (1.2.0-1) ... 74s Selecting previously unselected package postgresql-server-dev-12. 74s Preparing to unpack .../09-postgresql-server-dev-12_12.22-0ubuntu0.20.04.3_s390x.deb ... 74s Unpacking postgresql-server-dev-12 (12.22-0ubuntu0.20.04.3) ... 74s Selecting previously unselected package postgresql-server-dev-all. 74s Preparing to unpack .../10-postgresql-server-dev-all_214ubuntu0.1_all.deb ... 74s Unpacking postgresql-server-dev-all (214ubuntu0.1) ... 74s Setting up postgresql-client-common (214ubuntu0.1) ... 74s Setting up libpq5:s390x (12.22-0ubuntu0.20.04.3) ... 74s Setting up libpq-dev (12.22-0ubuntu0.20.04.3) ... 74s Setting up postgresql-client-12 (12.22-0ubuntu0.20.04.3) ... 74s update-alternatives: using /usr/share/postgresql/12/man/man1/psql.1.gz to provide /usr/share/man/man1/psql.1.gz (psql.1.gz) in auto mode 74s Setting up ssl-cert (1.0.39) ... 75s Setting up postgresql-common (214ubuntu0.1) ... 75s ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored. 75s Adding user postgres to group ssl-cert 75s 75s Creating config file /etc/postgresql-common/createcluster.conf with new version 75s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 75s Removing obsolete dictionary files: 75s Created symlink /etc/systemd/system/multi-user.target.wants/postgresql.service → /lib/systemd/system/postgresql.service. 76s Setting up libxslt1.1:s390x (1.1.34-4ubuntu0.20.04.3) ... 76s Setting up postgresql-12 (12.22-0ubuntu0.20.04.3) ... 76s Creating new PostgreSQL cluster 12/main ... 76s /usr/lib/postgresql/12/bin/initdb -D /var/lib/postgresql/12/main --auth-local peer --auth-host md5 76s The files belonging to this database system will be owned by user "postgres". 76s This user must also own the server process. 76s 76s The database cluster will be initialized with locale "C.UTF-8". 76s The default database encoding has accordingly been set to "UTF8". 76s The default text search configuration will be set to "english". 76s 76s Data page checksums are disabled. 76s 76s fixing permissions on existing directory /var/lib/postgresql/12/main ... ok 76s creating subdirectories ... ok 76s selecting dynamic shared memory implementation ... posix 76s selecting default max_connections ... 100 76s selecting default shared_buffers ... 128MB 76s selecting default time zone ... Etc/UTC 76s creating configuration files ... ok 76s running bootstrap script ... ok 76s performing post-bootstrap initialization ... ok 76s syncing data to disk ... ok 76s 76s Success. You can now start the database server using: 76s 76s pg_ctlcluster 12 main start 76s 77s Ver Cluster Port Status Owner Data directory Log file 77s 12 main 5432 down postgres /var/lib/postgresql/12/main /var/log/postgresql/postgresql-12-main.log 77s update-alternatives: using /usr/share/postgresql/12/man/man1/postmaster.1.gz to provide /usr/share/man/man1/postmaster.1.gz (postmaster.1.gz) in auto mode 79s Setting up postgresql-server-dev-12 (12.22-0ubuntu0.20.04.3) ... 79s Setting up postgresql-12-cron (1.2.0-1) ... 79s Setting up postgresql-server-dev-all (214ubuntu0.1) ... 79s Processing triggers for systemd (245.4-4ubuntu3.24) ... 79s Processing triggers for man-db (2.9.1-1) ... 80s Processing triggers for libc-bin (2.31-0ubuntu9.17) ... 81s autopkgtest [14:55:47]: test cron: [----------------------- 81s + id -un 81s + [ ubuntu = root ] 81s + export PGPASSFILE=/home/ubuntu/.pgpass 81s + trap sed -i -e '/# added by pg_cron testsuite/,$ d' /home/ubuntu/.pgpass 0 2 3 15 81s + pg_buildext supported-versions 81s + pg_virtualenv -v 12 -o shared_preload_libraries=pg_cron 81s Creating new PostgreSQL cluster 12/regress ... 81s /usr/lib/postgresql/12/bin/initdb -D /tmp/pg_virtualenv.5ynkkR/data/12/regress --auth-local peer --auth-host md5 --username=ubuntu --pwfile=/tmp/pg_virtualenv.5ynkkR/postgresql-common/pwfile --nosync 81s The files belonging to this database system will be owned by user "ubuntu". 81s This user must also own the server process. 81s 81s The database cluster will be initialized with locale "C.UTF-8". 81s The default database encoding has accordingly been set to "UTF8". 81s The default text search configuration will be set to "english". 81s 81s Data page checksums are disabled. 81s 81s fixing permissions on existing directory /tmp/pg_virtualenv.5ynkkR/data/12/regress ... ok 81s creating subdirectories ... ok 81s selecting dynamic shared memory implementation ... posix 81s selecting default max_connections ... 100 81s selecting default shared_buffers ... 128MB 81s selecting default time zone ... Etc/UTC 81s creating configuration files ... ok 81s running bootstrap script ... ok 82s performing post-bootstrap initialization ... ok 82s 82s Sync to disk skipped. 82s The data directory might become corrupt if the operating system crashes. 82s 82s Success. You can now start the database server using: 82s 82s pg_ctlcluster 12 regress start 82s 82s Warning: The parent /var/run/postgresql of the selected 82s stats_temp_directory is not writable for the cluster owner. Not adding this 82s setting in postgresql.conf. 84s Ver Cluster Port Status Owner Data directory Log file 84s 12 regress 5433 online ubuntu /tmp/pg_virtualenv.5ynkkR/data/12/regress /tmp/pg_virtualenv.5ynkkR/log/postgresql-12-regress.log 84s 84s + umask 077 84s + echo '# added by pg_cron testsuite' 84s + echo localhost:5433:postgres:ubuntu:fcc104fef7a1e98078127992d5d82f3d 84s + psql -eX 84s CREATE EXTENSION pg_cron VERSION '1.0'; 84s CREATE EXTENSION 84s ALTER EXTENSION pg_cron UPDATE; 84s ALTER EXTENSION 84s SELECT cron.schedule('* * * * *', 'CREATE TABLE foo()'); 84s schedule 84s ---------- 84s 1 84s (1 row) 84s 84s SELECT * FROM cron.job; 84s jobid | schedule | command | nodename | nodeport | database | username | active 84s -------+-----------+--------------------+-----------+----------+----------+----------+-------- 84s 1 | * * * * * | CREATE TABLE foo() | localhost | 5433 | postgres | ubuntu | t 84s (1 row) 84s 84s SELECT pg_sleep(70); 154s pg_sleep 154s ---------- 154s 154s (1 row) 154s 154s SELECT * FROM foo; 154s *** /tmp/pg_virtualenv.5ynkkR/log/postgresql-12-regress.log (last 100 lines) *** 154s 2025-03-26 14:57:13.520 UTC [4610] LOG: starting PostgreSQL 12.22 (Ubuntu 12.22-0ubuntu0.20.04.3) on s390x-ibm-linux-gnu, compiled by gcc (Ubuntu 9.4.0-1ubuntu1~20.04.3) 9.4.0, 64-bit 154s 2025-03-26 14:57:13.520 UTC [4610] LOG: listening on IPv6 address "::1", port 5433 154s 2025-03-26 14:57:13.520 UTC [4610] LOG: listening on IPv4 address "127.0.0.1", port 5433 154s 2025-03-26 14:57:13.520 UTC [4610] LOG: listening on Unix socket "/tmp/.s.PGSQL.5433" 154s 2025-03-26 14:57:13.524 UTC [4611] LOG: database system was shut down at 2025-03-26 14:57:13 UTC 154s 2025-03-26 14:57:13.525 UTC [4610] LOG: database system is ready to accept connections 154s 2025-03-26 14:57:13.528 UTC [4617] LOG: pg_cron scheduler started 154s 2025-03-26 14:56:48.051 UTC [4615] LOG: stats collector's time 2025-03-26 14:57:13.526217+00 is later than backend local time 2025-03-26 14:56:48.051388+00 154s 2025-03-26 14:57:00.241 UTC [4646] ubuntu@postgres ERROR: relation "foo" does not exist at character 15 154s 2025-03-26 14:57:00.241 UTC [4646] ubuntu@postgres STATEMENT: SELECT * FROM foo; 154s Dropping cluster 12/regress ... 154s ERROR: relation "foo" does not exist 154s LINE 1: SELECT * FROM foo; 154s ^ 154s + sed -i -e /# added by pg_cron testsuite/,$ d /home/ubuntu/.pgpass 154s autopkgtest [14:57:00]: test cron: -----------------------] 155s cron FAIL non-zero exit status 3 155s autopkgtest [14:57:01]: test cron: - - - - - - - - - - results - - - - - - - - - - 155s autopkgtest [14:57:01]: @@@@@@@@@@@@@@@@@@@@ summary 155s cron FAIL non-zero exit status 3 162s nova [W] Using flock in prodstack6-s390x 162s Creating nova instance adt-focal-s390x-pg-cron-20250326-145423-juju-7f2275-prod-proposed-migration-environment-2-87474f58-343e-4d6a-87d2-5f4c80629de6 from image adt/ubuntu-focal-s390x-server-20250326.img (UUID 72d9eccf-f8ef-474d-a90b-ec349cfc529f)... 162s nova [W] Timed out waiting for c08d461e-455c-4901-8d0a-0cff4f160767 to get deleted.