Created attachment 565870 [details] meson-logs/testlog.txt >>> Test phase: app-accessibility/at-spi2-core-2.30.0 * abi_x86_64.amd64: running multilib-minimal_abi_src_test * Scanning for an open DISPLAY to start Xvfb ... * Starting Xvfb on $DISPLAY=1 ... ninja -v -j1 -l0 -C /var/tmp/portage/app-accessibility/at-spi2-core-2.30.0/work/at-spi2-core-2.30.0-abi_x86_64.amd64 test ninja: Entering directory `/var/tmp/portage/app-accessibility/at-spi2-core-2.30.0/work/at-spi2-core-2.30.0-abi_x86_64.amd64' [0/1] /usr/lib64/python-exec/python3.6/meson test --no-rebuild --print-errorlogs 1/2 dbind-test OK 0.02 s 2/2 memory TIMEOUT 30.01 s Ok: 1 Expected Fail: 0 Fail: 1 Unexpected Pass: 0 Skipped: 0 Timeout: 1 The output from the failed tests: 2/2 memory TIMEOUT 30.01 s --- command --- /var/tmp/portage/app-accessibility/at-spi2-core-2.30.0/work/at-spi2-core-2.30.0-abi_x86_64.amd64/test/memory ------- Full log written to /var/tmp/portage/app-accessibility/at-spi2-core-2.30.0/work/at-spi2-core-2.30.0-abi_x86_64.amd64/meson-logs/testlog.txt FAILED: meson-test /usr/lib64/python-exec/python3.6/meson test --no-rebuild --print-errorlogs ninja: build stopped: subcommand failed. * ninja -v -j1 -l0 -C /var/tmp/portage/app-accessibility/at-spi2-core-2.30.0/work/at-spi2-core-2.30.0-abi_x86_64.amd64 test failed * ERROR: app-accessibility/at-spi2-core-2.30.0::gentoo failed (test phase): * Failed to run 'meson_src_test'
Created attachment 565872 [details] build.log # emerge -pqv '=app-accessibility/at-spi2-core-2.30.0::gentoo' [ebuild R ] app-accessibility/at-spi2-core-2.30.0 USE="X introspection test* -gtk-doc" # emerge --info '=app-accessibility/at-spi2-core-2.30.0::gentoo' Portage 2.3.61 (python 3.6.6-final-0, default/linux/amd64/17.0/no-multilib/hardened, gcc-8.2.0, glibc-2.28-r5, 4.20.10 x86_64) ================================================================= System Settings ================================================================= System uname: Linux-4.20.10-x86_64-Intel-R-_Core-TM-_i7-3720QM_CPU_@_2.60GHz-with-gentoo-2.6 KiB Mem: 16385212 total, 10553120 free KiB Swap: 17825788 total, 17825788 free Timestamp of repository gentoo: Tue, 19 Feb 2019 16:00:01 +0000 Head commit of repository gentoo: 7a5969530c37d52d675b5f34dc72c4ff6fbcef6b sh bash 5.0_p2 ld GNU ld (Gentoo 2.31.1 p5) 2.31.1 app-shells/bash: 5.0_p2::gentoo dev-java/java-config: 2.2.0-r4::gentoo dev-lang/perl: 5.28.0::gentoo dev-lang/python: 2.7.15::gentoo, 3.6.6::gentoo dev-util/cmake: 3.13.4::gentoo dev-util/pkgconfig: 0.29.2::gentoo sys-apps/baselayout: 2.6-r1::gentoo sys-apps/openrc: 0.40.3::gentoo sys-apps/sandbox: 2.15::gentoo sys-devel/autoconf: 2.13-r1::gentoo, 2.69-r4::gentoo sys-devel/automake: 1.16.1-r1::gentoo sys-devel/binutils: 2.31.1-r4::gentoo sys-devel/gcc: 7.3.0-r6::gentoo, 8.2.0-r6::gentoo sys-devel/gcc-config: 2.0::gentoo sys-devel/libtool: 2.4.6-r5::gentoo sys-devel/make: 4.2.1-r4::gentoo sys-kernel/linux-headers: 4.20::gentoo (virtual/os-headers) sys-libs/glibc: 2.28-r5::gentoo Repositories: gentoo location: /usr/portage sync-type: rsync sync-uri: rsync://rsync.gentoo.org/gentoo-portage priority: -1000 sync-rsync-extra-opts: sync-rsync-verify-metamanifest: yes sync-rsync-verify-max-age: 24 sync-rsync-verify-jobs: 1 local location: /var/lib/portage-local/local masters: gentoo priority: 0 testing location: /var/lib/portage-local/testing masters: gentoo priority: 1 ACCEPT_KEYWORDS="amd64 ~amd64" ACCEPT_LICENSE="* -@EULA" CBUILD="x86_64-pc-linux-gnu" CFLAGS="-march=native -mtune=native -O2 -pipe" CHOST="x86_64-pc-linux-gnu" CONFIG_PROTECT="/etc /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 /etc/texmf/language.dat.d /etc/texmf/language.def.d /etc/texmf/updmap.d /etc/texmf/web2c" CXXFLAGS="-march=native -mtune=native -O2 -pipe" DISTDIR="/usr/portage/distfiles" EMERGE_DEFAULT_OPTS="--with-bdeps=y" ENV_UNSET="DBUS_SESSION_BUS_ADDRESS DISPLAY GOBIN 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" FEATURES="assume-digests binpkg-docompress binpkg-dostrip binpkg-logs cgroup collision-protect config-protect-if-modified distlocks ebuild-locks fixlafiles ipc-sandbox merge-sync multilib-strict network-sandbox news parallel-fetch pid-sandbox preserve-libs protect-owned sandbox sfperms strict test unknown-features-warn unmerge-logs unmerge-orphans userfetch userpriv usersandbox usersync xattr" FFLAGS="-O2 -pipe" GENTOO_MIRRORS="http://distfiles.gentoo.org" LDFLAGS="-Wl,-O1 -Wl,--as-needed -Wl,--hash-style=gnu" LINGUAS="" MAKEOPTS="-j8" PKGDIR="/usr/portage/packages" 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 acl acpi alsa amd64 avx bzip2 cairo caps cdda cddb cdparanoia crypt cups cxx dbus dri dts dvd fam ffmpeg fftw flac fontconfig gif gimp gmp gnutls gtk hardened iconv icu idn ipv6 jpeg lame lcms libnotify libtirpc lzma mad matroska mmap mmx mmxext mp3 mpeg ncurses networkmanager nptl ogg opengl openmp pam pcre pie png policykit ppds readline sasl sdl seccomp sse sse2 sse3 sse4 sse4_1 sse4_2 ssl ssp ssse3 startup-notification svg test theora threads tiff truetype udev unicode upower vaapi vorbis wayland x264 xattr xcb xinerama xml xtpax xv xvid zlib" ABI_X86="64" ALSA_CARDS="ali5451 als4000 atiixp atiixp-modem bt87x ca0106 cmipci emu10k1x ens1370 ens1371 es1938 es1968 fm801 hda-intel intel8x0 intel8x0m maestro3 trident usb-audio via82xx via82xx-modem ymfpci" 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" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" CPU_FLAGS_X86="aes avx f16c mmx mmxext pclmul popcnt sse sse2 sse3 sse4_1 sse4_2 ssse3" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock 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="evdev synaptics" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LIBREOFFICE_EXTENSIONS="presenter-console presenter-minimizer" NETBEANS_MODULES="apisupport cnd groovy gsf harness ide identity j2ee java mobility nb php profiler soa visualweb webcommon websvccommon xml" OFFICE_IMPLEMENTATION="libreoffice" PHP_TARGETS="php5-6 php7-1" POSTGRES_TARGETS="postgres9_5 postgres10" PYTHON_SINGLE_TARGET="python3_6" PYTHON_TARGETS="python2_7 python3_6" RUBY_TARGETS="ruby24" USERLAND="GNU" VIDEO_CARDS="i965 intel nouveau" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq steal rawnat logmark ipmark dhcpmac delude chaos account" Unset: CC, CPPFLAGS, CTARGET, CXX, INSTALL_MASK, LANG, LC_ALL, PORTAGE_BINHOST, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS ================================================================= Package Settings ================================================================= app-accessibility/at-spi2-core-2.30.0::gentoo was built with the following: USE="X introspection -gtk-doc -test"
The timeout also happens with FEATURES="-ipc-sandbox -network-sandbox -pid-sandbox" set. So, portage's new sandbox features don't seem to be the issue here.
appeared recently at the tinderbox image 17.1-desktop-gnome_libressl-abi32+64-test_20190621-191902
Created attachment 580508 [details] emerge-info.txt
Created attachment 580510 [details] app-accessibility:at-spi2-core-2.30.1:20190622-225100.log
Created attachment 580512 [details] emerge-history.txt
Created attachment 580514 [details] environment
Created attachment 580516 [details] etc.portage.tbz2
Created attachment 580518 [details] temp.tbz2
Same problem with =app-accessibility/at-spi2-core on ia64.
Please try if it's just being slow for these machines. You should be able to do so by locally modifying the virtx meson_src_test to virtx meson_src_test --timeout-multiplier 4 or so.
FYI, I no more hit this issue on ia64 with =app-accessibility/at-spi2-core-2.32.1.
Created attachment 625440 [details] testlog.txt (log of failing test) (In reply to Mart Raudsepp from comment #11) > Please try if it's just being slow for these machines. > > You should be able to do so by locally modifying the > virtx meson_src_test > to > virtx meson_src_test --timeout-multiplier 4 > or so. Hit this again with at-spi2-core-2.36.0, even with timeout-multiplier set: >>> Test phase: app-accessibility/at-spi2-core-2.36.0 * abi_x86_64.amd64: running multilib-minimal_abi_src_test * Scanning for an open DISPLAY to start Xvfb ... * Starting Xvfb on $DISPLAY=1 ... ninja: Entering directory `/var/tmp/portage/app-accessibility/at-spi2-core-2.36.0/work/at-spi2-core-2.36.0-abi_x86_64.amd64' ninja: no work to do. dbus-daemon[85]: [session uid=250 pid=85] Activating service name='org.a11y.Bus' requested by ':1.1' (uid=250 pid=93 comm="/var/tmp/portage/app-accessibility/at-spi2-core-2.") dbus-daemon[85]: [session uid=250 pid=85] Successfully activated service 'org.a11y.Bus' dbus-daemon[112]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=250 pid=93 comm="/var/tmp/portage/app-accessibility/at-spi2-core-2.") dbus-daemon[112]: Successfully activated service 'org.a11y.atspi.Registry' SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry 1/2 dbind-test OK 0.01 s 2/2 memory TIMEOUT 120.01 s Ok: 1 Expected Fail: 0 Fail: 0 Unexpected Pass: 0 Skipped: 0 Timeout: 1 Full log written to /var/tmp/portage/app-accessibility/at-spi2-core-2.36.0/work/at-spi2-core-2.36.0-abi_x86_64.amd64/meson-logs/testlog.txt * ERROR: app-accessibility/at-spi2-core-2.36.0::gentoo failed (test phase): * Failed to run 'dbus-run-session meson test --timeout-multiplier 4 -C /var/tmp/portage/app-accessibility/at-spi2-core-2.36.0/work/at-spi2-core-2.36.0-abi_x86_64.amd64' It's a 6-core Core i5-8400 CPU @ 2.80GH, so I don't think it's slow...
For reference, I do see the issue with =app-accessibility/at-spi2-core-2.32.1 on x86 (single-core VM on a i7-7700K, which should confirm speed isn't the issue here).
Hit on my Pi 4.
The root-cause appears to be a missing test-dependency to app-editors/gedit in the at-spi2-core ebuild. After installing that package manually, the memory test passes for me (on at-spi2-core-2.32.1). The test source code in at-spi2-core-2.32.1/test/memory.c contains the following: int main() { atspi_init (); listener = atspi_event_listener_new (on_event, NULL, NULL); atspi_event_listener_register (listener, "object:children-changed", NULL); child_pid = fork (); if (!child_pid) execlp ("gedit", "gedit", NULL); atspi_event_main (); return 0; } Just please don't ask me why a test would execlp() a random editor..
(In reply to ahudson.news from comment #16) > The root-cause appears to be a missing test-dependency to app-editors/gedit > in the at-spi2-core ebuild. After installing that package manually, the > memory test passes for me (on at-spi2-core-2.32.1). > > > The test source code in at-spi2-core-2.32.1/test/memory.c contains the > following: > > int > main() > { > atspi_init (); > > listener = atspi_event_listener_new (on_event, NULL, NULL); > atspi_event_listener_register (listener, "object:children-changed", NULL); > child_pid = fork (); > if (!child_pid) > execlp ("gedit", "gedit", NULL); > atspi_event_main (); > return 0; > } > > > Just please don't ask me why a test would execlp() a random editor.. Ok. Thanks for the information. As installing gedit is not an option for me (too many gnome dependencies for my taste), I'll keep "test" p.u.mask-ed.
Still happening in 2.36.0.
Seems to be fixed in version 2.38.0: >>> Test phase: app-accessibility/at-spi2-core-2.38.0 * abi_x86_64.amd64: running multilib-minimal_abi_src_test * Scanning for an open DISPLAY to start Xvfb ... * Starting Xvfb on $DISPLAY=1 ... ninja: Entering directory `/var/tmp/portage/app-accessibility/at-spi2-core-2.38.0/work/at-spi2-core-2.38.0-abi_x86_64.amd64' ninja: no work to do. dbus-daemon[92]: [session uid=250 pid=92] Activating service name='org.a11y.Bus' requested by ':1.1' (uid=250 pid=101 comm="/var/tmp/portage/app-accessibility/at-spi2-core-2.") dbus-daemon[92]: [session uid=250 pid=92] Successfully activated service 'org.a11y.Bus' dbus-daemon[128]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=250 pid=101 comm="/var/tmp/portage/app-accessibility/at-spi2-core-2.") dbus-daemon[128]: Successfully activated service 'org.a11y.atspi.Registry' SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry 1/2 dbind-test OK 0.01s 2/2 memory OK 3.10s Ok: 2 Expected Fail: 0 Fail: 0 Unexpected Pass: 0 Skipped: 0 Timeout: 0 Full log written to /var/tmp/portage/app-accessibility/at-spi2-core-2.38.0/work/at-spi2-core-2.38.0-abi_x86_64.amd64/meson-logs/testlog.txt >>> Completed testing app-accessibility/at-spi2-core-2.38.0
tinderbox_musl has reproduced this issue with version 2.42.0 - Updating summary.
lto_tinderbox has reproduced this issue with version 2.44.1 - Updating summary.
dash_tinderbox has reproduced this issue with version 2.46.0 - Updating summary.
(In reply to ahudson.news from comment #16) > The root-cause appears to be a missing test-dependency to app-editors/gedit > in the at-spi2-core ebuild. After installing that package manually, the > memory test passes for me (on at-spi2-core-2.32.1). > > > The test source code in at-spi2-core-2.32.1/test/memory.c contains the > following: > > int > main() > { > atspi_init (); > > listener = atspi_event_listener_new (on_event, NULL, NULL); > atspi_event_listener_register (listener, "object:children-changed", NULL); > child_pid = fork (); > if (!child_pid) > execlp ("gedit", "gedit", NULL); > atspi_event_main (); > return 0; > } > > > Just please don't ask me why a test would execlp() a random editor.. This was changed upstream for 2.38 and onwards, but maybe a different timeout thing is happening now as per "reproduced this issue" reports.
