[Solved] Sync - High CPU, large logs, constant indexing

NickInNC · July 14, 2016

I have Sync 2.3.8 running on Ubuntu 16.04 LTS (64 bit), backing up files from my Android phone to an external hard drive (file system is NTFS). Only 2 directories are being synced, and there are only 500 or so files total.

Sync is constantly using 25% or more of my CPU, and if I go to the gui screen both folders constantly show they are indexing.

The debug logs are filling up rapidly, and there's 2.4 GB of files in the log directory. It's creating a new sync.log file every six or seven minutes, with hundreds of new lines being created every second. There are also several journal files which look like they contain similar information to the sync.log.

Any suggestions? It's seriously impacting the performance of my system, and while I love having my photos automatically moved it's not worth it to have this running so inefficiently.

Here's a sample of the sync.log:

[20160713 22:24:10.582] JOURNAL[B03D]: assign file_id 2065:153054 to entry with path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160504_185951.mp4"
[20160713 22:24:10.582] [OnNotifyFileChange] "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160504_185951.mp4"
[20160713 22:24:10.582] JOURNAL[B03D]: remote entry job complete for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160504_185951.mp4"
[20160713 22:24:10.582] JOURNAL[B03D]: { "dl_failed": false, "file_hash": "353F85EDBDCDB20C10380D163D83E71B41B1C1CC", "file_id": "2065:153054", "fs_error": false, "have_pieces": 931, "ignored": false, "info_hash": "A9CDCE81A6A64DD49EE95A4ECDCEE7C31BBB44EE", "invalidated": false, "link_content": "", "locked": false, "mtime": 1462402791, "name_on_disk": "VID_20160504_185951.mp4", "otime": 204, "owner": "11826B1AC930F4A97EE4BE6A55D5C2B5EEB0BBB6", "path": "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160504_185951.mp4", "perm": 420, "ph_ext": ".bts", "placeholder": false, "pvi": "pvi[NULL]", "selected_for_dl": true, "size": 30483883, "state": 1, "time": 1462402791, "total_pieces": 931, "type": 1 }
[20160713 22:24:10.673] JOURNAL[B03D]: tf completed for "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4" - avg hash speed: <unknown>
[20160713 22:24:10.673] Update have pieces for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", was: 0, now: 934
[20160713 22:24:10.674] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4" = pvi[type:1 s:30598379 mt:1462669913 h:13ADB05B9DEB5A193605AE63AE1A44446E56EE7C]
[20160713 22:24:10.674] Update have pieces for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", was: 934, now: 0
[20160713 22:24:10.674] JOURNAL[B03D]: set fs error for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", type 1, will be recreated (R/O)
[20160713 22:24:10.674] JOURNAL[B03D]: will recreate entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", type 1
[20160713 22:24:10.674] JOURNAL[B03D]: Setup entry job "FileEntryJob" for path "Camera/VID_20160507_130532.mp4", next state is "PERFORM", queue size 0 1 37
[20160713 22:24:10.674] JOURNAL[B03D]: file entry job for path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] JOURNAL[B03D]: { "dl_failed": false, "file_hash": "13ADB05B9DEB5A193605AE63AE1A44446E56EE7C", "file_id": "2065:153002", "fs_error": true, "have_pieces": 0, "ignored": false, "info_hash": "AA8040C1336263C89C01260E0087F9ECAAD23FEF", "invalidated": false, "link_content": "", "locked": false, "mtime": 1462640732, "name_on_disk": "VID_20160507_130532.mp4", "otime": 205, "owner": "11826B1AC930F4A97EE4BE6A55D5C2B5EEB0BBB6", "path": "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", "perm": 420, "ph_ext": ".bts", "placeholder": false, "pvi": "pvi[type:1 s:30598379 mt:1462669913 h:13ADB05B9DEB5A193605AE63AE1A44446E56EE7C]", "selected_for_dl": true, "size": 30598379, "state": 1, "time": 1462640732, "total_pieces": 934, "type": 1 }
[20160713 22:24:10.674] JOURNAL[B03D]: entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4" has state CREATED, current/desired entry type: 1/1
[20160713 22:24:10.674] JOURNAL[B03D]: writing new attributes to file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] SyncFileEntry: writing file attributes to file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", mt:1462640732
[20160713 22:24:10.674] SyncFileEntry: Failed to write attributes for file /media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4 - 1
[20160713 22:24:10.674] SyncFileEntry: Can't set file attributes 0 for file /media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4 - 1
[20160713 22:24:10.674] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4" = pvi[NULL] (reset)
[20160713 22:24:10.674] Finished downloading file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4" = pvi[NULL]
[20160713 22:24:10.674] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4" = pvi[NULL] (reset)
[20160713 22:24:10.674] JOURNAL[B03D]: erase file_id 2065:153002 assigned to entry with path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] JOURNAL[B03D]: assign file_id 2065:153002 to entry with path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] [OnNotifyFileChange] "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] JOURNAL[B03D]: remote entry job complete for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4"
[20160713 22:24:10.674] JOURNAL[B03D]: { "dl_failed": false, "file_hash": "13ADB05B9DEB5A193605AE63AE1A44446E56EE7C", "file_id": "2065:153002", "fs_error": false, "have_pieces": 934, "ignored": false, "info_hash": "AA8040C1336263C89C01260E0087F9ECAAD23FEF", "invalidated": false, "link_content": "", "locked": false, "mtime": 1462640732, "name_on_disk": "VID_20160507_130532.mp4", "otime": 205, "owner": "11826B1AC930F4A97EE4BE6A55D5C2B5EEB0BBB6", "path": "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", "perm": 420, "ph_ext": ".bts", "placeholder": false, "pvi": "pvi[NULL]", "selected_for_dl": true, "size": 30598379, "state": 1, "time": 1462640732, "total_pieces": 934, "type": 1 }
[20160713 22:24:10.825] JOURNAL[B03D]: tf completed for "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4" - avg hash speed: <unknown>
[20160713 22:24:10.826] Update have pieces for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", was: 0, now: 787
[20160713 22:24:10.826] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4" = pvi[type:1 s:51515631 mt:1464175189 h:F19D7715056A4F6F819C7FEAD7C0C3FD9EBE44BD]
[20160713 22:24:10.826] Update have pieces for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", was: 787, now: 0
[20160713 22:24:10.826] JOURNAL[B03D]: set fs error for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", type 1, will be recreated (R/O)
[20160713 22:24:10.826] JOURNAL[B03D]: will recreate entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", type 1
[20160713 22:24:10.826] JOURNAL[B03D]: Setup entry job "FileEntryJob" for path "Camera/VID_20160523_191445.mp4", next state is "PERFORM", queue size 0 1 36
[20160713 22:24:10.826] JOURNAL[B03D]: file entry job for path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.826] JOURNAL[B03D]: { "dl_failed": false, "file_hash": "F19D7715056A4F6F819C7FEAD7C0C3FD9EBE44BD", "file_id": "2065:155541", "fs_error": true, "have_pieces": 0, "ignored": false, "info_hash": "5EC21FDFA9C010306E4EEF0399EC4EA1A93F3AA2", "invalidated": false, "link_content": "", "locked": false, "mtime": 1464045285, "name_on_disk": "VID_20160523_191445.mp4", "otime": 728, "owner": "11826B1AC930F4A97EE4BE6A55D5C2B5EEB0BBB6", "path": "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", "perm": 420, "ph_ext": ".bts", "placeholder": false, "pvi": "pvi[type:1 s:51515631 mt:1464175189 h:F19D7715056A4F6F819C7FEAD7C0C3FD9EBE44BD]", "selected_for_dl": true, "size": 51515631, "state": 1, "time": 1464045285, "total_pieces": 787, "type": 1 }
[20160713 22:24:10.826] JOURNAL[B03D]: entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4" has state CREATED, current/desired entry type: 1/1
[20160713 22:24:10.826] JOURNAL[B03D]: writing new attributes to file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.826] SyncFileEntry: writing file attributes to file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", mt:1464045285
[20160713 22:24:10.826] SyncFileEntry: Failed to write attributes for file /media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4 - 1
[20160713 22:24:10.826] SyncFileEntry: Can't set file attributes 0 for file /media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4 - 1
[20160713 22:24:10.826] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4" = pvi[NULL] (reset)
[20160713 22:24:10.826] Finished downloading file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.826] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4" = pvi[NULL]
[20160713 22:24:10.826] Set new pvinfo for file "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4" = pvi[NULL] (reset)
[20160713 22:24:10.826] JOURNAL[B03D]: erase file_id 2065:155541 assigned to entry with path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.826] JOURNAL[B03D]: assign file_id 2065:155541 to entry with path "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.826] [OnNotifyFileChange] "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.826] JOURNAL[B03D]: remote entry job complete for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4"
[20160713 22:24:10.827] JOURNAL[B03D]: { "dl_failed": false, "file_hash": "F19D7715056A4F6F819C7FEAD7C0C3FD9EBE44BD", "file_id": "2065:155541", "fs_error": false, "have_pieces": 787, "ignored": false, "info_hash": "5EC21FDFA9C010306E4EEF0399EC4EA1A93F3AA2", "invalidated": false, "link_content": "", "locked": false, "mtime": 1464045285, "name_on_disk": "VID_20160523_191445.mp4", "otime": 728, "owner": "11826B1AC930F4A97EE4BE6A55D5C2B5EEB0BBB6", "path": "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160523_191445.mp4", "perm": 420, "ph_ext": ".bts", "placeholder": false, "pvi": "pvi[NULL]", "selected_for_dl": true, "size": 51515631, "state": 1, "time": 1464045285, "total_pieces": 787, "type": 1 }

