Some poblems with syncthing, part II

[DonPedro]

This is a continuation of this thread:

Some problems with syncthing

Unfortunately I have four problems with syncthing. The setup is as follows: Several machines (one Windows and several Linux machines) as a client sync to a Linux based syncthing server. The clients are all configured as write only and the server is configured as read only. The clients each sync one or more directories to the server. The problems are: One Linux machine shows up as “not synchronized” and some files as “not synchronized elements”, “failed elements” and “local changed elements”.…

It was auto-closed some time ago due to me being too slow to reply cause I was very busy

I have meanwhile made some more screenshots that document the weird behavior described in mentioned link. I start with this scenario:

This is a Windows machine set to “send only” synced to a Linux machine set to “receive only”. If I compare a file mentioned claimed to be “locally changed” I get this result:

That doesn’t really confuse anybody, does it? Basically that’s what sync is for and if that didn’t work it would be a major reason for distrust!
And here’s the rights of the file, left the Windows machine, right the Linux machine:

It doesn’t matter though, the connection meanwhile is set to “ignore rights”.

Then I did like @imsodin said, I enabled the db and model logging and clicked on “reset local changes”

But unfortunately the log display stays empty:

or where is this log meant to be? SyncTrazor is running on the Windows machine, thus I expect the log to be on the Windows machine as well, or precisely what logfile do you mean? I am specifically asking because I got that wrong already before and uploaded the wrong logfile

And since the latest update of SyncTrayzor (at least I’m guessing it’s since then) I have an additional quirk:

I’m confused again. Not only that I did not change the configuration, it’s also that the first machine it’s coming up with this message is a Windows machine. The help only talks of the problem solving on Linux machines, so what do I on a Windows machine? Also I’m confused about the fact that the machines involved seem to change every few minutes.

[imsodin (Simon)]

@DonPedro

This is a Windows machine set to “send only” synced to a Linux machine set to “receive only”. If I compare a file mentioned claimed to be “locally changed” I get this result:

The screenshot you show is of receive-only folders, so I assume it’s the linux side instead.

@DonPedro

That doesn’t really confuse anybody, does it? Basically that’s what sync is for and if that didn’t work it would be a major reason for distrust!

If that’s what you want, set both sides to send-receive. Using either send- or receive-only will lead to files differing and not syncing if changed on the “wrong” side.

@DonPedro

I’m confused again. Not only that I did not change the configuration, it’s also that the first machine it’s coming up with this message is a Windows machine. The help only talks of the problem solving on Linux machines, so what do I on a Windows machine? Also I’m confused about the fact that the machines involved seem to change every few minutes.

That error should never occur on a windows machine. Are you sure it is?

@DonPedro

It doesn’t matter though, the connection meanwhile is set to “ignore rights”.

Meaning the folders on both sides are set to ignore permissions?

The problem is that it shows files as “locally added”, while your check finds them identical - right? Quite a bit of the info here is unrelated (e.g. Dateisystembeobachter error).

@DonPedro

Also I’m confused about the fact that the machines involved seem to change every few minutes.

That and other things confuse me too. Last time it was about you hitting the red revert button, and nothing happened. You don’t mention that anymore now? Please explain what you are doing and what is not working as expected again, and post screenshots of the full web UI from both sides.

[DonPedro]

I wanted to reply but I got this:
“closed 1 hour ago
This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.”
Darn, this p****s me off! I am forced to open another thread just because it’s disallowed to write an answer. I’m quite busy these days and often I don’t manage to answer quickly. I’m referring to this thread: Some problems with syncthing, part II

The screenshot you show is of receive-only folders, so I assume it’s the linux side instead.

Yes, admittedly it was written a bit confusing and not clear! Sorry for that

• The screenshot indeed shows the Linux side
• The Windows machine is set to “send-only” for the folder
• The Linux machine is set to “read-only” for the folder
• The folders are set to “ignore rights” on both, the Linux and the Windows machine
• The files have not been changed on the receiving Linux machine (a binary comparison proves it), nevertheless it shows “local changes”
• If against what I claimed a file was changed on the receiving Linux machine I would expect it to be reset if I click on “revert local changes”. But the “local changes” do return displayed on the receiving Linux machine.
• The same folder shows up as “current” on the sending Windows machine.

If that’s what you want, set both sides to send-receive. Using either send- or receive-only will lead to files differing and not syncing if changed on the “wrong” side.

