Syncthing Not Doing Anything For A Couple of Hours

Hello:

Haha - so hot on the tail of my previous statement, my Syncthing seems to have ground to a halt for the last couple of hours.

I’m happy to be patient if that’s what’s required - but I thought it might be a good opportunity to look at this state, in case it’s of concern.

You’ll see I’m very out of sync, but my uploads and downloads aren’t doing anything. The device which is Connected is the one shared with this exposed folder.

Here’s the log for the last few hours; I had main and model logging, and then added db logging in the hope it might expose some activity - but as you see, it looks like precious little is going on (though I understand that might not be the case!).

2020-06-03 14:57:17 sendreceive/bvkm*-*****@0xc000052a80 parent not missing <filepath1>
2020-06-03 14:57:17 sendreceive/bvkm*-*****@0xc000052a80 need file <filepath1>; copy 1, reused 0
2020-06-03 14:57:17 sharedPullerState bvkm*-***** <filepath2> copyNeeded -> 0
2020-06-03 14:57:17 sendreceive/bvkm*-*****@0xc000052a80 closing <filepath3>
2020-06-03 14:57:17 progress emitter: disabled, skip deregistering
2020-06-03 14:57:17 progress emitter: disabled, skip registering
2020-06-03 14:57:17 sendreceive/bvkm*-*****@0xc000052a80 parent not missing <filepath4>
2020-06-03 14:57:17 sendreceive/bvkm*-*****@0xc000052a80 need file <filepath4>; copy 1, reused 0
2020-06-03 14:57:17 sharedPullerState bvkm*-***** <filepath1> copyNeeded -> 0
2020-06-03 14:57:18 sendreceive/bvkm*-*****@0xc000052a80 closing <filepath5>
2020-06-03 14:57:18 progress emitter: bytes completed for bvkm*-*****: 0
2020-06-03 14:57:18 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "bvkm*-*****"): 100.000000 (0 / 42891768774928 = 0.000000)
2020-06-03 14:57:46 sendreceive/xec**-*****@0xc032f37880 Scanning due to timer
2020-06-03 14:57:49 sendreceive/xec**-*****@0xc032f37880 next rescan in 6h45m2.894557942s
2020-06-03 15:00:02 sendreceive/qpf**-*****@0xc02a23d880 Scanning due to timer
2020-06-03 15:03:24 sendreceive/6xm**-*****@0xc000176700 Scanning due to timer
2020-06-03 15:03:41 sendreceive/6xm**-*****@0xc000176700 next rescan in 7h3m41.462320903s
2020-06-03 15:04:35 sendreceive/qpf**-*****@0xc02a23d880 next rescan in 4h33m45.253294258s
2020-06-03 15:08:54 Listen (BEP/relay): TLS handshake: read tcp 192.168.1.3:34312->37.120.175.208:22067: i/o timeout
2020-06-03 15:09:47 Established secure connection to GSXHK4J-*******-*******-*******-*******-*******-*******-******* at 192.168.1.3:34315-37.120.175.208:22067/relay-server/TLS1.3-TLS_AES_128_GCM_SHA256
2020-06-03 15:09:47 Replacing old connection 192.168.1.3:37616-81.157.abc.d:18352/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256 with 192.168.1.3:34315-37.120.175.208:22067/relay-server/TLS1.3-TLS_AES_128_GCM_SHA256 for GSXHK4J-*******-*******-*******-*******-*******-*******-*******
2020-06-03 15:12:25 Connection to NU5YGME-*******-*******-*******-*******-*******-*******-******* at 192.168.1.3:41455-81.141.167.162:22465/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256 closed: read timeout
2020-06-03 15:12:25 Exiting indexSender for 3qg**-***** to  at 192.168.1.3:41455-81.141.167.162:22465/tcp-client/TLS1.3-TLS_AES_128_GCM_SHA256: <nil>
2020-06-03 15:21:59 Enabled debug data for "db"
2020-06-03 15:31:38 Sent usage report (version 3)
2020-06-03 15:44:16 sendreceive/fcr**-*****@0xc016988700 Scanning due to timer
2020-06-03 15:57:13 sendreceive/dxf**-*****@0xc012f2c000 Scanning due to timer
2020-06-03 15:57:13 dxf**-***** WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, "")
2020-06-03 15:57:13 sendreceive/dxf**-*****@0xc012f2c000 next rescan in 6h29m38.426232965s
2020-06-03 15:59:39 sendreceive/ick**-*****@0xc00e5e5880 Scanning due to timer
2020-06-03 15:59:57 ick**-***** WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, "")
2020-06-03 15:59:58 sendreceive/ick**-*****@0xc00e5e5880 next rescan in 1h1m15.309676471s
2020-06-03 16:00:08 sendreceive/3qg**-*****@0xc000176380 Scanning due to timer
2020-06-03 16:02:34 3qg**-***** WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, "")
2020-06-03 16:02:35 sendreceive/3qg**-*****@0xc000176380 next rescan in 7h14m54.22188666s
2020-06-03 16:33:52 fcr**-***** WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, "")
2020-06-03 16:38:42 sendreceive/fcr**-*****@0xc016988700 next rescan in 7h0m41.217218801s
2020-06-03 17:01:13 sendreceive/ick**-*****@0xc00e5e5880 Scanning due to timer
2020-06-03 17:02:15 ick**-***** WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4, "")
2020-06-03 17:02:16 sendreceive/ick**-*****@0xc00e5e5880 next rescan in 58m58.8899036s
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "3qg**-*****"): 100.000000 (0 / 57901094679 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for 3qg**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(NU5YGME-*******-*******-*******-*******-*******-*******-*******, "3qg**-*****"): 100.000000 (0 / 57901094679 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for 6xm**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "6xm**-*****"): 100.000000 (0 / 22471660869 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for bvkm*-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "bvkm*-*****"): 100.000000 (0 / 42891768774928 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for c7g**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "c7g**-*****"): 100.000000 (0 / 217364610252 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for dxf**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "dxf**-*****"): 100.000000 (0 / 1957791461 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for fcr**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "fcr**-*****"): 100.000000 (0 / 9465720007164 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for gpt**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "gpt**-*****"): 100.000000 (0 / 3962439869 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for ick**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "ick**-*****"): 100.000000 (0 / 23001543783 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for jgl**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "jgl**-*****"): 100.000000 (0 / 1061476626 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for kg5**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "kg5**-*****"): 100.000000 (0 / 1835722926 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for qpf**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "qpf**-*****"): 100.000000 (0 / 1973049340247 = 0.000000)
2020-06-03 17:15:53 progress emitter: bytes completed for xec**-*****: 0
2020-06-03 17:15:53 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "xec**-*****"): 100.000000 (0 / 3484037054 = 0.000000)
2020-06-03 17:15:54 progress emitter: bytes completed for yvq**-*****: 0
2020-06-03 17:15:54 model@0xc00016d200 Completion(GSXHK4J-*******-*******-*******-*******-*******-*******-*******, "yvq**-*****"): 100.000000 (0 / 45966397644 = 0.000000)

If this is worthy of further investigation, I’ve already grabbed a Support Bundle - but IIRC this has private filenames in it, so I can’t post it publicly.

Likewise, if there’s a way of preserving this state whilst it’s being investigated, please let me know!

Many thanks!

…And no sooner do I post this, than it all starts moving again! :roll_eyes:

Those trace levels are pretty intense. Just db on a folder with millions of files can probably grind things to a halt all by itself.

Hi Jakob:

With respect, enabling the db tracing doesn’t slow this system down significantly whilst it’s actively doing stuff - and I’d already waited over 20 minutes, with no sign of activity from the stalled folder, before enabling it.

But if there’s nothing useful to see here, I’ll drop it! :wink:

Thanks.

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.