Helen · July 14, 2016

9 hours ago, NickInNC said:

set fs error for entry "/media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4", type 1, will be recreated (R/O)

Means that in RO folder files have their "modified time" updated. This folder being RO, Sync tried to get the mtime back, but fails for reason:

9 hours ago, NickInNC said:

SyncFileEntry: Failed to write attributes for file /media/nhlavace/Elements/hsync/DCIM/Camera/VID_20160507_130532.mp4 - 1

Operation not permitted. i.e. your user doesn't have permission for changing files' attributes.

NickInNC · July 14, 2016

I'll check the file permissions and see if that helps. Thanks!

NickInNC · July 15, 2016

File permissions for all the directories and files is 777, so I'm not sure what could be preventing Sync from being able to change the files' attributes. The dates on the files match when they were downloaded from my phone to my computer, but in the logs it looks like it's trying to redo every file. Any other ideas?

Helen · July 15, 2016

no, it's not permission to read -write the file. it's the lack of permissions to change files attributes, most likely imposed by the file system or the way it's mounted. I suppose if you go to /media/nhlavace/Elements/hsync/DCIM/Camera/ and try some chmod command you may hit a "read-only system" error or any other error output.

Check with what flags the drive is mounted: command mount.

Dragonfire · December 20, 2016

I have nearly the same problem. Only my files from the Android Phone on the new Sync 2.4.4 client on my raspberry indexing all the time ...

