Syncthing-android: Eats battery since 0.14.45

[vsespb]

Started eating battery at 0.14.45 and not fixed in 14.45+1
Was fine before those versions.
Seems it consumes ~ 10-15% CPU all the time.

Screenshot where syncthing is on top accross all apps:

Screenshot where syncthing is showing 13% cpu:

logcat at moment when eats battery (I enabled all checkboxes in syncthing logging and filtered by "Syncthing" in logcat):

03-16 10:00:15.394 I/ActivityManager(1348): Process com.nutomic.syncthingandroid (pid 21231) has died
03-16 10:00:15.399 W/ActivityManager(1348): Scheduling restart of crashed service com.nutomic.syncthingandroid/.service.SyncthingService in 30669ms
03-16 10:00:46.109 I/ActivityManager(1348): Start proc 23803:com.nutomic.syncthingandroid/u0a156 for service com.nutomic.syncthingandroid/.service.SyncthingService
03-16 10:00:46.441 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:00:47.429 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:00:47.463 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:00:47.474 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:00:52.392 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:00:52.608 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:02.836 I/ActivityManager(1348): Process com.nutomic.syncthingandroid (pid 23803) has died
03-16 10:01:02.869 W/ActivityManager(1348): Scheduling restart of crashed service com.nutomic.syncthingandroid/.service.SyncthingService in 10859ms
03-16 10:01:14.173 I/ActivityManager(1348): Start proc 25959:com.nutomic.syncthingandroid/u0a156 for service com.nutomic.syncthingandroid/.service.SyncthingService
03-16 10:01:15.460 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:18.936 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:19.042 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:19.049 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:22.818 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:23.219 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:01:40.194 I/ActivityManager(1348): Process com.nutomic.syncthingandroid (pid 25959) has died
03-16 10:01:40.199 W/ActivityManager(1348): Scheduling restart of crashed service com.nutomic.syncthingandroid/.service.SyncthingService in 20984ms
03-16 10:02:01.290 I/ActivityManager(1348): Start proc 29796:com.nutomic.syncthingandroid/u0a156 for service com.nutomic.syncthingandroid/.service.SyncthingService
03-16 10:02:01.612 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:02.565 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:02.620 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:02.628 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:04.408 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:04.605 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:24.267 I/ActivityManager(1348): Process com.nutomic.syncthingandroid (pid 29796) has died
03-16 10:02:24.284 W/ActivityManager(1348): Scheduling restart of crashed service com.nutomic.syncthingandroid/.service.SyncthingService in 10980ms
03-16 10:02:35.321 I/ActivityManager(1348): Start proc 376:com.nutomic.syncthingandroid/u0a156 for service com.nutomic.syncthingandroid/.service.SyncthingService
03-16 10:02:35.617 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:37.141 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:37.184 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:37.200 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:38.970 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:39.149 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:02:42.896 I/ActivityManager(1348): Process com.nutomic.syncthingandroid (pid 376) has died
03-16 10:02:42.927 W/ActivityManager(1348): Scheduling restart of crashed service com.nutomic.syncthingandroid/.service.SyncthingService in 112342ms
03-16 10:04:35.296 I/ActivityManager(1348): Start proc 3766:com.nutomic.syncthingandroid/u0a156 for service com.nutomic.syncthingandroid/.service.SyncthingService
03-16 10:04:35.354 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:04:35.752 I/SyncthingService(3766): Shutting down background service
03-16 10:04:35.777 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:04:35.792 I/SyncthingService(3766): Starting syncthing according to current state and preferences
03-16 10:04:35.801 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:04:35.808 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:04:35.829 D/ConfigXml(3766): Trying to read '/data/user/0/com.nutomic.syncthingandroid/files/config.xml'
03-16 10:04:35.884 I/ConfigXml(3766): Loaded Syncthing config file
03-16 10:04:35.915 I/SyncthingService(3766): Web GUI will be available at https://127.0.0.1:8384
03-16 10:04:36.218 W/chmod   (3789): type=1400 audit(0.0:27939): avc: denied { setattr } for name="libsyncthing.so" dev="dm-0" ino=393377 scontext=u:r:untrusted_app:s0:c512,c768 tcontext=u:object_r:apk_data_file:s0 tclass=file permissive=0
03-16 10:04:36.273 W/linker  (3797): /data/app/com.nutomic.syncthingandroid-1/lib/arm64/libsyncthing.so: unsupported flags DT_FLAGS_1=0x8000000
03-16 10:04:36.274 W/SyncthingNativeCode(3766): WARNING: linker: /data/app/com.nutomic.syncthingandroid-1/lib/arm64/libsyncthing.so: unsupported flags DT_FLAGS_1=0x8000000
03-16 10:04:36.575 I/SyncthingNativeCode(3766): [SST4V] INFO: syncthing v0.14.45+1-gbf165d68 "Dysprosium Dragonfly" (go1.9.4 android-arm64) felix@p50 2018-03-13 18:08:09 UTC [noupgrade]
03-16 10:04:36.577 I/SyncthingNativeCode(3766): [SST4V] INFO: My ID: SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:04:36.586 I/SyncthingNativeCode(3766): [SST4V] INFO: Disabling weak hash
03-16 10:04:36.637 I/SyncthingNativeCode(3766): [SST4V] INFO: Starting deadlock detector with 20m0s timeout
03-16 10:04:36.639 I/SyncthingNativeCode(3766): [SST4V] INFO: Ready to synchronize "Camera" (2srju-3nqqb) (readwrite)
03-16 10:04:36.646 I/SyncthingNativeCode(3766): [SST4V] INFO: Ready to synchronize "CityGuide Int" (2rfiy-7re2v) (readwrite)
03-16 10:04:36.653 I/SyncthingNativeCode(3766): [SST4V] INFO: Ready to synchronize "CityGuide SD" (hku3c-ud81p) (readonly)
03-16 10:04:36.658 I/SyncthingNativeCode(3766): [SST4V] INFO: Ready to synchronize "SoundRec" (uua35-odjy0) (readwrite)
03-16 10:04:36.660 I/SyncthingNativeCode(3766): [SST4V] INFO: Send rate is unlimited, receive rate is unlimited
03-16 10:04:36.661 I/SyncthingNativeCode(3766): [SST4V] INFO: Rate limits do not apply to LAN connections
03-16 10:04:36.669 I/SyncthingNativeCode(3766): [SST4V] INFO: TCP listener ([::]:22000) starting
03-16 10:04:36.745 I/SyncthingNativeCode(3766): [SST4V] INFO: Completed initial scan of readonly folder "CityGuide SD" (hku3c-ud81p)
03-16 10:04:36.803 I/SyncthingNativeCode(3766): [SST4V] INFO: Completed initial scan of readwrite folder "SoundRec" (uua35-odjy0)
03-16 10:04:36.852 I/SyncthingNativeCode(3766): [SST4V] INFO: Device DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 is "GREEN-U" at [tcp4://192.168.0.150,tcp4://home.vs-dev.com]
03-16 10:04:36.854 I/SyncthingNativeCode(3766): [SST4V] INFO: Device SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO is "Redmi 4" at [dynamic]
03-16 10:04:36.855 I/SyncthingNativeCode(3766): [SST4V] INFO: No automatic upgrades; STNOUPGRADE environment variable defined.
03-16 10:04:36.867 I/SyncthingNativeCode(3766): [SST4V] INFO: GUI and API listening on 127.0.0.1:8384
03-16 10:04:36.868 I/SyncthingNativeCode(3766): [SST4V] INFO: Access the GUI via the following URL: http://127.0.0.1:8384/
03-16 10:04:36.888 I/SyncthingNativeCode(3766): [SST4V] INFO: Completed initial scan of readwrite folder "Camera" (2srju-3nqqb)
03-16 10:04:37.137 I/SyncthingService(3766): Web GUI has come online at https://127.0.0.1:8384
03-16 10:04:37.141 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:04:37.159 I/SyncthingNativeCode(3766): [SST4V] INFO: Completed initial scan of readwrite folder "CityGuide Int" (2rfiy-7re2v)
03-16 10:04:37.163 I/RestApi (3766): Syncthing version is v0.14.45+1-gbf165d68
03-16 10:04:37.239 V/NotificationService(1348): pkg=com.nutomic.syncthingandroid canInterrupt=false intercept=true
03-16 10:04:37.387 I/SyncthingRunnableIoNice(3766): ionice performed on libsyncthing.so
03-16 10:05:37.059 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:05:52.177 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:05:59.113 I/ActivityManager(1348): START u0 {cmp=com.nutomic.syncthingandroid/.activities.FirstStartActivity bnds=[53,616][667,746]} from uid 10156 on display 0
03-16 10:05:59.139 W/ActivityManager(1348): startActivity called from non-Activity context; forcing Intent.FLAG_ACTIVITY_NEW_TASK for: Intent { cmp=com.nutomic.syncthingandroid/.activities.FirstStartActivity bnds=[53,616][667,746] }
03-16 10:05:59.168 D/WtProcessController(2447): set foreground process size 1 pid:3766pacakgeName:com.nutomic.syncthingandroid
03-16 10:05:59.168 W/WtProcessStrategy(2447): {WhetstonePackageInfo#PacakgeName:com.nutomic.syncthingandroid uiMemoryThresold:0 nonUiMemoryThresold:0 Flag:4160,0x1040 [,TRIMHEAPS,FLAG_DEAL_SCHEDULE] Type:64[,AUTO_START] }not support ZRAM
03-16 10:05:59.295 I/ActivityManager(1348): START u0 {cmp=com.nutomic.syncthingandroid/.activities.MainActivity (has extras)} from uid 10156 on display 0
03-16 10:05:59.667 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:05:59.750 I/ActivityManager(1348): Displayed com.nutomic.syncthingandroid/.activities.MainActivity: +440ms (total +587ms)
03-16 10:05:59.750 I/Timeline(1348): Timeline: Activity_windows_visible id: ActivityRecord{b03916d u0 com.nutomic.syncthingandroid/.activities.MainActivity t22074} time:17958204
03-16 10:06:02.457 I/ActivityManager(1348): START u0 {cmp=com.nutomic.syncthingandroid/.activities.WebGuiActivity} from uid 10156 on display 0
03-16 10:06:02.819 D/ConfigXml(3766): Trying to read '/data/user/0/com.nutomic.syncthingandroid/files/config.xml'
03-16 10:06:02.824 I/ConfigXml(3766): Loaded Syncthing config file
03-16 10:06:03.036 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:06:03.039 I/ActivityManager(1348): Displayed com.nutomic.syncthingandroid/.activities.WebGuiActivity: +561ms
03-16 10:06:03.098 W/cr_CrashFileManager(3766): /data/user/0/com.nutomic.syncthingandroid/cache/WebView/Crash Reports does not exist or is not a directory
03-16 10:06:03.301 I/Timeline(1348): Timeline: Activity_windows_visible id: ActivityRecord{8d64c87 u0 com.nutomic.syncthingandroid/.activities.WebGuiActivity t22074} time:17961755
03-16 10:06:06.899 I/chromium(3766): [INFO:CONSOLE(1497)] "loadFormIntoScope", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (1497)
03-16 10:06:07.013 I/chromium(3766): [INFO:CONSOLE(1497)] "loadFormIntoScope", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (1497)
03-16 10:06:08.153 I/chromium(3766): [INFO:CONSOLE(114)] "UIOnline", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (114)
03-16 10:06:08.405 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:06:08.515 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:06:08.728 I/chromium(3766): [INFO:CONSOLE(560)] "refreshConfig", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (560)
03-16 10:06:09.235 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:06:09.310 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:06:09.542 I/chromium(3766): [INFO:CONSOLE(679)] "refreshDeviceStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (679)
03-16 10:06:09.932 I/chromium(3766): [INFO:CONSOLE(364)] "refreshFolder", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (364)
03-16 10:06:10.030 I/chromium(3766): [INFO:CONSOLE(498)] "recalcCompletion", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (498)
03-16 10:06:10.088 I/chromium(3766): [INFO:CONSOLE(364)] "refreshFolder", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (364)
03-16 10:06:10.198 I/chromium(3766): [INFO:CONSOLE(364)] "refreshFolder", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (364)
03-16 10:06:10.332 I/chromium(3766): [INFO:CONSOLE(498)] "recalcCompletion", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (498)
03-16 10:06:10.379 I/chromium(3766): [INFO:CONSOLE(498)] "recalcCompletion", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (498)
03-16 10:06:10.403 I/chromium(3766): [INFO:CONSOLE(498)] "recalcCompletion", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (498)
03-16 10:06:10.430 I/chromium(3766): [INFO:CONSOLE(364)] "refreshFolder", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (364)
03-16 10:06:13.097 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:06:14.460 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:14.456958 gui.go:635: INFO: Enabled debug data for "beacon"
03-16 10:06:14.867 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:14.867058 gui.go:635: INFO: Enabled debug data for "config"
03-16 10:06:15.289 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:15.289461 gui.go:635: INFO: Enabled debug data for "connections"
03-16 10:06:16.619 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:16.619619 gui.go:635: INFO: Enabled debug data for "dialer"
03-16 10:06:17.147 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:17.147130 gui.go:642: INFO: Disabled debug data for "dialer"
03-16 10:06:17.564 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:06:17.628 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:06:17.650 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:06:17.802 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:06:17.869 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:17.869671 gui.go:635: INFO: Enabled debug data for "db"
03-16 10:06:18.522 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:18.521729 gui.go:635: INFO: Enabled debug data for "dialer"
03-16 10:06:20.361 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:20.360932 gui.go:635: INFO: Enabled debug data for "discover"
03-16 10:06:21.003 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:21.003178 gui.go:635: INFO: Enabled debug data for "events"
03-16 10:06:22.314 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:06:22.679 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:22.679561 gui.go:635: INFO: Enabled debug data for "fs"
03-16 10:06:23.209 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:23.209514 gui.go:635: INFO: Enabled debug data for "http"
03-16 10:06:23.213 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:23.212993 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=http": status 0, 0 bytes in 3.54 ms
03-16 10:06:23.312 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:23.312266 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 899 bytes in 0.58 ms
03-16 10:06:23.848 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:23.848694 gui.go:635: INFO: Enabled debug data for "main"
03-16 10:06:23.850 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:23.850607 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=main": status 0, 0 bytes in 2.02 ms
03-16 10:06:23.982 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:23.982439 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 906 bytes in 0.59 ms
03-16 10:06:24.516 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:24.516476 gui.go:985: DEBUG: <nil>
03-16 10:06:24.519 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:24.519043 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A21.004340482Z": status 200, 920 bytes in 2.69 ms
03-16 10:06:24.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:24.696984 gui.go:635: INFO: Enabled debug data for "model"
03-16 10:06:24.699 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:24.699276 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=model": status 0, 0 bytes in 3.30 ms
03-16 10:06:24.827 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:24.827513 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 914 bytes in 0.52 ms
03-16 10:06:25.213 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:25.213425 gui.go:635: INFO: Enabled debug data for "nat"
03-16 10:06:25.215 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:25.215567 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=nat": status 0, 0 bytes in 2.29 ms
03-16 10:06:25.332 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:25.332660 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 920 bytes in 0.45 ms
03-16 10:06:26.645 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:26.644894 gui.go:635: INFO: Enabled debug data for "pmp"
03-16 10:06:26.647 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:26.645118 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=pmp": status 0, 0 bytes in 0.37 ms
03-16 10:06:26.856 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:26.856110 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 926 bytes in 0.59 ms
03-16 10:06:26.910 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:26.910207 gui.go:985: DEBUG: <nil>
03-16 10:06:26.912 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:26.912418 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A24.51774272Z": status 200, 1361 bytes in 2.32 ms
03-16 10:06:27.191 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.190735 gui.go:635: INFO: Enabled debug data for "protocol"
03-16 10:06:27.193 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.190969 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=protocol": status 0, 0 bytes in 0.41 ms
03-16 10:06:27.405 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.405155 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 937 bytes in 0.56 ms
03-16 10:06:27.526 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.525733 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.25 ms
03-16 10:06:27.530 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:06:27.540 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.539893 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.63 ms
03-16 10:06:27.549 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.548906 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 3.40 ms
03-16 10:06:27.550 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:27.549891 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 474 bytes in 13.07 ms
03-16 10:06:27.560 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:06:27.585 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:06:27.616 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:06:27.651 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:06:28.209 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.208538 rwfolder.go:204: DEBUG: sendReceiveFolder/2rfiy-7re2v@0x44200a7180 Scanning subdirectories
03-16 10:06:28.211 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.210907 logfs.go:163: DEBUG: size.go:85 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211864576 10618138624} <nil>
03-16 10:06:28.212 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.212012 events.go:244: DEBUG: log 47 StateChanged map[folder:2rfiy-7re2v to:scanning from:idle duration:57.95361654]
03-16 10:06:28.214 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.212735 logfs.go:163: DEBUG: size.go:85 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211864576 10618138624} <nil>
03-16 10:06:28.215 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.213994 gui.go:475: DEBUG: http: GET "/rest/events?since=46": status 200, 172 bytes in 17698.91 ms
03-16 10:06:28.687 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.687592 gui.go:635: INFO: Enabled debug data for "sha256"
03-16 10:06:28.689 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.687848 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=sha256": status 0, 0 bytes in 0.38 ms
03-16 10:06:28.818 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.817820 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 946 bytes in 0.62 ms
03-16 10:06:28.890 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.890052 logfs.go:163: DEBUG: size.go:85 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211860480 10618138624} <nil>
03-16 10:06:28.891 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.890225 set.go:198: DEBUG: 2rfiy-7re2v WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:06:28.947 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.946934 logfs.go:163: DEBUG: size.go:85 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211860480 10618138624} <nil>
03-16 10:06:28.948 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.948249 events.go:244: DEBUG: log 48 StateChanged map[folder:2rfiy-7re2v to:idle from:scanning duration:0.736309948]
03-16 10:06:28.950 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.948439 folderscanner.go:44: DEBUG: &{60000000000 0x4420450880 0x442021ce40 0x442021cea0} next rescan in 1m3.396114286s
03-16 10:06:28.952 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.948754 gui.go:475: DEBUG: http: GET "/rest/events?since=47": status 200, 172 bytes in 719.98 ms
03-16 10:06:28.964 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:28.964764 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.91 ms
03-16 10:06:29.031 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:06:29.081 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:29.081549 gui.go:985: DEBUG: <nil>
03-16 10:06:29.084 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:29.082556 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A26.911889281Z": status 200, 3413 bytes in 1.16 ms
03-16 10:06:29.347 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:29.346841 gui.go:635: INFO: Enabled debug data for "scanner"
03-16 10:06:29.348 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:29.348440 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=scanner": status 0, 0 bytes in 1.72 ms
03-16 10:06:29.481 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:29.480781 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 956 bytes in 0.96 ms
03-16 10:06:31.112 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:31.112615 gui.go:985: DEBUG: <nil>
03-16 10:06:31.115 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:31.114958 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A29.081666051Z": status 200, 638 bytes in 2.47 ms
03-16 10:06:31.898 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:31.897848 gui.go:635: INFO: Enabled debug data for "sync"
03-16 10:06:31.899 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:31.898771 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=sync": status 0, 0 bytes in 1.15 ms
03-16 10:06:32.026 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:32.025752 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 963 bytes in 0.48 ms
03-16 10:06:32.503 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:32.502721 gui.go:635: INFO: Enabled debug data for "stats"
03-16 10:06:32.505 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:32.505165 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=stats": status 0, 0 bytes in 2.57 ms
03-16 10:06:32.630 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:32.630050 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 971 bytes in 0.49 ms
03-16 10:06:33.266 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:33.266651 gui.go:635: INFO: Enabled debug data for "upgrade"
03-16 10:06:33.268 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:33.266885 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=upgrade": status 0, 0 bytes in 0.69 ms
03-16 10:06:33.386 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:33.386595 gui.go:985: DEBUG: <nil>
03-16 10:06:33.388 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:33.387104 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A31.114494436Z": status 200, 1242 bytes in 1.16 ms
03-16 10:06:33.412 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:33.411841 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 981 bytes in 1.45 ms
03-16 10:06:34.014 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:34.014278 gui.go:635: INFO: Enabled debug data for "upnp"
03-16 10:06:34.016 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:34.015059 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=upnp": status 0, 0 bytes in 0.92 ms
03-16 10:06:34.154 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:34.153852 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 988 bytes in 0.47 ms
03-16 10:06:35.338 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.338163 gui.go:635: INFO: Enabled debug data for "versioner"
03-16 10:06:35.340 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.339165 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=versioner": status 0, 0 bytes in 1.13 ms
03-16 10:06:35.477 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.476724 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 1000 bytes in 1.03 ms
03-16 10:06:35.500 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.500620 gui.go:985: DEBUG: <nil>
03-16 10:06:35.502 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.501175 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A33.386684019Z": status 200, 1140 bytes in 0.73 ms
03-16 10:06:35.939 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.939485 gui.go:635: INFO: Enabled debug data for "walkfs"
03-16 10:06:35.942 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:35.941970 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=walkfs": status 0, 0 bytes in 2.63 ms
03-16 10:06:36.077 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.076801 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 1009 bytes in 0.60 ms
03-16 10:06:36.682 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.682095 broadcast.go:122: DEBUG: addresses: [192.168.0.255]
03-16 10:06:36.691 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.690772 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 lo
03-16 10:06:36.693 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.693120 broadcast.go:147: DEBUG: sent 69 bytes to 192.168.0.255:21027
03-16 10:06:36.696 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695098 broadcast.go:204: DEBUG: recv 69 bytes from 192.168.0.100:44802
03-16 10:06:36.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695280 local.go:193: DEBUG: discover: Received local announcement from 192.168.0.100:44802 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:06:36.700 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695351 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on dummy0
03-16 10:06:36.702 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695554 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 sit0
03-16 10:06:36.704 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695677 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet0
03-16 10:06:36.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695790 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data0
03-16 10:06:36.713 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.695902 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data1
03-16 10:06:36.714 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.699112 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data2
03-16 10:06:36.716 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.699718 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data3
03-16 10:06:36.717 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.699888 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data4
03-16 10:06:36.720 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.700006 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data5
03-16 10:06:36.722 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.700393 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data6
03-16 10:06:36.723 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.701299 multicast.go:209: DEBUG: recv 69 bytes from [fe80::440f:30ff:fe89:93f6%dummy0]:41226
03-16 10:06:36.724 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.701530 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:06:36.725 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.701736 local.go:193: DEBUG: discover: Received local announcement from [fe80::440f:30ff:fe89:93f6%dummy0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:06:36.726 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.701829 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:06:36.727 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.702049 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on rmnet_data7
03-16 10:06:36.728 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.702795 multicast.go:209: DEBUG: recv 69 bytes from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226
03-16 10:06:36.730 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.703246 local.go:193: DEBUG: discover: Received local announcement from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:06:36.731 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.703475 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on r_rmnet_data0
03-16 10:06:36.733 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.703714 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data1
03-16 10:06:36.734 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.704220 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data2
03-16 10:06:36.735 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.704383 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data3
03-16 10:06:36.736 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.706815 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:06:36.737 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.707167 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:06:36.738 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.708440 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data4
03-16 10:06:36.739 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.708681 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data5
03-16 10:06:36.741 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.708829 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data6
03-16 10:06:36.742 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.708930 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data7
03-16 10:06:36.743 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.709029 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data8
03-16 10:06:36.744 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.709544 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on wlan0
03-16 10:06:36.745 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.709791 multicast.go:209: DEBUG: recv 69 bytes from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226
03-16 10:06:36.746 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.709964 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 p2p0
03-16 10:06:36.748 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:36.710066 local.go:193: DEBUG: discover: Received local announcement from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:06:37.422 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.421692 gui.go:635: INFO: Enabled debug data for "watchaggregator"
03-16 10:06:37.424 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.423103 gui.go:475: DEBUG: http: POST "/rest/system/debug?enable=watchaggregator": status 0, 0 bytes in 1.54 ms
03-16 10:06:37.473 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:06:37.518 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.518084 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 0.80 ms
03-16 10:06:37.551 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.551308 gui.go:475: DEBUG: http: GET "/rest/system/debug": status 200, 1027 bytes in 0.66 ms
03-16 10:06:37.578 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.578299 gui.go:475: DEBUG: http: GET "/rest/events?since=46&limit=0": status 200, 343 bytes in 1.16 ms
03-16 10:06:37.588 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.588599 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 474 bytes in 0.99 ms
03-16 10:06:37.595 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.594862 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.23 ms
03-16 10:06:37.596 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.596742 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.92 ms
03-16 10:06:37.598 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.598774 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 2.96 ms
03-16 10:06:37.647 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.646873 gui.go:985: DEBUG: <nil>
03-16 10:06:37.650 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:37.650547 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A35.500722143Z": status 200, 8041 bytes in 3.75 ms
03-16 10:06:37.669 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:06:37.714 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:06:37.735 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:06:37.757 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:06:39.152 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.151952 rwfolder.go:204: DEBUG: sendReceiveFolder/uua35-odjy0@0x44200a7400 Scanning subdirectories
03-16 10:06:39.153 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.153598 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211803136 10618138624} <nil>
03-16 10:06:39.162 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.162249 events.go:244: DEBUG: log 49 StateChanged map[duration:65.361141902 folder:uua35-odjy0 to:scanning from:idle]
03-16 10:06:39.164 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.163987 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd160 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021d260 0x44201ea888 0x44202fca98}
03-16 10:06:39.165 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.165216 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211803136 10618138624} <nil>
03-16 10:06:39.167 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.166263 gui.go:475: DEBUG: http: GET "/rest/events?since=48": status 200, 173 bytes in 10196.49 ms
03-16 10:06:39.174 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.174014 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:06:39.210 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.210726 walk.go:164: DEBUG: Walk progress done uua35-odjy0 [] 131072 &{0x44201cd160 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021d260 0x44201ea888 0x44202fca98}
03-16 10:06:39.212 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.211599 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211803136 10618138624} <nil>
03-16 10:06:39.213 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.212530 set.go:198: DEBUG: uua35-odjy0 WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:06:39.227 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.227011 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211799040 10618138624} <nil>
03-16 10:06:39.233 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.227446 events.go:244: DEBUG: log 50 StateChanged map[folder:uua35-odjy0 to:idle from:scanning duration:0.072656719]
03-16 10:06:39.238 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.227916 folderscanner.go:44: DEBUG: &{60000000000 0x4420450b40 0x442021d3e0 0x442021d440} next rescan in 56.746848352s
03-16 10:06:39.242 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.235580 gui.go:475: DEBUG: http: GET "/assets/img/favicon-default.png": status 200, 4093 bytes in 5.80 ms
03-16 10:06:39.245 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.244177 gui.go:475: DEBUG: http: GET "/rest/events?since=49": status 200, 172 bytes in 2.53 ms
03-16 10:06:39.301 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.300780 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.97 ms
03-16 10:06:39.340 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:06:39.791 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.790838 gui.go:985: DEBUG: <nil>
03-16 10:06:39.792 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:39.792595 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A37.646966309Z": status 200, 2867 bytes in 1.89 ms
03-16 10:06:42.536 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:42.536337 gui.go:985: DEBUG: <nil>
03-16 10:06:42.538 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:42.537974 gui.go:475: DEBUG: http: GET "/rest/system/log?since=2018-03-16T07%3A06%3A39.791664276Z": status 200, 265 bytes in 2.17 ms
03-16 10:06:43.292 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.291938 rofolder.go:50: DEBUG: sendOnlyFolder/hku3c-ud81p@0x4420304200 Scanning subdirectories
03-16 10:06:43.294 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.294632 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211627008 10618138624} <nil>
03-16 10:06:43.296 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.296462 events.go:244: DEBUG: log 51 StateChanged map[folder:hku3c-ud81p to:scanning from:idle duration:57.404548207]
03-16 10:06:43.298 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.296710 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd140 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021cfc0 0x44201ea868 0x44202fca00}
03-16 10:06:43.300 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.297225 gui.go:475: DEBUG: http: GET "/rest/events?since=50": status 200, 172 bytes in 3993.87 ms
03-16 10:06:43.302 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.298403 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211627008 10618138624} <nil>
03-16 10:06:43.346 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.345936 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:06:43.347 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.346893 walk.go:164: DEBUG: Walk progress done hku3c-ud81p [] 131072 &{0x44201cd140 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021cfc0 0x44201ea868 0x44202fca00}
03-16 10:06:43.349 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.347100 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211627008 10618138624} <nil>
03-16 10:06:43.350 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.347215 set.go:198: DEBUG: hku3c-ud81p WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:06:43.355 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.355714 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211622912 10618138624} <nil>
03-16 10:06:43.357 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.356829 events.go:244: DEBUG: log 52 StateChanged map[duration:0.060381407 folder:hku3c-ud81p to:idle from:scanning]
03-16 10:06:43.359 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.357884 folderscanner.go:44: DEBUG: &{60000000000 0x4420450a40 0x442021d140 0x442021d1a0} next rescan in 1m2.359221201s
03-16 10:06:43.361 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.358281 gui.go:475: DEBUG: http: GET "/rest/events?since=51": status 200, 172 bytes in 42.91 ms
03-16 10:06:43.417 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:43.417130 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 1.21 ms
03-16 10:06:43.529 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:06:48.557 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:48.557189 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 474 bytes in 2.31 ms
03-16 10:06:48.562 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:48.561935 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 1.02 ms
03-16 10:06:48.576 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:48.576629 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.35 ms
03-16 10:06:48.582 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:48.582252 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 2.99 ms
03-16 10:06:48.613 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:06:48.678 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:06:48.697 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:06:48.721 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:06:52.603 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:06:52.649 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:52.649329 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 1.62 ms
03-16 10:06:52.709 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:52.708521 gui.go:475: DEBUG: http: GET "/rest/events?since=48&limit=0": status 200, 686 bytes in 1.23 ms
03-16 10:06:58.560 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:58.560114 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 474 bytes in 1.04 ms
03-16 10:06:58.564 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:06:58.564 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:58.563000 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 3.35 ms
03-16 10:06:58.578 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:58.578162 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.80 ms
03-16 10:06:58.585 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:06:58.585005 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.27 ms
03-16 10:06:58.623 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:06:58.707 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:06:58.731 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:06:58.760 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:07:06.662 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.662560 broadcast.go:122: DEBUG: addresses: [192.168.0.255]
03-16 10:07:06.666 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.666090 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 lo
03-16 10:07:06.667 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.666784 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on dummy0
03-16 10:07:06.669 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667045 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 sit0
03-16 10:07:06.670 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667195 broadcast.go:204: DEBUG: recv 69 bytes from 192.168.0.100:44802
03-16 10:07:06.671 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667335 local.go:193: DEBUG: discover: Received local announcement from 192.168.0.100:44802 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:06.672 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667409 broadcast.go:147: DEBUG: sent 69 bytes to 192.168.0.255:21027
03-16 10:07:06.673 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667494 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet0
03-16 10:07:06.676 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667719 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data0
03-16 10:07:06.678 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667853 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data1
03-16 10:07:06.679 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.667969 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data2
03-16 10:07:06.680 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.668092 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data3
03-16 10:07:06.682 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.668255 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data4
03-16 10:07:06.683 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.668392 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data5
03-16 10:07:06.684 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.668507 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data6
03-16 10:07:06.686 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.668638 multicast.go:209: DEBUG: recv 69 bytes from [fe80::440f:30ff:fe89:93f6%dummy0]:41226
03-16 10:07:06.687 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.668817 local.go:193: DEBUG: discover: Received local announcement from [fe80::440f:30ff:fe89:93f6%dummy0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:06.692 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669015 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on rmnet_data7
03-16 10:07:06.694 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669313 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:07:06.695 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669494 multicast.go:209: DEBUG: recv 69 bytes from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226
03-16 10:07:06.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669588 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on r_rmnet_data0
03-16 10:07:06.698 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669723 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:06.699 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669827 local.go:193: DEBUG: discover: Received local announcement from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:06.700 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.669910 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data1
03-16 10:07:06.702 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.670087 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data2
03-16 10:07:06.703 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.670226 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data3
03-16 10:07:06.704 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.670349 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data4
03-16 10:07:06.706 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.670466 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data5
03-16 10:07:06.707 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.670729 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data6
03-16 10:07:06.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.673126 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data7
03-16 10:07:06.710 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.673439 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data8
03-16 10:07:06.711 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.674602 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on wlan0
03-16 10:07:06.712 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.674963 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 p2p0
03-16 10:07:06.714 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.685853 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:07:06.715 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.686190 multicast.go:209: DEBUG: recv 69 bytes from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226
03-16 10:07:06.716 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.686394 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:06.717 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:06.686500 local.go:193: DEBUG: discover: Received local announcement from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:07.737 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:07.783 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:07.782870 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 3.77 ms
03-16 10:07:08.266 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:08.266081 gui.go:475: DEBUG: http: GET "/rest/events/disk?limit=25": status 200, 2 bytes in 60001.27 ms
03-16 10:07:08.288 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:08.288207 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 1.22 ms
03-16 10:07:08.292 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:08.291806 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.31 ms
03-16 10:07:08.310 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:08.310270 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.49 ms
03-16 10:07:08.311 I/chromium(3766): [INFO:CONSOLE(709)] "refreshGlobalChanges", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (709)
03-16 10:07:08.332 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:08.332256 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.32 ms
03-16 10:07:08.350 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:07:08.412 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:07:08.444 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:07:08.484 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:07:09.306 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:09.306542 service.go:305: DEBUG: Reconnect loop
03-16 10:07:09.310 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:09.308147 service.go:342: DEBUG: Reconnect loop for DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 [tcp4://192.168.0.150,tcp4://home.vs-dev.com]
03-16 10:07:09.499 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:09.499155 structs.go:188: DEBUG: dialing DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 tcp4://192.168.0.150,tcp4://home.vs-dev.com prio 10
03-16 10:07:09.592 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:09.592652 structs.go:191: DEBUG: dialing DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 tcp4://192.168.0.150,tcp4://home.vs-dev.com error: dial tcp4: lookup 192.168.0.150,tcp4: No address associated with hostname
03-16 10:07:09.594 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:09.593621 service.go:804: DEBUG: failed to connect to DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 10
03-16 10:07:09.595 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:09.593740 service.go:426: DEBUG: sleep until next dial 1m0s
03-16 10:07:11.465 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.465154 rwfolder.go:204: DEBUG: sendReceiveFolder/2srju-3nqqb@0x44200a6280 Scanning subdirectories
03-16 10:07:11.467 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.467004 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211508224 10618138624} <nil>
03-16 10:07:11.468 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.467618 events.go:244: DEBUG: log 53 StateChanged map[from:idle duration:62.491230237 folder:2srju-3nqqb to:scanning]
03-16 10:07:11.470 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.467807 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd100 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021c9c0 0x44201ea828 0x44202fc878}
03-16 10:07:11.471 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.468044 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211504128 10618138624} <nil>
03-16 10:07:11.476 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.468928 gui.go:475: DEBUG: http: GET "/rest/events?since=52": status 200, 173 bytes in 28046.66 ms
03-16 10:07:11.478 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.469760 gui.go:475: DEBUG: http: GET "/rest/events?since=52&limit=0": status 200, 173 bytes in 3622.59 ms
03-16 10:07:11.551 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.551619 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:07:11.556 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.553548 walk.go:164: DEBUG: Walk progress done 2srju-3nqqb [] 131072 &{0x44201cd100 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021c9c0 0x44201ea828 0x44202fc878}
03-16 10:07:11.558 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.553869 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211495936 10618138624} <nil>
03-16 10:07:11.561 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.553979 set.go:198: DEBUG: 2srju-3nqqb WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:07:11.581 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.581339 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211495936 10618138624} <nil>
03-16 10:07:11.583 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.582498 events.go:244: DEBUG: log 54 StateChanged map[folder:2srju-3nqqb to:idle from:scanning duration:0.11498625]
03-16 10:07:11.584 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.582998 folderscanner.go:44: DEBUG: &{60000000000 0x4420450740 0x442021cb40 0x442021cba0} next rescan in 1m14.772894116s
03-16 10:07:11.585 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.583243 gui.go:475: DEBUG: http: GET "/rest/events?since=53": status 200, 171 bytes in 99.66 ms
03-16 10:07:11.599 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:11.599181 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.78 ms
03-16 10:07:11.662 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:07:18.562 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:18.562409 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 474 bytes in 4.82 ms
03-16 10:07:18.562 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:18.576 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:18.576432 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.45 ms
03-16 10:07:18.579 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:18.578790 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.42 ms
03-16 10:07:18.585 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:18.585433 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.79 ms
03-16 10:07:18.610 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:07:18.670 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:07:18.704 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:07:18.730 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:07:26.523 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:26.567 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:26.567445 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 171 bytes in 0.69 ms
03-16 10:07:26.617 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:26.617648 gui.go:475: DEBUG: http: GET "/rest/events?since=53&limit=0": status 200, 171 bytes in 0.71 ms
03-16 10:07:28.557 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:28.557723 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 4.09 ms
03-16 10:07:28.559 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:28.561 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:28.560821 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 1.97 ms
03-16 10:07:28.567 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:28.566762 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.33 ms
03-16 10:07:28.584 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:28.570751 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.49 ms
03-16 10:07:28.593 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:07:28.626 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:07:28.667 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:07:28.691 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:07:32.345 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.345023 rwfolder.go:204: DEBUG: sendReceiveFolder/2rfiy-7re2v@0x44200a7180 Scanning subdirectories
03-16 10:07:32.347 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.345825 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211360768 10618138624} <nil>
03-16 10:07:32.348 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.346613 events.go:244: DEBUG: log 55 StateChanged map[folder:2rfiy-7re2v to:scanning from:idle duration:63.39816383]
03-16 10:07:32.349 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.346867 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd120 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021ccc0 0x44201ea848 0x44202fc928}
03-16 10:07:32.352 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.347223 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211360768 10618138624} <nil>
03-16 10:07:32.356 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.347486 gui.go:475: DEBUG: http: GET "/rest/events?since=54": status 200, 171 bytes in 20742.67 ms
03-16 10:07:32.703 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.702683 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:07:32.705 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.703494 walk.go:164: DEBUG: Walk progress done 2rfiy-7re2v [] 131072 &{0x44201cd120 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021ccc0 0x44201ea848 0x44202fc928}
03-16 10:07:32.707 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.704280 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211352576 10618138624} <nil>
03-16 10:07:32.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.704514 set.go:198: DEBUG: 2rfiy-7re2v WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:07:32.772 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.771840 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211352576 10618138624} <nil>
03-16 10:07:32.773 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.772751 events.go:244: DEBUG: log 56 StateChanged map[folder:2rfiy-7re2v to:idle from:scanning duration:0.426329219]
03-16 10:07:32.775 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.772978 folderscanner.go:44: DEBUG: &{60000000000 0x4420450880 0x442021ce40 0x442021cea0} next rescan in 45.358065047s
03-16 10:07:32.777 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.773390 gui.go:475: DEBUG: http: GET "/rest/events?since=55": status 200, 172 bytes in 411.75 ms
03-16 10:07:32.792 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:32.792144 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.99 ms
03-16 10:07:32.859 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:07:35.975 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:35.975423 rwfolder.go:204: DEBUG: sendReceiveFolder/uua35-odjy0@0x44200a7400 Scanning subdirectories
03-16 10:07:35.977 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:35.977385 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211328000 10618138624} <nil>
03-16 10:07:35.979 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:35.979387 events.go:244: DEBUG: log 57 StateChanged map[folder:uua35-odjy0 to:scanning from:idle duration:56.751733468]
03-16 10:07:35.981 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:35.980825 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd160 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021d260 0x44201ea888 0x44202fca98}
03-16 10:07:35.982 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:35.981626 gui.go:475: DEBUG: http: GET "/rest/events?since=56": status 200, 173 bytes in 3191.77 ms
03-16 10:07:35.983 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:35.982835 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211328000 10618138624} <nil>
03-16 10:07:36.010 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.010510 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:07:36.049 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.048879 walk.go:164: DEBUG: Walk progress done uua35-odjy0 [] 131072 &{0x44201cd160 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021d260 0x44201ea888 0x44202fca98}
03-16 10:07:36.050 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.049770 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211328000 10618138624} <nil>
03-16 10:07:36.052 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.049893 set.go:198: DEBUG: uua35-odjy0 WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:07:36.057 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.057637 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211328000 10618138624} <nil>
03-16 10:07:36.058 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.058223 events.go:244: DEBUG: log 58 StateChanged map[folder:uua35-odjy0 to:idle from:scanning duration:0.079051667]
03-16 10:07:36.060 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.059443 folderscanner.go:44: DEBUG: &{60000000000 0x4420450b40 0x442021d3e0 0x442021d440} next rescan in 1m14.882073035s
03-16 10:07:36.061 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.059849 gui.go:475: DEBUG: http: GET "/rest/events?since=57": status 200, 172 bytes in 59.85 ms
03-16 10:07:36.118 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.118393 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.96 ms
03-16 10:07:36.176 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:07:36.663 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.662839 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 lo
03-16 10:07:36.666 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.665921 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on dummy0
03-16 10:07:36.669 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.667871 broadcast.go:122: DEBUG: addresses: [192.168.0.255]
03-16 10:07:36.670 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.668617 broadcast.go:147: DEBUG: sent 69 bytes to 192.168.0.255:21027
03-16 10:07:36.673 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.668817 broadcast.go:204: DEBUG: recv 69 bytes from 192.168.0.100:44802
03-16 10:07:36.674 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.668918 multicast.go:209: DEBUG: recv 69 bytes from [fe80::440f:30ff:fe89:93f6%dummy0]:41226
03-16 10:07:36.676 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.669091 local.go:193: DEBUG: discover: Received local announcement from [fe80::440f:30ff:fe89:93f6%dummy0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:36.679 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.669190 local.go:193: DEBUG: discover: Received local announcement from 192.168.0.100:44802 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:36.681 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.669778 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 sit0
03-16 10:07:36.682 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.670903 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet0
03-16 10:07:36.683 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.671376 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data0
03-16 10:07:36.685 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.671632 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data1
03-16 10:07:36.687 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.671797 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data2
03-16 10:07:36.688 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.671927 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data3
03-16 10:07:36.689 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.672049 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data4
03-16 10:07:36.690 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.672169 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data5
03-16 10:07:36.692 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.672288 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data6
03-16 10:07:36.693 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.672812 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on rmnet_data7
03-16 10:07:36.694 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.673148 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:07:36.695 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.673320 multicast.go:209: DEBUG: recv 69 bytes from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226
03-16 10:07:36.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.673481 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:36.698 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.673586 local.go:193: DEBUG: discover: Received local announcement from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:36.703 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.674057 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on r_rmnet_data0
03-16 10:07:36.704 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.674349 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data1
03-16 10:07:36.705 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.674539 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data2
03-16 10:07:36.707 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.674733 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data3
03-16 10:07:36.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.674883 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data4
03-16 10:07:36.709 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.675009 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data5
03-16 10:07:36.710 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.675152 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data6
03-16 10:07:36.711 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.675308 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data7
03-16 10:07:36.712 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.675436 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data8
03-16 10:07:36.714 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.675863 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on wlan0
03-16 10:07:36.717 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.676160 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 p2p0
03-16 10:07:36.718 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.677207 multicast.go:209: DEBUG: recv 69 bytes from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226
03-16 10:07:36.720 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.677485 local.go:193: DEBUG: discover: Received local announcement from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:36.721 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.678093 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:07:36.722 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:36.678342 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:07:38.555 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:38.555230 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 474 bytes in 3.02 ms
03-16 10:07:38.560 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:38.560100 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.51 ms
03-16 10:07:38.564 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:38.564763 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 7.05 ms
03-16 10:07:38.579 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:38.578853 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.19 ms
03-16 10:07:38.598 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:07:38.657 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:07:38.684 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:07:38.712 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:07:41.633 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:41.678 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:41.678245 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 0.96 ms
03-16 10:07:41.739 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:41.738645 gui.go:475: DEBUG: http: GET "/rest/events?since=54&limit=0": status 200, 685 bytes in 1.19 ms
03-16 10:07:45.718 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.717626 rofolder.go:50: DEBUG: sendOnlyFolder/hku3c-ud81p@0x4420304200 Scanning subdirectories
03-16 10:07:45.725 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.721605 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211282944 10618138624} <nil>
03-16 10:07:45.726 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.726327 events.go:244: DEBUG: log 59 StateChanged map[folder:hku3c-ud81p to:scanning from:idle duration:62.369466017]
03-16 10:07:45.728 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.726969 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd140 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021cfc0 0x44201ea868 0x44202fca00}
03-16 10:07:45.729 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.729566 gui.go:475: DEBUG: http: GET "/rest/events?since=58": status 200, 173 bytes in 9610.42 ms
03-16 10:07:45.736 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.731103 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211278848 10618138624} <nil>
03-16 10:07:45.811 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.811580 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:07:45.813 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.813667 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211278848 10618138624} <nil>
03-16 10:07:45.815 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.814889 set.go:198: DEBUG: hku3c-ud81p WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:07:45.827 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.827608 walk.go:164: DEBUG: Walk progress done hku3c-ud81p [] 131072 &{0x44201cd140 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021cfc0 0x44201ea868 0x44202fca00}
03-16 10:07:45.835 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.835182 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211278848 10618138624} <nil>
03-16 10:07:45.836 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.836360 events.go:244: DEBUG: log 60 StateChanged map[folder:hku3c-ud81p to:idle from:scanning duration:0.110064063]
03-16 10:07:45.839 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.836642 folderscanner.go:44: DEBUG: &{60000000000 0x4420450a40 0x442021d140 0x442021d1a0} next rescan in 1m6.517489107s
03-16 10:07:45.840 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.837167 gui.go:475: DEBUG: http: GET "/rest/events?since=59": status 200, 172 bytes in 87.37 ms
03-16 10:07:45.894 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:45.894441 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.87 ms
03-16 10:07:45.934 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:07:48.557 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:48.557245 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 3.16 ms
03-16 10:07:48.564 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:48.562137 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.38 ms
03-16 10:07:48.579 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:48.579490 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.29 ms
03-16 10:07:48.588 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:48.588720 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 2.37 ms
03-16 10:07:48.619 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:07:48.672 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:07:48.692 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:07:48.713 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:07:56.770 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:56.819 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:56.818833 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 1.05 ms
03-16 10:07:56.878 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:56.877872 gui.go:475: DEBUG: http: GET "/rest/events?since=58&limit=0": status 200, 344 bytes in 0.73 ms
03-16 10:07:58.560 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:58.558825 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 5.37 ms
03-16 10:07:58.560 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:07:58.572 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:58.572167 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.30 ms
03-16 10:07:58.582 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:58.582706 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 8.72 ms
03-16 10:07:58.585 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:07:58.585064 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.31 ms
03-16 10:07:58.609 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:07:58.671 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:07:58.694 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:07:58.713 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:06.663 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.662628 broadcast.go:122: DEBUG: addresses: [192.168.0.255]
03-16 10:08:06.666 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.665574 broadcast.go:147: DEBUG: sent 69 bytes to 192.168.0.255:21027
03-16 10:08:06.667 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.665937 broadcast.go:204: DEBUG: recv 69 bytes from 192.168.0.100:44802
03-16 10:08:06.668 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.666233 local.go:193: DEBUG: discover: Received local announcement from 192.168.0.100:44802 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:06.670 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.666385 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 lo
03-16 10:08:06.671 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.666950 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on dummy0
03-16 10:08:06.674 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.667156 multicast.go:209: DEBUG: recv 69 bytes from [fe80::440f:30ff:fe89:93f6%dummy0]:41226
03-16 10:08:06.675 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.667366 local.go:193: DEBUG: discover: Received local announcement from [fe80::440f:30ff:fe89:93f6%dummy0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:06.676 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.667496 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 sit0
03-16 10:08:06.677 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.667713 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet0
03-16 10:08:06.679 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.667859 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data0
03-16 10:08:06.682 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.667984 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data1
03-16 10:08:06.683 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.668106 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data2
03-16 10:08:06.685 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.668225 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data3
03-16 10:08:06.686 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.668344 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data4
03-16 10:08:06.687 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.668465 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data5
03-16 10:08:06.688 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.668584 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data6
03-16 10:08:06.690 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.669133 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on rmnet_data7
03-16 10:08:06.691 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.669326 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:08:06.692 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.669498 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:06.693 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.669638 multicast.go:209: DEBUG: recv 69 bytes from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226
03-16 10:08:06.695 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.669768 local.go:193: DEBUG: discover: Received local announcement from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:06.696 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.669864 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on r_rmnet_data0
03-16 10:08:06.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670072 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data1
03-16 10:08:06.698 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670220 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data2
03-16 10:08:06.699 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670351 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data3
03-16 10:08:06.703 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670490 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data4
03-16 10:08:06.704 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670611 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data5
03-16 10:08:06.705 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670731 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data6
03-16 10:08:06.706 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670851 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data7
03-16 10:08:06.707 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.670970 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data8
03-16 10:08:06.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.672010 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on wlan0
03-16 10:08:06.710 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.672178 multicast.go:209: DEBUG: recv 69 bytes from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226
03-16 10:08:06.712 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.672497 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 p2p0
03-16 10:08:06.713 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.672651 local.go:193: DEBUG: discover: Received local announcement from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:06.714 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.673873 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:08:06.715 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:06.674170 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:08.557 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:08.557306 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 3.00 ms
03-16 10:08:08.562 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:08.562463 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.32 ms
03-16 10:08:08.575 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:08.573869 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 586 bytes in 0.77 ms
03-16 10:08:08.578 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:08.577651 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.31 ms
03-16 10:08:08.608 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:08:08.660 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:08:08.693 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:08:08.713 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:09.594 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:09.594493 service.go:305: DEBUG: Reconnect loop
03-16 10:08:09.596 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:09.595356 service.go:342: DEBUG: Reconnect loop for DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 [tcp4://192.168.0.150,tcp4://home.vs-dev.com]
03-16 10:08:09.793 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:09.793540 structs.go:188: DEBUG: dialing DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 tcp4://192.168.0.150,tcp4://home.vs-dev.com prio 10
03-16 10:08:09.881 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:09.880992 structs.go:191: DEBUG: dialing DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 tcp4://192.168.0.150,tcp4://home.vs-dev.com error: dial tcp4: lookup 192.168.0.150,tcp4: No address associated with hostname
03-16 10:08:09.882 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:09.881162 service.go:804: DEBUG: failed to connect to DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 10
03-16 10:08:09.882 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:09.881235 service.go:426: DEBUG: sleep until next dial 1m0s
03-16 10:08:11.895 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:08:11.938 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:11.938460 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 1.11 ms
03-16 10:08:17.069 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:08:18.132 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.131801 rwfolder.go:204: DEBUG: sendReceiveFolder/2rfiy-7re2v@0x44200a7180 Scanning subdirectories
03-16 10:08:18.134 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.133931 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211221504 10618138624} <nil>
03-16 10:08:18.135 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.134842 events.go:244: DEBUG: log 61 StateChanged map[folder:2rfiy-7re2v to:scanning from:idle duration:45.361801702]
03-16 10:08:18.136 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.135094 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd120 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021ccc0 0x44201ea848 0x44202fc928}
03-16 10:08:18.138 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.135435 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211221504 10618138624} <nil>
03-16 10:08:18.139 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.136155 gui.go:475: DEBUG: http: GET "/rest/events?since=60": status 200, 173 bytes in 32240.43 ms
03-16 10:08:18.140 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.137598 gui.go:475: DEBUG: http: GET "/rest/events?since=60&limit=0": status 200, 173 bytes in 1020.19 ms
03-16 10:08:18.141 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.138101 gui.go:475: DEBUG: http: GET "/rest/events?since=60&limit=0": status 200, 173 bytes in 6140.79 ms
03-16 10:08:18.158 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.157567 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 2.43 ms
03-16 10:08:18.160 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.160494 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.30 ms
03-16 10:08:18.167 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.167690 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.70 ms
03-16 10:08:18.170 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.170352 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.31 ms
03-16 10:08:18.254 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:08:18.294 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:08:18.314 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:08:18.337 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:18.724 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.724634 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:08:18.727 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.725692 walk.go:164: DEBUG: Walk progress done 2rfiy-7re2v [] 131072 &{0x44201cd120 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021ccc0 0x44201ea848 0x44202fc928}
03-16 10:08:18.728 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.726028 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211221504 10618138624} <nil>
03-16 10:08:18.729 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.726152 set.go:198: DEBUG: 2rfiy-7re2v WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:08:18.797 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.797481 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211217408 10618138624} <nil>
03-16 10:08:18.799 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.798460 events.go:244: DEBUG: log 62 StateChanged map[folder:2rfiy-7re2v to:idle from:scanning duration:0.663888072]
03-16 10:08:18.801 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.798661 folderscanner.go:44: DEBUG: &{60000000000 0x4420450880 0x442021ce40 0x442021cea0} next rescan in 1m0.320121716s
03-16 10:08:18.802 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.799047 gui.go:475: DEBUG: http: GET "/rest/events?since=61": status 200, 172 bytes in 618.74 ms
03-16 10:08:18.820 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:18.817925 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 1.01 ms
03-16 10:08:18.878 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:08:26.357 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.356812 rwfolder.go:204: DEBUG: sendReceiveFolder/2srju-3nqqb@0x44200a6280 Scanning subdirectories
03-16 10:08:26.359 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.359292 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211205120 10618138624} <nil>
03-16 10:08:26.361 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.360860 events.go:244: DEBUG: log 63 StateChanged map[folder:2srju-3nqqb to:scanning from:idle duration:74.777954034]
03-16 10:08:26.362 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.361165 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd100 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021c9c0 0x44201ea828 0x44202fc878}
03-16 10:08:26.367 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.361739 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211201024 10618138624} <nil>
03-16 10:08:26.369 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.362559 gui.go:475: DEBUG: http: GET "/rest/events?since=62": status 200, 172 bytes in 7543.06 ms
03-16 10:08:26.439 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.439539 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:08:26.440 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.440561 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211201024 10618138624} <nil>
03-16 10:08:26.442 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.440681 set.go:198: DEBUG: 2srju-3nqqb WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:08:26.443 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.443179 walk.go:164: DEBUG: Walk progress done 2srju-3nqqb [] 131072 &{0x44201cd100 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021c9c0 0x44201ea828 0x44202fc878}
03-16 10:08:26.462 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.462400 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211196928 10618138624} <nil>
03-16 10:08:26.464 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.463427 events.go:244: DEBUG: log 64 StateChanged map[duration:0.102851979 folder:2srju-3nqqb to:idle from:scanning]
03-16 10:08:26.465 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.463645 folderscanner.go:44: DEBUG: &{60000000000 0x4420450740 0x442021cb40 0x442021cba0} next rescan in 1m5.262403719s
03-16 10:08:26.466 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.463998 gui.go:475: DEBUG: http: GET "/rest/events?since=63": status 200, 172 bytes in 86.90 ms
03-16 10:08:26.485 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:26.485508 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.86 ms
03-16 10:08:26.537 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:08:28.562 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:28.561660 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.34 ms
03-16 10:08:28.569 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:28.568935 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 8.73 ms
03-16 10:08:28.572 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:28.572012 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.77 ms
03-16 10:08:28.582 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:28.582059 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.40 ms
03-16 10:08:28.619 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:08:28.654 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:08:28.679 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:08:28.719 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:33.154 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:08:33.198 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:33.198690 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 1.07 ms
03-16 10:08:33.258 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:33.258323 gui.go:475: DEBUG: http: GET "/rest/events?since=61&limit=0": status 200, 514 bytes in 1.19 ms
03-16 10:08:36.662 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.662488 broadcast.go:122: DEBUG: addresses: [192.168.0.255]
03-16 10:08:36.666 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.666274 broadcast.go:147: DEBUG: sent 69 bytes to 192.168.0.255:21027
03-16 10:08:36.667 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.666464 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 lo
03-16 10:08:36.669 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.666875 broadcast.go:204: DEBUG: recv 69 bytes from 192.168.0.100:44802
03-16 10:08:36.670 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667051 local.go:193: DEBUG: discover: Received local announcement from 192.168.0.100:44802 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:36.672 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667130 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on dummy0
03-16 10:08:36.673 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667340 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 sit0
03-16 10:08:36.675 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667476 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet0
03-16 10:08:36.676 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667599 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data0
03-16 10:08:36.677 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667720 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data1
03-16 10:08:36.679 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667841 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data2
03-16 10:08:36.680 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.667961 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data3
03-16 10:08:36.681 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.668080 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data4
03-16 10:08:36.682 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.668214 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data5
03-16 10:08:36.683 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.668334 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data6
03-16 10:08:36.684 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.669025 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on rmnet_data7
03-16 10:08:36.686 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.669663 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on r_rmnet_data0
03-16 10:08:36.688 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.671224 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data1
03-16 10:08:36.689 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.671414 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data2
03-16 10:08:36.690 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.671554 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data3
03-16 10:08:36.691 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.671693 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data4
03-16 10:08:36.692 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.671892 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data5
03-16 10:08:36.694 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.672147 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data6
03-16 10:08:36.695 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.672358 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data7
03-16 10:08:36.696 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.672486 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data8
03-16 10:08:36.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673053 multicast.go:209: DEBUG: recv 69 bytes from [fe80::440f:30ff:fe89:93f6%dummy0]:41226
03-16 10:08:36.698 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673235 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:08:36.700 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673345 multicast.go:209: DEBUG: recv 69 bytes from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226
03-16 10:08:36.701 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673437 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:08:36.703 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673569 local.go:193: DEBUG: discover: Received local announcement from [fe80::440f:30ff:fe89:93f6%dummy0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:36.704 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673669 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:36.705 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673756 local.go:193: DEBUG: discover: Received local announcement from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:36.707 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.673841 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:36.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.674419 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on wlan0
03-16 10:08:36.709 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.674699 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 p2p0
03-16 10:08:36.711 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.674809 multicast.go:209: DEBUG: recv 69 bytes from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226
03-16 10:08:36.712 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:36.674976 local.go:193: DEBUG: discover: Received local announcement from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:08:38.555 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:38.554869 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 1.30 ms
03-16 10:08:38.568 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:38.568326 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.30 ms
03-16 10:08:38.568 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:08:38.579 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:38.578649 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 585 bytes in 0.87 ms
03-16 10:08:38.581 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:38.580801 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.30 ms
03-16 10:08:38.610 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:08:38.670 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:08:38.692 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:38.715 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:08:48.287 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:08:48.328 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:48.328233 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 1.08 ms
03-16 10:08:48.557 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:48.556853 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 3.26 ms
03-16 10:08:48.560 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:48.559958 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.31 ms
03-16 10:08:48.575 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:48.575090 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.27 ms
03-16 10:08:48.577 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:48.577485 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.73 ms
03-16 10:08:48.612 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:08:48.675 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:08:48.703 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:48.725 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:08:50.942 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.942322 rwfolder.go:204: DEBUG: sendReceiveFolder/uua35-odjy0@0x44200a7400 Scanning subdirectories
03-16 10:08:50.944 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.944456 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211016704 10618138624} <nil>
03-16 10:08:50.945 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.945521 events.go:244: DEBUG: log 65 StateChanged map[duration:74.887100909 folder:uua35-odjy0 to:scanning from:idle]
03-16 10:08:50.952 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.945861 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd160 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021d260 0x44201ea888 0x44202fca98}
03-16 10:08:50.957 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.946690 gui.go:475: DEBUG: http: GET "/rest/events?since=64": status 200, 173 bytes in 24455.65 ms
03-16 10:08:50.960 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.947965 gui.go:475: DEBUG: http: GET "/rest/events?since=64&limit=0": status 200, 173 bytes in 2550.21 ms
03-16 10:08:50.962 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.951790 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211016704 10618138624} <nil>
03-16 10:08:50.985 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:50.984921 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:08:51.019 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.019211 walk.go:164: DEBUG: Walk progress done uua35-odjy0 [] 131072 &{0x44201cd160 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021d260 0x44201ea888 0x44202fca98}
03-16 10:08:51.020 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.020104 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211016704 10618138624} <nil>
03-16 10:08:51.021 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.020223 set.go:198: DEBUG: uua35-odjy0 WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:08:51.027 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.027693 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2211012608 10618138624} <nil>
03-16 10:08:51.029 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.029328 events.go:244: DEBUG: log 66 StateChanged map[from:scanning duration:0.083999011 folder:uua35-odjy0 to:idle]
03-16 10:08:51.030 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.030047 folderscanner.go:44: DEBUG: &{60000000000 0x4420450b40 0x442021d3e0 0x442021d440} next rescan in 1m2.424902549s
03-16 10:08:51.031 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.030436 gui.go:475: DEBUG: http: GET "/rest/events?since=65": status 200, 172 bytes in 60.29 ms
03-16 10:08:51.069 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:51.069408 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 0.88 ms
03-16 10:08:51.117 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:08:52.355 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.354813 rofolder.go:50: DEBUG: sendOnlyFolder/hku3c-ud81p@0x4420304200 Scanning subdirectories
03-16 10:08:52.357 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.357224 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2210971648 10618138624} <nil>
03-16 10:08:52.360 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.360189 events.go:244: DEBUG: log 67 StateChanged map[to:scanning from:idle duration:66.523799193 folder:hku3c-ud81p]
03-16 10:08:52.364 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.360682 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd140 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021cfc0 0x44201ea868 0x44202fca00}
03-16 10:08:52.366 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.361472 gui.go:475: DEBUG: http: GET "/rest/events?since=66": status 200, 173 bytes in 1290.14 ms
03-16 10:08:52.368 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.363272 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2210967552 10618138624} <nil>
03-16 10:08:52.418 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.417819 walk.go:241: DEBUG: ignored (internal): .stfolder
03-16 10:08:52.420 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.420195 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2210967552 10618138624} <nil>
03-16 10:08:52.422 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.420458 set.go:198: DEBUG: hku3c-ud81p WithPrefixedHaveTruncated(7777777-777777N-7777777-777777N-7777777-777777N-7777777-77777Q4)
03-16 10:08:52.423 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.422229 walk.go:164: DEBUG: Walk progress done hku3c-ud81p [] 131072 &{0x44201cd140 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021cfc0 0x44201ea868 0x44202fca00}
03-16 10:08:52.431 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.430636 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2210967552 10618138624} <nil>
03-16 10:08:52.432 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.430990 events.go:244: DEBUG: log 68 StateChanged map[from:scanning duration:0.070831563 folder:hku3c-ud81p to:idle]
03-16 10:08:52.435 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.431210 folderscanner.go:44: DEBUG: &{60000000000 0x4420450a40 0x442021d140 0x442021d1a0} next rescan in 59.6480241s
03-16 10:08:52.437 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:52.431803 gui.go:475: DEBUG: http: GET "/rest/events?since=67": status 200, 172 bytes in 52.31 ms
03-16 10:08:56.555 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:56.555303 gui.go:475: DEBUG: http: GET "/rest/stats/folder": status 200, 665 bytes in 1.10 ms
03-16 10:08:56.610 I/chromium(3766): [INFO:CONSOLE(694)] "refreshfolderStats", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (694)
03-16 10:08:58.557 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:58.556497 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 1.23 ms
03-16 10:08:58.563 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:58.563116 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.30 ms
03-16 10:08:58.575 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:58.575367 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.33 ms
03-16 10:08:58.578 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:08:58.578674 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 1.78 ms
03-16 10:08:58.608 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:08:58.664 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:08:58.691 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:08:58.715 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:09:05.968 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:09:06.009 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.008578 gui.go:475: DEBUG: http: GET "/rest/events?since=0&limit=1": status 200, 172 bytes in 1.09 ms
03-16 10:09:06.058 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.057948 gui.go:475: DEBUG: http: GET "/rest/events?since=65&limit=0": status 200, 515 bytes in 0.68 ms
03-16 10:09:06.662 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.662415 broadcast.go:122: DEBUG: addresses: [192.168.0.255]
03-16 10:09:06.666 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.665696 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 lo
03-16 10:09:06.667 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.667162 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on dummy0
03-16 10:09:06.668 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.668278 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 sit0
03-16 10:09:06.670 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.668610 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet0
03-16 10:09:06.671 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.668985 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data0
03-16 10:09:06.674 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.669149 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data1
03-16 10:09:06.675 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.669919 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data2
03-16 10:09:06.677 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.670091 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data3
03-16 10:09:06.678 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.670288 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data4
03-16 10:09:06.679 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.670488 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data5
03-16 10:09:06.680 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.670611 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 rmnet_data6
03-16 10:09:06.682 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.671507 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on rmnet_data7
03-16 10:09:06.683 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.676994 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on r_rmnet_data0
03-16 10:09:06.684 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.677459 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data1
03-16 10:09:06.685 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.677685 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data2
03-16 10:09:06.686 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.677850 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data3
03-16 10:09:06.688 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.677990 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data4
03-16 10:09:06.689 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.678120 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data5
03-16 10:09:06.690 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.678406 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data6
03-16 10:09:06.691 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.678555 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data7
03-16 10:09:06.693 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.678890 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 r_rmnet_data8
03-16 10:09:06.694 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.679607 multicast.go:129: DEBUG: sent 69 bytes to [ff12::8384]:21027 on wlan0
03-16 10:09:06.695 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.680045 broadcast.go:147: DEBUG: sent 69 bytes to 192.168.0.255:21027
03-16 10:09:06.697 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.680175 broadcast.go:204: DEBUG: recv 69 bytes from 192.168.0.100:44802
03-16 10:09:06.698 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.680331 local.go:193: DEBUG: discover: Received local announcement from 192.168.0.100:44802 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:09:06.701 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.680412 multicast.go:209: DEBUG: recv 69 bytes from [fe80::440f:30ff:fe89:93f6%dummy0]:41226
03-16 10:09:06.703 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.680553 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:09:06.704 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.681023 multicast.go:209: DEBUG: recv 69 bytes from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226
03-16 10:09:06.706 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.681663 multicast.go:209: DEBUG: recv 69 bytes from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226
03-16 10:09:06.707 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.682162 local.go:193: DEBUG: discover: Received local announcement from [fe80::440f:30ff:fe89:93f6%dummy0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:09:06.708 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.682299 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:09:06.709 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.682396 local.go:193: DEBUG: discover: Received local announcement from [fe80::448a:1cab:2f8a:5307%r_rmnet_data0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:09:06.710 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.682696 local.go:193: DEBUG: discover: Received local announcement from [fe80::7a02:f8ff:fea6:81e3%wlan0]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:09:06.712 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.682813 multicast.go:124: DEBUG: write udp [::]:41226->[ff12::8384]:21027: sendmsg: network is unreachable on write to [ff12::8384]:21027 p2p0
03-16 10:09:06.713 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.685379 multicast.go:209: DEBUG: recv 69 bytes from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226
03-16 10:09:06.715 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:06.685617 local.go:193: DEBUG: discover: Received local announcement from [fe80::c7c2:c7ac:401c:57f8%rmnet_data7]:41226 for SST4VY4-HSUXSRF-TYPGMMT-M7NTTWJ-RHZOLGO-JN3YE2X-E4ISH52-5HQVCQO
03-16 10:09:08.561 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:08.561439 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 4.67 ms
03-16 10:09:08.562 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:09:08.567 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:08.562543 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 0.59 ms
03-16 10:09:08.580 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:08.580187 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.96 ms
03-16 10:09:08.586 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:08.585855 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.29 ms
03-16 10:09:08.617 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:09:08.658 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:09:08.712 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:09:08.732 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:09:09.882 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:09.881947 service.go:305: DEBUG: Reconnect loop
03-16 10:09:09.883 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:09.882861 service.go:342: DEBUG: Reconnect loop for DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 [tcp4://192.168.0.150,tcp4://home.vs-dev.com]
03-16 10:09:10.087 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:10.087102 structs.go:188: DEBUG: dialing DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 tcp4://192.168.0.150,tcp4://home.vs-dev.com prio 10
03-16 10:09:10.172 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:10.171714 structs.go:191: DEBUG: dialing DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 tcp4://192.168.0.150,tcp4://home.vs-dev.com error: dial tcp4: lookup 192.168.0.150,tcp4: No address associated with hostname
03-16 10:09:10.173 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:10.172732 service.go:804: DEBUG: failed to connect to DWQAK3O-LNS35LI-UHGMY2J-T2GZSHY-MXI4DMO-MYALBPP-MJPFCY4-V3YHHQ6 10
03-16 10:09:10.174 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:10.172928 service.go:426: DEBUG: sleep until next dial 1m0s
03-16 10:09:18.560 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:18.559769 gui.go:475: DEBUG: http: GET "/rest/system/status": status 200, 475 bytes in 3.82 ms
03-16 10:09:18.560 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid, result:0x0
03-16 10:09:18.573 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:18.573475 gui.go:475: DEBUG: http: GET "/rest/system/connections": status 200, 587 bytes in 0.79 ms
03-16 10:09:18.584 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:18.584301 gui.go:475: DEBUG: http: GET "/rest/system/discovery": status 200, 2 bytes in 15.04 ms
03-16 10:09:18.592 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:18.591898 gui.go:475: DEBUG: http: GET "/rest/system/error": status 200, 15 bytes in 0.25 ms
03-16 10:09:18.609 I/chromium(3766): [INFO:CONSOLE(436)] "refreshSystem", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (436)
03-16 10:09:18.670 I/chromium(3766): [INFO:CONSOLE(546)] "refreshConnections", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (546)
03-16 10:09:18.689 I/chromium(3766): [INFO:CONSOLE(452)] "refreshDiscoveryCache", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (452)
03-16 10:09:18.713 I/chromium(3766): [INFO:CONSOLE(553)] "refreshErrors", source: https://127.0.0.1:8384/syncthing/core/syncthingController.js (553)
03-16 10:09:19.120 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:19.119699 rwfolder.go:204: DEBUG: sendReceiveFolder/2rfiy-7re2v@0x44200a7180 Scanning subdirectories
03-16 10:09:19.121 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:19.121413 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2210824192 10618138624} <nil>
03-16 10:09:19.123 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:19.122395 events.go:244: DEBUG: log 69 StateChanged map[to:scanning from:idle duration:60.323611487 folder:2rfiy-7re2v]
03-16 10:09:19.124 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:19.122632 walk.go:101: DEBUG: Walk [] 131072 &{0x44201cd120 [] [] false <nil> d41d8cd98f00b204e9800998ecf8427e 0x442021ccc0 0x44201ea848 0x44202fc928}
03-16 10:09:19.128 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:19.123326 gui.go:475: DEBUG: http: GET "/rest/events?since=68": status 200, 173 bytes in 26641.03 ms
03-16 10:09:19.130 I/SyncthingNativeCode(3766): [SST4V] 2018/03/16 07:09:19.125934 logfs.go:163: DEBUG: <autogenerated>:1 basic /data/user/0/com.nutomic.syncthingandroid/files/ Usage . {2210824192 10618138624} <nil>

Xiaomi Redmi 4, Android 6.0.1, MIUI 8.0

[vsespb]

Also when I set it not to run in background and choose exit from menu - it's still in the process list and eats around 13% CPU. Only Xiaomi MIUI close all apps helps to remove it.

[CyberShadow]

I rebooted my phone today, and an hour later noticed that it was hot. Battery was at 75% despite it being plugged in, so something was draining battery faster than it could charge (off a PC USB port).

The Android battery stats showed nothing but the screen and OS, but in htop I saw:

Curiously, the SyncThing app shows CPU usage below 1%, so it is lying about its own CPU usage.

[Catfriend1]

Ok, thats a good and huge log. I noticed the sendmsg failed lines and one time chrome webview crashed. Any memory issues so the memory got filled up? How much is the size of your sync folders and which are their paths? Another idea: please go to the syncthing options within the app and turn off local, global, nat discovery and relay. Test again with those settings disabled.

[vsespb]

Any memory issues so the memory got filled up?

yes, device is 2Gb RAM. it's often too low. however on clean system (all apps closed), if I run syncthing and immediatelly close it, issue is 100% reproducible - it's eating CPU (before and) after closed. in this case there are no issues with RAM

besides, it was ok before 0.14.45

How much is the size of your sync folders and which are their paths?

840 files, 1.2Gb in 4 folders. one of them on SD card (set as readonly)

Another idea: please go to the syncthing options within the app and turn off local, global, nat discovery and relay. Test again with those settings disabled.

done. turned off local discovery. others were off already.
it did not help

[AudriusButkevicius]

What is the web UI showing for folders?

[vsespb]

UI show green "Idle" for all folders. Web UI shows green square for all folders.
Folders are not broken and files from them syncs ok both directions.

[AudriusButkevicius]

Make sure they don't briefly appear as scanning. You can enable other logging facilities in the web UI and see via the low viewer if anything crops up. Suggest you start with model facility.

[vsespb]

You can enable other logging facilities in the web UI

As I wrote in 1st post, I enabled _all_ logging facilities before run logcat

Make sure they don't briefly appear as scanning

no, they don't. beside they should not. as I wrote above it eats CPU even after I exit the application.

[Catfriend1]

Could that be related to the issue?
Scheduling restart of crashed service com.nutomic.syncthingandroid/.service.SyncthingService in 112342ms
So the activity ends and the seevice gets reinstated in the Background. I had that sometimes on appv 0.9.x but now the problem did not occur with 0.10.x

[AudriusButkevicius]

It seems that there is a run-away syncthing process or something like that, especially if it does not go away when you kill the app.

The log doesn't seem to say that syncthing is doing anything much.

[AudriusButkevicius]

Actually, it's the android app that is consuming the CPU rather than syncthing itself.
Is there anything in the android log?

[vsespb]

nothing related to syncthing. nothing interesting. what to look for?

[AudriusButkevicius]

So android app used to have two logs, one for syncthing and one for the app, perhaps there is something in the app logs?

The only other alternative I could suggest would be compiling the app yourself and running it debug mode on the phone, and interrupting it when it's in this CPU burning cycle to see what it's doing.

[vsespb]

when I exit syncthing, logcat shows nothing for SyncthingNativeCode nor for SyncthingService, and it still eats cpu and still in the process list.

and this started only after upgrading to 0.14.45

[AudriusButkevicius]

0.14.45 is the version of syncthing, I assume that's 0.10.7 Android?

[AudriusButkevicius]

To be honest, Android sand box should kill all processes in the sandbox as you force kill the app, the fact it doesn't do that feels more like a OS issue.

[vsespb]

0.14.45 is the version of syncthing, I assume that's 0.10.7 Android?

0.10.6, latest from google play.

Android sand box should kill all processes in the sandbox as you force kill the app

• If I close all background apps (with xiaomi recent apps button->close button) syncthing process gone (in 80% cases). this way I can use my phone (otherwise it would eat all battery).

• If I set syncthing to not to run in background and exit it from syncthing menu (this way syncthing icon is gone from notifications) - it's eating cpu and in the process list.

• If set to run in backgroud - will eat cpu

• If I wont' close app, it eats cpu.

• If I open app and do thing, soon phone will be hot, and syncthing will be in very top of battery eaters with high total CPU value.

do that feels more like a OS issue

any other app that I use don't do that

[AudriusButkevicius]

Sure, but it doesn't do that on my phone, so I am not sure how I can help you if there is nothing in the logs, etc.

[AudriusButkevicius]

https://github.com/syncthing/syncthing-android/compare/0.10.5...0.10.6

There is pretty much nothing that changed between 10.5 and 10.6, apart from syncthing itself, which is clearly not the culprit here. Have you tried reinstalling the app fresh?

[Catfriend1]

What happens if you temporarily switch the two devices remote ip address to dynamic and configure the other two to proactively connect to the phones syncthing instance by tcp4://.... ? To save battery I did the same on my phone and nas server so nas connects to phone and phone does not try to connect every x seconds.

[AudriusButkevicius]

Also, do you have root mode enabled, or is your phone rooted in general?

[Catfriend1]

Does this debug build help you out or give any helpful catlog output you could not gather before with the release build?
Please Backup your data first - the debug build may contain bugs.
https://drive.google.com/open?id=1SpOh7NgKxhcGwu_6y7CE-tSpAP7wabAM

[vsespb]

Also, do you have root mode enabled, or is your phone rooted in general?

no root.

also, there are 3 more people experiencing battery problems in recent version. maybe similar reason.

https://github.com/syncthing/syncthing-android/issues/1043#issuecomment-373945077
https://github.com/syncthing/syncthing-android/issues/1038#issue-305078541
https://github.com/syncthing/syncthing-android/issues/1038#issuecomment-372970601

i'll try reinstall or debug build later.

[vsespb]

Does this debug build help you out or give any helpful catlog output you could not gather before with the release build?

I installed it. It has it's own settings (different one folder, no devices), I think default settings.
It behaves same way - eats 13% CPU (process name "debug").
How can I grab additional debug output? Nothing new in logcat after I exit app (and it still eats cpu).

[vsespb]

0.10.5...0.10.6
There is pretty much nothing that changed between 10.5 and 10.6,

previous syncthing version that I had was 0.14.43 (I think so because I reported this issue https://github.com/syncthing/syncthing-android/issues/1014 and specified version there).

0.10.5 had syncthing 0.14.45 ( I think so because I see here https://github.com/syncthing/syncthing-android/compare/0.10.4...0.10.5 the commit "Updated Syncthing to v0.14.45").

so, my previous version of syncthing was not 0.10.5.
I recall now that 0.10.5 was completelly broken ( https://github.com/syncthing/syncthing-android/issues/1033 and https://github.com/syncthing/syncthing-android/issues/1032 ), thus I dont count it as a version, because I did not use it much and any CPU usage could be related to its known issue.

[AudriusButkevicius]

0.10.3 was 0.14.43, yet there is no meaningful change between that and current versioner either.

[vsespb]

possibly related issue: https://github.com/syncthing/syncthing-android/issues/1020

what is same:

• 9-11% CPU
• I undertand that the fact is that it's always eats cpu.

what is different:

different version.

maybe bug related not to version, but to external factors

[Catfriend1]

@vsespb Please try removing the tcp4:// and temporarily set one side (the side we analyse) to dynamic. If it does not help, revert the change.

Second thing to try: Is there a way to temporarily disable Xiaomi Firewall or put syncthing to the exception list? It may probably cause some port binding to fail internally.

[vsespb]

Please try removing the tcp4:// and temporarily set one side (the side we analyse) to dynamic. If it does not help, revert the change

I ran this https://github.com/syncthing/syncthing-android/issues/1043#issuecomment-374768435 debug build. There are no folders at all. And no devices. No tcp4 settings.
Same - eats cpu untill killed.

Is there a way to temporarily disable Xiaomi Firewall or put syncthing to the exception list?

afaik Xiaomi Firewall settings for user is to allow/disable wifi and mobile traffic. that's it. both are enabled for syncthing. not sure about other functionality and how to disable it.

[Catfriend1]

Thanks for Feedback. sorry, but I have no idea whats causing the battery drain. Can you try ignoring battery optimizations in android settings or - if you did - turning regular battery opti by Android on as this is androids default? Again sorry if im out of ideas. I dont want to take your time that much.

[vsespb]

Okay.
but just in case for others who could have toughts about this. i found another two people who reports something that could be similar issue.
https://github.com/syncthing/syncthing-android/issues/942#issue-255495026
https://github.com/syncthing/syncthing-android/issues/942#issuecomment-331188030

[Catfriend1]

Hmm.. good reference. I also thought of FolderObserver in my other issue which does not seem to be the cause of the battery drain here but something we can optimize in the future in regard to cpu/ram resource usage. In this comment ( https://github.com/syncthing/syncthing-android/issues/942#issuecomment-331188030 ) Nutomic suspected the app's wait cycle for syncthing to launch never end. So, when I have more time I'll make a log comparison between yours and mine. I hope the code properly logs "the end of the wait cycle" with something like "syncthing start successfully confirmed". If not, we should implement this in code.

[Nutomic]

@Catfriend1 If FolderObserver is a problem, you could remove or disable that code and use the built in inotify in Syncthing. Once that is enabled by default, you should remove FolderObserver anyway.

[Catfriend1]

@Nutomic: Thanks for the hint. I'll make a debug build with disabled FolderObserver for @vsespb when I have the time.

[Catfriend1]

I made a debug build with FolderObserver disabled.

[vsespb]

I tried it. No changes. Still eats CPU.

btw, found another _possibly_ related issue https://github.com/syncthing/syncthing-android/issues/867

[vsespb]

and

Curiously, the SyncThing app shows CPU usage below 1%, so it is lying about its own CPU usage.

posted there
https://github.com/syncthing/syncthing-android/issues/1043#issuecomment-373945077

true for my case too.

[neckcen]

I can confirm the abnormally high CPU usage (and resulting battery drain) with version 0.10.7.

Reinstalling the app did not help.

The high CPU usage happens even with no files to sync (empty folder). However the bug doesn't seem to trigger with no folder added.

[AudriusButkevicius]

@Catfriend1 I'd make a build that explicitly removes the folder observer from the project and prints some distinct line in the logs. As far as I remember debug builds end up getting installed as a separate app, so perhaps people are launching the wrong one... The thing that did change in syncthing versions was syncthing had fs notifications enabled, which I guess makes sense that it could conflict with the built in watcher.

[vsespb]

As far as I remember debug builds end up getting installed as a separate app, so perhaps people are launching the wrong one..

me - no. I run this separate app. it has name "debug" in process list, and separate device and folder settings.

[Catfriend1]

Hmm still wondering why I cannot reproduce the high cpu usage on my phone with the debug or release build when folders are idle and devices connnected. Ran it for 30 minutes.

[Catfriend1]

@AudriusButkevicius Ok, I followed your suggestion. The new debug build has the FolderObserver completely removed from code and prints two new debug infos:

• onSyncthingStarted(): State.ACTIVE reached.
• onSyncthingStarted(): FolderObserver code was removed for debug purposes.
  I have a bad feeling about the app not detecting State.ACTIVE correctly on some phones and this causing the battery drain.
  @vsespb Can you please run the new debug build and upload a full logcat between start of the app and say, lets wait 5 minutes until the battery drain clearly occured with active folders? Link: https://drive.google.com/file/d/1Fz7JxUu8Rq7vWz_alECcxzPo7msEO5gS/view?usp=sharing

[vsespb]

ok. here is the log. full logcat. I ran syncthing, with 1 folder and 0 devices, waited ~2 mins and at 10:53 swithced to CPU monitor apps (yes, it was 10-13 % CPU) and then exited syncthing and then stopped log.
logcat.txt

[vsespb]

oh. i thing i downloaded but forgot to install recent build before collecting log.
now installed and collected again. attaching.
so i started syncthing, with 1 folder and 0 devices. and at 11:08 swithced to cpu monitor to make sure it eats cpu and then stopped logcat.
logcat.txt

[Catfriend1]

@vsespb Thanks. Your log relevant to syncthing is:

05:58.199 I/ActivityManager(1348): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.FirstStartActivity bnds=[206,657][342,793] (has extras)} from uid 10023 on display 0   
03-27 11:05:58.240 I/ActivityManager(1348): Start proc 8113:com.nutomic.syncthingandroid.debug/u0a162 for activity com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.FirstStartActivity    
03-27 11:05:58.306 D/WtProcessController(2447): set foreground process size 1 pid:8113pacakgeName:com.nutomic.syncthingandroid.debug    
03-27 11:05:58.307 W/WtProcessStrategy(2447): {WhetstonePackageInfo#PacakgeName:com.nutomic.syncthingandroid.debug uiMemoryThresold:0 nonUiMemoryThresold:0 Flag:1073747008,0x40001440 [,TRIMHEAPS,SOFT_RESET,FLAG_DEAL_SCHEDULE] Type:0[] }not support ZRAM    
03-27 11:05:58.637 I/ActivityManager(1348): START u0 {cmp=com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.MainActivity (has extras)} from uid 10162 on display 0 
03-27 11:05:58.713 I/SyncthingService(8113): Shutting down background service   
03-27 11:05:58.715 I/SyncthingService(8113): Starting syncthing according to current state and preferences  
03-27 11:05:58.727 D/ConfigXml(8113): Trying to read '/data/user/0/com.nutomic.syncthingandroid.debug/files/config.xml' 
03-27 11:05:58.756 I/ConfigXml(8113): Loaded Syncthing config file  
03-27 11:05:59.020 I/SyncthingService(8113): Web GUI will be available at https://127.0.0.1:38995   
03-27 11:05:59.086 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid.debug, result:0x0  
03-27 11:05:59.313 W/chmod   (8142): type=1400 audit(0.0:1072476): avc: denied { setattr } for name="libsyncthing.so" dev="dm-0" ino=409766 scontext=u:r:untrusted_app:s0:c512,c768 tcontext=u:object_r:apk_data_file:s0 tclass=file permissive=0   
03-27 11:05:59.677 I/SyncthingNativeCode(8113): [DBTGI] INFO: syncthing unknown-dev "Dysprosium Dragonfly" (go1.9 android-arm64) root@vm-build 2018-03-26 07:09:24 UTC [noupgrade]  
03-27 11:05:59.689 I/SyncthingNativeCode(8113): [DBTGI] INFO: My ID: REMOVED    
03-27 11:05:59.690 I/SyncthingNativeCode(8113): [DBTGI] INFO: Archiving a copy of old config file format at: /data/user/0/com.nutomic.syncthingandroid.debug/files/config.xml.v27   
03-27 11:05:59.704 I/SyncthingNativeCode(8113): [DBTGI] INFO: Disabling weak hash   
03-27 11:05:59.730 I/SyncthingNativeCode(8113): [DBTGI] INFO: Starting deadlock detector with 20m0s timeout 
03-27 11:05:59.732 I/SyncthingNativeCode(8113): [DBTGI] INFO: Ready to synchronize "VsespbCam" (ji9dr-a0qi0) (readwrite)    
03-27 11:05:59.735 I/SyncthingNativeCode(8113): [DBTGI] INFO: Send rate is unlimited, receive rate is unlimited 
03-27 11:05:59.739 I/SyncthingNativeCode(8113): [DBTGI] INFO: Rate limits do not apply to LAN connections   
03-27 11:05:59.740 I/SyncthingNativeCode(8113): [DBTGI] INFO: TCP listener ([::]:43953) starting    
03-27 11:05:59.810 I/SyncthingNativeCode(8113): [DBTGI] INFO: Completed initial scan of readwrite folder "VsespbCam" (ji9dr-a0qi0)  
03-27 11:05:59.880 I/SyncthingNativeCode(8113): [DBTGI] INFO: Device REMOVED is "Redmi 4" at [dynamic]  
03-27 11:05:59.882 I/SyncthingNativeCode(8113): [DBTGI] INFO: No automatic upgrades; STNOUPGRADE environment variable defined.  
03-27 11:05:59.889 I/SyncthingNativeCode(8113): [DBTGI] INFO: GUI and API listening on 127.0.0.1:38995  
03-27 11:05:59.892 I/SyncthingNativeCode(8113): [DBTGI] INFO: Access the GUI via the following URL: http://127.0.0.1:38995/ 
03-27 11:05:59.894 I/ActivityManager(1348): Displayed com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.MainActivity: +1s171ms (total +1s664ms)    
03-27 11:06:00.051 I/Timeline(1348): Timeline: Activity_windows_visible id: ActivityRecord{b2c4770 u0 com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.MainActivity t22915} time:865005669    
03-27 11:06:00.245 I/SyncthingService(8113): Web GUI has come online at https://127.0.0.1:38995 
03-27 11:06:00.306 I/RestApi (8113): Syncthing version is unknown-dev   
03-27 11:06:00.466 I/SyncthingService(8113): onSyncthingStarted(): State.ACTIVE reached.    
03-27 11:06:00.467 I/SyncthingService(8113): onSyncthingStarted(): FolderObserver code was removed for debug purposes.  
03-27 11:06:00.619 I/SyncthingRunnableIoNice(8113): ionice performed on libsyncthing.so 
03-27 11:06:45.473 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid.debug, result:0x0  
03-27 11:06:54.108 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid.debug, result:0x0  
03-27 11:06:54.149 I/Timeline(1348): Timeline: Activity_windows_visible id: ActivityRecord{b2c4770 u0 com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.MainActivity t22915} time:865059766    
03-27 11:06:55.198 E/StrictMode(8113): class com.nutomic.syncthingandroid.activities.MainActivity; instances=2; limit=1 
03-27 11:06:55.198 E/StrictMode(8113): android.os.StrictMode$InstanceCountViolation: class com.nutomic.syncthingandroid.activities.MainActivity; instances=2; limit=1   
03-27 11:06:55.364 I/Timeline(1348): Timeline: Activity_windows_visible id: ActivityRecord{b2c4770 u0 com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.MainActivity t22915} time:865060982    
03-27 11:06:55.376 I/WindowManager(1348): Screen frozen for +451ms due to Window{1e63938 u0 com.nutomic.syncthingandroid.debug/com.nutomic.syncthingandroid.activities.MainActivity}    
03-27 11:07:55.355 I/XiaomiFirewall(1908): firewall pkgName:com.nutomic.syncthingandroid.debug, result:0x0  

I get the point that FolderObserver was not the cause for the battery drain. But I don't have a clue what causes it.

[AudriusButkevicius]

This looks relevant:

03-27 11:06:55.198 E/StrictMode(8113): class com.nutomic.syncthingandroid.activities.MainActivity; instances=2; limit=1 
03-27 11:06:55.198 E/StrictMode(8113): android.os.StrictMode$InstanceCountViolation: class com.nutomic.syncthingandroid.activities.MainActivity; instances=2; limit=1   

[fusion-a]

I've been experiencing the same issues for the last few weeks (I can't tell when really, because I didn't know that syncthing was the problem at the time) - the phone heats up/loses battery rapidly.
Android 6.0.1 (Nexus 5) , ST Android version 10.6.0, ST version 0.14.45+1-gbf165d68
I had about 9000 files prior to the reset/reinstall, but I've had them for at least a year now, and the problems started recently. No change when I reduced the file count to ~1000.
I suspected that the problems started when I activated encryption on the phone, but this is not the case (deactivated it and did a factory reset and the problem persists).
Do tell if I can help with more info/testing.

[bastibe]

I am experiencing this, too. Syncthing starts two processes, one of which is using 30-40% of my CPU. This happens even if Syncthing is disabled (according to run conditions / the notification). It can be stopped in system settings -> Apps -> Syncthing -> Force Stop, but then of course it stops syncing.

[mx-psi]

I am experiencing this issue too. I use it to sync about 200 files yet it still drains my battery and heats up the phone. I'm running Android 6.0.1 (OnePlus 2), Syncthing Android version is 0.10.7, Syncthing version is 0.14.45+1-gbf165d68

[Ratio2]

+1
nexus 5 6.0.1

[vsespb]

Maybe start with building debug builds for several older versions to determine moment when issue introduced?

[AudriusButkevicius]

Well I can't reproduce on my Pixel so me building builds doesn't help much. Someone who can reproduce the issue and has the ability to build needs to do some investigation.

[vsespb]

I thought one who can build would build, others who can reproduce, will test the build.

[Nutomic]

@vsespb Old builds are all available on F-Droid fyi.

[AudriusButkevicius]

So it seems the project is unbuildable on Windows, for two reasons:

1. Cygwin python causes NDK to fail to detect host OS
2. build-syncthing.bash being a bash script cannot be handled by gradle daemon (even if the daemon is launched from cygwin, it looses the capability to run python scripts).

And my tiny linux vm is too small to handle this.
I guess I'll try to rewrite the build script in something else at some point.

[Nutomic]

@AudriusButkevicius If you have Windows 10, you should be able to run the build in the Linux shell inside of Windows.

[Catfriend1]

I've made a debug build of the current syncthing-android master containing 0.14.46 syncthing-release.

[AudriusButkevicius]

I got to the point where I can build APKs on Windows, so perhaps I'll ask someone who can repro the issue to do a binary search with me on where the issue was reproduced.

[vsespb]

I've made a debug build of the current syncthing-android master containing 0.14.46 syncthing-release. > Untested by now, so be careful.
https://drive.google.com/file/d/1R9_cRoq4AISLvB3mEh7aBZjP9UZ2NdWE/view?usp=sharing

tested. still comsumes CPU. issue exists.

[theHilikus]

I'm having the same issue. Two syncthing processes, one eats ~30-50% CPU, the other is pretty low ~1%
I have 4 folders, all up to date. One with 940 files. Syncing itself works find but CPU usage is crazy and it kills my battery time.
In my case I have the setting to "run only on wifi" and "always run in background" and I notice that when i shut down my wifi and the taskbar notification becomes "Syncthing is disabled", the CPU usage of the process drops to 0 so at least in my case it seems the resource leak is not so uncontrolled

I'm using an unrooted HTC One M8 with android 6.0

Syncthing version 0.14.45+1-gbf165d68

[AudriusButkevicius]

Can someone test if
https://build.syncthing.net/repository/download/SyncthingAndroid_Build/19114:id/debug/app-debug.apk
consumes a lot of CPU?

[Ratio2]

@AudriusButkevicius Wrote you by email 20 hours ago that build not use cpu

[AudriusButkevicius]

Sorry, was late, thought you never replied. Let me make another build.

[AudriusButkevicius]

Please try this:
https://build.syncthing.net/repository/download/SyncthingAndroid_Build/19145:id/debug/app-debug.apk
Check which version of syncthing it's reporting in the UI.

[Ratio2]

@AudriusButkevicius bad, cpu usage 30-50%, v0.14.45+1-gbf165d68

[Ratio2]

[AudriusButkevicius]

Try https://build.syncthing.net/repository/download/SyncthingAndroid_Build/19158:id/debug/app-debug.apk

[Ratio2]

good

[AudriusButkevicius]

Thanks. It does not happen on my phone, but I've managed to repro it on an emulator, so leave this with me.

[AudriusButkevicius]

Right, found the cause.

[AudriusButkevicius]

So it's fixed in latest (unreleased) syncthing, yet we need to make a custom release of syncthing and iron out the release procedure given @Nutomic is not available.

[Nutomic]

@AudriusButkevicius I am around if you need help. But I don't have my laptop, so I can't make a release myself.

[AudriusButkevicius]

So I think I have the signing keys, yet I am missing the keys.json that explains which play store account to use, and potentially a key for that?

I've pinged @calmh, perhaps he has that, yet if you read this, feel free to drop me a msg on the forum/email.

[calmh]

The play store account should probably be the [email protected] I registered for this, so I think we need to build a new json file for this. I'll pass you the account and I'm sure we can figure out the format.

[AudriusButkevicius]

That won't work, as people will have to reinstall the app under a different organization?

[klausenbusk]

Right, found the cause.

I'm curious, do you care to elaborate? or point me to the relevant commit?

[AudriusButkevicius]

https://github.com/syncthing/syncthing/pull/4844 was the fix.
For some syncthing prior to this pr crashed due to unknown syscalls, for others it caused java process manager to go wild as syncthing moved itself from the process group of the parent process.

[calmh]

It’s still the same app, just moved to another account. As far as I understand it. If not, it is what it is, as it was previously under nutomics personal account and that can’t remain.

[AudriusButkevicius]

Can you email me the creds pls, I just want to get the fking release out as shit is badly broken.

[AudriusButkevicius]

I've released something to the play store, let's see once/if this lands on peoples phones.

[AudriusButkevicius]

It seems it landed.

[bastibe]

Will this update be pushed to F-Droid as well?

[AudriusButkevicius]

I have not worked out how f-droid works, but I think they just build whenever they feel like it and when a new tag is available. F-Droid has 0.10.4 which is still a working version, so I suggest you rollback until they release the new version

[bastibe]

OK, thanks!

[imsodin]

There's not much magic with how F-Droid picks up new releases: A bot runs on regular, slightly longish (a day?) intervals to pick up new versions, in our case based on tags in the github repo. Then it enters the build queue. So a delay of one to several days is normal. If it takes longer, the reasons are usually environment problems (go version, gradle version, ...). And currently F-Droid has 0.10.7, which works fine.

[AudriusButkevicius]

0.10.7 shoukd exhibit the high cpu usage.

[calmh]

Since this is (apparently) the release discussion thread... How do you guys feel about normalizing the version numbers between Syncthing and the App? That is, when you release an app version with Syncthing 0.14.47, call it 0.14.47. If there's an app-only update after that, 0.14.47a or 0.14.47.1 or something, etc?

It might mean skipping versions some times (0.14.47 to 0.14.49 if there were no updates in between), is that painful? Wrong?

[gdt]

about release numbers:
I brought this up a while ago, and the motivation was that syncthing was having fairly frequent major version changes that included breaking protocol changes. So, as a user of both regular-computer and android syncthing, I had to know when the app updated to a new major version, and this required attention to release notes. Now, syncthing seems to be much more protocol-stable.

The other tricky thing is that the app will have significant changes that aren't about syncthing proper, and thus some need to have major version changes.

0.14.47.1 would be fine, as that sorts properly by all normal rules. 0.14.47a is likely to be interpreted as an alpha release before 0.14.47. There is lots of code in packaging systems to parse and compare version numbers, and projects making up new schemes messes that up. Skipping versions does not sound like a big problem.

I think almost all of the value could be gotten by having a rule that the app will increase (e.g., 0.10 to 0.11) when syncthing goes from 0.14.x to 0.15.0, whether or not there is a protocol flag day. In theory, micro releases are safe.

[JohnVeness]

Thanks for fixing this and pointing to which PR fixed it. Out of interest, which PR or commit broke it in the first place, do you know?

[AudriusButkevicius]

The one that added those files that I was modifying in the PR.