Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Eats battery since 0.14.45 #1043

Closed
vsespb opened this Issue Mar 16, 2018 · 90 comments

Comments

Projects
None yet
@vsespb
Copy link

commented Mar 16, 2018

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_2018-03-16-10-11-23-622_com miui securitycenter

Screenshot where syncthing is showing 13% cpu:
screenshot_2018-03-16-10-13-27-696_pavel ugo cpumonitor4

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

This comment has been minimized.

Copy link
Author

commented Mar 16, 2018

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

This comment has been minimized.

Copy link

commented Mar 17, 2018

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

This comment has been minimized.

Copy link
Contributor

commented Mar 19, 2018

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

This comment has been minimized.

Copy link
Author

commented Mar 19, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 19, 2018

What is the web UI showing for folders?

@vsespb

This comment has been minimized.

Copy link
Author

commented Mar 19, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Author

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Contributor

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

@vsespb

This comment has been minimized.

Copy link
Author

commented Mar 20, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Author

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Author

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Contributor

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Member

commented Mar 20, 2018

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

@Catfriend1

This comment has been minimized.

Copy link
Contributor

commented Mar 20, 2018

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

This comment has been minimized.

Copy link
Author

commented Mar 20, 2018

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.

#1043 (comment)
#1038 (comment)
#1038 (comment)

i'll try reinstall or debug build later.

@vsespb

This comment has been minimized.

Copy link
Author

commented Mar 21, 2018

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

This comment has been minimized.

Copy link
Author

commented Mar 21, 2018

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 #1014 and specified version there).

0.10.5 had syncthing 0.14.45 ( I think so because I see here 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 ( #1033 and #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

This comment has been minimized.

Copy link
Member

commented Mar 21, 2018

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

@vsespb

This comment has been minimized.

Copy link
Author

commented Mar 22, 2018

possibly related issue: #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

This comment has been minimized.

Copy link
Contributor

commented Mar 22, 2018

@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.
grafik
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.

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 9, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 9, 2018

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

This comment has been minimized.

Copy link

commented Apr 9, 2018

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

@Ratio2

This comment has been minimized.

Copy link

commented Apr 9, 2018

screenshot_20180409-231015

@Ratio2

This comment has been minimized.

Copy link

commented Apr 9, 2018

good
screenshot_20180409-232646

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 9, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 9, 2018

Right, found the cause.

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 9, 2018

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

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

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

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

The play store account should probably be the info@syncthing.net 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

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

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

@klausenbusk

This comment has been minimized.

Copy link

commented Apr 10, 2018

Right, found the cause.

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

syncthing/syncthing#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

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

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

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 10, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 11, 2018

It seems it landed.

@bastibe

This comment has been minimized.

Copy link

commented Apr 11, 2018

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

@AudriusButkevicius

This comment has been minimized.

Copy link
Member

commented Apr 11, 2018

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

This comment has been minimized.

Copy link

commented Apr 11, 2018

OK, thanks!

@imsodin

This comment has been minimized.

Copy link
Member

commented Apr 11, 2018

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

This comment has been minimized.

Copy link
Member

commented Apr 11, 2018

0.10.7 shoukd exhibit the high cpu usage.

@calmh

This comment has been minimized.

Copy link
Member

commented Apr 11, 2018

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

This comment has been minimized.

Copy link

commented Apr 11, 2018

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

This comment has been minimized.

Copy link
Contributor

commented Apr 11, 2018

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

This comment has been minimized.

Copy link
Member

commented Apr 11, 2018

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

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.