VFS - Opening unsynced files directly causes error

Desktop
1

Expected behaviour

With double-clicking on an unsynced file it should be downloaded automatically and open afterwards.

Actual behaviour

Double-clicking on an unsynced file results in an incomplete (?) download. The files is downloaded and marked as synced (green hook on white background). The file size is shown with 0kb. Opening the file results in an error-message.
Same issue is ocurring while copying unsynced files.

Steps to reproduce

  1. Open ownCloud folder
  2. Double-Click on unsynced file
  3. File opens with error-message

Server configuration

Operating system:
Synology Disk Station DS216+II
DSM 6.2.2-24922 Update 4

Web server:
Apache HTTP Server 2.2

Database:
MariaDB 10

PHP version:
5.6

ownCloud version:
10.0.7.2

Client configuration

Client version:
2.6.1 (build 12992)

Operating system:
Windows 10

OS language:
German

Installation path of client:
C:\Program Files (x86)\ownCloud

2

@PeterPan90 could you provide a few lines from the debug log from the time of the incident?

Here you can find more information:
https://doc.owncloud.org/desktop/2.6/troubleshooting.html#log-files

1 Like
3

Unfortunately I am not able to upload attachements. Therefore I pasted the content of the logfile of the client in the following (is there another / better method?).

I tried to open a file named “Testfile.txt” with on line of text in it.

  1. Double-clicked on file

  2. file was downloaded (Windows said “successfully”)

  3. file was stored with 0kb

  4. file was opened with no line of text - it was empty

    05-02 11:18:07:882 [ debug sync.networkjob ] [ OCC::AbstractNetworkJob::slotFinished ]: Network job OCC::GETFileJob finished for “/Testfile.txt”
    05-02 11:18:07:882 [ debug sync.networkjob.get ] [ OCC::GETFileJob::slotReadyRead ]: Actually finished!
    05-02 11:18:08:862 [ info gui.folderwatcher ]: Detected changes in paths: QSet(“C:/Users/Frank/ownCloud/Testfile.txt”)
    05-02 11:18:08:862 [ debug sync.localdiscoverytracker ] [ OCC::LocalDiscoveryTracker::addTouchedPath ]: inserted touched “Testfile.txt”
    05-02 11:18:08:862 [ debug sync.database.sql ] [ OCC::SqlQuery::bindValue ]: SQL bind 1 QVariant(qlonglong, -6894368967105351058)
    05-02 11:18:08:865 [ debug sync.database.sql ] [ OCC::SqlQuery::exec ]: SQL exec “SELECT path, inode, modtime, type, md5, fileid, remotePerm, filesize, ignoredChildrenRemote, contentchecksumtype.name || ‘:’ || contentChecksum FROM metadata LEFT JOIN checksumtype as contentchecksumtype ON metadata.contentChecksumTypeId == contentchecksumtype.id WHERE phash=?1”
    05-02 11:18:08:865 [ info gui.folder ]: Ignoring spurious notification for file “Testfile.txt”
    05-02 11:18:38:241 [ info sync.accessmanager ]: 6 “PROPFIND” “https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/” has X-Request-ID “42f72f49-bf4e-4be5-9d9c-7347cf3d0a9e”
    05-02 11:18:38:241 [ debug sync.cookiejar ] [ OCC::CookieJar::cookiesForUrl ]: QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/”) requests: (QNetworkCookie(“oc_sessionPassphrase=iZe00tYVj8AeMeoRLfLYZ3NdyZWrYADhgw7kKH91eudfq6ubW9jJ8s6jzQhZBBnNDYw%2FJKTxbyhbC%2FOWtm2Ui2J9RUsUw%2Fen%2BTF5d4sIRPP2BTkXFFIofpT8oXcwPUrH; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”), QNetworkCookie(“oc0l7t9dbwu1=vh28b6q4ai085va6cilic53215; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”))
    05-02 11:18:38:241 [ info sync.networkjob ]: OCC::PropfindJob created for “https://[DomainDeletedForPrivacyReasons]/owncloud/” + “/” “OCC::QuotaInfo”
    05-02 11:18:38:822 [ info sync.networkjob.propfind ]: PROPFIND of QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/”) FINISHED WITH STATUS “OK”
    05-02 11:18:38:822 [ debug sync.networkjob ] [ OCC::AbstractNetworkJob::slotFinished ]: Network job OCC::PropfindJob finished for “/”
    05-02 11:18:40:302 [ info gui.activity ]: Start fetching activities for “[DomainDeletedForPrivacyReasons] (Frank Drieslein)”
    05-02 11:18:40:302 [ info sync.accessmanager ]: 2 “” “https://[DomainDeletedForPrivacyReasons]/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json” has X-Request-ID “05da8581-f9a1-455b-984b-4d06f04b76cf”
    05-02 11:18:40:302 [ debug sync.cookiejar ] [ OCC::CookieJar::cookiesForUrl ]: QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json”) requests: (QNetworkCookie(“oc_sessionPassphrase=iZe00tYVj8AeMeoRLfLYZ3NdyZWrYADhgw7kKH91eudfq6ubW9jJ8s6jzQhZBBnNDYw%2FJKTxbyhbC%2FOWtm2Ui2J9RUsUw%2Fen%2BTF5d4sIRPP2BTkXFFIofpT8oXcwPUrH; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”), QNetworkCookie(“oc0l7t9dbwu1=vh28b6q4ai085va6cilic53215; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”))
    05-02 11:18:40:303 [ info sync.networkjob ]: OCC::JsonApiJob created for “https://[DomainDeletedForPrivacyReasons]/owncloud/” + “ocs/v1.php/cloud/activity” “OCC::ActivityListModel”
    05-02 11:18:40:303 [ info sync.accessmanager ]: 2 “” “https://[DomainDeletedForPrivacyReasons]/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json” has X-Request-ID “3fa0d7ce-dabf-44d1-ad92-857e664e3820”
    05-02 11:18:40:303 [ debug sync.cookiejar ] [ OCC::CookieJar::cookiesForUrl ]: QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json”) requests: (QNetworkCookie(“oc_sessionPassphrase=iZe00tYVj8AeMeoRLfLYZ3NdyZWrYADhgw7kKH91eudfq6ubW9jJ8s6jzQhZBBnNDYw%2FJKTxbyhbC%2FOWtm2Ui2J9RUsUw%2Fen%2BTF5d4sIRPP2BTkXFFIofpT8oXcwPUrH; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”), QNetworkCookie(“oc0l7t9dbwu1=vh28b6q4ai085va6cilic53215; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”))
    05-02 11:18:40:303 [ info sync.networkjob ]: OCC::JsonApiJob created for “https://[DomainDeletedForPrivacyReasons]/owncloud/” + “ocs/v2.php/apps/notifications/api/v1/notifications” “OCC::ServerNotificationHandler”
    05-02 11:18:41:016 [ info sync.networkjob.jsonapi ]: JsonApiJob of QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/ocs/v1.php/cloud/activity?page=0&pagesize=100&format=json”) FINISHED WITH STATUS “OK”
    05-02 11:18:41:017 [ debug sync.networkjob ] [ OCC::AbstractNetworkJob::slotFinished ]: Network job OCC::JsonApiJob finished for “ocs/v1.php/cloud/activity”
    05-02 11:18:41:392 [ info sync.networkjob.jsonapi ]: JsonApiJob of QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/ocs/v2.php/apps/notifications/api/v1/notifications?format=json”) FINISHED WITH STATUS “OK”
    05-02 11:18:41:392 [ debug sync.networkjob ] [ OCC::AbstractNetworkJob::slotFinished ]: Network job OCC::JsonApiJob finished for “ocs/v2.php/apps/notifications/api/v1/notifications”
    05-02 11:18:49:780 [ info gui.folder ]: Trying to check “https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/” for changes via ETag check. (time since last sync: 55 s)
    05-02 11:18:49:780 [ debug gui.folder.manager ] [ OCC::FolderMan::slotRunOneEtagJob ]: Scheduling “https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/” to check remote ETag
    05-02 11:18:49:781 [ info sync.accessmanager ]: 6 “PROPFIND” “https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/” has X-Request-ID “19960fdd-96d1-4544-b01b-c0940393daf0”
    05-02 11:18:49:781 [ debug sync.cookiejar ] [ OCC::CookieJar::cookiesForUrl ]: QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/”) requests: (QNetworkCookie(“oc_sessionPassphrase=iZe00tYVj8AeMeoRLfLYZ3NdyZWrYADhgw7kKH91eudfq6ubW9jJ8s6jzQhZBBnNDYw%2FJKTxbyhbC%2FOWtm2Ui2J9RUsUw%2Fen%2BTF5d4sIRPP2BTkXFFIofpT8oXcwPUrH; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”), QNetworkCookie(“oc0l7t9dbwu1=vh28b6q4ai085va6cilic53215; secure; HttpOnly; domain=[DomainDeletedForPrivacyReasons]; path=/owncloud”))
    05-02 11:18:49:781 [ info sync.networkjob ]: OCC::RequestEtagJob created for “https://[DomainDeletedForPrivacyReasons]/owncloud/” + “/” “OCC::Folder”
    05-02 11:18:50:333 [ info sync.networkjob.etag ]: Request Etag of QUrl(“https://[DomainDeletedForPrivacyReasons]/owncloud/remote.php/dav/files/Frank/”) FINISHED WITH STATUS “OK”
    05-02 11:18:50:337 [ debug sync.networkjob ] [ OCC::AbstractNetworkJob::slotFinished ]: Network job OCC::RequestEtagJob finished for “/”
    05-02 11:18:51:406 [ debug gui.account.state ] [ OCC::AccountState::checkConnectivity ]: “[DomainDeletedForPrivacyReasons] (Frank Drieslein)” The last ETag check succeeded within the last 30 secs. No connection check needed!
    05-02 11:19:02:049 [ info gui.socketapi ]: New connection QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Sending SocketAPI message --> “REGISTER_PATH:C:\Users\Frank\ownCloud” to QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Received SocketAPI message <-- “GET_STRINGS:CONTEXT_MENU_TITLE” from QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Sending SocketAPI message --> “GET_STRINGS:BEGIN” to QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Sending SocketAPI message --> “STRING:CONTEXT_MENU_TITLE:ownCloud” to QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Sending SocketAPI message --> “GET_STRINGS:END” to QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Received SocketAPI message <-- “GET_MENU_ITEMS:C:\Users\Frank\AppData\Local\Temp\ownCloud-logdir\20200502_1118_owncloud.log.0” from QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Sending SocketAPI message --> “GET_MENU_ITEMS:BEGIN” to QLocalSocket(0x88011b0)
    05-02 11:19:02:049 [ info gui.socketapi ]: Sending SocketAPI message --> “GET_MENU_ITEMS:END” to QLocalSocket(0x88011b0)
    05-02 11:19:02:100 [ info gui.socketapi ]: Lost connection QLocalSocket(0x88011b0)