After an evaluation, GNOME has moved from Bugzilla to GitLab. Learn more about GitLab.
No new issues can be reported in GNOME Bugzilla anymore.
To report an issue in a GNOME project, go to GNOME GitLab.
Do not go to GNOME Gitlab for: Bluefish, Doxygen, GnuCash, GStreamer, java-gnome, LDTP, NetworkManager, Tomboy.
Bug 700162 - gvfs-1.16.x: tests fail to run
gvfs-1.16.x: tests fail to run
Status: RESOLVED OBSOLETE
Product: gvfs
Classification: Core
Component: build
1.16.x
Other Linux
: Normal normal
: ---
Assigned To: gvfs-maint
gvfs-maint
Depends on:
Blocks:
 
 
Reported: 2013-05-12 11:39 UTC by Pacho Ramos
Modified: 2018-05-04 10:14 UTC
See Also:
GNOME target: ---
GNOME version: ---


Attachments
build.log (279.23 KB, text/plain)
2013-05-12 11:39 UTC, Pacho Ramos
Details
build.log (363.14 KB, text/plain)
2013-05-13 17:02 UTC, Pacho Ramos
Details
build.log (8.75 KB, text/plain)
2013-05-14 18:52 UTC, Pacho Ramos
Details
build.log (25.19 KB, text/plain)
2013-05-15 09:18 UTC, Pacho Ramos
Details
output (33.39 KB, text/plain)
2013-05-16 18:24 UTC, Pacho Ramos
Details

Description Pacho Ramos 2013-05-12 11:39:03 UTC
Created attachment 243903 [details]
build.log

We get lots of failures as seen in attached build.log
Comment 1 Tomas Bzatek 2013-05-13 09:55:36 UTC
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.
Comment 2 Pacho Ramos 2013-05-13 17:02:43 UTC
Created attachment 244071 [details]
build.log

I get the same failure with more USE flags enabled :(
Comment 3 Tomas Bzatek 2013-05-13 17:16:40 UTC
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?
Comment 4 Pacho Ramos 2013-05-13 17:39:24 UTC
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):
  • File "./gvfs-test", line 1326 in setUpClass
    subprocess.check_call([httpd_cmd, '-d', klass.httpd_sandbox, '-f', 'apache2.conf', '-k', 'start'])
  • File "/usr/lib64/python3.2/subprocess.py", line 489 in check_call
    raise CalledProcessError(retcode, cmd)
ubprocess.CalledProcessError: Command '['apache2', '-d', '/tmp/tmpof50qs', '-f', 'apache2.conf', '-k', 'start']' returned non-zero exit status 1

======================================================================
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
Comment 5 Tomas Bzatek 2013-05-14 15:34:23 UTC
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.
Comment 6 Pacho Ramos 2013-05-14 18:50:48 UTC
(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
Comment 7 Pacho Ramos 2013-05-14 18:52:39 UTC
Created attachment 244230 [details]
build.log

Updated error messages
Comment 8 Tomas Bzatek 2013-05-15 08:43:17 UTC
(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.
Comment 9 Pacho Ramos 2013-05-15 09:18:11 UTC
Created attachment 244288 [details]
build.log

Still fails even verifying apache / twisted are not running :(
Comment 10 Tomas Bzatek 2013-05-16 11:39:25 UTC
(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.
Comment 11 Pacho Ramos 2013-05-16 18:24:05 UTC
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)
Comment 12 Ondrej Holy 2018-05-04 10:14:48 UTC
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.