0s autopkgtest [16:08:24]: starting date and time: 2026-04-09 16:08:24+0000 0s autopkgtest [16:08:24]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [16:08:24]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.7jcgxcxd/out --timeout-copy=6000 --needs-internet=try --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:openssl --apt-upgrade redis --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=openssl/3.5.5-1ubuntu3 -- lxd -r lxd-armhf-10.145.243.197 lxd-armhf-10.145.243.197:autopkgtest/ubuntu/resolute/armhf 21s autopkgtest [16:08:45]: testbed dpkg architecture: armhf 23s autopkgtest [16:08:47]: testbed apt version: 3.1.16 27s autopkgtest [16:08:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 29s autopkgtest [16:08:53]: testbed release detected to be: None 40s autopkgtest [16:09:04]: updating testbed package index (apt update) 42s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 42s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 42s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 42s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 42s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [15.9 kB] 42s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [48.6 kB] 42s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5232 B] 42s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [462 kB] 42s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [58.2 kB] 42s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [218 kB] 43s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [3112 B] 43s Fetched 936 kB in 1s (844 kB/s) 44s Reading package lists... 50s autopkgtest [16:09:14]: upgrading testbed (apt dist-upgrade and autopurge) 52s Reading package lists... 52s Building dependency tree... 52s Reading state information... 53s Calculating upgrade... 53s The following packages will be upgraded: 53s libssl3t64 openssl openssl-provider-legacy 53s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 53s Need to get 3151 kB of archives. 53s After this operation, 18.4 kB disk space will be freed. 53s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl-provider-legacy armhf 3.5.5-1ubuntu3 [30.7 kB] 53s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libssl3t64 armhf 3.5.5-1ubuntu3 [1903 kB] 54s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf openssl armhf 3.5.5-1ubuntu3 [1217 kB] 55s Fetched 3151 kB in 1s (3665 kB/s) 55s (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 ... 70685 files and directories currently installed.) 55s Preparing to unpack .../openssl-provider-legacy_3.5.5-1ubuntu3_armhf.deb ... 55s Unpacking openssl-provider-legacy (3.5.5-1ubuntu3) over (3.5.5-1ubuntu1) ... 55s Setting up openssl-provider-legacy (3.5.5-1ubuntu3) ... 55s (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 ... 70685 files and directories currently installed.) 55s Preparing to unpack .../libssl3t64_3.5.5-1ubuntu3_armhf.deb ... 55s Unpacking libssl3t64:armhf (3.5.5-1ubuntu3) over (3.5.5-1ubuntu1) ... 55s Setting up libssl3t64:armhf (3.5.5-1ubuntu3) ... 55s (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 ... 70685 files and directories currently installed.) 55s Preparing to unpack .../openssl_3.5.5-1ubuntu3_armhf.deb ... 55s Unpacking openssl (3.5.5-1ubuntu3) over (3.5.5-1ubuntu1) ... 55s Setting up openssl (3.5.5-1ubuntu3) ... 55s Processing triggers for man-db (2.13.1-1build1) ... 56s Processing triggers for libc-bin (2.43-2ubuntu1) ... 58s Reading package lists... 59s Building dependency tree... 59s Reading state information... 59s Solving dependencies... 60s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s autopkgtest [16:09:26]: rebooting testbed after setup commands that affected boot 105s autopkgtest [16:10:09]: testbed running kernel: Linux 6.8.0-101-generic #101~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 11 12:52:14 UTC 131s autopkgtest [16:10:35]: @@@@@@@@@@@@@@@@@@@@ apt-source redis 155s Get:1 http://ftpmaster.internal/ubuntu resolute/universe redis 5:8.0.5-1 (dsc) [2228 B] 155s Get:2 http://ftpmaster.internal/ubuntu resolute/universe redis 5:8.0.5-1 (tar) [3873 kB] 155s Get:3 http://ftpmaster.internal/ubuntu resolute/universe redis 5:8.0.5-1 (diff) [31.1 kB] 155s gpgv: Signature made Mon Nov 3 18:58:40 2025 UTC 155s gpgv: using RSA key C2FE4BD271C139B86C533E461E953E27D4311E58 155s gpgv: Can't check signature: No public key 155s dpkg-source: warning: cannot verify inline signature for ./redis_8.0.5-1.dsc: no acceptable signature found 155s autopkgtest [16:10:59]: testing package redis version 5:8.0.5-1 158s autopkgtest [16:11:02]: build not needed 161s autopkgtest [16:11:05]: test 0001-redis-cli: preparing testbed 163s Reading package lists... 163s Building dependency tree... 163s Reading state information... 163s Solving dependencies... 164s The following NEW packages will be installed: 164s liblzf1 redis redis-sentinel redis-server redis-tools 164s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 164s Need to get 1317 kB of archives. 164s After this operation, 5455 kB of additional disk space will be used. 164s Get:1 http://ftpmaster.internal/ubuntu resolute/universe armhf liblzf1 armhf 3.6-4build1 [6592 B] 164s Get:2 http://ftpmaster.internal/ubuntu resolute/universe armhf redis-tools armhf 5:8.0.5-1 [1242 kB] 165s Get:3 http://ftpmaster.internal/ubuntu resolute/universe armhf redis-sentinel armhf 5:8.0.5-1 [12.5 kB] 165s Get:4 http://ftpmaster.internal/ubuntu resolute/universe armhf redis-server armhf 5:8.0.5-1 [53.2 kB] 165s Get:5 http://ftpmaster.internal/ubuntu resolute/universe armhf redis all 5:8.0.5-1 [3034 B] 165s Fetched 1317 kB in 1s (1741 kB/s) 165s Selecting previously unselected package liblzf1:armhf. 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 ... 70685 files and directories currently installed.) 165s Preparing to unpack .../liblzf1_3.6-4build1_armhf.deb ... 165s Unpacking liblzf1:armhf (3.6-4build1) ... 166s Selecting previously unselected package redis-tools. 166s Preparing to unpack .../redis-tools_5%3a8.0.5-1_armhf.deb ... 166s Unpacking redis-tools (5:8.0.5-1) ... 166s Selecting previously unselected package redis-sentinel. 166s Preparing to unpack .../redis-sentinel_5%3a8.0.5-1_armhf.deb ... 166s Unpacking redis-sentinel (5:8.0.5-1) ... 166s Selecting previously unselected package redis-server. 166s Preparing to unpack .../redis-server_5%3a8.0.5-1_armhf.deb ... 166s Unpacking redis-server (5:8.0.5-1) ... 166s Selecting previously unselected package redis. 166s Preparing to unpack .../redis_5%3a8.0.5-1_all.deb ... 166s Unpacking redis (5:8.0.5-1) ... 166s Setting up liblzf1:armhf (3.6-4build1) ... 166s Setting up redis-tools (5:8.0.5-1) ... 166s Setting up redis-server (5:8.0.5-1) ... 166s Created symlink '/etc/systemd/system/redis.service' → '/usr/lib/systemd/system/redis-server.service'. 166s Created symlink '/etc/systemd/system/multi-user.target.wants/redis-server.service' → '/usr/lib/systemd/system/redis-server.service'. 167s Setting up redis-sentinel (5:8.0.5-1) ... 167s Created symlink '/etc/systemd/system/sentinel.service' → '/usr/lib/systemd/system/redis-sentinel.service'. 167s Created symlink '/etc/systemd/system/multi-user.target.wants/redis-sentinel.service' → '/usr/lib/systemd/system/redis-sentinel.service'. 168s Setting up redis (5:8.0.5-1) ... 168s Processing triggers for man-db (2.13.1-1build1) ... 168s Processing triggers for libc-bin (2.43-2ubuntu1) ... 182s autopkgtest [16:11:26]: test 0001-redis-cli: [----------------------- 189s # Server 189s redis_version:8.0.5 189s redis_git_sha1:00000000 189s redis_git_dirty:0 189s redis_build_id:9729964261b8fc0f 189s redis_mode:standalone 189s os:Linux 6.8.0-101-generic armv7l 189s arch_bits:32 189s monotonic_clock:POSIX clock_gettime 189s multiplexing_api:epoll 189s atomicvar_api:c11-builtin 189s gcc_version:15.2.0 189s process_id:1358 189s process_supervised:systemd 189s run_id:d44fc2b369e10d1c45ed3dceb30304f284e7cea1 189s tcp_port:6379 189s server_time_usec:1775751093904228 189s uptime_in_seconds:5 189s uptime_in_days:0 189s hz:10 189s configured_hz:10 189s lru_clock:14143413 189s executable:/usr/bin/redis-server 189s config_file:/etc/redis/redis.conf 189s io_threads_active:0 189s listener0:name=tcp,bind=127.0.0.1,bind=-::1,port=6379 189s 189s # Clients 189s connected_clients:3 189s cluster_connections:0 189s maxclients:10000 189s client_recent_max_input_buffer:16 189s client_recent_max_output_buffer:0 189s blocked_clients:0 189s tracking_clients:0 189s pubsub_clients:1 189s watching_clients:0 189s clients_in_timeout_table:0 189s total_watched_keys:0 189s total_blocking_keys:0 189s total_blocking_keys_on_nokey:0 189s 189s # Memory 189s used_memory:781360 189s used_memory_human:763.05K 189s used_memory_rss:10616832 189s used_memory_rss_human:10.12M 189s used_memory_peak:781752 189s used_memory_peak_human:763.43K 189s used_memory_peak_perc:99.95% 189s used_memory_overhead:665112 189s used_memory_startup:612472 189s used_memory_dataset:116248 189s used_memory_dataset_perc:68.83% 189s allocator_allocated:5947776 189s allocator_active:14352384 189s allocator_resident:15269888 189s allocator_muzzy:0 189s total_system_memory:3845013504 189s total_system_memory_human:3.58G 189s used_memory_lua:23552 189s used_memory_vm_eval:23552 189s used_memory_lua_human:23.00K 189s used_memory_scripts_eval:0 189s number_of_cached_scripts:0 189s number_of_functions:0 189s number_of_libraries:0 189s used_memory_vm_functions:24576 189s used_memory_vm_total:48128 189s used_memory_vm_total_human:47.00K 189s used_memory_functions:120 189s used_memory_scripts:120 189s used_memory_scripts_human:120B 189s maxmemory:3221225472 189s maxmemory_human:3.00G 189s maxmemory_policy:noeviction 189s allocator_frag_ratio:2.16 189s allocator_frag_bytes:5518720 189s allocator_rss_ratio:1.06 189s allocator_rss_bytes:917504 189s rss_overhead_ratio:0.70 189s rss_overhead_bytes:-4653056 189s mem_fragmentation_ratio:13.93 189s mem_fragmentation_bytes:9854576 189s mem_not_counted_for_evict:0 189s mem_replication_backlog:0 189s mem_total_replication_buffers:0 189s mem_replica_full_sync_buffer:0 189s mem_clients_slaves:0 189s mem_clients_normal:4392 189s mem_cluster_links:0 189s mem_aof_buffer:0 189s mem_allocator:jemalloc-5.3.0 189s mem_overhead_db_hashtable_rehashing:0 189s active_defrag_running:0 189s lazyfree_pending_objects:0 189s lazyfreed_objects:0 189s 189s # Persistence 189s loading:0 189s async_loading:0 189s current_cow_peak:0 189s current_cow_size:0 189s current_cow_size_age:0 189s current_fork_perc:0.00 189s current_save_keys_processed:0 189s current_save_keys_total:0 189s rdb_changes_since_last_save:0 189s rdb_bgsave_in_progress:0 189s rdb_last_save_time:1775751088 189s rdb_last_bgsave_status:ok 189s rdb_last_bgsave_time_sec:-1 189s rdb_current_bgsave_time_sec:-1 189s rdb_saves:0 189s rdb_last_cow_size:0 189s rdb_last_load_keys_expired:0 189s rdb_last_load_keys_loaded:0 189s aof_enabled:0 189s aof_rewrite_in_progress:0 189s aof_rewrite_scheduled:0 189s aof_last_rewrite_time_sec:-1 189s aof_current_rewrite_time_sec:-1 189s aof_last_bgrewrite_status:ok 189s aof_rewrites:0 189s aof_rewrites_consecutive_failures:0 189s aof_last_write_status:ok 189s aof_last_cow_size:0 189s module_fork_in_progress:0 189s module_fork_last_cow_size:0 189s 189s # Threads 189s io_thread_0:clients=3,reads=9,writes=10 189s 189s # Stats 189s total_connections_received:3 189s total_commands_processed:10 189s instantaneous_ops_per_sec:1 189s total_net_input_bytes:511 189s total_net_output_bytes:6133 189s total_net_repl_input_bytes:0 189s total_net_repl_output_bytes:0 189s instantaneous_input_kbps:0.09 189s instantaneous_output_kbps:0.09 189s instantaneous_input_repl_kbps:0.00 189s instantaneous_output_repl_kbps:0.00 189s rejected_connections:0 189s sync_full:0 189s sync_partial_ok:0 189s sync_partial_err:0 189s expired_subkeys:0 189s expired_keys:0 189s expired_stale_perc:0.00 189s expired_time_cap_reached_count:0 189s expire_cycle_cpu_milliseconds:0 189s evicted_keys:0 189s evicted_clients:0 189s evicted_scripts:0 189s total_eviction_exceeded_time:0 189s current_eviction_exceeded_time:0 189s keyspace_hits:0 189s keyspace_misses:0 189s pubsub_channels:1 189s pubsub_patterns:0 189s pubsubshard_channels:0 189s latest_fork_usec:0 189s total_forks:0 189s migrate_cached_sockets:0 189s slave_expires_tracked_keys:0 189s active_defrag_hits:0 189s active_defrag_misses:0 189s active_defrag_key_hits:0 189s active_defrag_key_misses:0 189s total_active_defrag_time:0 189s current_active_defrag_time:0 189s tracking_total_keys:0 189s tracking_total_items:0 189s tracking_total_prefixes:0 189s unexpected_error_replies:0 189s total_error_replies:0 189s dump_payload_sanitizations:0 189s total_reads_processed:9 189s total_writes_processed:10 189s io_threaded_reads_processed:0 189s io_threaded_writes_processed:0 189s client_query_buffer_limit_disconnections:0 189s client_output_buffer_limit_disconnections:0 189s reply_buffer_shrinks:2 189s reply_buffer_expands:1 189s eventloop_cycles:59 189s eventloop_duration_sum:24758 189s eventloop_duration_cmd_sum:198 189s instantaneous_eventloop_cycles_per_sec:10 189s instantaneous_eventloop_duration_usec:466 189s acl_access_denied_auth:0 189s acl_access_denied_cmd:0 189s acl_access_denied_key:0 189s acl_access_denied_channel:0 189s 189s # Replication 189s role:master 189s connected_slaves:0 189s master_failover_state:no-failover 189s master_replid:609d528e5eb7756443d4a91fb1cfcc91faffb2f3 189s master_replid2:0000000000000000000000000000000000000000 189s master_repl_offset:0 189s second_repl_offset:-1 189s repl_backlog_active:0 189s repl_backlog_size:1048576 189s repl_backlog_first_byte_offset:0 189s repl_backlog_histlen:0 189s 189s # CPU 189s used_cpu_sys:0.041974 189s used_cpu_user:0.066501 189s used_cpu_sys_children:0.000770 189s used_cpu_user_children:0.000499 189s used_cpu_sys_main_thread:0.040230 189s used_cpu_user_main_thread:0.067375 189s 189s # Modules 189s module:name=vectorset,ver=1,api=1,filters=0,usedby=[],using=[],options=[handle-io-errors|handle-repl-async-load] 189s 189s # Errorstats 189s 189s # Cluster 189s cluster_enabled:0 189s 189s # Keyspace 189s 189s # Keysizes 189s ALTHOUGH THINGS FLOURISH 189s IT EXPANDS RAPIDLY 189s TILL HE MOVED HIS FINGERS SLOWLY 189s THEY ALL RETURN TO THEIR ROOTS 189s THE HEAD PRESSED UPON THE SHOULDER 189s WHILE THE MULTITUDE OF THINGS COMES INTO BEING 189s THE HAIR BETWEEN THE LIPS 189s THIRTY TIMES BRIGHTER THAN THE SUN 189s TRYING TO GRASP 189s THE SUMMIT OF THE CLOUD 189s 189s In 1961, Nanni Balestrini created one of the first computer-generated poems, TAPE MARK I, using an IBM 7090 mainframe. Each execution combined verses from three literary sources following algorithmic rules based on metrical compatibility and group constraints. This LOLWUT command reproduces Balestrini's original algorithm, generating new stanzas through the same computational poetry process described in Almanacco Letterario Bompiani, 1962. 189s 189s https://en.wikipedia.org/wiki/Digital_poetry 189s https://www.youtube.com/watch?v=8i7uFCK7G0o (English subs) 189s 189s Use: LOLWUT IT for the original Italian output. Redis ver. 8.0.5 189s 190s autopkgtest [16:11:34]: test 0001-redis-cli: -----------------------] 194s autopkgtest [16:11:38]: test 0001-redis-cli: - - - - - - - - - - results - - - - - - - - - - 194s 0001-redis-cli PASS 198s autopkgtest [16:11:42]: test 0002-benchmark: preparing testbed 200s Reading package lists... 201s Building dependency tree... 201s Reading state information... 201s Solving dependencies... 202s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 216s autopkgtest [16:12:00]: test 0002-benchmark: [----------------------- 224s PING_INLINE: rps=0.0 (overall: 0.0) avg_msec=nan (overall: nan) PING_INLINE: rps=324422.3 (overall: 323134.9) avg_msec=1.382 (overall: 1.382) ====== PING_INLINE ====== 224s 100000 requests completed in 0.31 seconds 224s 50 parallel clients 224s 3 bytes payload 224s keep alive: 1 224s host configuration "save": 3600 1 300 100 60 10000 224s host configuration "appendonly": no 224s multi-thread: no 224s 224s Latency by percentile distribution: 224s 0.000% <= 0.639 milliseconds (cumulative count 20) 224s 50.000% <= 1.303 milliseconds (cumulative count 50360) 224s 75.000% <= 1.551 milliseconds (cumulative count 75340) 224s 87.500% <= 1.711 milliseconds (cumulative count 87750) 224s 93.750% <= 1.807 milliseconds (cumulative count 94150) 224s 96.875% <= 1.959 milliseconds (cumulative count 96980) 224s 98.438% <= 2.071 milliseconds (cumulative count 98470) 224s 99.219% <= 2.263 milliseconds (cumulative count 99220) 224s 99.609% <= 2.471 milliseconds (cumulative count 99610) 224s 99.805% <= 2.615 milliseconds (cumulative count 99810) 224s 99.902% <= 2.871 milliseconds (cumulative count 99910) 224s 99.951% <= 3.063 milliseconds (cumulative count 99960) 224s 99.976% <= 3.095 mi