0s autopkgtest [09:48:11]: starting date and time: 2024-11-13 09:48:11+0000 0s autopkgtest [09:48:11]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [09:48:11]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.9seilqox/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults,src:python3-stdlib-extensions --apt-upgrade pagekite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.12.7-1 python3-stdlib-extensions/3.12.7-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-30.secgroup --name adt-plucky-arm64-pagekite-20241113-094810-juju-7f2275-prod-proposed-migration-environment-2-a35b66e7-1fc1-44cc-9977-29ebbef92ca3 --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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,keyserver.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/ 72s autopkgtest [09:49:23]: testbed dpkg architecture: arm64 72s autopkgtest [09:49:23]: testbed apt version: 2.9.8 72s autopkgtest [09:49:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 73s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 74s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 74s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 74s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 74s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 74s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [104 kB] 74s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 74s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [601 kB] 74s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [17.1 kB] 74s Fetched 1794 kB in 1s (2102 kB/s) 74s Reading package lists... 77s Reading package lists... 77s Building dependency tree... 77s Reading state information... 78s Calculating upgrade... 78s The following NEW packages will be installed: 78s python3.13-gdbm 78s The following packages will be upgraded: 78s libpython3-stdlib python3 python3-gdbm python3-minimal 79s 4 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 79s Need to get 101 kB of archives. 79s After this operation, 141 kB of additional disk space will be used. 79s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-minimal arm64 3.12.7-1 [27.4 kB] 79s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3 arm64 3.12.7-1 [24.0 kB] 79s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpython3-stdlib arm64 3.12.7-1 [10.0 kB] 79s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 python3.13-gdbm arm64 3.13.0-2 [30.7 kB] 79s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-gdbm arm64 3.12.7-1 [8642 B] 79s Fetched 101 kB in 0s (286 kB/s) 79s (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 ... 79924 files and directories currently installed.) 79s Preparing to unpack .../python3-minimal_3.12.7-1_arm64.deb ... 79s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 79s Setting up python3-minimal (3.12.7-1) ... 80s (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 ... 79924 files and directories currently installed.) 80s Preparing to unpack .../python3_3.12.7-1_arm64.deb ... 80s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 80s Preparing to unpack .../libpython3-stdlib_3.12.7-1_arm64.deb ... 80s Unpacking libpython3-stdlib:arm64 (3.12.7-1) over (3.12.6-0ubuntu1) ... 80s Selecting previously unselected package python3.13-gdbm. 80s Preparing to unpack .../python3.13-gdbm_3.13.0-2_arm64.deb ... 80s Unpacking python3.13-gdbm (3.13.0-2) ... 80s Preparing to unpack .../python3-gdbm_3.12.7-1_arm64.deb ... 80s Unpacking python3-gdbm:arm64 (3.12.7-1) over (3.12.6-1ubuntu1) ... 80s Setting up python3.13-gdbm (3.13.0-2) ... 80s Setting up libpython3-stdlib:arm64 (3.12.7-1) ... 80s Setting up python3 (3.12.7-1) ... 80s Setting up python3-gdbm:arm64 (3.12.7-1) ... 80s Processing triggers for man-db (2.12.1-3) ... 81s Reading package lists... 82s Building dependency tree... 82s Reading state information... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 83s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 83s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 83s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 85s Reading package lists... 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 86s Calculating upgrade... 86s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 87s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s autopkgtest [09:49:41]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 90s autopkgtest [09:49:41]: @@@@@@@@@@@@@@@@@@@@ apt-source pagekite 92s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pagekite 1.5.2.201011-2.1ubuntu1 (dsc) [2490 B] 92s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pagekite 1.5.2.201011-2.1ubuntu1 (tar) [690 kB] 92s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pagekite 1.5.2.201011-2.1ubuntu1 (diff) [19.1 kB] 92s gpgv: Signature made Mon Sep 2 22:22:14 2024 UTC 92s gpgv: using RSA key C6D8FC68EE91033CB8BD913A3D633E44A057F8D5 92s gpgv: issuer "dave.jones@canonical.com" 92s gpgv: Can't check signature: No public key 92s dpkg-source: warning: cannot verify inline signature for ./pagekite_1.5.2.201011-2.1ubuntu1.dsc: no acceptable signature found 92s autopkgtest [09:49:43]: testing package pagekite version 1.5.2.201011-2.1ubuntu1 93s autopkgtest [09:49:44]: build not needed 93s autopkgtest [09:49:44]: test test-client-running: preparing testbed 94s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Starting pkgProblemResolver with broken count: 0 95s Starting 2 pkgProblemResolver with broken count: 0 95s Done 96s The following additional packages will be installed: 96s daemon pagekite python3-six python3-socksipychain 96s The following NEW packages will be installed: 96s autopkgtest-satdep daemon pagekite python3-six python3-socksipychain 96s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 96s Need to get 247 kB/248 kB of archives. 96s After this operation, 939 kB of additional disk space will be used. 96s Get:1 /tmp/autopkgtest.EjwyCN/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [712 B] 96s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-six all 1.16.0-7 [13.1 kB] 96s Get:3 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-socksipychain all 2.1.2-2 [19.3 kB] 96s Get:4 http://ftpmaster.internal/ubuntu plucky/universe arm64 daemon arm64 0.8.4-1 [63.0 kB] 97s Get:5 http://ftpmaster.internal/ubuntu plucky/universe arm64 pagekite all 1.5.2.201011-2.1ubuntu1 [151 kB] 97s Fetched 247 kB in 0s (577 kB/s) 97s Selecting previously unselected package python3-six. 97s (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 ... 79931 files and directories currently installed.) 97s Preparing to unpack .../python3-six_1.16.0-7_all.deb ... 97s Unpacking python3-six (1.16.0-7) ... 97s Selecting previously unselected package python3-socksipychain. 97s Preparing to unpack .../python3-socksipychain_2.1.2-2_all.deb ... 97s Unpacking python3-socksipychain (2.1.2-2) ... 97s Selecting previously unselected package daemon. 97s Preparing to unpack .../daemon_0.8.4-1_arm64.deb ... 97s Unpacking daemon (0.8.4-1) ... 97s Selecting previously unselected package pagekite. 97s Preparing to unpack .../pagekite_1.5.2.201011-2.1ubuntu1_all.deb ... 97s Unpacking pagekite (1.5.2.201011-2.1ubuntu1) ... 97s Selecting previously unselected package autopkgtest-satdep. 97s Preparing to unpack .../1-autopkgtest-satdep.deb ... 97s Unpacking autopkgtest-satdep (0) ... 97s Setting up python3-six (1.16.0-7) ... 98s Setting up daemon (0.8.4-1) ... 98s Setting up python3-socksipychain (2.1.2-2) ... 98s Setting up pagekite (1.5.2.201011-2.1ubuntu1) ... 98s /usr/lib/python3/dist-packages/pagekite/common.py:153: SyntaxWarning: invalid escape sequence '\.' 98s SERVICE_DOMAIN_RE = re.compile('\.(' + '|'.join(SERVICE_DOMAINS) + ')$') 98s /usr/lib/python3/dist-packages/pagekite/pk.py:1242: SyntaxWarning: invalid escape sequence '\.' 98s 'localhost': '((::ffff:)?127\..*|::1)', 98s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:163: SyntaxWarning: invalid escape sequence '\d' 98s HTTP_HEADER = re.compile('(?ism)^(([A-Z]+) ([^\n]+) HTTP/\d+\.\d+\s*)$') 98s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:216: SyntaxWarning: invalid escape sequence '\.' 98s '(?:wp-admin/(?!admin-ajax|css/)|wp-config\.php' 98s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:218: SyntaxWarning: invalid escape sequence '\.' 98s '|system32/|\.\.|\.ht(?:access|pass)' 98s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:222: SyntaxWarning: invalid escape sequence '\.' 98s '|(?:adm[^\n]*|install[^\n]*|setup)\.php)' 98s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:224: SyntaxWarning: invalid escape sequence '\d' 98s ' HTTP/\d+\.\d+\s*)$') 98s /usr/lib/python3/dist-packages/pagekite/proto/selectables.py:656: SyntaxWarning: invalid escape sequence '\s' 98s XMPP_REGEXP = re.compile("<[^>]+\sto=([^\s>]+)[^>]*>") 98s /usr/lib/python3/dist-packages/pagekite/ui/basic.py:57: SyntaxWarning: invalid escape sequence '\.' 98s '(?:\.[a-z0-9!#$%&\'*+/=?^_`{|}~-]+)*@' 98s /usr/lib/python3/dist-packages/pagekite/ui/basic.py:58: SyntaxWarning: invalid escape sequence '\.' 98s '(?:[a-z0-9](?:[a-z0-9-]*[a-z0-9])?\.)*' 98s /usr/lib/python3/dist-packages/pagekite/ui/remote.py:46: SyntaxWarning: invalid escape sequence '\.' 98s '(?:\.[a-z0-9!#$%&\'*+/=?^_`{|}~-]+)*@' 98s /usr/lib/python3/dist-packages/pagekite/ui/remote.py:47: SyntaxWarning: invalid escape sequence '\.' 98s '(?:[a-z0-9](?:[a-z0-9-]*[a-z0-9])?\.)*' 99s Created symlink '/etc/systemd/system/multi-user.target.wants/pagekite.service' → '/usr/lib/systemd/system/pagekite.service'. 99s Setting up autopkgtest-satdep (0) ... 99s Processing triggers for man-db (2.12.1-3) ... 103s (Reading database ... 80015 files and directories currently installed.) 103s Removing autopkgtest-satdep (0) ... 103s autopkgtest [09:49:54]: test test-client-running: [----------------------- 104s ● pagekite.service - PageKite 104s Loaded: loaded (/usr/lib/systemd/system/pagekite.service; enabled; preset: enabled) 104s Active: active (running) since Wed 2024-11-13 09:49:55 UTC; 11ms ago 104s Invocation: a71baff7aa2b4cdaac9340e2a4dc4ca1 104s Docs: man:pagekite 104s file:///usr/share/doc/pagekite/README.md.gz 104s https://pagekite.net/ 104s Main PID: 2319 ((pagekite)) 104s Tasks: 1 (limit: 4560) 104s Memory: 1.2M (peak: 1.3M) 104s CPU: 4ms 104s CGroup: /system.slice/pagekite.service 104s └─2319 "(pagekite)" 104s 104s Nov 13 09:49:55 autopkgtest systemd[1]: Started pagekite.service - PageKite. 104s success: service running 104s autopkgtest [09:49:55]: test test-client-running: -----------------------] 105s autopkgtest [09:49:56]: test test-client-running: - - - - - - - - - - results - - - - - - - - - - 105s test-client-running PASS 105s autopkgtest [09:49:56]: test test-tunnel: preparing testbed 107s Reading package lists... 107s Building dependency tree... 107s Reading state information... 108s Starting pkgProblemResolver with broken count: 0 108s Starting 2 pkgProblemResolver with broken count: 0 108s Done 108s The following NEW packages will be installed: 108s autopkgtest-satdep 108s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 108s Need to get 0 B/716 B of archives. 108s After this operation, 0 B of additional disk space will be used. 108s Get:1 /tmp/autopkgtest.EjwyCN/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [716 B] 109s Selecting previously unselected package autopkgtest-satdep. 109s (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 ... 80015 files and directories currently installed.) 109s Preparing to unpack .../2-autopkgtest-satdep.deb ... 109s Unpacking autopkgtest-satdep (0) ... 109s Setting up autopkgtest-satdep (0) ... 112s (Reading database ... 80015 files and directories currently installed.) 112s Removing autopkgtest-satdep (0) ... 113s autopkgtest [09:50:04]: test test-tunnel: [----------------------- 113s starting frontend 113s ts=6734764c; t=2024-11-13T09:50:04; ll=0; started=/usr/bin/pagekite; version=1.5.2.201011; platform=linux; python=3.12.7 (main, Nov 8 2024, 17:55:36) [GCC 14.2.0]; argv=--isfrontend --ports=8080 --protos=http --domain=http:autopkgtest:foo; ca_certs=/etc/ssl/certs/ca-certificates.crt; send_always_buffers=False; tunnel_socket_blocks=False; optfile_.SELF/defaults.cfg=ok 113s ts=6734764c; t=2024-11-13T09:50:04; ll=1; listen=:8080; id=s1 114s starting backend 114s ts=6734764d; t=2024-11-13T09:50:05; ll=0; started=/usr/bin/pagekite; version=1.5.2.201011; platform=linux; python=3.12.7 (main, Nov 8 2024, 17:55:36) [GCC 14.2.0]; argv=--frontend=autopkgtest:8080 --service_on=http:autopkgtest:localhost:113:foo; ca_certs=/etc/ssl/certs/ca-certificates.crt; send_always_buffers=False; tunnel_socket_blocks=False; optfile_.SELF/defaults.cfg=ok 124s trying to connect to ident server via pagekite 124s ts=67347657; t=2024-11-13T09:50:15; ll=2; port=8080; accept=~.1:59606; id=s1 125s success 125s stopping pagekite processes 130s autopkgtest [09:50:21]: test test-tunnel: -----------------------] 131s test-tunnel PASS 131s autopkgtest [09:50:22]: test test-tunnel: - - - - - - - - - - results - - - - - - - - - - 131s autopkgtest [09:50:22]: @@@@@@@@@@@@@@@@@@@@ summary 131s test-client-running PASS 131s test-tunnel PASS 144s virt: nova [W] Skipping flock in bos03-arm64 144s virt: Creating nova instance adt-plucky-arm64-pagekite-20241113-094810-juju-7f2275-prod-proposed-migration-environment-2-a35b66e7-1fc1-44cc-9977-29ebbef92ca3 from image adt/ubuntu-plucky-arm64-server-20241113.img (UUID 2d7760e6-2439-4200-89d6-5ed33e5c6330)...