(With 2.0.93 on a different raspberry I have no problems ...)

[20161219 23:38:33.330] JOURNAL[9C16]: tf completed for "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" - avg hash speed: <unknown>
[20161219 23:38:33.330] Update have pieces for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", was: 0, now: 84
[20161219 23:38:33.330] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[type:1 s:2732597 mt:1481933858 h:44C3AD768906EB26C3567E49288A414E4F6FA0C0]
[20161219 23:38:33.330] Update have pieces for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", was: 84, now: 0
[20161219 23:38:33.330] JOURNAL[9C16]: set fs error for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", type 1, will be recreated (R/O)
[20161219 23:38:33.330] JOURNAL[9C16]: will recreate entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", type 1
[20161219 23:38:33.331] JOURNAL[9C16]: Setup entry job "FileEntryJob" for path "Camera/IMG_20160406_185446930_HDR.jpg", next state is "PERFORM", queue size 0 1 427 
[20161219 23:38:33.331] JOURNAL[9C16]: file entry job for path "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:38:33.331] JOURNAL[9C16]: { "dl_failed": false, "file_hash": "44C3AD768906EB26C3567E49288A414E4F6FA0C0", "file_id": "2049:117707", "fs_error": true, "have_pieces": 0, "ignored": false, "info_hash": "9E584832152A442E1068D7FB64843B5E2FAF1430", "invalidated": false, "link_content": "", "locked": 0, "mtime": 1459961688, "name_on_disk": "IMG_20160406_185446930_HDR.jpg", "otime": 1031, "owner": "1183A21AC3AEC2CFA4DD184EE1247D901AB6F6B9", "path": "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", "perm": 420, "ph_ext": ".rsls", "placeholder": false, "pvi": "pvi[type:1 s:2732597 mt:1481933858 h:44C3AD768906EB26C3567E49288A414E4F6FA0C0]", "select_children_for_dl": false, "selected_for_dl": true, "size": 2732597, "state": 1, "time": 1459961688, "total_pieces": 84, "type": 1 }
[20161219 23:38:33.333] JOURNAL[9C16]: entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" has state CREATED, current/desired entry type: 1/1
[20161219 23:38:33.333] JOURNAL[9C16]: writing new attributes to file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:38:33.333] SyncFileEntry: writing file attributes to file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", mt:1459961688
[20161219 23:38:33.333] SyncFileEntry: Failed to write attributes for file /media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg - 1
[20161219 23:38:33.333] SyncFileEntry: Can't set file attributes 0 for file /media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg - 1
[20161219 23:38:33.333] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[NULL] (reset)
[20161219 23:38:33.333] Finished downloading file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:38:33.333] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[NULL]
[20161219 23:38:33.333] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[NULL] (reset)
[20161219 23:38:33.333] JOURNAL[9C16]: erase file_id 2049:117707 assigned to entry with path "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:38:33.333] JOURNAL[9C16]: assign file_id 2049:117707 to entry with path "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:38:33.333] [OnNotifyFileChange] "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:38:33.334] JOURNAL[9C16]: remote entry job complete for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", result = 1
[20161219 23:38:33.334] JOURNAL[9C16]: { "dl_failed": false, "file_hash": "44C3AD768906EB26C3567E49288A414E4F6FA0C0", "file_id": "2049:117707", "fs_error": false, "have_pieces": 84, "ignored": false, "info_hash": "9E584832152A442E1068D7FB64843B5E2FAF1430", "invalidated": false, "link_content": "", "locked": 0, "mtime": 1459961688, "name_on_disk": "IMG_20160406_185446930_HDR.jpg", "otime": 1031, "owner": "1183A21AC3AEC2CFA4DD184EE1247D901AB6F6B9", "path": "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", "perm": 420, "ph_ext": ".rsls", "placeholder": false, "pvi": "pvi[NULL]", "select_children_for_dl": false, "selected_for_dl": true, "size": 2732597, "state": 1, "time": 1459961688, "total_pieces": 84, "type": 1 }

