Created attachment 751328 [details] pulseaudio-15.0-r1:20211115-164722.log.gz 44/51 thread-test FAIL 4.02s exit status 1 >>> MAKE_CHECK=1 MALLOC_PERTURB_=142 /var/tmp/portage/media-sound/pulseaudio-15.0-r1/work/pulseaudio-15.0-abi_x86_64.amd64/src/tests/ thread-test 45/51 mult-s16-test OK 4.93s 46/51 cpu-volume-test OK 6.37s 47/51 cpu-mix-test OK 8.66s 48/51 thread-mainloop-test OK 10.02s 49/51 cpu-remap-test OK 11.27s 50/51 once-test OK 10.08s 51/51 lock-autospawn-test OK 20.03s Ok: 50 Expected Fail: 0 Fail: 1 Unexpected Pass: 0 Skipped: 0 Timeout: 0 Full log written to /var/tmp/portage/media-sound/pulseaudio-15.0-r1/work/pulseaudio-15.0-abi_x86_64.amd64/meson-logs/testlog.txt Relevant part of testlog.txt says: 44/51 thread-test FAIL 4.02s exit status 1 16:48:53 MAKE_CHECK=1 MALLOC_PERTURB_=142 /var/tmp/portage/media-sound/pulseaudio-15.0-r1/work/pulseaudio-15.0-abi_x86_64.amd64/src/t ests/thread-test ----------------------------------- output ----------------------------------- stdout: Running suite(s): Thread 0%: Checks: 1, Failures: 0, Errors: 1 ../pulseaudio-15.0/src/tests/thread-test.c:108:E:thread:thread_test:0: (after this point) Test timeout expired stderr: loop-init once! ------------------------------------------------------------------------------ # emerge --info =media-sound/pulseaudio-15.0-r1 Portage 3.0.28 (python 3.9.7-final-0, default/linux/amd64/17.1/desktop/systemd, gcc-11.2.0, glibc-2.33-r7, 5.10.76-gentoo-r1 x86_64) ================================================================= System Settings ================================================================= System uname: Linux-5.10.76-gentoo-r1-x86_64-AMD_Phenom-tm-_II_X4_965_Processor-with-glibc2.33 KiB Mem: 7888700 total, 6474332 free KiB Swap: 8388604 total, 8388604 free Timestamp of repository gentoo: Tue, 02 Nov 2021 09:15:01 +0000 Head commit of repository gentoo: 10c58657c904d8ec23c3690180735e888a4b263d sh dash 0.5.11.3-r1 ld GNU gold (Gentoo 2.37_p1 p0 2.37) 1.16 distcc 3.4 x86_64-pc-linux-gnu [enabled] ccache version 4.4.2 [disabled] app-shells/bash: 5.1_p8::gentoo dev-java/java-config: 2.3.1::gentoo dev-lang/perl: 5.34.0-r3::gentoo dev-lang/python: 3.9.7_p1::gentoo dev-lang/rust: 1.53.0::gentoo dev-util/ccache: 4.4.2::gentoo dev-util/cmake: 3.20.5::gentoo sys-apps/baselayout: 2.7::gentoo sys-apps/sandbox: 2.25::gentoo sys-devel/autoconf: 2.13-r1::gentoo, 2.71-r1::gentoo sys-devel/automake: 1.16.4::gentoo sys-devel/binutils: 2.37_p1::gentoo sys-devel/gcc: 11.2.0::gentoo sys-devel/gcc-config: 2.4::gentoo sys-devel/libtool: 2.4.6-r6::gentoo sys-devel/make: 4.3::gentoo sys-kernel/linux-headers: 5.10::gentoo (virtual/os-headers) sys-libs/glibc: 2.33-r7::gentoo Repositories: gentoo location: /var/db/repos/gentoo sync-type: rsync sync-uri: rsync://rsync.europe.gentoo.org/gentoo-portage priority: -1000 sync-rsync-extra-opts: sync-rsync-verify-metamanifest: yes sync-rsync-verify-jobs: 1 sync-rsync-verify-max-age: 24 x-portage location: /usr/local/portage masters: gentoo priority: 0 ACCEPT_KEYWORDS="amd64" ACCEPT_LICENSE="* -@EULA" CBUILD="x86_64-pc-linux-gnu" CFLAGS="-O2 -pipe -frecord-gcc-switches -march=amdfam10 -flto=4" CHOST="x86_64-pc-linux-gnu" CONFIG_PROTECT="/etc /usr/lib64/libreoffice/program/sofficerc /usr/share/config /usr/share/easy-rsa /usr/share/gnupg/qualified.txt" CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/dconf /etc/env.d /etc/fonts/fonts.conf /etc/gconf /etc/gentoo-release /etc/revdep-rebuild /etc/sandbox.d /etc/terminfo" CXXFLAGS="-O2 -pipe -frecord-gcc-switches -march=amdfam10 -flto=4" DISTDIR="/var/cache/distfiles" EMERGE_DEFAULT_OPTS="--keep-going y --with-bdeps y" ENV_UNSET="CARGO_HOME DBUS_SESSION_BUS_ADDRESS DISPLAY GOBIN GOPATH PERL5LIB PERL5OPT PERLPREFIX PERL_CORE PERL_MB_OPT PERL_MM_OPT XAUTHORITY XDG_CACHE_HOME XDG_CONFIG_HOME XDG_DATA_HOME XDG_RUNTIME_DIR" FCFLAGS="-O2 -pipe -frecord-gcc-switches -march=amdfam10 -flto=4" FEATURES="assume-digests binpkg-docompress binpkg-dostrip binpkg-logs cgroup compress-build-logs config-protect-if-modified distcc distlocks ebuild-locks ipc-sandbox merge-sync multilib-strict network-sandbox news parallel-fetch pid-sandbox preserve-libs protect-owned qa-unresolved-soname-deps sandbox sfperms split-elog split-log strict test unknown-features-warn unmerge-logs unmerge-orphans userfetch userpriv usersandbox usersync xattr" FFLAGS="-O2 -pipe -frecord-gcc-switches -march=amdfam10 -flto=4" GENTOO_MIRRORS="http://mirror.leaseweb.com/gentoo/ http://mirror.init7.net/gentoo/ https://mirror.init7.net/gentoo/ http://ftp.uni-hannover.de/gentoo/" LANG="it_IT.UTF-8" LDFLAGS="-Wl,-O1 -Wl,--as-needed -Wl,--hash-style=gnu -Wl,--sort-common -flto=4" LINGUAS="it it_IT" MAKEOPTS="-j12 -l4" PKGDIR="/var/cache/binpkgs" PORTAGE_CONFIGROOT="/" PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --omit-dir-times --compress --force --whole-file --delete --stats --human-readable --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages --exclude=/.git" PORTAGE_TMPDIR="/var/tmp" USE="X a52 aac aalib acl acpi alsa amd64 ao audiofile bash-completion bluetooth branding brotli bzip2 cairo caps cdda cddb cdparanoia cdr cli crypt css cups curl dbus dga djvu dri dts dvd dvdr emboss encode exif expat fbcon ffmpeg fftw flac fontconfig fortran ftp gd gdbm gif gmp gnutls gpm graphviz gtk gui guile iconv icu idn imagemagick imlib inotify introspection ipv6 jbig jemalloc jpeg jpeg2k kde lame lcms libass libglvnd libnotify libsamplerate libtirpc lm-sensors lua lzma lzo mad magic mmap mng mp3 mp4 mpeg mplayer multilib musicbrainz ncurses nls nptl offensive ogg openal opengl openmp pam pango pch pcre pdf png policykit postscript ppds pulseaudio qt5 rdesktop readline samba sctp sdl seccomp sndfile speex spell split-usr sqlite ssl startup-notification svg symlink syslog systemd taglib test theora threads tiff truetype udev udisks unicode upower usb vaapi vala vdpau verify-sig vim-syntax vorbis wavpack wayland webp win32codecs wmf wxwidgets x264 xattr xcb xcomposite xine xinerama xml xpm xscreensaver xv xvid zip zlib zstd" ABI_X86="64" ADA_TARGET="gnat_2019" ALSA_CARDS="hda-intel virmidi" APACHE2_MODULES="authn_core authz_core socache_shmcb unixd actions alias auth_basic authn_alias authn_anon authn_dbm authn_default authn_file authz_dbm authz_default authz_groupfile authz_host authz_owner authz_user autoindex cache cgi cgid dav dav_fs dav_lock deflate dir disk_cache env expires ext_filter file_cache filter headers include info log_config logio mem_cache mime mime_magic negotiation rewrite setenvif speling status unique_id userdir usertrack vhost_alias" CALLIGRA_FEATURES="karbon sheets words" CAMERAS="ptp2" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" CPU_FLAGS_X86="3dnow 3dnowext mmx mmxext popcnt sse sse2 sse3 sse4a" CURL_SSL="gnutls" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock greis isync itrax mtk3301 nmea ntrip navcom oceanserver oldstyle oncore rtcm104v2 rtcm104v3 sirf skytraq superstar2 timing tsip tripmate tnt ublox ubx" GRUB_PLATFORMS="pc" INPUT_DEVICES="libinput" KERNEL="linux" L10N="it en" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LIBREOFFICE_EXTENSIONS="presenter-console presenter-minimizer" LUA_SINGLE_TARGET="lua5-1" LUA_TARGETS="lua5-1" OFFICE_IMPLEMENTATION="libreoffice" PHP_TARGETS="php7-3 php7-4" POSTGRES_TARGETS="postgres12 postgres13" PYTHON_SINGLE_TARGET="python3_9" PYTHON_TARGETS="python3_9" RUBY_TARGETS="ruby26 ruby27" USERLAND="GNU" VIDEO_CARDS="radeon r600" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq proto steal rawnat logmark ipmark dhcpmac delude chaos account" Unset: CC, CPPFLAGS, CTARGET, CXX, INSTALL_MASK, LC_ALL, PORTAGE_BINHOST, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS, RUSTFLAGS # emerge -1pqv =media-sound/pulseaudio-15.0-r1 [ebuild U ] media-sound/pulseaudio-15.0-r1 [13.0-r1] USE="X alsa bluetooth (daemon%*) dbus gdbm glib gtk ipv6 orc ssl systemd test udev webrtc-aec -alsa-plugin -asyncns -doc (-elogind) -equalizer -gstreamer% -jack -lirc -native-headset -ofono-headset (-oss) (-selinux) -sox (-system-wide) -tcpd -zeroconf (-caps%*) (-gconf%) (-libsamplerate%) (-qt5%*) (-realtime%*)" ABI_X86="(64) -32 (-x32)"
If you still can reproduce this, please try again with the following change to thread-test.c - this test is seen to seldom fail on VMs with limited CPU resources: diff --git a/src/tests/thread-test.c b/src/tests/thread-test.c index 0c83e67e0..4fcbfd4d3 100644 --- a/src/tests/thread-test.c +++ b/src/tests/thread-test.c @@ -153,6 +153,7 @@ int main(int argc, char *argv[]) { s = suite_create("Thread"); tc = tcase_create("thread"); tcase_add_test(tc, thread_test); + tcase_set_timeout(tc, 60 * 60); suite_add_tcase(s, tc); sr = srunner_create(s);
(In reply to Paolo Pedroni from comment #0) ... > > 44/51 thread-test > FAIL > 4.02s exit status 1 Hi Paolo, could you please check the environment where this test fails, and if this is a slower/resource-constrained VM the patch helps?
(In reply to Igor V. Kovalenko from comment #2) > (In reply to Paolo Pedroni from comment #0) > ... > > > > 44/51 thread-test > > FAIL > > 4.02s exit status 1 > Hi Paolo, could you please check the environment where this test fails, and > if this is a slower/resource-constrained VM the patch helps? It is not a VM, just an old, slow PC. The patch actually helps: [...] 40/51 cpu-sconv-test OK 1.80s 41/51 mainloop-test-glib OK 0.41s 42/51 mainloop-test OK 2.16s 43/51 volume-test OK 3.60s 44/51 mult-s16-test OK 5.33s 45/51 cpu-volume-test OK 6.60s 46/51 thread-test OK 6.14s 47/51 cpu-mix-test OK 8.93s 48/51 thread-mainloop-test OK 10.02s 49/51 cpu-remap-test OK 11.19s 50/51 once-test OK 10.61s 51/51 lock-autospawn-test OK 20.02s Ok: 51 Expected Fail: 0 Fail: 0 Unexpected Pass: 0 Skipped: 0 Timeout: 0 Full log written to /var/tmp/portage/media-sound/pulseaudio-15.0-r2/work/pulseaudio-15.0-abi_x86_64.amd64/meson-logs/testlog.txt >>> Completed testing media-sound/pulseaudio-15.0-r2
(In reply to Paolo Pedroni from comment #3) > (In reply to Igor V. Kovalenko from comment #2) > > (In reply to Paolo Pedroni from comment #0) > > ... > > > > > > 44/51 thread-test > > > FAIL > > > 4.02s exit status 1 > > Hi Paolo, could you please check the environment where this test fails, and > > if this is a slower/resource-constrained VM the patch helps? > > It is not a VM, just an old, slow PC. The patch actually helps: > [...] Thanks for confirming this! Do you have any runtime experience with pulseaudio on this old, slow PC? This test is not a performance test of any kind, it checks that pulseaudio use of thread conditional variables results in execution progress. I think we can just bump the timeout upstream.
Just in case, upstream PR https://gitlab.freedesktop.org/pulseaudio/pulseaudio/-/merge_requests/696
(In reply to Igor V. Kovalenko from comment #4) > Thanks for confirming this! Do you have any runtime experience with > pulseaudio on this old, slow PC? Not really. It's a media player with Kodi on it. Pulseaudio is installed as a dependency of something, but Kodi is configured to use Pipewire for audio. > This test is not a performance test of any kind, it checks that pulseaudio > use of thread conditional variables results in execution progress. I think > we can just bump the timeout upstream. Thanks.
Upstream change is now merged and should appear in upcoming 16.0 release. Can we close this issue now?
(In reply to Igor V. Kovalenko from comment #7) > Can we close this issue now? Fine for me.