• No, I want the Windows machine (send-only) to make a current one-way-sync to the Linux machine (read-only). Every night I do a backup of all folders on the Linux machine.
• I don’t want to sync back changes on the Linux machine back to the Windows machine as I’ll use it as one of the measures against Trojan horses, etc. The restore of a backup should explicitly be made manually!
• Regularly no changes at all are made on the Linux machine, it’s only purpose is to duplicate the current state of the folders and do a backup of the copy.
• As said, a binary comparison between Windows and the Linux machine also shows that the content is identical. The file rights don’t play a role here, as they’re set to “ignore” on both machines.
• As also said, if I click on “revert local changes” I expect it to go away but it reappears.

That error should never occur on a windows machine. Are you sure it is?

Does that answer this question?

Meaning the folders on both sides are set to ignore permissions?
The problem is that it shows files as “locally added”, while your check finds them identical - right?

Yes and yes, that’s the problem!

Quite a bit of the info here is unrelated (e.g. Dateisystembeobachter error).

Yes, this is a second (minor?) problem that occurred meanwhile and is not connected to the “local changes” problem.

That and other things confuse me too. Last time it was about you hitting the red revert button, and nothing happened. You don’t mention that anymore now? Please explain what you are doing and what is not working as expected again, and post screenshots of the full web UI from both sides.

OK

• The effect with the “revert local changes” is still there. I just didn’t mention it
• I do very little. Some (not only one!) folders have the status “local changes”, I hit “the red button”, for the moment the state changes to “current” but then it returns back to “local changes”.
• Regarding the screenshot see below, the Linux screenshot is without the error messages as it didn’t fit on the screen.
  
  
  
  
  
  
  
  

[imsodin (Simon)]

Thanks for the thorough infos, that clears things up

The “Dateisystembeobachter” errors are unrelated. To fix that, follow the instructions in the link given in the error.

@DonPedro

• I do very little. Some (not only one!) folders have the status “local changes”, I hit “the red button”, for the moment the state changes to “current” but then it returns back to “local changes”.

That’s the relevant bit (the bug). Please enable model and db debug logging (menu actions>logs) and then hit “the red button” again. Then copy the logs of that here. I hope that will contain info on what happens here and bring us closer to fixing it.

[DonPedro]

@imsodin

That’s the relevant bit (the bug). Please enable model and db debug logging (menu actions>logs) and then hit “the red button” again. Then copy the logs of that here. I hope that will contain info on what happens here and bring us closer to fixing it.

Unfortunately not!

• I switched on db and model log:
  

  
  
  

• I click on close

• I hit “the red button”

• I wait til the process is finished, it says “current now”

• I open up the protocol and I see - nothing:
  

  

• When I activate any debug option the existing log seems to be cleared!

• Please also note the message at the bottom of the screenshot. This cannot be made go away. Maybe this is another problem/bug? I cannot scroll down as the log is empty!

• Also note that after you copied my post to the owning thread I edited the post and added another screenshot (which is kind of weird as well).

[imsodin (Simon)]

@DonPedro

Please also note the message at the bottom of the screenshot. This cannot be made go away. Maybe this is another problem/bug? I cannot scroll down as the log is empty!

Yes: GUI log tailing is broken if closed when scrolled up · Issue #7267 · syncthing/syncthing · GitHub

The log should reappear if you reload the web UI. And depending on how you start Syncthing logs are also written to a file or the system log.

@DonPedro

I wait til the process is finished, it says “current now”

So that’s different from before? :

@DonPedro

I do very little. Some (not only one!) folders have the status “local changes”, I hit “the red button”, for the moment the state changes to “current” but then it returns back to “local changes”.

[DonPedro]

@imsodin

Yes: GUI log tailing is broken if closed when scrolled up · Issue #7267 · syncthing/syncthing · GitHub

OK

Good news, the error message is gone after I replaced the default value of 8192 by a larger value, thumbs up!

The log should reappear if you reload the web UI. And depending on how you start Syncthing logs are also written to a file or the system log.

Tried to reload and yes, this seems to be a WAR, see the log below.

So that’s different from before? :

No, it just changes to “current” for a short time, then returns to “local changes”.

Here’s the log you’ve been waiting for. Please note that I have replaced some paths, names and IDs with “…” to not unnecessarily spread this information in the forum.

----8<----