mount

Quote

/dev/sda1 on /media/Seagate type fuseblk (rw,noatime,user_id=0,group_id=0,default_permissions,allow_other,blksize=4096)

I was able to`chmod` all files and also to change the mtime with`touch` command as pi user.

How can I give the user `rslsync` correct permissions?

Helen · December 20, 2016

try command touch -mt 1411171533 /media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg,

1411171533 - is just a random timestamp from the past.

Dragonfire · December 20, 2016

Command executed without any problems ...

I also run: `touch -mt 1411171533 /media/Seagate/Sync/DCIM/Camera/*`

Still indexing

and now I have some errors messages in the web gui:

log

[20161219 23:37:11.027] JOURNAL[9C16]: tf completed for "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" - avg hash speed: <unknown>
[20161219 23:37:11.027] Update have pieces for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", was: 0, now: 84
[20161219 23:37:11.027] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[type:1 s:2732597 mt:1481933858 h:44C3AD768906EB26C3567E49288A414E4F6FA0C0]
[20161219 23:37:11.027] Update have pieces for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", was: 84, now: 0
[20161219 23:37:11.027] JOURNAL[9C16]: set fs error for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", type 1, will be recreated (R/O)
[20161219 23:37:11.027] JOURNAL[9C16]: will recreate entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", type 1
[20161219 23:37:11.029] JOURNAL[9C16]: skipping load torrent for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" - has fs error
[20161219 23:37:11.029] SF[9C16] [3845]: connect files to peer, queue:0 dowloading:0/0 speed:0
[20161219 23:37:11.029] JOURNAL[9C16]: skipping load torrent for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" - has fs error
[20161219 23:37:11.029] SF[9C16] [4731]: connect files to peer, queue:0 dowloading:0/0 speed:0
[20161219 23:37:11.029] ScheduledTask: "ConnectMorePeers" invoked: timer, reason:LoadTorrent failed
[20161219 23:37:11.029] SF[9C16] [4731]: SyncStateWithPeer - waiting for previous merge to complete
[20161219 23:37:11.029] SF[9C16] [3845]: SyncStateWithPeer - waiting for previous merge to complete
  
  ...
  
  [20161219 23:37:11.051] JOURNAL[9C16]: Setup entry job "FileEntryJob" for path "Camera/IMG_20160406_185446930_HDR.jpg", next state is "PERFORM", queue size 0 1 425 
