0s autopkgtest [13:41:54]: starting date and time: 2025-01-04 13:41:54+0000 0s autopkgtest [13:41:54]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [13:41:54]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3trmzmsa/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade fatrace --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.13.1-1~exp2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-7.secgroup --name adt-plucky-amd64-fatrace-20250104-124124-juju-7f2275-prod-proposed-migration-environment-2-61ee865b-6903-4ae4-bf2a-68bfaf1ab302 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -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/ 157s autopkgtest [13:44:31]: testbed dpkg architecture: amd64 158s autopkgtest [13:44:32]: testbed apt version: 2.9.18 158s autopkgtest [13:44:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 158s autopkgtest [13:44:32]: testbed release detected to be: None 159s autopkgtest [13:44:33]: updating testbed package index (apt update) 159s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 159s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 160s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 160s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 160s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [80.3 kB] 160s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.5 kB] 160s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 160s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [703 kB] 160s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [123 kB] 160s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [74.3 kB] 160s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 160s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 160s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [285 kB] 160s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [771 kB] 160s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [5208 B] 160s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [25.7 kB] 160s Fetched 2210 kB in 1s (2562 kB/s) 161s Reading package lists... 161s Reading package lists... 161s Building dependency tree... 161s Reading state information... 162s Calculating upgrade... 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 0 upgraded, 0 newly installed, 0 to remove and 3 not upgraded. 162s autopkgtest [13:44:36]: upgrading testbed (apt dist-upgrade and autopurge) 162s Reading package lists... 162s Building dependency tree... 162s Reading state information... 163s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 163s Starting 2 pkgProblemResolver with broken count: 0 163s Done 163s Entering ResolveByKeep 163s 163s The following packages were automatically installed and are no longer required: 163s python3.12 python3.12-minimal 163s Use 'sudo apt autoremove' to remove them. 163s The following NEW packages will be installed: 163s libpython3.13-minimal libpython3.13-stdlib python3.13 python3.13-minimal 163s The following packages will be upgraded: 163s libpython3-stdlib python3 python3-minimal 163s 3 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 163s Need to get 6118 kB of archives. 163s After this operation, 22.7 MB of additional disk space will be used. 163s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 libpython3.13-minimal amd64 3.13.1-2 [881 kB] 164s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13-minimal amd64 3.13.1-2 [2358 kB] 164s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-minimal amd64 3.13.1-1~exp2 [27.6 kB] 164s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3 amd64 3.13.1-1~exp2 [23.9 kB] 164s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libpython3.13-stdlib amd64 3.13.1-2 [2088 kB] 164s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13 amd64 3.13.1-2 [729 kB] 164s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libpython3-stdlib amd64 3.13.1-1~exp2 [10.2 kB] 164s Fetched 6118 kB in 1s (8151 kB/s) 164s Selecting previously unselected package libpython3.13-minimal:amd64. 165s (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 ... 75804 files and directories currently installed.) 165s Preparing to unpack .../libpython3.13-minimal_3.13.1-2_amd64.deb ... 165s Unpacking libpython3.13-minimal:amd64 (3.13.1-2) ... 165s Selecting previously unselected package python3.13-minimal. 165s Preparing to unpack .../python3.13-minimal_3.13.1-2_amd64.deb ... 165s Unpacking python3.13-minimal (3.13.1-2) ... 165s Setting up libpython3.13-minimal:amd64 (3.13.1-2) ... 165s Setting up python3.13-minimal (3.13.1-2) ... 165s (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 ... 76124 files and directories currently installed.) 165s Preparing to unpack .../python3-minimal_3.13.1-1~exp2_amd64.deb ... 165s Unpacking python3-minimal (3.13.1-1~exp2) over (3.12.8-1) ... 165s Setting up python3-minimal (3.13.1-1~exp2) ... 165s (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 ... 76124 files and directories currently installed.) 165s Preparing to unpack .../python3_3.13.1-1~exp2_amd64.deb ... 165s Unpacking python3 (3.13.1-1~exp2) over (3.12.8-1) ... 166s Selecting previously unselected package libpython3.13-stdlib:amd64. 166s Preparing to unpack .../libpython3.13-stdlib_3.13.1-2_amd64.deb ... 166s Unpacking libpython3.13-stdlib:amd64 (3.13.1-2) ... 166s Selecting previously unselected package python3.13. 166s Preparing to unpack .../python3.13_3.13.1-2_amd64.deb ... 166s Unpacking python3.13 (3.13.1-2) ... 166s Preparing to unpack .../libpython3-stdlib_3.13.1-1~exp2_amd64.deb ... 166s Unpacking libpython3-stdlib:amd64 (3.13.1-1~exp2) over (3.12.8-1) ... 166s Setting up libpython3.13-stdlib:amd64 (3.13.1-2) ... 166s Setting up libpython3-stdlib:amd64 (3.13.1-1~exp2) ... 166s Setting up python3.13 (3.13.1-2) ... 166s Setting up python3 (3.13.1-1~exp2) ... 166s /usr/bin/py3clean:101: DeprecationWarning: glob.glob1 is deprecated and will be removed in Python 3.15. Use glob.glob and pass a directory to its root_dir argument instead. 166s for fn in glob1(directory, "%s.*" % fname): 167s Processing triggers for man-db (2.13.0-1) ... 167s Processing triggers for systemd (257-2ubuntu1) ... 167s Reading package lists... 168s Building dependency tree... 168s Reading state information... 168s Starting pkgProblemResolver with broken count: 0 168s Starting 2 pkgProblemResolver with broken count: 0 168s Done 168s The following packages will be REMOVED: 168s python3.12* python3.12-minimal* 168s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 168s After this operation, 8926 kB disk space will be freed. 168s (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 ... 76541 files and directories currently installed.) 168s Removing python3.12 (3.12.8-3) ... 168s Removing python3.12-minimal (3.12.8-3) ... 168s /usr/bin/py3clean:125: DeprecationWarning: glob.glob1 is deprecated and will be removed in Python 3.15. Use glob.glob and pass a directory to its root_dir argument instead. 168s for fn in glob1(directory, "%s.%s.py[co]" % (fname, magic_tag)): 169s Processing triggers for man-db (2.13.0-1) ... 169s Processing triggers for systemd (257-2ubuntu1) ... 169s (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 ... 76513 files and directories currently installed.) 169s Purging configuration files for python3.12-minimal (3.12.8-3) ... 172s autopkgtest [13:44:46]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 172s autopkgtest [13:44:46]: @@@@@@@@@@@@@@@@@@@@ apt-source fatrace 173s Get:1 http://ftpmaster.internal/ubuntu plucky/universe fatrace 0.17.0-1 (dsc) [1888 B] 173s Get:2 http://ftpmaster.internal/ubuntu plucky/universe fatrace 0.17.0-1 (tar) [25.2 kB] 173s Get:3 http://ftpmaster.internal/ubuntu plucky/universe fatrace 0.17.0-1 (diff) [3660 B] 173s gpgv: Signature made Fri Nov 11 10:12:01 2022 UTC 173s gpgv: using RSA key 3DB46B55EFA59D40E6232148D14EF15DAFE11347 173s gpgv: Can't check signature: No public key 173s dpkg-source: warning: cannot verify inline signature for ./fatrace_0.17.0-1.dsc: no acceptable signature found 173s autopkgtest [13:44:47]: testing package fatrace version 0.17.0-1 173s autopkgtest [13:44:47]: build not needed 174s autopkgtest [13:44:48]: test fatrace: preparing testbed 174s Reading package lists... 174s Building dependency tree... 174s Reading state information... 174s Starting pkgProblemResolver with broken count: 0 174s Starting 2 pkgProblemResolver with broken count: 0 174s Done 175s The following NEW packages will be installed: 175s fatrace 175s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 175s Need to get 13.1 kB of archives. 175s After this operation, 44.0 kB of additional disk space will be used. 175s Get:1 http://ftpmaster.internal/ubuntu plucky/universe amd64 fatrace amd64 0.17.0-1 [13.1 kB] 175s Fetched 13.1 kB in 0s (90.8 kB/s) 175s Selecting previously unselected package fatrace. 175s (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 ... 76513 files and directories currently installed.) 175s Preparing to unpack .../fatrace_0.17.0-1_amd64.deb ... 175s Unpacking fatrace (0.17.0-1) ... 175s Setting up fatrace (0.17.0-1) ... 175s Processing triggers for man-db (2.13.0-1) ... 176s autopkgtest [13:44:50]: test fatrace: [----------------------- 176s starting fatrace... 177s read a file... 177s create a file... 177s waiting for fatrace... 178s checking log... 179s autopkgtest [13:44:53]: test fatrace: -----------------------] 179s fatrace PASS 179s autopkgtest [13:44:53]: test fatrace: - - - - - - - - - - results - - - - - - - - - - 180s autopkgtest [13:44:54]: test fatrace-currentmount: preparing testbed 180s Reading package lists... 180s Building dependency tree... 180s Reading state information... 180s Starting pkgProblemResolver with broken count: 0 180s Starting 2 pkgProblemResolver with broken count: 0 180s Done 181s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 181s autopkgtest [13:44:55]: test fatrace-currentmount: [----------------------- 182s starting fatrace... 183s read a file... 183s create a file... 183s moving a file within the same directory 183s robustness against ELOOP 183s waiting for fatrace... 184s checking log... 184s autopkgtest [13:44:58]: test fatrace-currentmount: -----------------------] 184s fatrace-currentmount PASS 184s autopkgtest [13:44:58]: test fatrace-currentmount: - - - - - - - - - - results - - - - - - - - - - 185s autopkgtest [13:44:59]: test power-usage-report: preparing testbed 185s Reading package lists... 185s Building dependency tree... 185s Reading state information... 185s Starting pkgProblemResolver with broken count: 0 185s Starting 2 pkgProblemResolver with broken count: 0 185s Done 186s The following NEW packages will be installed: 186s powertop 186s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 186s Need to get 180 kB of archives. 186s After this operation, 569 kB of additional disk space will be used. 186s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 powertop amd64 2.15-3build1 [180 kB] 186s Fetched 180 kB in 0s (512 kB/s) 186s Selecting previously unselected package powertop. 186s (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 ... 76519 files and directories currently installed.) 186s Preparing to unpack .../powertop_2.15-3build1_amd64.deb ... 186s Unpacking powertop (2.15-3build1) ... 186s Setting up powertop (2.15-3build1) ... 186s Processing triggers for man-db (2.13.0-1) ... 187s autopkgtest [13:45:01]: test power-usage-report: [----------------------- 188s modprobe cpufreq_stats failed 188s RAPL device for cpu 0 188s RAPL device for cpu 0 188s RAPL device for cpu 1 188s RAPL device for cpu 1 188s Devfreq not enabled 188s glob returned GLOB_ABORTED 188s Preparing to take measurements 189s Taking 1 measurement(s) for a duration of 1 second(s) each. 190s PowerTOP outputting using base filename powertop.csv 190s Cannot load from file /var/cache/powertop/saved_results.powertop 190s Cannot load from file /var/cache/powertop/saved_parameters.powertop 190s File will be loaded after taking minimum number of measurement(s) with battery only 190s Cannot load from file /var/cache/powertop/saved_parameters.powertop 190s File will be loaded after taking minimum number of measurement(s) with battery only 190s /usr/sbin/power-usage-report:84: SyntaxWarning: invalid escape sequence '\d' 190s event_re = re.compile('(\d+.\d+) (\S+)\((\d+)\): ([A-Z]+) (.+)$') 190s Measurement will begin in 5 seconds. Please make sure that the 190s computer is idle, i. e. do not press keys, start or operate programs, and that 190s programs are not busy with active tasks other than the one you want to examine. 195s Starting measurement for 60 seconds... 195s modprobe cpufreq_stats failed 195s Loaded 0 prior measurements 195s RAPL device for cpu 0 195s RAPL device for cpu 0 195s RAPL device for cpu 1 195s RAPL device for cpu 1 195s Devfreq not enabled 195s glob returned GLOB_ABORTED 195s Preparing to take measurements 197s Taking 1 measurement(s) for a duration of 61 second(s) each. 258s PowerTOP outputting using base filename /tmp/powertopgxw9zq52.csv 258s Measurement complete. Generating report... 259s autopkgtest [13:46:13]: test power-usage-report: -----------------------] 259s autopkgtest [13:46:13]: test power-usage-report: - - - - - - - - - - results - - - - - - - - - - 259s power-usage-report PASS 259s autopkgtest [13:46:13]: @@@@@@@@@@@@@@@@@@@@ summary 259s fatrace PASS 259s fatrace-currentmount PASS 259s power-usage-report PASS 276s nova [W] Skipping flock for amd64 276s Creating nova instance adt-plucky-amd64-fatrace-20250104-124124-juju-7f2275-prod-proposed-migration-environment-2-61ee865b-6903-4ae4-bf2a-68bfaf1ab302 from image adt/ubuntu-plucky-amd64-server-20250103.img (UUID 3ae630f3-a3d1-4440-8779-557815008fbb)... 276s nova [W] Timed out waiting for 3331c68f-d517-4735-8d1e-123b0747c77f to get deleted.