INFO: Puller ... delete <nil>

theincogtion · June 9, 2015, 9:17pm

I am using v11.8 on Windows and I always get this:

[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>
[6TQJ3] 21:23:41 INFO: Puller (folder "Referendariat", dir "Englisch\\Projekt\\Making a Magazine in groups"): delete: <nil>

The folder is not synced to other devices…

AudriusButkevicius · June 10, 2015, 9:18am

Yeah, the next version will have a bug fix which actually exposes the real error, rather than a nil.

Usually one of the potential causes for this is that some files which are still in the folder are ignored, preventing syncthing from removing the folder.

Eddy2909 · June 10, 2015, 11:59am

got the same problem… should we delete the folder which should not have been synced?

Zillode · June 10, 2015, 6:08pm

that would probably fix it yes

neutreno · June 10, 2015, 6:56pm

I am using v11.8 on Ubuntu and it keeps consuming extremely high amounts of CPU whilst throwing similar messages, then repetitively dies and re-spawns:

Jun 10 19:38:56 x syncthing: [XUUME] 19:38:56 INFO: Puller (folder "code", dir "js/bower_components/modernizr/test/caniuse_files"): delete: <nil>
Jun 10 19:38:56 x syncthing: panic: open /home/x/.config/syncthing/index-v0.11.0.db/101436.ldb: too many open files
....Go routines debug...
Jun 10 19:38:56 x kernel: [769308.457690] init: syncthing main process ended, respawning
Jun 10 19:38:56 x syncthing: [XUUME] 19:38:56 INFO: syncthing v0.11.8 (go1.4.2 linux-amd64 default) unknown-user@syncthing-builder 2015-06-07 10:57:26 UTC

Full excerpt of the relevant log here: http://pastebin.com/UJKi4imH

Zillode · June 10, 2015, 8:49pm

run:

sudo sh -c ‘echo fs.inotify.max_user_watches=204800\n >> /etc/sysctl.conf’

Info: https://github.com/syncthing/syncthing-inotify/issues/53

neutreno · June 10, 2015, 11:05pm

Thanks @Zillode . I already had 1048576 max watches on the server where syncthing produced this log, so I guess it was actually complaining about the computer it was syncing from. It is not that clear to me from the error as both computers have the same syncthing config path that it mentions. Perhaps that error message could be improved to clarify which computer has too many open files.

I deleted the slave folder on the server causing the issues and increased the max watches on the other PC as you suggested. Then it appeared that I needed to reset the indexes before it could successfully sync again as it was showing ‘cluster out of sync’ and was not performing any actions even a while after I had clicked ‘override’.

theincogtion · June 13, 2015, 1:33pm

On one device ST is trying to delete a folder which is not ment to be deleted and an all other devices the folder is also not deleted. I always get this:

[Y7IWD] 13:15:53 INFO: Puller (folder "Referendariat", dir "Pädagogik\\Fachsitzungen"): delete: <nil>

kahun · June 13, 2015, 5:19pm

I have the same problem on Arm Linux on Android 11.8.

The weird thing is that I do not know why it is trying to delete the folder, because the folder it is complaning about has not changed in months on any of my devices.

I do not use ignores on any of my sync devices.

theincogtion · June 14, 2015, 5:39pm

Now I am on 11.9 still the same problem

[6TQJ3] 19:34:37 INFO: Puller (folder "Referendariat", dir "Pädagogik\\Fachsitzungen"): delete: <nil>

lfam · June 15, 2015, 2:02am

I’m having this problem on 64-bit OS X, syncthing version 11.9. It’s for a folder that I deleted on another device a few days ago, when the whole cluster was on a different version. The deletion was propagated to the rest of the cluster but I just booted up the darwin machine and upgraded syncthing from 11.8 to 11.9 and now I’m getting this error.

We should open a bug report on github.

theincogtion · June 15, 2015, 4:55pm

I am now getting this on my RPi:

[MVWYV] 2015/06/15 18:51:52.976491 rwfolder.go:924: INFO: Puller (folder “Referendariat”, file “Pädagogik/Fachsitzungen/10. Fachsitzung/10. Mitschrift.docx”): shortcut: chmod: chmod /mnt/HDD1/sync/xxx/Referendariat/Pädagogik/Fachsitzungen/10. Fachsitzung/10. Mitschrift.docx: no such file or directory

On the RPi this folder does not exist but it exists on 3 other devices and it should be synced to the RPi. This problem persists since over one week.

AudriusButkevicius · June 15, 2015, 6:29pm

Did you rescan on the RPI? You can get STTRACE=scanner trace and then http://docs.syncthing.net/rest/db-file-get.html (takes folder and file query arguments) for the file in question?

theincogtion · June 15, 2015, 7:23pm

I could manage to start syncthing in linux with sttrace but how to do it in windows? The file is not in linux so I can not use the commadn there.

AudriusButkevicius · June 15, 2015, 8:05pm

You can use the command, even if the file is not there. Make sure that file is relative to the folder root, on windows you do set "STTRACE=scanner" before launching syncthing.

theincogtion · June 15, 2015, 8:36pm

Could you please give me an example on how to execute the sttrace and the “db file”. sttrace seems not an executable which I can use in windows. or do I have t execute this in connection with syncthing? Sorry for the stupid qeuestions

AudriusButkevicius · June 15, 2015, 9:45pm

STTRACE is just an environment variable (check how to set one on your system), where as db-file-get can be done via the web browser/curl.

theincogtion · June 16, 2015, 8:00pm

env is set and I also tried:

http://localhost:8080/rest/db/file?folder=Referendariat&file=\Pädagogik\Fachsitzungen\18.%20Fachsitzung\18.%20Sitzung.docx

and

http://localhost:8080/rest/db/file?folder=Referendariat&file=/Pädagogik/Fachsitzungen/18.%20Fachsitzung/18.%20Sitzung.docx

The path is relative to the root folder. The output are just zeros.

AudriusButkevicius · June 16, 2015, 8:05pm

Try skipping the first / for the file. I think the path expects unix slashes, rather than Windows (even if its on Windows).

theincogtion · June 16, 2015, 8:33pm

{“availability”:[“XXX-RL4XETQ-63EOY22-TRG44N6-3J2GV3Q-SMX5YMX-Z4EZELR-J3H6SAO”,“XXX-LNM3A5F-7IRFKEF-DYFPNR5-PE5UOBN-WHX5NQ4-VEJG7JP-HT6NLAN”],“global”:{“flags”:“0644”,“localVersion”:242333,“modified”:“2015-06-11T22:19:44+02:00”,“name”:“Pädagogik\Fachsitzungen\18. Fachsitzung\18. Sitzung.docx”,“numBlocks”:1,“size”:13941,“version”:[“14398397015826002487:4”,“17645276920380550982:4”]},“local”:{“flags”:“0644”,“localVersion”:105640,“modified”:“2015-06-11T22:19:44+02:00”,“name”:“Pädagogik\Fachsitzungen\18. Fachsitzung\18. Sitzung.docx”,“numBlocks”:1,“size”:13941,“version”:[“14398397015826002487:4”,“17645276920380550982:4”]}}