[20161219 23:37:11.051] JOURNAL[9C16]: file entry job for path "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:37:11.051] JOURNAL[9C16]: { "dl_failed": false, "file_hash": "44C3AD768906EB26C3567E49288A414E4F6FA0C0", "file_id": "2049:117707", "fs_error": true, "have_pieces": 0, "ignored": false, "info_hash": "9E584832152A442E1068D7FB64843B5E2FAF1430", "invalidated": false, "link_content": "", "locked": 0, "mtime": 1459961688, "name_on_disk": "IMG_20160406_185446930_HDR.jpg", "otime": 1031, "owner": "1183A21AC3AEC2CFA4DD184EE1247D901AB6F6B9", "path": "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", "perm": 420, "ph_ext": ".rsls", "placeholder": false, "pvi": "pvi[type:1 s:2732597 mt:1481933858 h:44C3AD768906EB26C3567E49288A414E4F6FA0C0]", "select_children_for_dl": false, "selected_for_dl": true, "size": 2732597, "state": 1, "time": 1459961688, "total_pieces": 84, "type": 1 }
[20161219 23:37:11.053] JOURNAL[9C16]: entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" has state CREATED, current/desired entry type: 1/1
[20161219 23:37:11.053] JOURNAL[9C16]: writing new attributes to file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:37:11.053] SyncFileEntry: writing file attributes to file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", mt:1459961688
[20161219 23:37:11.054] SyncFileEntry: Failed to write attributes for file /media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg - 1
[20161219 23:37:11.054] SyncFileEntry: Can't set file attributes 0 for file /media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg - 1
[20161219 23:37:11.054] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[NULL] (reset)
[20161219 23:37:11.054] Finished downloading file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:37:11.054] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[NULL]
[20161219 23:37:11.054] Set new pvinfo for file "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" = pvi[NULL] (reset)
[20161219 23:37:11.054] JOURNAL[9C16]: erase file_id 2049:117707 assigned to entry with path "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:37:11.054] JOURNAL[9C16]: assign file_id 2049:117707 to entry with path "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:37:11.054] [OnNotifyFileChange] "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg"
[20161219 23:37:11.055] JOURNAL[9C16]: remote entry job complete for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", result = 1
[20161219 23:37:11.055] JOURNAL[9C16]: { "dl_failed": false, "file_hash": "44C3AD768906EB26C3567E49288A414E4F6FA0C0", "file_id": "2049:117707", "fs_error": false, "have_pieces": 84, "ignored": false, "info_hash": "9E584832152A442E1068D7FB64843B5E2FAF1430", "invalidated": false, "link_content": "", "locked": 0, "mtime": 1459961688, "name_on_disk": "IMG_20160406_185446930_HDR.jpg", "otime": 1031, "owner": "1183A21AC3AEC2CFA4DD184EE1247D901AB6F6B9", "path": "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg", "perm": 420, "ph_ext": ".rsls", "placeholder": false, "pvi": "pvi[NULL]", "select_children_for_dl": false, "selected_for_dl": true, "size": 2732597, "state": 1, "time": 1459961688, "total_pieces": 84, "type": 1 }
  
  ...
