New user. MBP M1 - Sonoma 14.2.1 (23C71). Iphone SE 3rd gen - iOS 17.1.2. Permissions have been granted in my MBP to Syncthing to allow it to make external connections fyi.
I’m having an issue where both devices are connected and have successfully shared a folder. I can create new files on each and they are properly synced between them regardless of which device adds the new file. But when I make edits from my iPhone to a file that is already on both devices it immediately puts the synced folder on my MBP out of sync. It’s strange.
I’ve tried with the Syncthing-Mac app and CLI. I initially made some configurations to the install that following successive attempts to resolve this issue, I undid by deleting, deleting the config folders (for the CLI) and reinstalling.
The error is listed as syncing: finishing: pull: generic error, and in the logs it says
2024-01-18 16:17:56 Puller (folder "sync" (k4ayy-qzl2j), item "test"): syncing: no connected device has the required version of this file
Since i was able to identify what causes syncing to fail, I’ve captured the logs from before and after I introduced a change in a file from my iphone (called test.org). Logs below.
Would love to be able to resolve this issue so I can use what sounds like a fantastic tool that is highly regarded on the forums.
logs from before syncing stopped which still caught my eye (e.g.: Why is there the parsing time error? Why would something have a date stamp from 2006? Do the sendmsg errors matter ("can't assign requested address on write to [ff12::8384]:21027..."), etc.)
2024-01-18 16:58:49 parsing time "" as "2006-01-02T15:04:05Z07:00": cannot parse "" as "2006"[...]
2024-01-18 16:58:25 Enabled debug data for "fs"
2024-01-18 16:58:25 http: POST "/rest/system/debug?enable=fs": status 0, 0 bytes in 0.16 ms
2024-01-18 16:58:25 http: GET "/rest/system/debug": status 200, 1257 bytes in 0.39 ms
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on lo0
2024-01-18 16:58:26 recv 381 bytes from [fe80::1%lo0]:50462
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 anpi1
2024-01-18 16:58:26 addresses: [172.20.10.15]
2024-01-18 16:58:26 sent 381 bytes to 172.20.10.15:21027
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 anpi0
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 anpi2
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: can't assign requested address on write to [ff12::8384]:21027 en4
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: can't assign requested address on write to [ff12::8384]:21027 en5
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: can't assign requested address on write to [ff12::8384]:21027 en6
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 en1
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 en2
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 en3
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: invalid argument on write to [ff12::8384]:21027 bridge0
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on ap1
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: can't assign requested address on write to [ff12::8384]:21027 en0
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on llw0
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on utun0
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on utun1
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on utun2
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on utun3
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on utun5
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on en7
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on en10
2024-01-18 16:58:26 sent 381 bytes to [ff12::8384]:21027 on en11
2024-01-18 16:58:26 write udp [::]:50462->[ff12::8384]:21027: sendmsg: can't assign requested address on write to [ff12::8384]:21027 utun4
2024-01-18 16:58:27 http: GET "/rest/system/log?since=2024-01-18T16%3A58%3A24.25028%2B01%3A00": status 200, 5282 bytes in 0.23 ms
2024-01-18 16:58:28 Enabled debug data for "main"
[...]
Logs from when change was introduced in file and syncing stopped. The file to look for is `test.org`
[...]
2024-01-18 17:12:45 Not dialing VK66KO7 via relay://45.14.233.128:22067/?globalLimitBps=0&id=V2T4PS5-FYCFHSO-SVTIOEW-WXHZLHM-ATL4CWK-ZQCJ6LE-W54T7UA-SOFBOQ2&networkTimeout=2m0s&pingInterval=1m0s&providedBy=vidister&sessionLimitBps=0&statusAddr=%3A22070 as it's not time yet
2024-01-18 17:12:45 Not dialing VK66KO7 via relay://83.240.51.14:443/?globalLimitBps=20000000&id=NCIJELL-4BNAKN3-GABVCNA-VNEFTM2-M5ELGSD-CNNOZQV-DFXPZ2B-LSKM3QA&networkTimeout=2m0s&pingInterval=1m0s&providedBy=chucic&statusAddr=%3A22070 as it's not time yet
2024-01-18 17:12:45 Not dialing VK66KO7 at tcp://146.70.184.26:22000 using TCP Dialer as priority is worse than current connection (30 > 20)
2024-01-18 17:12:45 Not dialing VK66KO7 at tcp://192.168.8.106:22000 using TCP Dialer as priority is worse than current connection (30 > 20)
2024-01-18 17:12:45 Not dialing VK66KO7 at tcp://[2001:ac8:25:3a::e010]:22000 using TCP Dialer as priority is worse than current connection (30 > 20)
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://10.143.62.13:22000 prio 10
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://10.253.37.238:22000 prio 10
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://169.254.192.64:22000 prio 10
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://172.20.10.1:22000 prio 10
2024-01-18 17:12:45 Dialing reuse result tcp 169.254.192.64:22000: &{{0x140005f4080}} <nil>
2024-01-18 17:12:45 Dial (BEP/tcp): setting traffic class: setsockopt: invalid argument
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://169.254.192.64:22000 success: 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-
2024-01-18 17:12:45 connected to VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO 10 using 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10- 10
2024-01-18 17:12:45 Next connection loop in 52.09213525s
2024-01-18 17:12:45 Dialing reuse result tcp 10.143.62.13:22000: <nil> dial tcp [::]:22000->10.143.62.13:22000: operation was canceled
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://10.143.62.13:22000 error: context canceled
2024-01-18 17:12:45 Dialing reuse result tcp 10.253.37.238:22000: <nil> dial tcp [::]:22000->10.253.37.238:22000: operation was canceled
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://10.253.37.238:22000 error: context canceled
2024-01-18 17:12:45 Dialing reuse result tcp 172.20.10.1:22000: <nil> dial tcp [::]:22000->172.20.10.1:22000: operation was canceled
2024-01-18 17:12:45 dialing VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO tcp://172.20.10.1:22000 error: context canceled
2024-01-18 17:12:45 discarding 0 connections while connecting to VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO 10
2024-01-18 17:12:45 Added connection for VK66KO7 (now 2), they want 0 connections
2024-01-18 17:12:45 Closing connection 169.254.230.66:22000-169.254.192.64:22000/quic-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P20-2ULNQ18TPCD5GT7SQ62C0QHVGA to VK66KO7 with priority 20 (cutoff 10)
2024-01-18 17:12:45 Established secure connection to VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E
2024-01-18 17:12:45 Additional connection (+1) for device VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E
2024-01-18 17:12:45 stats.DeviceStatisticsReference.WasSeen: VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO
2024-01-18 17:12:45 Writing close
2024-01-18 17:12:45 wrote 30 bytes on the wire (2 bytes length, 2 bytes header, 4 bytes message length, 22 bytes message), err=<nil>
2024-01-18 17:12:45 close connection to VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/quic-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P20-2ULNQ18TPCD5GT7SQ62C0QHVGA due to replacing connection
2024-01-18 17:12:45 log 312 DeviceConnected map[addr:169.254.192.64:22000 clientName:syncthing clientVersion:v1.23.2-41-ga6a9adeeb-i deviceName:localhost id:VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO type:tcp-client]
2024-01-18 17:12:45 Removed connection for VK66KO7 (now [{{1374399193952 1374399193952 1374486676224 1374389593984}}])
2024-01-18 17:12:45 Connection loop
2024-01-18 17:12:45 stats.DeviceStatisticsReference.GetLastSeen: VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO 2024-01-18 17:12:45 +0100 WAT
2024-01-18 17:12:45 stats.DeviceStatisticsReference.GetLastConnectionDuration: VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO 913355683000
2024-01-18 17:12:45 Skipping dial to VK66KO7 because we already have 1 connections and our best dialer is not better than 10
2024-01-18 17:12:45 Next connection loop in 52.064850708s
2024-01-18 17:12:45 Exiting index handler for k4ayy-qzl2j to VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/quic-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P20-2ULNQ18TPCD5GT7SQ62C0QHVGA: context canceled
2024-01-18 17:12:45 unsubscribe Unknown
2024-01-18 17:12:45 stats.DeviceStatisticsReference.LastConnectionDuration: VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO 21.38474s
2024-01-18 17:12:45 stats.DeviceStatisticsReference.WasSeen: VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO
2024-01-18 17:12:45 Lost primary connection to VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/quic-server/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P20-2ULNQ18TPCD5GT7SQ62C0QHVGA: replacing connection (1 remain)
2024-01-18 17:12:45 http: GET "/rest/events?since=305": status 200, 407 bytes in 19428.37 ms
2024-01-18 17:12:46 http: GET "/rest/system/error": status 200, 21 bytes in 0.47 ms
2024-01-18 17:12:46 http: GET "/rest/system/connections": status 200, 919 bytes in 0.08 ms
2024-01-18 17:12:46 http: GET "/rest/system/discovery": status 200, 2025 bytes in 0.07 ms
2024-01-18 17:12:46 http: GET "/rest/system/status": status 200, 6463 bytes in 1.00 ms
2024-01-18 17:12:46 promotion timer fired
2024-01-18 17:12:46 k4ayy-qzl2j IndexID(VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO)
2024-01-18 17:12:46 k4ayy-qzl2j IndexID(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2024-01-18 17:12:46 Promoting connection to VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E
2024-01-18 17:12:46 handle cluster-config message
2024-01-18 17:12:46 Handling ClusterConfig from VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E
2024-01-18 17:12:46 Writing cluster-config
2024-01-18 17:12:46 wrote 213 bytes on the wire (2 bytes length, 0 bytes header, 4 bytes message length, 207 bytes message), err=<nil>
2024-01-18 17:12:46 Registering folder default for VK66KO7
2024-01-18 17:12:46 Removing index handler for device VK66KO7 and folder default
2024-01-18 17:12:46 Removed index handler for device VK66KO7 and folder default
2024-01-18 17:12:46 Registering folder k4ayy-qzl2j for VK66KO7
2024-01-18 17:12:46 Not resuming index handler for device VK66KO7 and folder k4ayy-qzl2j as none is paused and there is no start info
2024-01-18 17:12:46 k4ayy-qzl2j IndexID(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2024-01-18 17:12:46 Device VK66KO7 folder "emacs-sync" (k4ayy-qzl2j) is delta index compatible (mlv=9)
2024-01-18 17:12:46 k4ayy-qzl2j IndexID(VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO)
2024-01-18 17:12:46 log 313 ClusterConfigReceived {VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO}
2024-01-18 17:12:46 Starting index handler for k4ayy-qzl2j to VK66KO7 at 169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E (slv=9)
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 k4ayy-qzl2j WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2024-01-18 17:12:46 http: GET "/rest/events?since=306": status 200, 235 bytes in 990.82 ms
2024-01-18 17:12:46 k4ayy-qzl2j WithHaveSequence(10)
2024-01-18 17:12:46 subscribe Unknown
2024-01-18 17:12:46 folderconfiguration.go:120 basic /Users/toby/Sync/emacs-sync Stat . {0x1400138e270} <nil>
2024-01-18 17:12:46 folderconfiguration.go:138 basic /Users/toby/Sync/emacs-sync Stat .stfolder {0x1400138e340} <nil>
2024-01-18 17:12:46 folder.go:338 basic /Users/toby/Library/Application Support/Syncthing/index-v0.14.0.db Usage . {80949301248 494384795648} <nil>
2024-01-18 17:12:46 folderconfiguration.go:120 basic /Users/toby/Sync/emacs-sync Stat . {0x1400138e410} <nil>
2024-01-18 17:12:46 folderconfiguration.go:138 basic /Users/toby/Sync/emacs-sync Stat .stfolder {0x1400138e4e0} <nil>
2024-01-18 17:12:46 folder.go:338 basic /Users/toby/Library/Application Support/Syncthing/index-v0.14.0.db Usage . {80949301248 494384795648} <nil>
2024-01-18 17:12:46 log 314 StateChanged map[duration:21.386848 folder:k4ayy-qzl2j from:idle to:sync-waiting]
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 pulling
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 copiers: 2 pullerPendingKiB: 32768
2024-01-18 17:12:46 k4ayy-qzl2j WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2024-01-18 17:12:46 k4ayy-qzl2j Get(test.org)
2024-01-18 17:12:46 k4ayy-qzl2j GetGlobal(test.org)
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 parent not missing test.org
2024-01-18 17:12:46 k4ayy-qzl2j Availability(test.org)
2024-01-18 17:12:46 k4ayy-qzl2j Get(test.org)
2024-01-18 17:12:46 log 315 StateChanged map[duration:0.387007 folder:k4ayy-qzl2j from:sync-waiting to:sync-preparing]
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 need file test.org; copy 1, reused 0
2024-01-18 17:12:46 log 316 ItemStarted map[action:update folder:k4ayy-qzl2j item:test.org type:file]
2024-01-18 17:12:46 folderconfiguration.go:275 basic /Users/toby/Sync/emacs-sync Usage . {80949301248 494384795648} <nil>
2024-01-18 17:12:46 progress emitter: registering k4ayy-qzl2j test.org
2024-01-18 17:12:46 sharedPullerState k4ayy-qzl2j test.org pullNeeded start -> 1
2024-01-18 17:12:46 k4ayy-qzl2j Availability(test.org)
2024-01-18 17:12:46 model@0x140001aa180 REQ(out): VK66KO7 (169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E): "k4ayy-qzl2j" / "test.org" b=0 o=0 s=6 h=45dd805b0eabcd7ad5e2c5ae78971813b24d6e9c42919bf3cc300b774164d354 wh=5fb020b ft=false
2024-01-18 17:12:46 Writing request for "3.syncthing-enc/IT/N2E8GMC682LQD4VHOEGGKH94MUIKNUL1ADVG" in k4ayy-qzl2j
2024-01-18 17:12:46 log 317 StateChanged map[duration:0.388606 folder:k4ayy-qzl2j from:sync-preparing to:syncing]
2024-01-18 17:12:46 wrote 81 bytes on the wire (2 bytes length, 2 bytes header, 4 bytes message length, 73 bytes message), err=<nil>
2024-01-18 17:12:46 http: GET "/rest/events?since=307": status 200, 1010 bytes in 0.16 ms
2024-01-18 17:12:46 handle response message
2024-01-18 17:12:46 request: k4ayy-qzl2j test.org 0 6 VK66KO7 returned error: generic error
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 closing test.org
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 new error for test.org: finishing: pull: generic error
2024-01-18 17:12:46 progress emitter: deregistering k4ayy-qzl2j test.org
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 changed 1 on try 1
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 copiers: 2 pullerPendingKiB: 32768
2024-01-18 17:12:46 k4ayy-qzl2j WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2024-01-18 17:12:46 k4ayy-qzl2j Get(test.org)
2024-01-18 17:12:46 k4ayy-qzl2j GetGlobal(test.org)
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 parent not missing test.org
2024-01-18 17:12:46 k4ayy-qzl2j Availability(test.org)
2024-01-18 17:12:46 k4ayy-qzl2j Get(test.org)
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 need file test.org; copy 1, reused 0
2024-01-18 17:12:46 log 318 ItemFinished map[action:update error:0x14000853f80 folder:k4ayy-qzl2j item:test.org type:file]
2024-01-18 17:12:46 log 319 StateChanged map[duration:0.495398 folder:k4ayy-qzl2j from:syncing to:sync-preparing]
2024-01-18 17:12:46 log 320 ItemStarted map[action:update folder:k4ayy-qzl2j item:test.org type:file]
2024-01-18 17:12:46 folderconfiguration.go:275 basic /Users/toby/Sync/emacs-sync Usage . {80949297152 494384795648} <nil>
2024-01-18 17:12:46 http: GET "/rest/events?since=311": status 200, 782 bytes in 103.63 ms
2024-01-18 17:12:46 progress emitter: registering k4ayy-qzl2j test.org
2024-01-18 17:12:46 sharedPullerState k4ayy-qzl2j test.org pullNeeded start -> 1
2024-01-18 17:12:46 k4ayy-qzl2j Availability(test.org)
2024-01-18 17:12:46 model@0x140001aa180 REQ(out): VK66KO7 (169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E): "k4ayy-qzl2j" / "test.org" b=0 o=0 s=6 h=45dd805b0eabcd7ad5e2c5ae78971813b24d6e9c42919bf3cc300b774164d354 wh=5fb020b ft=false
2024-01-18 17:12:46 Writing request for "3.syncthing-enc/IT/N2E8GMC682LQD4VHOEGGKH94MUIKNUL1ADVG" in k4ayy-qzl2j
2024-01-18 17:12:46 wrote 83 bytes on the wire (2 bytes length, 2 bytes header, 4 bytes message length, 75 bytes message), err=<nil>
2024-01-18 17:12:46 log 321 StateChanged map[duration:0.49746 folder:k4ayy-qzl2j from:sync-preparing to:syncing]
2024-01-18 17:12:46 handle response message
2024-01-18 17:12:46 request: k4ayy-qzl2j test.org 0 6 VK66KO7 returned error: generic error
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 closing test.org
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 new error for test.org: finishing: pull: generic error
2024-01-18 17:12:46 progress emitter: deregistering k4ayy-qzl2j test.org
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 changed 1 on try 2
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 copiers: 2 pullerPendingKiB: 32768
2024-01-18 17:12:46 k4ayy-qzl2j WithNeed(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
2024-01-18 17:12:46 k4ayy-qzl2j Get(test.org)
2024-01-18 17:12:46 k4ayy-qzl2j GetGlobal(test.org)
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 parent not missing test.org
2024-01-18 17:12:46 k4ayy-qzl2j Availability(test.org)
2024-01-18 17:12:46 k4ayy-qzl2j Get(test.org)
2024-01-18 17:12:46 log 322 ItemFinished map[action:update error:0x14006165790 folder:k4ayy-qzl2j item:test.org type:file]
2024-01-18 17:12:46 log 323 StateChanged map[duration:0.502308 folder:k4ayy-qzl2j from:syncing to:sync-preparing]
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 need file test.org; copy 1, reused 0
2024-01-18 17:12:46 log 324 ItemStarted map[action:update folder:k4ayy-qzl2j item:test.org type:file]
2024-01-18 17:12:46 folderconfiguration.go:275 basic /Users/toby/Sync/emacs-sync Usage . {80949297152 494384795648} <nil>
2024-01-18 17:12:46 progress emitter: registering k4ayy-qzl2j test.org
2024-01-18 17:12:46 sharedPullerState k4ayy-qzl2j test.org pullNeeded start -> 1
2024-01-18 17:12:46 k4ayy-qzl2j Availability(test.org)
2024-01-18 17:12:46 model@0x140001aa180 REQ(out): VK66KO7 (169.254.230.66:22000-169.254.192.64:22000/tcp-client/TLS1.3-TLS_CHACHA20_POLY1305_SHA256/LAN-P10-2ULNQ2F72UGS0JFUGVTV77DO8E): "k4ayy-qzl2j" / "test.org" b=0 o=0 s=6 h=45dd805b0eabcd7ad5e2c5ae78971813b24d6e9c42919bf3cc300b774164d354 wh=5fb020b ft=false
2024-01-18 17:12:46 Writing request for "3.syncthing-enc/IT/N2E8GMC682LQD4VHOEGGKH94MUIKNUL1ADVG" in k4ayy-qzl2j
2024-01-18 17:12:46 wrote 83 bytes on the wire (2 bytes length, 2 bytes header, 4 bytes message length, 75 bytes message), err=<nil>
2024-01-18 17:12:46 log 325 StateChanged map[duration:0.503388 folder:k4ayy-qzl2j from:sync-preparing to:syncing]
2024-01-18 17:12:46 http: GET "/rest/events?since=314": status 200, 1037 bytes in 0.31 ms
2024-01-18 17:12:46 handle response message
2024-01-18 17:12:46 request: k4ayy-qzl2j test.org 0 6 VK66KO7 returned error: generic error
2024-01-18 17:12:46 basic /Users/toby/Sync/emacs-sync Watch: Ignoring .syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync/emacs-sync Watch: Ignoring .syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync/emacs-sync Watch: Ignoring .syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync/emacs-sync Watch: Ignoring .syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync/emacs-sync Watch: Ignoring .syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync/emacs-sync Watch: Ignoring .syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync Watch: Ignoring emacs-sync/.syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync Watch: Ignoring emacs-sync/.syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync Watch: Ignoring emacs-sync/.syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync Watch: Ignoring emacs-sync/.syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync Watch: Ignoring emacs-sync/.syncthing.test.org.tmp
2024-01-18 17:12:46 basic /Users/toby/Sync Watch: Ignoring emacs-sync/.syncthing.test.org.tmp
2024-01-18 17:12:46 http: GET "/rest/events?since=318": status 200, 257 bytes in 0.23 ms
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 closing test.org
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 new error for test.org: finishing: pull: generic error
2024-01-18 17:12:46 progress emitter: deregistering k4ayy-qzl2j test.org
2024-01-18 17:12:46 sendreceive/k4ayy-qzl2j@0x14000f34000 changed 1 on try 3
2024-01-18 17:12:46 Puller (folder "emacs-sync" (k4ayy-qzl2j), item "test.org"): syncing: finishing: pull: generic error
2024-01-18 17:12:46 "emacs-sync" (k4ayy-qzl2j): Failed to sync 1 items
2024-01-18 17:12:46 Folder "emacs-sync" (k4ayy-qzl2j) isn't making sync progress - retrying in 1m0s.
2024-01-18 17:12:46 log 326 ItemFinished map[action:update error:0x14006165ff0 folder:k4ayy-qzl2j item:test.org type:file]
2024-01-18 17:12:46 log 327 FolderErrors map[errors:[{test.org syncing: finishing: pull: generic error}] folder:k4ayy-qzl2j]
2024-01-18 17:12:46 log 328 StateChanged map[duration:0.509352 folder:k4ayy-qzl2j from:syncing to:idle]
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 progress emitter: bytes completed for k4ayy-qzl2j: 0
2024-01-18 17:12:46 k4ayy-qzl2j Snapshot()
2024-01-18 17:12:46 model@0x140001aa180 Completion(VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO, "k4ayy-qzl2j"): map[completion:100 globalBytes:6410 globalItems:5 needBytes:0 needDeletes:0 needItems:0 remoteState:valid sequence:13]
2024-01-18 17:12:46 log 329 FolderSummary {k4ayy-qzl2j 0x1400147af20}
2024-01-18 17:12:46 log 330 FolderCompletion map[completion:100 device:VK66KO7-YX22ZXW-XQN3TZI-KMWPITX-KX6PIMS-YUGTUTZ-VAGT5F4-5LBGXQO folder:k4ayy-qzl2j globalBytes:6410 globalItems:5 needBytes:0 needDeletes:0 needItems:0 remoteState:valid sequence:13]
2024-01-18 17:12:46 http: GET "/rest/events?since=319": status 200, 2560 bytes in 0.26 ms
2024-01-18 17:12:46 http: GET "/rest/system/log?since=2024-01-18T17%3A12%3A42.928785%2B01%3A00": status 200, 45640 bytes in 1.21 ms
Another thing which might not be related: I need to share encrypted folders (either by adding a password to device when sharing, or by marking my device as “insecure”) from my phone so I can properly also mark the received folder in my MBP as “Send & Receive” and add the expected password for my iPhone before saving. If I initiate the share from my MBP i am not given the opportunity to change the folder from “Receive Encrypted” on my iPhone.
I can initiate the share from either device if they’re not encrypted.