0s autopkgtest [00:19:55]: starting date and time: 2024-05-06 00:19:55+0000 0s autopkgtest [00:19:55]: git checkout: 699e7f9f ssh-setup/nova: explicitely set 'fqdn' in cloud-init 0s autopkgtest [00:19:55]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.i05ig5lu/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pyopenssl --apt-upgrade pagekite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pyopenssl/24.1.0-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@lcy02-40.secgroup --name adt-oracular-amd64-pagekite-20240506-001955-juju-7f2275-prod-proposed-migration-environment-2-5bb8eea7-c90a-4529-95bf-9966520431fd --image adt/ubuntu-oracular-amd64-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/ 1107s autopkgtest [00:38:22]: testbed dpkg architecture: amd64 1108s autopkgtest [00:38:23]: testbed apt version: 2.7.14build2 1108s autopkgtest [00:38:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1108s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [64.7 kB] 1108s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [110 kB] 1108s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [1658 kB] 1108s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [23.5 kB] 1108s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [2800 B] 1108s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main i386 Packages [167 kB] 1108s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 Packages [195 kB] 1108s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 Packages [8572 B] 1108s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted i386 Packages [1260 B] 1108s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe i386 Packages [636 kB] 1108s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 Packages [1295 kB] 1108s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse i386 Packages [16.0 kB] 1108s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 Packages [52.6 kB] 1108s Fetched 4230 kB in 1s (7403 kB/s) 1108s Reading package lists... 1110s Reading package lists... 1110s Building dependency tree... 1110s Reading state information... 1110s Calculating upgrade... 1111s The following packages will be upgraded: 1111s python3-openssl 1111s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1111s Need to get 46.8 kB of archives. 1111s After this operation, 11.3 kB disk space will be freed. 1111s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 python3-openssl all 24.1.0-1 [46.8 kB] 1111s Fetched 46.8 kB in 0s (3104 kB/s) 1111s (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 ... 73894 files and directories currently installed.) 1111s Preparing to unpack .../python3-openssl_24.1.0-1_all.deb ... 1111s Unpacking python3-openssl (24.1.0-1) over (23.2.0-1) ... 1111s Setting up python3-openssl (24.1.0-1) ... 1112s Reading package lists... 1112s Building dependency tree... 1112s Reading state information... 1112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1112s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 1112s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 1112s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 1112s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 1113s Reading package lists... 1114s Reading package lists... 1114s Building dependency tree... 1114s Reading state information... 1114s Calculating upgrade... 1114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1114s Reading package lists... 1114s Building dependency tree... 1114s Reading state information... 1115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1115s autopkgtest [00:38:30]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 00:40:06 UTC 2024 1115s autopkgtest [00:38:30]: @@@@@@@@@@@@@@@@@@@@ apt-source pagekite 1116s Get:1 http://ftpmaster.internal/ubuntu oracular/universe pagekite 1.5.2.201011-2 (dsc) [2338 B] 1116s Get:2 http://ftpmaster.internal/ubuntu oracular/universe pagekite 1.5.2.201011-2 (tar) [690 kB] 1116s Get:3 http://ftpmaster.internal/ubuntu oracular/universe pagekite 1.5.2.201011-2 (diff) [19.0 kB] 1116s gpgv: Signature made Tue Feb 28 05:46:02 2023 UTC 1116s gpgv: using RSA key 46A2DFE28C087B294E6FD92481280AA087BAFB0E 1116s gpgv: Can't check signature: No public key 1116s dpkg-source: warning: cannot verify inline signature for ./pagekite_1.5.2.201011-2.dsc: no acceptable signature found 1116s autopkgtest [00:38:31]: testing package pagekite version 1.5.2.201011-2 1116s autopkgtest [00:38:31]: build not needed 1116s autopkgtest [00:38:31]: test test-client-running: preparing testbed 1117s Reading package lists... 1117s Building dependency tree... 1117s Reading state information... 1117s Starting pkgProblemResolver with broken count: 0 1117s Starting 2 pkgProblemResolver with broken count: 0 1117s Done 1118s The following additional packages will be installed: 1118s daemon pagekite python3-socksipychain 1118s The following NEW packages will be installed: 1118s autopkgtest-satdep daemon pagekite python3-socksipychain 1118s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 1118s Need to get 233 kB/234 kB of archives. 1118s After this operation, 853 kB of additional disk space will be used. 1118s Get:1 /tmp/autopkgtest.gPp3wt/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [716 B] 1118s Get:2 http://ftpmaster.internal/ubuntu oracular/universe amd64 python3-socksipychain all 2.1.2-1 [19.3 kB] 1118s Get:3 http://ftpmaster.internal/ubuntu oracular/universe amd64 daemon amd64 0.8.4-1 [62.7 kB] 1118s Get:4 http://ftpmaster.internal/ubuntu oracular/universe amd64 pagekite all 1.5.2.201011-2 [151 kB] 1118s Fetched 233 kB in 0s (5508 kB/s) 1118s Selecting previously unselected package python3-socksipychain. 1118s (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 ... 73894 files and directories currently installed.) 1118s Preparing to unpack .../python3-socksipychain_2.1.2-1_all.deb ... 1118s Unpacking python3-socksipychain (2.1.2-1) ... 1118s Selecting previously unselected package daemon. 1118s Preparing to unpack .../daemon_0.8.4-1_amd64.deb ... 1118s Unpacking daemon (0.8.4-1) ... 1118s Selecting previously unselected package pagekite. 1118s Preparing to unpack .../pagekite_1.5.2.201011-2_all.deb ... 1118s Unpacking pagekite (1.5.2.201011-2) ... 1118s Selecting previously unselected package autopkgtest-satdep. 1118s Preparing to unpack .../1-autopkgtest-satdep.deb ... 1118s Unpacking autopkgtest-satdep (0) ... 1118s Setting up python3-socksipychain (2.1.2-1) ... 1118s Setting up daemon (0.8.4-1) ... 1118s Setting up pagekite (1.5.2.201011-2) ... 1118s /usr/lib/python3/dist-packages/pagekite/common.py:153: SyntaxWarning: invalid escape sequence '\.' 1118s SERVICE_DOMAIN_RE = re.compile('\.(' + '|'.join(SERVICE_DOMAINS) + ')$') 1118s /usr/lib/python3/dist-packages/pagekite/pk.py:1242: SyntaxWarning: invalid escape sequence '\.' 1118s 'localhost': '((::ffff:)?127\..*|::1)', 1119s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:163: SyntaxWarning: invalid escape sequence '\d' 1119s HTTP_HEADER = re.compile('(?ism)^(([A-Z]+) ([^\n]+) HTTP/\d+\.\d+\s*)$') 1119s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:216: SyntaxWarning: invalid escape sequence '\.' 1119s '(?:wp-admin/(?!admin-ajax|css/)|wp-config\.php' 1119s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:218: SyntaxWarning: invalid escape sequence '\.' 1119s '|system32/|\.\.|\.ht(?:access|pass)' 1119s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:222: SyntaxWarning: invalid escape sequence '\.' 1119s '|(?:adm[^\n]*|install[^\n]*|setup)\.php)' 1119s /usr/lib/python3/dist-packages/pagekite/proto/filters.py:224: SyntaxWarning: invalid escape sequence '\d' 1119s ' HTTP/\d+\.\d+\s*)$') 1119s /usr/lib/python3/dist-packages/pagekite/proto/selectables.py:656: SyntaxWarning: invalid escape sequence '\s' 1119s XMPP_REGEXP = re.compile("<[^>]+\sto=([^\s>]+)[^>]*>") 1119s /usr/lib/python3/dist-packages/pagekite/ui/basic.py:57: SyntaxWarning: invalid escape sequence '\.' 1119s '(?:\.[a-z0-9!#$%&\'*+/=?^_`{|}~-]+)*@' 1119s /usr/lib/python3/dist-packages/pagekite/ui/basic.py:58: SyntaxWarning: invalid escape sequence '\.' 1119s '(?:[a-z0-9](?:[a-z0-9-]*[a-z0-9])?\.)*' 1119s /usr/lib/python3/dist-packages/pagekite/ui/remote.py:46: SyntaxWarning: invalid escape sequence '\.' 1119s '(?:\.[a-z0-9!#$%&\'*+/=?^_`{|}~-]+)*@' 1119s /usr/lib/python3/dist-packages/pagekite/ui/remote.py:47: SyntaxWarning: invalid escape sequence '\.' 1119s '(?:[a-z0-9](?:[a-z0-9-]*[a-z0-9])?\.)*' 1119s Created symlink /etc/systemd/system/multi-user.target.wants/pagekite.service → /usr/lib/systemd/system/pagekite.service. 1119s Setting up autopkgtest-satdep (0) ... 1119s Processing triggers for man-db (2.12.0-4build2) ... 1122s (Reading database ... 73968 files and directories currently installed.) 1122s Removing autopkgtest-satdep (0) ... 1122s autopkgtest [00:38:37]: test test-client-running: [----------------------- 1122s ● pagekite.service - PageKite 1122s Loaded: loaded (/usr/lib/systemd/system/pagekite.service; enabled; preset: enabled) 1122s Active: active (running) since Mon 2024-05-06 00:38:37 UTC; 12ms ago 1122s Docs: man:pagekite 1122s file:///usr/share/doc/pagekite/README.md.gz 1122s https://pagekite.net/ 1122s Main PID: 2280 ((pagekite)) 1122s Tasks: 1 (limit: 4623) 1122s Memory: 356.0K (peak: 356.0K) 1122s CPU: 8ms 1122s CGroup: /system.slice/pagekite.service 1122s └─2280 "(pagekite)" 1122s 1122s May 06 00:38:37 autopkgtest systemd[1]: Started pagekite.service - PageKite. 1122s success: service running 1123s autopkgtest [00:38:38]: test test-client-running: -----------------------] 1123s autopkgtest [00:38:38]: test test-client-running: - - - - - - - - - - results - - - - - - - - - - 1123s test-client-running PASS 1123s autopkgtest [00:38:38]: test test-tunnel: preparing testbed 1124s Reading package lists... 1124s Building dependency tree... 1124s Reading state information... 1124s Starting pkgProblemResolver with broken count: 0 1124s Starting 2 pkgProblemResolver with broken count: 0 1124s Done 1125s The following NEW packages will be installed: 1125s autopkgtest-satdep 1125s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1125s Need to get 0 B/720 B of archives. 1125s After this operation, 0 B of additional disk space will be used. 1125s Get:1 /tmp/autopkgtest.gPp3wt/2-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [720 B] 1125s Selecting previously unselected package autopkgtest-satdep. 1125s (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 ... 73968 files and directories currently installed.) 1125s Preparing to unpack .../2-autopkgtest-satdep.deb ... 1125s Unpacking autopkgtest-satdep (0) ... 1125s Setting up autopkgtest-satdep (0) ... 1127s (Reading database ... 73968 files and directories currently installed.) 1127s Removing autopkgtest-satdep (0) ... 1127s autopkgtest [00:38:42]: test test-tunnel: [----------------------- 1127s starting frontend 1127s ts=66382692; t=2024-05-06T00:38:42; ll=0; started=/usr/bin/pagekite; version=1.5.2.201011; platform=linux; python=3.12.3 (main, Apr 10 2024, 05:33:47) [GCC 13.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 1127s ts=66382692; t=2024-05-06T00:38:42; ll=1; listen=:8080; id=s1 1128s starting backend 1128s ts=66382693; t=2024-05-06T00:38:43; ll=0; started=/usr/bin/pagekite; version=1.5.2.201011; platform=linux; python=3.12.3 (main, Apr 10 2024, 05:33:47) [GCC 13.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 1138s trying to connect to ident server via pagekite 1138s ts=6638269d; t=2024-05-06T00:38:53; ll=2; port=8080; accept=~.1:53764; id=s1 1139s success 1139s stopping pagekite processes 1144s autopkgtest [00:38:59]: test test-tunnel: -----------------------] 1145s autopkgtest [00:39:00]: test test-tunnel: - - - - - - - - - - results - - - - - - - - - - 1145s test-tunnel PASS 1145s autopkgtest [00:39:00]: @@@@@@@@@@@@@@@@@@@@ summary 1145s test-client-running PASS 1145s test-tunnel PASS 1213s Creating nova instance adt-oracular-amd64-pagekite-20240506-001955-juju-7f2275-prod-proposed-migration-environment-2-5bb8eea7-c90a-4529-95bf-9966520431fd from image adt/ubuntu-oracular-amd64-server-20240505.img (UUID b7c640bd-0b66-4d87-8d8c-2bf086621bf4)...