Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

1.3.0-beta - a file deleted from local folder after local rename #640

Closed
moscicki opened this issue May 24, 2013 · 16 comments
Closed

1.3.0-beta - a file deleted from local folder after local rename #640

moscicki opened this issue May 24, 2013 · 16 comments
Milestone

Comments

@moscicki
Copy link
Contributor

Hello,

I setup a brand new test account both locally and on my owncloud server, both called octuser2. I created a file via the web interface, it got synced to a local client. Then I renamed the file locally and after a while it was deleted by mirall. However the file appears OK on the server.

Here is the detailed log:

test client: 1.30-beta compiled on slc6
test server: owncloud-5.0.3-17.1.noarch (note: after upgraded the server to the newest version (5.0.6-3.1) the issue persists)

/tmp/mirall-build/bin/owncloud --logfile ~/mirall.log &

created web1.txt via WEB INTERFACE - got synced to the client

[octuser2 ownCloud]$ sqlite3 -column -header .csync_journal.db "select * from metadata limit 10;"
phash pathlen path inode uid gid mode modtime type md5


2121407186260769061 8 web1.txt 6422578 9406 9406 33188 1369318729 0 519e2549973ae

[octuser2 ownCloud]$ ls -li
total 0
6422578 -rw-r--r--. 1 octuser2 octuser2 0 May 23 16:18 web1.txt

renamed the file:

[octuser2 ownCloud]$ mv web1.txt web1-rename.txt

[octuser2 ownCloud]$ sqlite3 -column -header .csync_journal.db "select * from metadata limit 10;"
phash pathlen path inode uid gid mode modtime type md5


4189687575036615272 15 web1-rename.txt 6422578 9406 9406 33188 1369318729 0 519e2549973ae

PROBLEM #1: change not reflected in the web interface even after page reload

On the server, however:

the file exists with a correct name:

ls -l /cloud_data1/octuser2/files/web1-rename.txt

the database table also seems to have a correct path:

mysql> select * from oc_filecache where path like "%web1%.txt";
+--------+---------+-----------------------+----------------------------------+--------+----------+----------+----------+------+------------+-----------+---------------+
| fileid | storage | path | path_hash | parent | name | mimetype | mimepart | size | mtime | encrypted | etag |
+--------+---------+-----------------------+----------------------------------+--------+----------+----------+----------+------+------------+-----------+---------------+
| 53171 | 22 | files/web1-rename.txt | cdfc9cf0f8cc9bfc83ffd6f175f7dc95 | 53163 | web1.txt | 10 | 5 | 0 | 1369318729 | 0 | 519e2549973ae |
+--------+---------+-----------------------+----------------------------------+--------+----------+----------+----------+------+------------+-----------+---------------+

PROBLEM #2:

after a while running of mirral the file disappeared from the client local folder and local state db altogether:

[octuser2 ownCloud]$ sqlite3 -column -header .csync_journal.db "select * from metadata limit 10;"

however it is still on the server:

[root]# ls -l /cloud_data1/octuser2/files/*
-rw-r--r--. 1 apache apache 0 May 23 16:18 /cloud_data1/octuser2/files/web1-rename.txt
-rw-r--r--. 1 apache apache 0 May 23 17:16 /cloud_data1/octuser2/files/web2.txt

and in servers db:

mysql> select * from oc_filecache where path like "%web1%.txt";
+--------+---------+-----------------------+----------------------------------+--------+----------+----------+----------+------+------------+-----------+---------------+
| fileid | storage | path | path_hash | parent | name | mimetype | mimepart | size | mtime | encrypted | etag |
+--------+---------+-----------------------+----------------------------------+--------+----------+----------+----------+------+------------+-----------+---------------+
| 53171 | 22 | files/web1-rename.txt | cdfc9cf0f8cc9bfc83ffd6f175f7dc95 | 53163 | web1.txt | 10 | 5 | 0 | 1369318729 | 0 | 519e2549973ae |
+--------+---------+-----------------------+----------------------------------+--------+----------+----------+----------+------+------------+-----------+---------------+

Below is the mirall logfile. It looks like a clear bug - the number of csync_statedb_inster sqlite_step errors grows in time for one file entry - I suspect this is the entry of the renamed file.

I have only two files in this directory.