[20161219 23:37:26.600] Entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" was updated, current state: pvi[type:1 s:2732597 mt:1481933858 h:0000000000000000000000000000000000000000], expected: pvi[type:1 s:2732597 mt:1459961688 h:44C3AD768906EB26C3567E49288A414E4F6FA0C0]
[20161219 23:37:26.600] FC[9C16]: fs event for entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" t:1481933858 s:2732597 id:2049:117707 type:1
[20161219 23:37:26.601] Entry "/media/Seagate/Sync/DCIM/Camera/IMG_20160406_185446930_HDR.jpg" was updated, current state: pvi[type:1 s:2732597 mt:1481933858 h:0000000000000000000000000000000000000000], expected: pvi[type:1 s:2732597 mt:1459961688 h:44C3AD768906EB26C3567E49288A414E4F6FA0C0]

Helen · December 21, 2016

well, Sync still cannot set the mtime, cause of errno 1

EPERM            1    Operation not permitted

Is there a reason why you don't install Sync on seagate?
And what file system is on that partition? fuseblk implies it's ntfs??

Dragonfire · December 21, 2016

Seagate is an external hdd.

5 minutes ago, Helen said:

And what file system is on that partition? fuseblk implies it's ntfs??

Correct, it is mounted with this options:

`sudo mount -t ntfs-3g -o uid=pi,gid=pi,big_writes,async,noatime /dev/sda1 /media/Seagate`

Dragonfire · December 21, 2016

Finally I solved the issue ...

I mounted the hdd as `rslsync` user with

sudo mount -t ntfs-3g -o uid=rslsync,gid=rslsync,big_writes,async,noatime /dev/sda1 /media/Seagate

and deleted some files to solve the `initial recheck` errors

[20161221 13:45:18.108] JOURNAL[9C16]: Error downloading file Camera/IMG_20160317_143649931_HDR.jpg: initial recheck

Maybe is it realated to ntfs permission limitations ...

https://wiki.archlinux.org/index.php/NFS/Troubleshooting#Group.2FGID_permissions_issues

Sign In

[Solved] Sync - High CPU, large logs, constant indexing

Recommended Posts

NickInNC

Link to comment

Share on other sites

Helen

Link to comment

Share on other sites

NickInNC

Link to comment

Share on other sites

NickInNC

Link to comment

Share on other sites

Helen

Link to comment

Share on other sites

Dragonfire

Link to comment

Share on other sites

Helen

Link to comment

Share on other sites

Dragonfire

Link to comment

Share on other sites

Helen

Link to comment

Share on other sites

Dragonfire

Link to comment

Share on other sites

Dragonfire

Link to comment

Share on other sites

Join the conversation

Browse

Activity