2021-02-11 01:32:50 Enabled debug data for “db”
2021-02-11 01:32:52 Enabled debug data for “model”
2021-02-11 01:33:13 receiveonly/…\CD’s@0x2b5f860 Running something due to request
2021-02-11 01:33:13 Reverting folder 0x865e44
2021-02-11 01:33:13 …\CD’s Snapshot()
2021-02-11 01:33:13 …\CD’s WithHave(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-02-11 01:33:16 …\CD’s Update(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, [3])
2021-02-11 01:33:16 removing sequence; folder=“…\CD’s” sequence=4299 Whitesnake - 1987/Back.jpg
2021-02-11 01:33:16 insert (local); folder=“…\CD’s” File{Name:“Whitesnake - 1987/Back.jpg”, Sequence:4301, Permissions:0644, ModTime:2014-11-09 00:09:08.2376226 +0100 CET, Version:{}, VersionHash:, Length:195738, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:true, BlockSize:131072, Blocks:[Block{0/131072/1669508084/65df21e2b6ca8acc0e1be9a631dfbcfa44e617f3841c7f3b03b28778b3604062} Block{131072/64666/3455991983/a97ef631d658825f2f6b887c31e3625345e7e10ce0b224801caf1ed114737403}], BlocksHash:5f5f62bc7c6e7230263f60a78bcfc1d921bcfe10d8e66d99ea922969190575df}
2021-02-11 01:33:16 update global; folder=“…\CD’s” device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file=“Whitesnake - 1987/Back.jpg” version={} invalid=false
2021-02-11 01:33:16 new global for “Whitesnake - 1987/Back.jpg” after update: {{Version:{[{A25MLZC 22} {YTF3NI5 21}]}, Deleted:false, Devices:{A25MLZC}, Invalid:{}}, {Version:{}, Deleted:false, Devices:{7777777}, Invalid:{}}}
2021-02-11 01:33:16 local need insert; folder=“…\CD’s”, name=“Whitesnake - 1987/Back.jpg”
2021-02-11 01:33:16 adding sequence; folder=“…\CD’s” sequence=4301 Whitesnake - 1987/Back.jpg
2021-02-11 01:33:16 removing sequence; folder=“…\CD’s” sequence=4300 Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp
2021-02-11 01:33:16 insert (local); folder=“…\CD’s” File{Name:“Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp”, Sequence:4302, Permissions:0644, ModTime:2014-11-09 00:09:08.2376226 +0100 CET, Version:{}, VersionHash:, Length:195738, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:true, BlockSize:131072, Blocks:[Block{0/131072/1669508084/65df21e2b6ca8acc0e1be9a631dfbcfa44e617f3841c7f3b03b28778b3604062} Block{131072/64666/3455991983/a97ef631d658825f2f6b887c31e3625345e7e10ce0b224801caf1ed114737403}], BlocksHash:5f5f62bc7c6e7230263f60a78bcfc1d921bcfe10d8e66d99ea922969190575df}
2021-02-11 01:33:16 update global; folder=“…\CD’s” device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file=“Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp” version={} invalid=false
2021-02-11 01:33:16 new global for “Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp” after update: {{Version:{[{A25MLZC 19} {YTF3NI5 18}]}, Deleted:false, Devices:{A25MLZC}, Invalid:{}}, {Version:{}, Deleted:false, Devices:{7777777}, Invalid:{}}}
2021-02-11 01:33:16 local need insert; folder=“…\CD’s”, name=“Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp”
2021-02-11 01:33:16 adding sequence; folder=“…\CD’s” sequence=4302 Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp
2021-02-11 01:33:16 removing sequence; folder=“…\CD’s” sequence=4298 Whitesnake - 1987/Front.jpg
2021-02-11 01:33:16 insert (local); folder=“…\CD’s” File{Name:“Whitesnake - 1987/Front.jpg”, Sequence:4303, Permissions:0644, ModTime:2014-11-09 00:09:08.1907183 +0100 CET, Version:{}, VersionHash:, Length:323930, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:true, BlockSize:131072, Blocks:[Block{0/131072/2714418030/ee29e23e2dccfb51876bd293740a731aea3996604ee763938677920de6e0d5d0} Block{131072/131072/2138264052/1e1c11f129ca46746c37be8389eeb545f1ec8a7d30584175a5736afe5e5917fa} Block{262144/61786/226788982/89a336ba503e602a1b879ea2f27c3bcc79b583534c00d3ff2529518815562a24}], BlocksHash:3b592fc3349805abbee0269b54d5d5f2b3a43fcae57b5dac93a1e7910dca8d86}
2021-02-11 01:33:16 update global; folder=“…\CD’s” device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file=“Whitesnake - 1987/Front.jpg” version={} invalid=false
2021-02-11 01:33:16 new global for “Whitesnake - 1987/Front.jpg” after update: {{Version:{[{A25MLZC 14} {YTF3NI5 12}]}, Deleted:false, Devices:{A25MLZC}, Invalid:{}}, {Version:{}, Deleted:false, Devices:{7777777}, Invalid:{}}}
2021-02-11 01:33:16 local need insert; folder=“…\CD’s”, name=“Whitesnake - 1987/Front.jpg”
2021-02-11 01:33:16 adding sequence; folder=“…\CD’s” sequence=4303 Whitesnake - 1987/Front.jpg
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 …\CD’s WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 …\CD’s WithHaveSequence(4301)
2021-02-11 01:33:16 indexSender@0x5b710e0 for …\CD’s to … at 192.168.178.11:22000-192.168.178.20:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256: Sending 3 files (<673 bytes)
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 pulling
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 copiers: 2 pullerPendingKiB: 32768
2021-02-11 01:33:16 …\CD’s WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-02-11 01:33:16 …\CD’s Get(Whitesnake - 1987/Back.jpg)
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 taking shortcut on Whitesnake - 1987/Back.jpg
2021-02-11 01:33:16 …\CD’s Get(Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp)
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 taking shortcut on Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp
2021-02-11 01:33:16 …\CD’s Get(Whitesnake - 1987/Front.jpg)
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 taking shortcut on Whitesnake - 1987/Front.jpg
2021-02-11 01:33:16 …\CD’s Update(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, [3])
2021-02-11 01:33:16 removing sequence; folder=“…\CD’s” sequence=4301 Whitesnake - 1987/Back.jpg
2021-02-11 01:33:16 insert (local); folder=“…\CD’s” File{Name:“Whitesnake - 1987/Back.jpg”, Sequence:4304, Permissions:0644, ModTime:2014-11-09 00:02:46.3646271 +0100 CET, Version:{[{A25MLZC 22} {YTF3NI5 21}]}, VersionHash:, Length:195738, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:false, BlockSize:0, Blocks:[Block{0/131072/0/65df21e2b6ca8acc0e1be9a631dfbcfa44e617f3841c7f3b03b28778b3604062} Block{131072/64666/0/a97ef631d658825f2f6b887c31e3625345e7e10ce0b224801caf1ed114737403}], BlocksHash:5b565f973821fb81a7c2ae57d3700a3dd2a2fa225d4145b429f0adb7cc50bcd9}
2021-02-11 01:33:16 update global; folder=“…\CD’s” device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file=“Whitesnake - 1987/Back.jpg” version={[{A25MLZC 22} {YTF3NI5 21}]} invalid=false
2021-02-11 01:33:16 new global for “Whitesnake - 1987/Back.jpg” after update: {{Version:{[{A25MLZC 22} {YTF3NI5 21}]}, Deleted:false, Devices:{A25MLZC, 7777777}, Invalid:{}}}
2021-02-11 01:33:16 local need delete; folder=“…\CD’s”, name=“Whitesnake - 1987/Back.jpg”
2021-02-11 01:33:16 adding sequence; folder=“…\CD’s” sequence=4304 Whitesnake - 1987/Back.jpg
2021-02-11 01:33:16 removing sequence; folder=“…\CD’s” sequence=4302 Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp
2021-02-11 01:33:16 insert (local); folder=“…\CD’s” File{Name:“Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp”, Sequence:4305, Permissions:0644, ModTime:2014-11-09 00:09:08.221968 +0100 CET, Version:{[{A25MLZC 19} {YTF3NI5 18}]}, VersionHash:, Length:195738, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:false, BlockSize:0, Blocks:[Block{0/131072/0/65df21e2b6ca8acc0e1be9a631dfbcfa44e617f3841c7f3b03b28778b3604062} Block{131072/64666/0/a97ef631d658825f2f6b887c31e3625345e7e10ce0b224801caf1ed114737403}], BlocksHash:5b565f973821fb81a7c2ae57d3700a3dd2a2fa225d4145b429f0adb7cc50bcd9}
2021-02-11 01:33:16 update global; folder=“…\CD’s” device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file=“Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp” version={[{A25MLZC 19} {YTF3NI5 18}]} invalid=false
2021-02-11 01:33:16 new global for “Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp” after update: {{Version:{[{A25MLZC 19} {YTF3NI5 18}]}, Deleted:false, Devices:{A25MLZC, 7777777}, Invalid:{}}}
2021-02-11 01:33:16 local need delete; folder=“…\CD’s”, name=“Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp”
2021-02-11 01:33:16 adding sequence; folder=“…\CD’s” sequence=4305 Whitesnake - 1987/Cover.ncd.Files/Cover_8.bmp
2021-02-11 01:33:16 removing sequence; folder=“…\CD’s” sequence=4303 Whitesnake - 1987/Front.jpg
2021-02-11 01:33:16 insert (local); folder=“…\CD’s” File{Name:“Whitesnake - 1987/Front.jpg”, Sequence:4306, Permissions:0644, ModTime:2014-11-09 00:01:37.5283039 +0100 CET, Version:{[{A25MLZC 14} {YTF3NI5 12}]}, VersionHash:, Length:323930, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:false, BlockSize:0, Blocks:[Block{0/131072/0/ee29e23e2dccfb51876bd293740a731aea3996604ee763938677920de6e0d5d0} Block{131072/131072/0/1e1c11f129ca46746c37be8389eeb545f1ec8a7d30584175a5736afe5e5917fa} Block{262144/61786/0/89a336ba503e602a1b879ea2f27c3bcc79b583534c00d3ff2529518815562a24}], BlocksHash:9d99ba77d1055dff34bacb229221e3a5024e715c9625cb849f0597817b69d705}
2021-02-11 01:33:16 update global; folder=“…\CD’s” device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file=“Whitesnake - 1987/Front.jpg” version={[{A25MLZC 14} {YTF3NI5 12}]} invalid=false
2021-02-11 01:33:16 new global for “Whitesnake - 1987/Front.jpg” after update: {{Version:{[{A25MLZC 14} {YTF3NI5 12}]}, Deleted:false, Devices:{A25MLZC, 7777777}, Invalid:{}}}
2021-02-11 01:33:16 local need delete; folder=“…\CD’s”, name=“Whitesnake - 1987/Front.jpg”
2021-02-11 01:33:16 adding sequence; folder=“…\CD’s” sequence=4306 Whitesnake - 1987/Front.jpg
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 changed 3 on try 1
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 copiers: 2 pullerPendingKiB: 32768
2021-02-11 01:33:16 …\CD’s WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-02-11 01:33:16 receiveonly/…\CD’s@0x2b5f860 changed 0 on try 2
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 progress emitter: bytes completed for …\CD’s: 0
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 model@0x2483420 Completion(A25MLZC-OAMHMRS-WJ4JMHA-GLDYMYL-RE4VLR4-YOVJLBM-2RAOBNU-U7OOSAB, “…\CD’s”): map[completion:100 globalBytes:23069879712 globalItems:568 needBytes:0 needDeletes:0 needItems:0 sequence:3766]
2021-02-11 01:33:16 …\CD’s Snapshot()
2021-02-11 01:33:16 …\CD’s WithHaveSequence(4304)
2021-02-11 01:33:16 indexSender@0x5b710e0 for …\CD’s to … at 192.168.178.11:22000-192.168.178.20:22000/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256: Sending 3 files (<670 bytes)

[imsodin (Simon)]

@DonPedro

No, it just changes to “current” for a short time, then returns to “local changes”.

Here’s the log you’ve been waiting for. Please note that I have replaced some paths, names and IDs with “…” to not unnecessarily spread this information in the forum.

That log shows how the files are reverted, and then brought back in sync. At the end of the log, your folder was “up-to-date”. You describe that after a short time, it goes back to “local changes”. I need the logs including that process.

[DonPedro]

I’ll try to supply this log. But until now I don’t when exactly this does happen.

What is also inconsistent: Here you can see the state of syncthing as of today:

• You see that it says “revert local changes” again.
• It says the last action it did was to delete a file named “Cover_8.bmp”.
• The date of this action is very weird (27.9.2020), the last scan happened yesterday and the time on the Linux and on the Windows machine is correct, I just checked it and additionally a timeserver on the internet is used by all machines.
• If you look at the send-only Windows machine this file does exist, no reason to delete it:
  
  
• If you look at the receive only Linux machine the file does exist as well:
  
  So it did not delete it as well. What is the meaning behind all this?

[DonPedro]

I think I found out when the reset of the state is taking place! What I tried:

• I click on “Revert local changes”:
  
  
  You see that now it says “Current”
• If I now click on “new scan” it returns to the old state, “local changes”
  

I’ll try to log what it does during “new scan”. Do I also have to enable db and model or is there any else to log?

[DonPedro]

Hm, I tried to log two times but nothing was logged for the affected folder… Can you say what to log?

[imsodin (Simon)]

@DonPedro

I’ll try to log what it does during “new scan”. Do I also have to enable db and model or is there any else to log?

Again, db and model please.

@DonPedro

Hm, I tried to log two times but nothing was logged for the affected folder… Can you say what to log?

Above you posted a whole bunch of lines - similar ones should be logged when you it goes from “Aktuell” (up-to-date instead of current in the English UI ) to “Lokale Hinzufügungen”.

[DonPedro]

Hello, I finally managed to get the logs. I removed everything that belongs to another machine or to another folder. Also I replaced everything that has some personal information by “???”. I switched on logging, clicked on reset, waited till the surface announced “current”, clicked on rescan, waited till the surface announced “local changes”, switched off logging again and finally switched off logging. Here it is:

---------8<----------

2021-03-15 18:45:16 ???\??? Snapshot()
2021-03-15 18:45:16 ???\??? WithHaveSequence(399)
2021-03-15 18:45:16 ???\??? WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-03-15 18:45:16 ???\??? Get(???/VIDEO_TS.BUP)
2021-03-15 18:45:16 receiveonly/???\???@0x23a9860 taking shortcut on ???/VIDEO_TS.BUP
2021-03-15 18:45:16 ???\??? Get(???/VTS_01_0.BUP)
2021-03-15 18:45:16 indexSender@0x251d6d0 for ???\??? to ???-???-???-???-???-???-???-??? at 192.168.178.34:22000-192.168.178.20:22000/tcp-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256: Sending 2 files (<334 bytes)
2021-03-15 18:45:16 receiveonly/???\???@0x23a9860 taking shortcut on ???/VTS_01_0.BUP
2021-03-15 18:45:16 ???\??? Update(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, [2])
2021-03-15 18:45:16 removing sequence; folder="???\\???" sequence=399 ???/VIDEO_TS.BUP
2021-03-15 18:45:16 insert (local); folder="???\\???" File{Name:"???/VIDEO_TS.BUP", Sequence:401, Permissions:0644, ModTime:2010-01-30 23:01:09 +0100 CET, Version:{[{??? 14} {YTF3NI5 13}]}, VersionHash:, Length:6144, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:false, BlockSize:0, Blocks:[Block{0/6144/0/5e25d607fbaebff6bfa313f114ca76c307f5ae0b6abe82e3fe4d991755db5977}], BlocksHash:02cd40cb053624aa4df429e7a5d308fff3ff34c250b1f2b4c7554c3c21766d35}
2021-03-15 18:45:16 update global; folder="???\\???" device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file="???/VIDEO_TS.BUP" version={[{??? 14} {YTF3NI5 13}]} invalid=false
2021-03-15 18:45:16 new global for "???/VIDEO_TS.BUP" after update: {{Version:{[{??? 14} {YTF3NI5 13}]}, Deleted:false, Devices:{???, 7777777}, Invalid:{}}}
2021-03-15 18:45:16 local need delete; folder="???\\???", name="???/VIDEO_TS.BUP"
2021-03-15 18:45:16 adding sequence; folder="???\\???" sequence=401 ???/VIDEO_TS.BUP
2021-03-15 18:45:16 removing sequence; folder="???\\???" sequence=400 ???/VTS_01_0.BUP
2021-03-15 18:45:16 insert (local); folder="???\\???" File{Name:"???/VTS_01_0.BUP", Sequence:402, Permissions:0644, ModTime:2010-01-30 23:01:13.84375 +0100 CET, Version:{[{??? 14} {YTF3NI5 13}]}, VersionHash:, Length:88064, Deleted:false, Invalid:false, LocalFlags:0x0, NoPermissions:false, BlockSize:0, Blocks:[Block{0/88064/0/3009b8968e103133c5e54c90b07d35dc3b5201a370bd6cbad6f7f63104c06b2a}], BlocksHash:fb6c7de7c8f88a8c0cdc2a73bb60b42a60d948128b4a1e87f9b9ac9287c81869}
2021-03-15 18:45:16 update global; folder="???\\???" device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file="???/VTS_01_0.BUP" version={[{??? 14} {YTF3NI5 13}]} invalid=false
2021-03-15 18:45:16 new global for "???/VTS_01_0.BUP" after update: {{Version:{[{??? 14} {YTF3NI5 13}]}, Deleted:false, Devices:{???, 7777777}, Invalid:{}}}
2021-03-15 18:45:16 local need delete; folder="???\\???", name="???/VTS_01_0.BUP"
2021-03-15 18:45:16 adding sequence; folder="???\\???" sequence=402 ???/VTS_01_0.BUP
2021-03-15 18:45:16 receiveonly/???\???@0x23a9860 changed 2 on try 1
2021-03-15 18:45:16 ???\??? Snapshot()
2021-03-15 18:45:16 receiveonly/???\???@0x23a9860 copiers: 2 pullerPendingKiB: 32768
2021-03-15 18:45:16 ???\??? WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-03-15 18:45:16 receiveonly/???\???@0x23a9860 changed 0 on try 2
2021-03-15 18:45:16 ???\??? Snapshot()
2021-03-15 18:45:16 progress emitter: bytes completed for ???\???: 0
2021-03-15 18:45:16 ???\??? Snapshot()
2021-03-15 18:45:16 model@0x2083a40 Completion(???-???-???-???-???-???-???-???, "???\\???"): map[completion:100 globalBytes:44833844244 globalItems:119 needBytes:0 needDeletes:0 needItems:0 sequence:208]
2021-03-15 18:45:16 ???\??? Snapshot()
2021-03-15 18:45:16 ???\??? WithHaveSequence(401)
2021-03-15 18:45:16 indexSender@0x251d6d0 for ???\??? to ???-???-???-???-???-???-???-??? at 192.168.178.34:22000-192.168.178.20:22000/tcp-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256: Sending 2 files (<340 bytes)
2021-03-15 18:45:19 progress emitter: timer - looking after 9
2021-03-15 18:45:19 progress emitter: emitting map[string]map[string]*model.pullerProgress{}
2021-03-15 18:45:19 receiveonly/???\???@0x23a9860 Running something due to request
2021-03-15 18:45:19 receiveonly/???\???@0x23a9860 scanning
2021-03-15 18:45:19 ???\??? Snapshot()
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???/Subs)
2021-03-15 18:45:19 ???\??? Get(???/Subs/???.sfv)
2021-03-15 18:45:19 ???\??? Get(???/Subs/???.rar)
2021-03-15 18:45:19 ???\??? Get(???/???.avi)
2021-03-15 18:45:19 ???\??? Get(???)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E16 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E08 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E13 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E07 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E14 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E02 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E04 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E21 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E18 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E20 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E12 ???.avi)
2021-03-15 18:45:19 ???\??? Get(???/??? S01E22 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E09 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E15 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E06 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E11 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E05 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E10 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E01 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E17 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E19 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???/??? S01E03 ???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/posterlg01512eab14jpg.jpg)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_0.BUP)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_2.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VIDEO_TS.IFO)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_4.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_1.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VIDEO_TS.BUP)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_3.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_0.IFO)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_0.BUP)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_2.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VIDEO_TS.IFO)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_4.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_1.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VIDEO_TS.BUP)
2021-03-15 18:45:20 ???\??? Get(???/VIDEO_TS.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_3.VOB)
2021-03-15 18:45:20 ???\??? Get(???/VTS_01_0.IFO)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:20 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? Get(Jumper)
2021-03-15 18:45:21 ???\??? Get(Jumper/???.img)
2021-03-15 18:45:21 ???\??? Get(Postal)
2021-03-15 18:45:21 ???\??? Get(Postal/???.avi)
2021-03-15 18:45:21 ???\??? Get(Postal/???.avi)
2021-03-15 18:45:21 ???\??? Get(???)
2021-03-15 18:45:21 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? Get(???)
2021-03-15 18:45:21 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? Get(???)
2021-03-15 18:45:21 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? Get(???)
2021-03-15 18:45:21 ???\??? Get(???/???.jpg)
2021-03-15 18:45:21 ???\??? Get(???/???.avi)
2021-03-15 18:45:21 ???\??? GetGlobal(???/VIDEO_TS.IFO)
2021-03-15 18:45:21 ???\??? GetGlobal(???/VTS_01_0.IFO)
2021-03-15 18:45:21 ???\??? Update(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, [2])
2021-03-15 18:45:21 removing sequence; folder="???\\???" sequence=395 ???/VIDEO_TS.IFO
2021-03-15 18:45:21 insert (local); folder="???\\???" File{Name:"???/VIDEO_TS.IFO", Sequence:403, Permissions:0644, ModTime:2010-01-30 23:01:09 +0100 CET, Version:{[{??? 14} {YTF3NI5 1615830320}]}, VersionHash:, Length:6144, Deleted:false, Invalid:false, LocalFlags:0x8, NoPermissions:true, BlockSize:131072, Blocks:[Block{0/6144/1976110660/5e25d607fbaebff6bfa313f114ca76c307f5ae0b6abe82e3fe4d991755db5977}], BlocksHash:2b9b616963f523c664ca0e229fbae23436a3528c9c794d4db0712c00791cf69e}
2021-03-15 18:45:21 update global; folder="???\\???" device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file="???/VIDEO_TS.IFO" version={[{??? 14} {YTF3NI5 1615830320}]} invalid=true
2021-03-15 18:45:21 new global for "???/VIDEO_TS.IFO" after update: {{Version:{[{??? 14} {YTF3NI5 1615830320}]}, Deleted:false, Devices:{}, Invalid:{7777777}}, {Version:{[{??? 14} {YTF3NI5 13}]}, Deleted:false, Devices:{???}, Invalid:{}}}
2021-03-15 18:45:21 adding sequence; folder="???\\???" sequence=403 ???/VIDEO_TS.IFO
2021-03-15 18:45:21 removing sequence; folder="???\\???" sequence=396 ???/VTS_01_0.IFO
2021-03-15 18:45:21 insert (local); folder="???\\???" File{Name:"???/VTS_01_0.IFO", Sequence:404, Permissions:0644, ModTime:2010-01-30 23:01:13.84375 +0100 CET, Version:{[{??? 14} {YTF3NI5 1615830320}]}, VersionHash:, Length:88064, Deleted:false, Invalid:false, LocalFlags:0x8, NoPermissions:true, BlockSize:131072, Blocks:[Block{0/88064/2679341998/3009b8968e103133c5e54c90b07d35dc3b5201a370bd6cbad6f7f63104c06b2a}], BlocksHash:f73b68c04c8c5b21a5c57cec998f208a94006752a9120307824a877cc8000d65}
2021-03-15 18:45:21 update global; folder="???\\???" device=7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4 file="???/VTS_01_0.IFO" version={[{??? 14} {YTF3NI5 1615830320}]} invalid=true
2021-03-15 18:45:21 new global for "???/VTS_01_0.IFO" after update: {{Version:{[{??? 14} {YTF3NI5 1615830320}]}, Deleted:false, Devices:{}, Invalid:{7777777}}, {Version:{[{??? 14} {YTF3NI5 13}]}, Deleted:false, Devices:{???}, Invalid:{}}}
2021-03-15 18:45:21 adding sequence; folder="???\\???" sequence=404 ???/VTS_01_0.IFO
2021-03-15 18:45:21 ???\??? Snapshot()
2021-03-15 18:45:21 ???\??? WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, "")
2021-03-15 18:45:21 ???\??? Snapshot()
2021-03-15 18:45:21 ???\??? WithHaveSequence(403)
2021-03-15 18:45:21 indexSender@0x251d6d0 for ???\??? to ???-???-???-???-???-???-???-??? at 192.168.178.34:22000-192.168.178.20:22000/tcp-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256: Sending 2 files (<332 bytes)
2021-03-15 18:45:21 receiveonly/???\???@0x23a9860 finished scanning, detected 2 changes
2021-03-15 18:45:21 ???\??? Snapshot()
2021-03-15 18:45:21 ???\??? WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2021-03-15 18:45:23 ???\??? Snapshot()
2021-03-15 18:45:23 progress emitter: bytes completed for ???\???: 0
2021-03-15 18:45:23 ???\??? Snapshot()
2021-03-15 18:45:23 model@0x2083a40 Completion(???-???-???-???-???-???-???-???, "???\\???"): map[completion:100 globalBytes:44833844244 globalItems:119 needBytes:0 needDeletes:0 needItems:0 sequence:208]

[imsodin (Simon)]

There’s nothing about reverting in those logs.

[DonPedro]

It’s driving me nuts!!
Here’s what I did:

• open actions/protocol, select tab “Debugging Möglichkeiten”, switch on db and modeller, select OK
• click on “lokale Änderungen zurücksetzen” and wait till the GUI says “Aktuell” for some of the affected folders
• click on “neu scannen” and wait till the GUI says “lokale Hinzufügungen”
• open actions/protocol again, select tab “Protokolle”, type Ctrl-A, Ctrl-C
• select tab “Debugging Möglichkeiten” and switch off db and modeller
• paste the result in notepad++, delete all info related to other machines or folders, replace all personal info with “???”

Questions and remarks:

• Anything wrong here?
• What do I have to look for to make sure the information is relevant contained in the log I post?
• Some strange behavior I realized lastly: Despite the fact that files on sender and receiver are identical (or at least should be as soon as I click on “lokale Änderungen zurücksetzten” on the receiver) the effect goes away as soon as I manually copy the file from sender to receiver for that respective file (the others, non-copied files stay in their behavior)

[imsodin (Simon)]

The log buffer in the UI was full. You are running on windows, right? Then the full logs should be written to your syncthing config folder.