@moscicki
Copy link
Contributor Author

I cannot post the logfile because it is too large (600MB).

Let me know how would you like to get the log.

kuba

@moscicki
Copy link
Contributor Author

moscicki commented Jun 3, 2013

I started a 1.3.0-beta2 client on my directory with the state left from the previous run with beta1 as described above.

The problem persists. The logical error is still in the code: every iteration of the mirall update cycle the number of sqlite_step errors increases.

So the issue is not solved in beta2 yet.

@dragotin
Copy link
Contributor

dragotin commented Jun 3, 2013

I just tried to reproduce that with a stable5 server (upcoming 5.0.7) and the current 1.3.0 beta2 with some patches from today, which I don't consider changing anything. For me, local renaming of the file results in a MOVE command on the WebDAV server and works like a charm.

@moscicki do you think you can isolate a testcase with one file or so and get me the client log file and if possible the access_log? Thanks.

@moscicki
Copy link
Contributor Author

moscicki commented Jun 4, 2013

Hi, I think it may have to do with creating/modyfing the file via the web interface in the first place, before the file is moved locally and synced by the client. The client log is here: http://mail.kde.org/pipermail/owncloud/2013-May/009278.html

The net result is that the file is missing on the client (is deleted), while on the server it exists.

An excerpt of access_log of the apache server:

137.138.32.188 - octuser2 [23/May/2013:16:17:46 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:17:46 +0200] "GET /owncloud/remote.php/webdav// HTTP/1.1" 200 720 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:18:09 +0200] "GET /owncloud/remote.php/webdav// HTTP/1.1" 200 720 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:18:09 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:18:09 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 527 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:18:10 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:18:40 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:18:40 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 527 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:18:41 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:09 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:09 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:11 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:11 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:19:11 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:11 +0200] "GET /owncloud/remote.php/webdav/web1.txt HTTP/1.1" 200 - "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:19:12 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:42 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:19:42 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:19:43 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:20:13 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:20:13 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:20:14 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:20:44 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:20:44 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:20:45 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:21:15 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:21:15 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:21:16 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:21:46 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:21:46 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:21:47 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:22:17 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:22:17 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:22:18 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:22:48 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:22:48 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:22:48 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:23:19 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:23:19 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:23:19 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:23:50 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:23:50 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:23:50 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:24:21 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:24:21 +0200] "PROPFIND /owncloud/remote.php/webdav HTTP/1.1" 207 848 "-" "csyncoC/0.70.90 neon/0.29.3"
137.138.32.188 - octuser2 [23/May/2013:16:24:21 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"
137.138.32.188 - octuser2 [23/May/2013:16:24:52 +0200] "GET /owncloud/status.php HTTP/1.1" 200 75 "-" "mirall-1.3.0beta1"

kuba

@danimo
Copy link
Contributor

danimo commented Jun 11, 2013

Can you try 1.3.0 nightly builds?

@danimo
Copy link
Contributor

danimo commented Jun 12, 2013

We now released beta3. Can you test with that?

@moscicki
Copy link
Contributor Author

Hi, yes, sure, thank you. I will try to come back to you tomorrow with a result.

kuba

On Jun 12, 2013, at 4:47 PM, Daniel Molkentin notifications@github.com wrote:

We now released beta3. Can you test with that?


Reply to this email directly or view it on GitHub.

@moscicki
Copy link
Contributor Author

Hello,

TEST1:

I started beta3 client at the point where I left it from the previous run.

The file is still not downloaded, however the log file looks differently now. See below.

Observations:

  • the entry on web1.txt (web1-rename.txt) disappeared from local csync db
  • the entry on the server remained unchanged
  • the mirall Detailed Sync Protocol log report windows reports it as "Errors, web1.txt Downloaded".

So beta3 does not recover from that bad state. I think it is important that mirall is able to recover from bad state.

TEST2:

I deleted a .csync_journal.db on the client to see if anything changes. The file is not synced however there is a different error in the log file. Maybe this will be of some use for you.

TEST3:
I also performed a repetition of this test with another file web3.txt. This time it works. I can see the rename propagated to the web interface.

TEST4:
I also performed a test in the opposite direction: created a file on web, synced to the client, renamed on the web. This works correctly as well - next time around I got the file renamed on the client (mirall notified me of the file being first deleted and then added again). That's an OK behaviour for now I guess.

LOGFILES:

TEST1:

06-14 17:57:34:558 #-------# oC found on "http://box.cern.ch/owncloud"
06-14 17:57:34:563 oc_module: Simple propfind result code 207.
06-14 17:57:34:563 oc_module: opendir returning handle 0x7f9d9c013080
06-14 17:57:34:563 oc_module: owncloud_stat owncloud://box.cern.ch/owncloud/remote.php/webdav/web2.txt called
06-14 17:57:34:563 csync_walker: file: owncloud://box.cern.ch/owncloud/remote.php/webdav/web2.txt
06-14 17:57:34:563 _csync_detect_update: ==> file: web2.txt - hash 7585186374886480495, mtime: 1369322171
06-14 17:57:34:563 _csync_detect_update: Database entry found, compare: 1369322171 <-> 1369322171, md5: 519e32bbcc0d4 <-> 519e32bbcc0d4
06-14 17:57:34:563 _csync_detect_update: file: web2.txt, instruction: INSTRUCTION_NONE <<=
06-14 17:57:34:563 oc_module: owncloud_stat owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt called
06-14 17:57:34:563 csync_walker: file: owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt
06-14 17:57:34:563 _csync_detect_update: ==> file: web1.txt - hash 2121407186260769061, mtime: 1369318729
06-14 17:57:34:563 csync_statedb_get_stat_by_hash: No result record found for phash = 2121407186260769061
06-14 17:57:34:563 _csync_detect_update: file: web1.txt, instruction: INSTRUCTION_NEW <<=
06-14 17:57:34:563 oc_module: Skipping target resource.
06-14 17:57:34:563 oc_module: closedir method called 0x7f9d9c013080!
06-14 17:57:34:563 csync_ftw: <= Closing walk for owncloud://box.cern.ch/owncloud/remote.php/webdav with read_from_db 0
06-14 17:57:34:563 csync_update: Update detection for remote replica took 0.10 seconds walking 2 files.
06-14 17:57:34:563 csync_memstat_check: Memory: 733964K total size, 26664K resident, 19700K shared
06-14 17:57:34:563 <<#### Update end ###########################################################
06-14 17:57:34:564 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web2.txt
06-14 17:57:34:564 csync_reconcile: Reconciliation for local replica took 0.00 seconds visiting 1 files.
06-14 17:57:34:564 _csync_merge_algorithm_visitor: INSTRUCTION_NEW file: web1.txt
06-14 17:57:34:564 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web2.txt
06-14 17:57:34:564 csync_reconcile: Reconciliation for remote replica took 0.00 seconds visiting 2 files.
06-14 17:57:34:564 * csync thread started
06-14 17:57:34:564 ==> load folder icon "owncloud-framed"
06-14 17:57:34:564 Returning configured owncloud url: "http://box.cern.ch/owncloud/remote.php/webdav/"
06-14 17:57:34:576 Check status.php from statusdialog.
06-14 17:57:34:576 Get Request to "status.php"
06-14 17:57:34:576 Returning configured owncloud url: "http://box.cern.ch/owncloud/"
06-14 17:57:34:576 Returning configured owncloud url: "http://box.cern.ch/owncloud/"
06-14 17:57:34:576 Setting up host header: "box.cern.ch"
06-14 17:57:34:576 Folder in overallStatus Message: Mirall::ownCloudFolder(0x1473830) with name "ownCloud"
06-14 17:57:34:576 Sync state changed for folder "ownCloud" : "Sync Running"
06-14 17:57:34:564 csync_propagate: Propagation for local replica took 0.00 seconds visiting 1 files.
06-14 17:57:34:564 csync_propagate: Propagation for remote replica took 0.00 seconds visiting 2 files.
06-14 17:57:34:564 void Mirall::CSyncThread::startSync() Sync finished
06-14 17:57:34:594 status.php returns: "{"installed":"true","version":"5.0.8","versionstring":"5.0.6","edition":""}" 0 Reply: QNetworkReplyImpl(0x1312700)
06-14 17:57:34:594 #-------# oC found on "http://box.cern.ch/owncloud"
06-14 17:57:35:151 _insert_metadata_visitor: SQL statement: INSERT INTO metadata_temp
(phash, pathlen, path, inode, uid, gid, mode, modtime, type, md5) VALUES
(7585186374886480495, 8, web2.txt, 6422616, 9406, 9406, 33188, 1369322171, 0, 519e32bbcc0d4);
06-14 17:57:35:369 csync_statedb_write_progressinfo: INSERT INTO progress (phash, modtime, md5, chunk, transferid, error_count, tmpfile, error_string) VALUES(2121407186260769061, 1369318729, '519e2549973ae', 0, 0, 48, '(NULL)', '(NULL)');
06-14 17:57:35:751 _merge_and_write_statedb: Writing the statedb of 1 files to disk took 1.19 seconds
06-14 17:57:35:851 csync_commit: Journal: /home/octuser2/ownCloud/.csync_journal.db

TEST2:

06-14 18:31:51:929 oc_module: owncloud_stat owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt called
06-14 18:31:51:929 csync_walker: file: owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt
06-14 18:31:51:929 _csync_detect_update: ==> file: web1.txt - hash 2121407186260769061, mtime: 1369318729
06-14 18:31:51:929 _csync_detect_update: file: web1.txt, instruction: INSTRUCTION_NEW <<=
06-14 18:31:51:929 oc_module: Skipping target resource.
06-14 18:31:51:929 oc_module: closedir method called 0x7fa71c013ca0!
...
06-14 18:31:51:929 csync_ftw: <= Closing walk for owncloud://box.cern.ch/owncloud/remote.php/webdav with read_from_db 0
06-14 18:31:51:929 csync_update: Update detection for remote replica took 0.11 seconds walking 4 files.
06-14 18:31:51:929 csync_memstat_check: Memory: 733964K total size, 26628K resident, 19664K shared
06-14 18:31:51:929 <<#### Update end ###########################################################
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web3-RENAME.txt
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web2.txt
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web4-WEB-RENAME.txt
06-14 18:31:51:929 csync_reconcile: Reconciliation for local replica took 0.00 seconds visiting 3 files.
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NEW file: web1.txt
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web3-RENAME.txt
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web2.txt
06-14 18:31:51:929 _csync_merge_algorithm_visitor: INSTRUCTION_NONE file: web4-WEB-RENAME.txt
06-14 18:31:51:929 csync_reconcile: Reconciliation for remote replica took 0.00 seconds visiting 4 files.
06-14 18:31:51:929 * csync thread started
06-14 18:31:51:929 ==> load folder icon "owncloud-framed"
06-14 18:31:51:930 Returning configured owncloud url: "http://box.cern.ch/owncloud/remote.php/webdav/"
06-14 18:31:51:941 Check status.php from statusdialog.
06-14 18:31:51:941 Get Request to "status.php"
06-14 18:31:51:942 Returning configured owncloud url: "http://box.cern.ch/owncloud/"
06-14 18:31:51:942 Returning configured owncloud url: "http://box.cern.ch/owncloud/"
06-14 18:31:51:942 Setting up host header: "box.cern.ch"
06-14 18:31:51:942 Folder in overallStatus Message: Mirall::ownCloudFolder(0xc44470) with name "ownCloud"
06-14 18:31:51:942 Sync state changed for folder "ownCloud" : "Sync Running"
06-14 18:31:51:929 csync_propagate: Propagation for local replica took 0.00 seconds visiting 3 files.
06-14 18:31:51:929 oc_module: => open called for owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt
06-14 18:31:51:929 oc_module: GET request on /owncloud/remote.php/webdav/web1.txt
06-14 18:31:51:930 oc_module: Sendfile handling request type GET.
06-14 18:31:51:930 oc_module: -- GET on owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt
06-14 18:31:51:957 status.php returns: "{"installed":"true","version":"5.0.8","versionstring":"5.0.6","edition":""}" 0 Reply: QNetworkReplyImpl(0xb1c1b0)
06-14 18:31:51:957 #-------# oC found on "http://box.cern.ch/owncloud"
06-14 18:31:51:992 oc_module: Content encoding ist with status 404
06-14 18:31:51:992 oc_module: GET http result 404 (Not Found)
06-14 18:31:51:992 oc_module: sendfile request failed with http status 404!
06-14 18:31:51:992 _csync_push_file: file: owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt, command: sendfile, error: from errno 2
06-14 18:31:51:993 csync_propagate: Propagation for remote replica took 0.06 seconds visiting 4 files.
06-14 18:31:51:993 void Mirall::CSyncThread::startSync() Sync finished

06-14 18:31:52:342 _insert_metadata_visitor: SQL statement: INSERT INTO metadata_temp
(phash, pathlen, path, inode, uid, gid, mode, modtime, type, md5) VALUES
(4279778990719192970, 15, web3-RENAME.txt, 6422620, 9406, 9406, 33188, 1371226292, 0, 51bb4108cce65);
06-14 18:31:52:342 _insert_metadata_visitor: SQL statement: INSERT INTO metadata_temp
(phash, pathlen, path, inode, uid, gid, mode, modtime, type, md5) VALUES
(7585186374886480495, 8, web2.txt, 6422616, 9406, 9406, 33188, 1369322171, 0, 519e32bbcc0d4);
06-14 18:31:52:342 _insert_metadata_visitor: SQL statement: INSERT INTO metadata_temp
(phash, pathlen, path, inode, uid, gid, mode, modtime, type, md5) VALUES
(-3110722182856517209, 19, web4-WEB-RENAME.txt, 6422621, 9406, 9406, 33188, 1371226425, 0, 51bb41396580d);
06-14 18:31:52:450 csync_statedb_write_progressinfo: INSERT INTO progress (phash, modtime, md5, chunk, transferid, error_count, tmpfile, error_string) VALUES(2121407186260769061, 1369318729, '519e2549973ae', 0, 0, 1, '(NULL)', '(NULL)');
06-14 18:31:52:509 _merge_and_write_statedb: Writing the statedb of 3 files to disk took 0.52 seconds
06-14 18:31:52:536 csync_commit: Journal: /home/octuser2/ownCloud/.csync_journal.db

@ogoffart
Copy link
Contributor

Here is my analysis from the logs:

Test1:

The file was blacklisted in the database because of previous errors. Which is why 'nothing' happens.
In order to unblacklist the file, it needs to be changed.

Test2:

Apparently the GET on owncloud://box.cern.ch/owncloud/remote.php/webdav/web1.txt returns with 404 not found.
I really wonder why does that happen, because the stat did work before.

The part of the log that does the update is missing, but since the client knows about web1.txt, it is probably what is returned by the server. So maybe the server is laying and pretends it has a file which it does not have. Maybe some wrong cache on the server?

@pcreadycl
Copy link

any solution for this??

@ogoffart
Copy link
Contributor

ogoffart commented Jul 8, 2013

We removed the blacklist in 1.3 final.
So at least we will get the proper error reported by the server all the time.

We still don't know wh the server had an error.

@ogoffart
Copy link
Contributor

Is this bug still valid?
Without a way to reproduce it or more information there is nothing we can do.

@buskjan
Copy link

buskjan commented Jul 30, 2013

Yes its valid.. I just lost my vacation photos. Lucky for me I still have
them on the memory card. I will try to reproduce the error with logging
turned on.
Den 30 jul 2013 17:29 skrev "Olivier Goffart" notifications@github.com:

Is this bug still valid?

Without a way to reproduce it or more information there is nothing we can
do.


Reply to this email directly or view it on GitHubhttps://github.com//issues/640#issuecomment-21799164
.

@mophilly
Copy link

I am working through ownCloud for an application. I have not encountered this problem.
If someone will post a "brain dead end user" test script, I will execute it on my server and report the outcome.
My config is OC Server 5.0.9 on CentOS5 and the OC Client 1.3.0 on Mac OS X 10.8

@milanshrestha
Copy link

reverting the file names to its previous names brings back lost files.

@dragotin
Copy link
Contributor

dragotin commented Aug 8, 2013

I retested this again with a 5.0.10 server. I was not able to reproduce an fail with a file or directory created in the web interface, synced to the client and rename it on the client machine. It worked all the time, also with proper MOVE commands sent to the server.

I will close this report, please reopen or create a new bug if the problem persists. Thanks!

@dragotin dragotin closed this as completed Aug 8, 2013
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

8 participants