GNOME Bugzilla – Bug 700162
gvfs-1.16.x: tests fail to run
Last modified: 2018-05-04 10:14:48 UTC
Created attachment 243903 [details] build.log We get lots of failures as seen in attached build.log
Please check your USE flags, you've got hell of a lot features disabled by configure (look for "gvfs configuration summary"). The testsuite expects full stack available for the moment. Moreover I don't recommend running "make check" during package emerge, it's too fragile given the number of variables it has.
Created attachment 244071 [details] build.log I get the same failure with more USE flags enabled :(
Hmm, interesting. I remember emerge running in some kind of sandbox, perhaps that's the problem? The test suite starts its own dbus daemon and creates artificial environment for it. Does it fail the same way when you extract the tarball, compile it and run the tests?
I get this outside portage: $ make check Making check in common make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/common' make check-am make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/common' make[2]: Nothing to be done for `check-am'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/common' make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/common' Making check in metadata make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/metadata' make check-am make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/metadata' make[2]: Nothing to be done for `check-am'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/metadata' make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/metadata' Making check in client make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/client' make[1]: Nothing to be done for `check'. make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/client' Making check in daemon make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/daemon' make check-recursive make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/daemon' Making check in trashlib make[3]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/daemon/trashlib' make[3]: Nothing to be done for `check'. make[3]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/daemon/trashlib' make[3]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/daemon' make[3]: Nothing to be done for `check-am'. make[3]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/daemon' make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/daemon' make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/daemon' Making check in monitor make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor' Making check in proxy make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/proxy' make check-am make[3]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/proxy' make[3]: Nothing to be done for `check-am'. make[3]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/proxy' make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/proxy' Making check in gdu make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/gdu' make[2]: Nothing to be done for `check'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/gdu' Making check in udisks2 make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/udisks2' make[2]: Nothing to be done for `check'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/udisks2' Making check in gphoto2 make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/gphoto2' make check-am make[3]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/gphoto2' make[3]: Nothing to be done for `check-am'. make[3]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/gphoto2' make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/gphoto2' Making check in afc make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/afc' make[2]: Nothing to be done for `check'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/afc' Making check in mtp make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/mtp' make[2]: Nothing to be done for `check'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/mtp' Making check in goa make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/goa' make[2]: Nothing to be done for `check'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor/goa' make[2]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/monitor' make[2]: Nothing to be done for `check-am'. make[2]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor' make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/monitor' Making check in po make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/po' INTLTOOL_EXTRACT="/usr/bin/intltool-extract" XGETTEXT="/usr/bin/xgettext" srcdir=. /usr/bin/intltool-update --gettext-package gvfs --pot rm -f missing notexist srcdir=. /usr/bin/intltool-update -m if [ -r missing -o -r notexist ]; then \ exit 1; \ fi make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/po' Making check in programs make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/programs' make[1]: Nothing to be done for `check'. make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/programs' Making check in test make[1]: Entering directory `/home/pacho/Descargas/gvfs-1.16.1/test' ./run-in-tree.sh ./gvfs-test test_api (__main__.ArchiveMounter) archive:// with Gio API ... ok test_iso_joliet (__main__.ArchiveMounter) archive:// for Joliet .iso ... ok test_iso_rr (__main__.ArchiveMounter) archive:// for RockRidge .iso ... ok test_tar (__main__.ArchiveMounter) archive:// for tar ... ok test_tar_bz2 (__main__.ArchiveMounter) archive:// for tar.bz2 ... ok test_tar_gz (__main__.ArchiveMounter) archive:// for tar.gz ... ok test_zip (__main__.ArchiveMounter) archive:// for .zip ... ok [Mon May 13 19:37:56 2013] [warn] VirtualHost localhost:4443 overlaps with VirtualHost localhost:4443, the first has precedence, perhaps you need a NameVirtualHost directive (98)Address already in use: make_sock: could not bind to address 127.0.0.1:8088 no listening sockets available, shutting down Unable to open logs ERROR test_cdrom (__main__.Drive) drive mount: cdrom ... skipped 'not running under gvfs-testbed' test_cdrom_api (__main__.Drive) drive mount: cdrom with Gio API ... skipped 'not running under gvfs-testbed' test_media_player (__main__.Drive) drive mount: media player ... skipped 'not running under gvfs-testbed' test_system_partition (__main__.Drive) drive mount: system partition ... skipped 'not running under gvfs-testbed' test_system_partition_api (__main__.Drive) drive mount: system partition with Gio API ... skipped 'not running under gvfs-testbed' test_anonymous_api (__main__.Ftp) ftp:// anonymous (API) ... /usr/share/eselect/modules/python.eselect: line 217: echo: write error: Broken pipe /usr/share/eselect/modules/python.eselect: line 221: echo: write error: Broken pipe FAIL ----- dbus stdout ----- Added new job source 0x16a5040 (GVfsBackendFtp) Queued new job 0x169d1c0 (GVfsJobMount) send_reply, failed: 1 ----- dbus stderr ----- ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused test_anonymous_cli (__main__.Ftp) ftp:// anonymous (CLI) ... Error mounting location: Could not connect to localhost: Connection refused ERROR ----- dbus stdout ----- Added new job source 0xf67040 (GVfsBackendFtp) Queued new job 0xf5f1c0 (GVfsJobMount) send_reply, failed: 1 ----- dbus stderr ----- ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused test_authenticated_api (__main__.Ftp) ftp:// authenticated (API) ... FAIL ----- dbus stdout ----- Added new job source 0x2076000 (GVfsBackendFtp) Queued new job 0x206e1c0 (GVfsJobMount) send_reply, failed: 1 Added new job source 0xd3e000 (GVfsBackendFtp) Queued new job 0xd371c0 (GVfsJobMount) send_reply, failed: 1 Added new job source 0x2343000 (GVfsBackendFtp) Queued new job 0x233b1c0 (GVfsJobMount) send_reply, failed: 1 Added new job source 0x1049000 (GVfsBackendFtp) Queued new job 0x10411c0 (GVfsJobMount) send_reply, failed: 1 ----- dbus stderr ----- ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused test_authenticated_cli (__main__.Ftp) ftp:// authenticated (CLI) ... FAIL ----- dbus stdout ----- Added new job source 0x1ac2040 (GVfsBackendFtp) Queued new job 0x1abb1c0 (GVfsJobMount) send_reply, failed: 1 ----- dbus stderr ----- ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Could not connect to localhost: Connection refused test_gvfs_info_filesystem (__main__.Programs) gvfs-info --filesystem ... ok test_rsa (__main__.Sftp) sftp://localhost with RSA authentication ... skipped 'No $XDG_RUNTIME_DIR available' test_unknown_host (__main__.Sftp) sftp:// with RSA authentication for unknown host ... skipped 'No $XDG_RUNTIME_DIR available' test_anonymous (__main__.Smb) smb:// anonymous ... Error mounting location: Failed to mount Windows share: Connection timed out FAIL ----- dbus stdout ----- ### SMB: g_vfs_backend_smb_init: default workgroup = 'NULL' Added new job source 0xae0000 (GVfsBackendSmb) Queued new job 0xad8250 (GVfsJobMount) INFO: Current debug levels: all: 6 tdb: 6 printdrivers: 6 lanman: 6 smb: 6 rpc_parse: 6 rpc_srv: 6 rpc_cli: 6 passdb: 6 sam: 6 auth: 6 winbind: 6 vfs: 6 idmap: 6 quota: 6 acls: 6 locking: 6 msdfs: 6 dmapi: 6 registry: 6 Using netbios name BELKIN4. Using workgroup WORKGROUP. ### SMB: do_mount - URI = smb://belkin4/public ### SMB: do_mount - try #0 smbc_stat(smb://belkin4/public) ### SMB: auth_callback - anonymous pass ### SMB: auth_callback - out: last_user = 'pacho', last_domain = 'WORKGROUP' SMBC_server: server_n=[belkin4] server=[belkin4] -> server_n=[belkin4] server=[belkin4] Opening cache file at /var/cache/samba/gencache.tdb tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: No such file or directory Attempt to open gencache.tdb has failed. sitename_fetch: No stored sitename for Opening cache file at /var/cache/samba/gencache.tdb tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: No such file or directory Attempt to open gencache.tdb has failed. no entry for belkin4#20 found. resolve_lmhosts: Attempting lmhosts lookup for name belkin4<0x20> resolve_lmhosts: Attempting lmhosts lookup for name belkin4<0x20> getlmhostsent: lmhost entry: 127.0.0.1 localhost resolve_wins: Attempting wins lookup for name belkin4<0x20> resolve_wins: WINS server resolution selected and no WINS servers listed. resolve_hosts: Attempting host lookup for name belkin4<0x20> namecache_store: storing 1 address for belkin4#20: 127.0.0.1 Opening cache file at /var/cache/samba/gencache.tdb tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: No such file or directory Attempt to open gencache.tdb has failed. Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 0 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 662950 SO_RCVBUF = 262126 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 session request ok Substituting charset 'ANSI_X3.4-1968' for LOCALE ### SMB: do_mount - [smb://belkin4/public; 0] res = -1, cancelled = 0, errno = [110] 'Connection timed out' ### SMB: do_mount - (errno != EPERM && errno != EACCES), cancelled = 0, breaking send_reply, failed: 1 ----- dbus stderr ----- sh: nc: command not found ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Failed to mount Windows share: Connection timed out ----- smbd log ----- Unable to setup corepath for smbd: Permission denied smbd version 3.6.14 started. Copyright Andrew Tridgell and the Samba Team 1992-2011 Registered MSG_REQ_POOL_USAGE Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384) Processing section "[public]" Processing section "[private]" added interface lo ip=127.0.0.1 bcast=127.255.255.255 netmask=255.0.0.0 interpret_interface: using netmask value 8 from config file on interface lo tdb(/tmp/tmpjadnx7/samba/registry.tdb): tdb_open_ex: could not open file /tmp/tmpjadnx7/samba/registry.tdb: No such file or directory registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION\PRINT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION\PRINT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\PRINT\ENVIRONMENTS\WINDOWS NT X86\PRINT PROCESSORS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\PRINT\ENVIRONMENTS\WINDOWS NT X86' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\PRINT\ENVIRONMENTS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\LANMANSERVER' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\SAMBA' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE\MICROSOFT\WINDOWS\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE\MICROSOFT\WINDOWS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE\MICROSOFT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION\WINLOGON' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\TERMINAL SERVER' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\TCPIP' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\NETLOGON' from scan_parent_subkeys this should not happen (too frequently)... tdb(/tmp/tmpjadnx7/samba/account_policy.tdb): tdb_open_ex: could not open file /tmp/tmpjadnx7/samba/account_policy.tdb: No such file or directory account_policy_get: tdb_fetch_uint32 failed for type 1 (min password length), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 2 (password history), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 3 (user must logon to change password), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 4 (maximum password age), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 5 (minimum password age), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 6 (lockout duration), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 7 (reset count minutes), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 8 (bad lockout attempt), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 9 (disconnect time), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 10 (refuse machine password change), returning 0 file_init: Information only: requested 16384 open files, 4076 are available. registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\SPOOLER' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\REMOTEREGISTRY' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\WINS' from scan_parent_subkeys this should not happen (too frequently)... waiting for connections ------ test_authenticated (__main__.Smb) smb:// authenticated ... FAIL ----- dbus stdout ----- ### SMB: g_vfs_backend_smb_init: default workgroup = 'NULL' Added new job source 0x1e27040 (GVfsBackendSmb) Queued new job 0x1e20250 (GVfsJobMount) INFO: Current debug levels: all: 6 tdb: 6 printdrivers: 6 lanman: 6 smb: 6 rpc_parse: 6 rpc_srv: 6 rpc_cli: 6 passdb: 6 sam: 6 auth: 6 winbind: 6 vfs: 6 idmap: 6 quota: 6 acls: 6 locking: 6 msdfs: 6 dmapi: 6 registry: 6 Using netbios name BELKIN4. Using workgroup WORKGROUP. ### SMB: do_mount - URI = smb://belkin4/private ### SMB: do_mount - try #0 smbc_stat(smb://belkin4/private) ### SMB: auth_callback - normal pass ### SMB: auth_callback - asking for password... ### SMB: auth_callback - out: last_user = 'pacho', last_domain = 'WORKGROUP' SMBC_server: server_n=[belkin4] server=[belkin4] -> server_n=[belkin4] server=[belkin4] Opening cache file at /var/cache/samba/gencache.tdb tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: No such file or directory Attempt to open gencache.tdb has failed. sitename_fetch: No stored sitename for Opening cache file at /var/cache/samba/gencache.tdb tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: No such file or directory Attempt to open gencache.tdb has failed. no entry for belkin4#20 found. resolve_lmhosts: Attempting lmhosts lookup for name belkin4<0x20> resolve_lmhosts: Attempting lmhosts lookup for name belkin4<0x20> getlmhostsent: lmhost entry: 127.0.0.1 localhost resolve_wins: Attempting wins lookup for name belkin4<0x20> resolve_wins: WINS server resolution selected and no WINS servers listed. resolve_hosts: Attempting host lookup for name belkin4<0x20> namecache_store: storing 1 address for belkin4#20: 127.0.0.1 Opening cache file at /var/cache/samba/gencache.tdb tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: No such file or directory Attempt to open gencache.tdb has failed. Socket options: SO_KEEPALIVE = 0 SO_REUSEADDR = 0 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_SNDBUF = 662950 SO_RCVBUF = 262126 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 session request ok Substituting charset 'ANSI_X3.4-1968' for LOCALE ### SMB: do_mount - [smb://belkin4/private; 0] res = -1, cancelled = 0, errno = [110] 'Connection timed out' ### SMB: do_mount - (errno != EPERM && errno != EACCES), cancelled = 0, breaking send_reply, failed: 1 ----- dbus stderr ----- sh: nc: command not found ** (gvfsd:21294): WARNING **: dbus_mount_reply: Error from org.gtk.vfs.Mountable.mount(): Failed to mount Windows share: Connection timed out ----- smbd log ----- Unable to setup corepath for smbd: Permission denied smbd version 3.6.14 started. Copyright Andrew Tridgell and the Samba Team 1992-2011 Registered MSG_REQ_POOL_USAGE Registered MSG_REQ_DMALLOC_MARK and LOG_CHANGED rlimit_max: increasing rlimit_max (1024) to minimum Windows limit (16384) Processing section "[public]" Processing section "[private]" added interface lo ip=127.0.0.1 bcast=127.255.255.255 netmask=255.0.0.0 interpret_interface: using netmask value 8 from config file on interface lo tdb(/tmp/tmpi6w8om/samba/registry.tdb): tdb_open_ex: could not open file /tmp/tmpi6w8om/samba/registry.tdb: No such file or directory registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION\PRINT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION\PRINT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\PRINT\ENVIRONMENTS\WINDOWS NT X86\PRINT PROCESSORS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\PRINT\ENVIRONMENTS\WINDOWS NT X86' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\PRINT\ENVIRONMENTS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\LANMANSERVER' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\SAMBA' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE\MICROSOFT\WINDOWS\CURRENTVERSION' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE\MICROSOFT\WINDOWS' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKCU\SOFTWARE\MICROSOFT' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SOFTWARE\MICROSOFT\WINDOWS NT\CURRENTVERSION\WINLOGON' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\CONTROL\TERMINAL SERVER' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\TCPIP' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\NETLOGON' from scan_parent_subkeys this should not happen (too frequently)... tdb(/tmp/tmpi6w8om/samba/account_policy.tdb): tdb_open_ex: could not open file /tmp/tmpi6w8om/samba/account_policy.tdb: No such file or directory account_policy_get: tdb_fetch_uint32 failed for type 1 (min password length), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 2 (password history), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 3 (user must logon to change password), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 4 (maximum password age), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 5 (minimum password age), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 6 (lockout duration), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 7 (reset count minutes), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 8 (bad lockout attempt), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 9 (disconnect time), returning 0 account_policy_get: tdb_fetch_uint32 failed for type 10 (refuse machine password change), returning 0 file_init: Information only: requested 16384 open files, 4076 are available. registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\SPOOLER' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\REMOTEREGISTRY' from scan_parent_subkeys this should not happen (too frequently)... registry/reg_backend_db.c:1527 WARNING: recreating the sorted subkeys cache for key 'HKLM\SYSTEM\CURRENTCONTROLSET\SERVICES\WINS' from scan_parent_subkeys this should not happen (too frequently)... waiting for connections ------ test_deletion_with_same_path (__main__.Trash) trash:// deletion of two files with the same path ... ok test_file_in_home_api (__main__.Trash) trash:// deletion, attributes, restoring for a file in $HOME (API) ... ok test_file_in_home_cli (__main__.Trash) trash:// deletion, attributes, restoring for a file in $HOME (CLI) ... ok test_file_in_system (__main__.Trash) trash:// deletion for system location ... ok ====================================================================== ERROR: setUpClass (__main__.Dav) ---------------------------------------------------------------------- Traceback (most recent call last):
+ Trace 231936
subprocess.check_call([httpd_cmd, '-d', klass.httpd_sandbox, '-f', 'apache2.conf', '-k', 'start'])
raise CalledProcessError(retcode, cmd)
====================================================================== ERROR: test_anonymous_cli (__main__.Ftp) ftp:// anonymous (CLI) ---------------------------------------------------------------------- Traceback (most recent call last): File "./gvfs-test", line 552, in test_anonymous_cli subprocess.check_call(['gvfs-mount', uri]) File "/usr/lib64/python3.2/subprocess.py", line 489, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command '['gvfs-mount', 'ftp://anonymous@localhost:2121']' returned non-zero exit status 2 ====================================================================== FAIL: test_anonymous_api (__main__.Ftp) ftp:// anonymous (API) ---------------------------------------------------------------------- Traceback (most recent call last): File "./gvfs-test", line 613, in test_anonymous_api self.assertEqual(self.mount_api(gfile), True) AssertionError: GError('Could not connect to localhost: Connection refused',) != True ====================================================================== FAIL: test_authenticated_api (__main__.Ftp) ftp:// authenticated (API) ---------------------------------------------------------------------- Traceback (most recent call last): File "./gvfs-test", line 639, in test_authenticated_api self.assertEqual(res, True) AssertionError: GError('Could not connect to localhost: Connection refused',) != True ====================================================================== FAIL: test_authenticated_cli (__main__.Ftp) ftp:// authenticated (CLI) ---------------------------------------------------------------------- Traceback (most recent call last): File "./gvfs-test", line 566, in test_authenticated_cli self.wait_for_gvfs_mount_user_prompt(mount) File "./gvfs-test", line 184, in wait_for_gvfs_mount_user_prompt 'timed out waiting for auth prompt') AssertionError: 0 not greater than 0 : timed out waiting for auth prompt ====================================================================== FAIL: test_anonymous (__main__.Smb) smb:// anonymous ---------------------------------------------------------------------- Traceback (most recent call last): File "./gvfs-test", line 767, in test_anonymous self.assertEqual(mount.returncode, 0, 'gvfs-mount %s failed' % uri) AssertionError: 2 != 0 : gvfs-mount smb://belkin4/public failed ====================================================================== FAIL: test_authenticated (__main__.Smb) smb:// authenticated ---------------------------------------------------------------------- Traceback (most recent call last): File "./gvfs-test", line 789, in test_authenticated self.assertEqual(mount.returncode, 0, err) AssertionError: 2 != 0 : b'Error mounting location: Failed to mount Windows share: Connection timed out\n' ---------------------------------------------------------------------- Ran 25 tests in 25.982s FAILED (failures=5, errors=2, skipped=7) make[1]: *** [check] Error 1 make[1]: Leaving directory `/home/pacho/Descargas/gvfs-1.16.1/test' make: *** [check-recursive] Error 1
Please always post long outputs as attachments. (In reply to comment #4) > test_anonymous_api (__main__.Ftp) > ftp:// anonymous (API) ... /usr/share/eselect/modules/python.eselect: line 217: > echo: write error: Broken pipe > /usr/share/eselect/modules/python.eselect: line 221: echo: write error: Broken > pipe > FAIL This looks serious, do you have dev-python/twisted (and perhaps even dev-python/twisted-web) installed? It needs to be of certain version, having 12.3.0 here. The testsuite starts its own ftp server, which clearly failed to start and the "Connection refused" message is a result of that. > sh: nc: command not found This is obvious.
(In reply to comment #5) > Please always post long outputs as attachments. You are true, I didn't considered it being so long until finally posted :S > > (In reply to comment #4) > > test_anonymous_api (__main__.Ftp) > > ftp:// anonymous (API) ... /usr/share/eselect/modules/python.eselect: line 217: > > echo: write error: Broken pipe > > /usr/share/eselect/modules/python.eselect: line 221: echo: write error: Broken > > pipe > > FAIL > > This looks serious, do you have dev-python/twisted (and perhaps even > dev-python/twisted-web) installed? It needs to be of certain version, having > 12.3.0 here. I have 11.0.0 of both, updating to twisted-12.3.0 looks to make this problem go away :O (will probably need to add a DEPEND on it for tests) > > The testsuite starts its own ftp server, which clearly failed to start and the > "Connection refused" message is a result of that. > > > sh: nc: command not found > > This is obvious. OK, then, netcat is also needed
Created attachment 244230 [details] build.log Updated error messages
(In reply to comment #7) > Created an attachment (id=244230) [details] > build.log > > Updated error messages Cool, this looks better. Both errors from apache and twistd seems to be caused by another instance already running on the specified ports. Please check running processes and kill those hanging there. Even though the testsuite tries to tear running daemons down, there may be interruption or python traceback preventing to reach the cleanup point.
Created attachment 244288 [details] build.log Still fails even verifying apache / twisted are not running :(
(In reply to comment #9) > Still fails even verifying apache / twisted are not running :( Not sure about the apache, perhaps something else is running on that port (check netstat). About the rest, since many tests succeeded in your previous runs, I think this is a timing issue. I came across this several times, especially under heavy load the processes were slow to start (twistd is python and sometimes it takes even 30 seconds for python to start) yet the testsuite was expecting them to be up. There are lots of sleeps that should be tweaked or, preferably, converted to a function calls that ensure the started daemon is up and ready. That's just my theory though.
Created attachment 244451 [details] output This is the netstat output, but I don't see anything causing the collision. Regarding workload... I have just reproduced the failure without anything else consuming (and only one job for the two cores)
This is an old bug and many changed since it was reported (the test suite is fragile and is intended for gvfs developers), let's close as obsolete.