search

seedvault-app / seedvault

A backup application for the Android Open Source Project.
1.25k stars 74 forks source link

Restore only restores system packagemanager #312

Closed rugk closed 2 years ago

rugk commented 3 years ago

My backup restoration from Nextcloud in https://github.com/seedvault-app/seedvault/issues/310 was rather unsuccessful, but maybe it also was not properly backed-up(?).

STR

I did this after my hassle with https://github.com/seedvault-app/seedvault/issues/307 and a new system reset. Again, as I needed some apps first, I had to install them before and thus could not run it in the setup wizard, but tried to run it as one of the first things (after just updating F-Droid, refreshing it's index and installing the required apps). Then I just setup microG, rebooted and manually triggered the restoration process.

What happens

Anyway, I guess I better report it. Only the data of app (System package manager) could be restored. :sweat_smile: (I don't even know what that does or what data this includes :sweat_smile: )

See the screenshots in https://github.com/seedvault-app/seedvault/issues/310

What should happen

Apps, where I expected an error:

Why the other apps had errors is not really clear to me. AFAIK all APKs (except of OpenKeychain for some reason) could successfully be installed from the backup. I installed OpenKeychain manually and then continued.

Logcat

``` grep -i -E "seedvault" --context=10 ~/Downloads/logcat_seedvault.txt 08-29 00:21:24.558 1385 1513 W ActivityManager: Slow operation: 55ms so far, now at startProcess: done updating pids map 08-29 00:21:24.670 1385 3099 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 1000; state: DISABLED 08-29 00:21:24.703 1385 1513 I ActivityManager: Start proc 3629:com.android.localtransport/1000 for service {com.android.localtransport/com.android.localtransport.LocalTransportService} 08-29 00:21:24.813 1385 1385 I TransportClient: LocalTransportService#0: Notifying [TransportManager.registerTransport()] transport = IBackupTransport 08-29 00:21:24.817 1385 3099 D BackupTransportManager: Transport com.android.localtransport/.LocalTransportService registered 08-29 00:21:24.817 1385 3099 D BackupManagerService: [UserID:0] Transport com.android.localtransport/.LocalTransport registered 3207ms after first request (delay = 3000ms) 08-29 00:21:24.828 1385 3099 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10092; state: DISABLED 08-29 00:21:24.829 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10092; state: ENABLED 08-29 00:21:24.832 1385 3360 I ActivityManager: Killing 2726:org.protonaosp.deviceconfig/u0a138 (adj 975): empty #17 08-29 00:21:24.856 1385 3120 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10121; state: DISABLED 08-29 00:21:24.857 1385 1513 I ActivityManager: Start proc 3660:com.stevesoltys.seedvault/u0a92 for service {com.stevesoltys.seedvault/com.stevesoltys.seedvault.transport.ConfigurableBackupTransportService} 08-29 00:21:24.858 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10121; state: ENABLED 08-29 00:21:24.881 1385 1513 I ActivityManager: Start proc 3666:com.android.onetimeinitializer/u0a121 for broadcast {com.android.onetimeinitializer/com.android.onetimeinitializer.OneTimeInitializerReceiver} 08-29 00:21:24.968 503 511 I vold : Configuring read_ahead of /mnt/user/0/F12B-F4F2 fuse filesystem to 256kb 08-29 00:21:24.970 503 511 E vold : Failed to open /sys/class/bdi/0:94/read_ahead_kb: Permission denied 08-29 00:21:24.970 503 511 I vold : Configuring max_ratio of /mnt/user/0/F12B-F4F2 fuse filesystem to 40 08-29 00:21:24.970 503 511 I vold : Writing 40 to /sys/class/bdi/0:94/max_ratio 08-29 00:21:24.971 1385 1723 I StorageManagerService: Mounted volume VolumeInfo{public:179,65}: 08-29 00:21:24.971 1385 1723 I StorageManagerService: type=PUBLIC diskId=disk:179,64 partGuid= mountFlags=VISIBLE mountUserId=0 08-29 00:21:24.971 1385 1723 I StorageManagerService: state=MOUNTED 08-29 00:21:24.971 1385 1723 I StorageManagerService: fsType=exfat fsUuid=F12B-F4F2 fsLabel=android -- 08-29 00:21:25.018 1385 1847 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10091; state: DISABLED 08-29 00:21:25.019 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10091; state: ENABLED 08-29 00:21:25.019 1385 1847 I ActivityManager: Killing 2844:com.android.permissioncontroller/u0a156 (adj 985): empty #17 08-29 00:21:25.022 1385 1385 I TransportClient: ConfigurableBackupTransportService#1: Notifying [TransportManager.registerTransport()] transport = IBackupTransport 08-29 00:21:25.025 1385 1847 W StorageManagerService: Reporting emulated;0 unmounted due to system locked 08-29 00:21:25.026 1385 1847 W StorageManagerService: Reporting public:179,65 unmounted due to system locked 08-29 00:21:25.027 1385 1385 W StorageManagerService: Reporting emulated;0 unmounted due to system locked 08-29 00:21:25.027 1385 1385 W StorageManagerService: Reporting public:179,65 unmounted due to system locked 08-29 00:21:25.032 1385 1385 W StorageManagerService: Reporting emulated;0 unmounted due to system locked 08-29 00:21:25.032 1385 1385 W StorageManagerService: Reporting public:179,65 unmounted due to system locked 08-29 00:21:25.040 1385 3099 D BackupTransportManager: Transport com.stevesoltys.seedvault/.transport.ConfigurableBackupTransportService registered 08-29 00:21:25.041 1385 3099 D BackupManagerService: [UserID:0] Transport com.stevesoltys.seedvault.transport.ConfigurableBackupTransport registered 3430ms after first request (delay = 3000ms) 08-29 00:21:25.056 1385 3099 I BackupManagerService: [UserID:0] Found stale backup journal, scheduling 08-29 00:21:25.057 1385 3099 I chatty : uid=1000(system) backup-0 identical 10 lines 08-29 00:21:25.057 1385 3099 I BackupManagerService: [UserID:0] Found stale backup journal, scheduling 08-29 00:21:25.057 1385 1513 I ActivityManager: Start proc 3709:com.android.packageinstaller/u0a91 for broadcast {com.android.packageinstaller/com.android.packageinstaller.TemporaryFileManager} 08-29 00:21:25.084 1385 3120 I ActivityManager: Killing 2884:com.qualcomm.timeservice/u0a120 (adj 985): empty #17 08-29 00:21:25.184 1385 1424 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10156; state: DISABLED 08-29 00:21:25.185 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10156; state: DISABLED 08-29 00:21:25.185 1385 1424 I ActivityManager: Killing 2986:com.android.carrierdefaultapp/u0a105 (adj 985): empty #17 08-29 00:21:25.204 1385 1385 D NotificationService: 0|com.android.systemui|1397773634|public:179,65|10143: granting content://settings/system/notification_sound 08-29 00:21:25.205 1385 1385 I chatty : uid=1000 system_server identical 1 line -- 08-29 00:21:33.695 1385 3360 W ActivityManager: Unable to start service Intent { act=com.android.launcher3.WINDOW_OVERLAY dat=app://com.android.launcher3:10140?v=9&cv=14 pkg=com.google.android.googlequicksearchbox } U=0: not found 08-29 00:21:35.008 1385 1489 D AutofillManagerService: onBackKeyPressed() 08-29 00:21:35.073 0 0 E kgsl-3d0: |counter_delta| Abnormal value:0x56ae3d (0x72ebac) from perf counter : 0x3b0 08-29 00:21:36.970 0 0 E kgsl-3d0: |counter_delta| Abnormal value:0x616000b (0x61d1beb) from perf counter : 0x3b0 08-29 00:21:37.272 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:21:37.772 1385 2014 I ActivityTaskManager: START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=org.fdroid.fdroid/.views.main.MainActivity bnds=[35,606][287,906]} from uid 10140 08-29 00:21:37.807 1385 1492 D CompatibilityChangeReporter: Compat change id reported: 136274596; UID 10111; state: DISABLED 08-29 00:21:39.732 1385 1512 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10157; state: DISABLED 08-29 00:21:39.733 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10157; state: ENABLED 08-29 00:21:39.759 1385 1513 I ActivityManager: Start proc 4215:com.android.cellbroadcastreceiver.module/u0a157 for broadcast {com.android.cellbroadcastreceiver.module/com.android.cellbroadcastreceiver.CellBroadcastReceiver} 08-29 00:21:39.907 1385 3830 I ActivityManager: Killing 3660:com.stevesoltys.seedvault/u0a92 (adj 985): empty #17 08-29 00:21:40.867 0 0 I aw8898_mute: mute state=1 08-29 00:21:40.867 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:21:40.869 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:21:41.552 1385 1512 I ActivityManager: Waited long enough for: ServiceRecord{315f8a6 u0 com.google.android.gms/org.microg.gms.gcm.McsService} 08-29 00:21:42.066 1385 2017 I ActivityTaskManager: START u0 {cmp=org.fdroid.fdroid/.views.apps.AppListActivity} from uid 10111 08-29 00:21:42.080 0 0 I aw8898_startup: enter 08-29 00:21:42.083 1385 2017 W ActivityTaskManager: Tried to set launchTime (0) < mLastActivityLaunchTime (144651) 08-29 00:21:42.107 0 0 I aw8898_mute: mute state=0 08-29 00:21:42.107 0 0 I : aw8898_smartpa_cfg, flag = 1 08-29 00:21:45.146 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). -- 08-29 00:23:13.275 1385 1385 W WindowManager: removeWindowToken: Attempted to remove non-existing token: android.os.Binder@bdb54bc 08-29 00:23:13.519 1385 4574 D AdbDebuggingManager: Received public key: QAAAAF0TpkMLJ/Kh+Okido1yD3n5hHi/meI3G2x2bsl49gvZLqui+gMO/jyofU7cFmsG5g3YjOURpuWZbv6ACS+kDcBH/UkJLoVLSFsOFiXETSyfwP8BaHzOwLJn2HZ0QTbvDST1tOSJI7a1JyMuPwB0dxm2z5PqBeaNToWChALuhXmxd9MV5AaxjFOHTKfp8HxHrTYGw6MziejJjq/bOJ1Ct+qurJ/yTduAWV6W4tBFndXPPAlKzw/nh9O8HY/iXwWe7PpJtZe32KG+qVMrGkNmSb276efDMt1dXFw5ZKYZVHPi4Op3+06DTnsl8tqS7/PMF4/QppZQwcVQnzyA8DPiL1RvNBS+ITa8vPD2UwJ04XvqSyNLudxKKRiyRe2Vm3Se5XPDXEqOyKNjvKeTRrUW/ccSrV/x4Pt1GF1GrTL9T51p2+Ba7tBsCnlnGVE3bsVDzbkAX61RmH9HRJfL3jvJiZUdpEoeXGls6GZtusWoKoDPUPSMBCxPhUvyKO5D5PEJIaI4MZya9m+Ibqdrjf89PTlBe98KxrEmg2Xf9MrZseqdyuI87orw24gRC2IAIz5rwGmjbw3gwIk4jDdmoVJfzb2shn7m1TBEA063Msi5SjC+zutVPiZQjB2RWqH6bYgQixo2LjwHGsAUBEI5o92gzsYczWtHx7Cvr+zxcciFEOKpgR8ABgEAAQA= **NEXTCLOUD_USER**@fedidea 08-29 00:23:13.520 1385 1488 D AdbDebuggingManager: Logging key QAAAAF0TpkMLJ/Kh+Okido1yD3n5hHi/meI3G2x2bsl49gvZLqui+gMO/jyofU7cFmsG5g3YjOURpuWZbv6ACS+kDcBH/UkJLoVLSFsOFiXETSyfwP8BaHzOwLJn2HZ0QTbvDST1tOSJI7a1JyMuPwB0dxm2z5PqBeaNToWChALuhXmxd9MV5AaxjFOHTKfp8HxHrTYGw6MziejJjq/bOJ1Ct+qurJ/yTduAWV6W4tBFndXPPAlKzw/nh9O8HY/iXwWe7PpJtZe32KG+qVMrGkNmSb276efDMt1dXFw5ZKYZVHPi4Op3+06DTnsl8tqS7/PMF4/QppZQwcVQnzyA8DPiL1RvNBS+ITa8vPD2UwJ04XvqSyNLudxKKRiyRe2Vm3Se5XPDXEqOyKNjvKeTRrUW/ccSrV/x4Pt1GF1GrTL9T51p2+Ba7tBsCnlnGVE3bsVDzbkAX61RmH9HRJfL3jvJiZUdpEoeXGls6GZtusWoKoDPUPSMBCxPhUvyKO5D5PEJIaI4MZya9m+Ibqdrjf89PTlBe98KxrEmg2Xf9MrZseqdyuI87orw24gRC2IAIz5rwGmjbw3gwIk4jDdmoVJfzb2shn7m1TBEA063Msi5SjC+zutVPiZQjB2RWqH6bYgQixo2LjwHGsAUBEI5o92gzsYczWtHx7Cvr+zxcciFEOKpgR8ABgEAAQA= **NEXTCLOUD_USER**@fedidea, state = 1, alwaysAllow = false, lastConnectionTime = 0, authWindow = 604800000 08-29 00:23:13.522 1385 1488 I ActivityTaskManager: START u0 {flg=0x10000000 cmp=com.android.systemui/.usb.UsbDebuggingActivity (has extras)} from uid 1000 08-29 00:23:15.321 1385 1488 D AdbDebuggingManager: Logging key QAAAAF0TpkMLJ/Kh+Okido1yD3n5hHi/meI3G2x2bsl49gvZLqui+gMO/jyofU7cFmsG5g3YjOURpuWZbv6ACS+kDcBH/UkJLoVLSFsOFiXETSyfwP8BaHzOwLJn2HZ0QTbvDST1tOSJI7a1JyMuPwB0dxm2z5PqBeaNToWChALuhXmxd9MV5AaxjFOHTKfp8HxHrTYGw6MziejJjq/bOJ1Ct+qurJ/yTduAWV6W4tBFndXPPAlKzw/nh9O8HY/iXwWe7PpJtZe32KG+qVMrGkNmSb276efDMt1dXFw5ZKYZVHPi4Op3+06DTnsl8tqS7/PMF4/QppZQwcVQnzyA8DPiL1RvNBS+ITa8vPD2UwJ04XvqSyNLudxKKRiyRe2Vm3Se5XPDXEqOyKNjvKeTRrUW/ccSrV/x4Pt1GF1GrTL9T51p2+Ba7tBsCnlnGVE3bsVDzbkAX61RmH9HRJfL3jvJiZUdpEoeXGls6GZtusWoKoDPUPSMBCxPhUvyKO5D5PEJIaI4MZya9m+Ibqdrjf89PTlBe98KxrEmg2Xf9MrZseqdyuI87orw24gRC2IAIz5rwGmjbw3gwIk4jDdmoVJfzb2shn7m1TBEA063Msi5SjC+zutVPiZQjB2RWqH6bYgQixo2LjwHGsAUBEI5o92gzsYczWtHx7Cvr+zxcciFEOKpgR8ABgEAAQA= **NEXTCLOUD_USER**@fedidea, state = 2, alwaysAllow = false, lastConnectionTime = 0, authWindow = 604800000 08-29 00:23:15.402 1385 3145 D ActivityManager: cgroup.freeze not present 08-29 00:23:15.427 1385 3123 I ProcessStatsService: Added stats: 1970-01-01-08-10-31, over +12m34s998ms 08-29 00:23:16.041 1385 1489 D AutofillManagerService: onBackKeyPressed() 08-29 00:23:16.101 1385 3145 I SystemUpdateManagerService: No existing info file /data/system/system-update-info.xml 08-29 00:23:18.991 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:23:19.036 1385 1425 I ActivityTaskManager: START u0 {act=com.stevesoltys.seedvault.RESTORE_BACKUP flg=0x10000000 cmp=com.stevesoltys.seedvault/.restore.RestoreActivity} from uid 2000 08-29 00:23:19.053 1385 1492 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10092; state: DISABLED 08-29 00:23:19.054 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10092; state: ENABLED 08-29 00:23:19.081 1385 1513 I ActivityManager: Start proc 4606:com.stevesoltys.seedvault/u0a92 for pre-top-activity {com.stevesoltys.seedvault/com.stevesoltys.seedvault.restore.RestoreActivity} 08-29 00:23:19.122 0 0 I aw8898_mute: mute state=1 08-29 00:23:19.123 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:23:19.344 1385 3145 I ActivityTaskManager: START u0 {cmp=com.stevesoltys.seedvault/.ui.storage.StorageActivity (has extras)} from uid 10092 08-29 00:23:19.378 1385 3145 W ActivityTaskManager: Tried to set launchTime (0) < mLastActivityLaunchTime (245987) 08-29 00:23:19.532 1385 1425 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10090; state: DISABLED 08-29 00:23:19.533 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10090; state: ENABLED 08-29 00:23:19.560 1385 1513 I ActivityManager: Start proc 4635:com.android.documentsui/u0a90 for content provider {com.android.documentsui/com.android.documentsui.archives.ArchivesProvider} 08-29 00:23:19.562 1385 3145 V WindowManager: nav_input_consumer created. 08-29 00:23:19.826 1385 1425 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 2000; state: DISABLED 08-29 00:23:19.867 1385 1513 I ActivityManager: Start proc 4664:com.android.shell/2000 for content provider {com.android.shell/com.android.shell.BugreportStorageProvider} 08-29 00:23:19.956 0 0 W healthd : battery l=89 v=4288 t=33.7 h=2 st=2 c=283203 fc=3060000 cc=0 chg=a 08-29 00:23:20.002 1385 3145 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10158; state: DISABLED 08-29 00:23:20.003 1385 1513 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10158; state: DISABLED -- 08-29 00:27:40.376 0 0 W healthd : battery l=90 v=4364 t=33.7 h=2 st=2 c=1042633 fc=3060000 cc=0 chg=a 08-29 00:27:41.270 0 0 I himax_cable_detect_func: Cable status change: 0x01 08-29 00:27:43.176 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:27:45.132 0 0 I aw8898_startup: enter 08-29 00:27:45.159 0 0 I aw8898_mute: mute state=0 08-29 00:27:45.159 0 0 I : aw8898_smartpa_cfg, flag = 1 08-29 00:27:45.173 1385 1488 D AutofillManagerService: Close system dialogs 08-29 00:27:50.402 0 0 I aw8898_mute: mute state=1 08-29 00:27:50.402 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:27:50.414 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:27:53.606 1385 5333 I ActivityTaskManager: START u0 {dat=content://org.nextcloud.documents/tree/1824776246/1 flg=0x43 cmp=com.stevesoltys.seedvault/.ui.storage.PermissionGrantActivity} from uid 10092 08-29 00:27:53.617 0 0 I aw8898_startup: enter 08-29 00:27:53.625 1385 5333 W ActivityTaskManager: Tried to set launchTime (0) < mLastActivityLaunchTime (246234) 08-29 00:27:53.642 0 0 I aw8898_mute: mute state=0 08-29 00:27:53.642 0 0 I : aw8898_smartpa_cfg, flag = 1 08-29 00:27:56.786 0 0 I aw8898_mute: mute state=1 08-29 00:27:56.786 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:27:57.191 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:28:01.372 1385 3143 I ActivityTaskManager: START u0 {cmp=com.stevesoltys.seedvault/.ui.recoverycode.RecoveryCodeActivity (has extras)} from uid 10092 08-29 00:28:01.424 1385 3143 V WindowManager: nav_input_consumer created. 08-29 00:28:01.459 1385 3123 W ActivityTaskManager: Tried to set launchTime (0) < mLastActivityLaunchTime (520481) 08-29 00:28:01.466 1385 3123 V WindowManager: nav_input_consumer dismissed. 08-29 00:28:01.754 1385 3123 V WindowManager: nav_input_consumer created. 08-29 00:28:02.764 0 0 W healthd : battery l=90 v=4319 t=34.3 h=2 st=4 c=602722 fc=3060000 cc=0 chg=a 08-29 00:28:02.825 0 0 W healthd : battery l=90 v=4236 t=34.3 h=2 st=4 c=-223541 fc=3060000 cc=0 chg=a 08-29 00:28:03.721 0 0 W healthd : battery l=90 v=4217 t=34.3 h=2 st=4 c=-182190 fc=3060000 cc=0 chg=a 08-29 00:28:05.017 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:28:07.661 0 0 W healthd : battery l=90 v=4266 t=34.2 h=2 st=2 c=-239410 fc=3060000 cc=0 chg=a 08-29 00:28:07.755 0 0 W healthd : battery l=90 v=4276 t=34.1 h=2 st=2 c=393066 fc=3060000 cc=0 chg=a -- 08-29 00:32:25.870 0 0 I aw8898_mute: mute state=1 08-29 00:32:25.870 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:32:25.884 1385 1739 D ConnectivityService: Lingering [101 CELLULAR] for 30000ms 08-29 00:32:25.888 1385 1739 D ConnectivityService: Sending DISCONNECTED broadcast for type 0 [101 CELLULAR] isDefaultNetwork=true 08-29 00:32:25.889 1385 1739 D ConnectivityService: Sending CONNECTED broadcast for type 1 [104 WIFI] isDefaultNetwork=true 08-29 00:32:25.920 1385 1739 D ConnectivityService: Setting DNS servers for network 104 to [/fd1c:f1a1:f84b::1, /192.168.1.1] 08-29 00:32:25.921 1385 1739 D DnsManager: sendDnsConfigurationForNetwork(104, [fd1c:f1a1:f84b::1, 192.168.1.1], [lan], 1800, 25, 8, 64, 0, 0, , [fd1c:f1a1:f84b::1, 192.168.1.1]) 08-29 00:32:26.739 0 0 I aw8898_startup: enter 08-29 00:32:26.766 0 0 I aw8898_mute: mute state=0 08-29 00:32:26.766 0 0 I : aw8898_smartpa_cfg, flag = 1 08-29 00:32:33.201 1385 3143 V BackupManagerService: [UserID:0] beginRestoreSession: pkg=null transport=com.stevesoltys.seedvault.transport.ConfigurableBackupTransport 08-29 00:32:33.206 1385 3143 V BackupManagerService: [UserID:0] Acquired wakelock:*backup*-0-3099 08-29 00:32:33.250 1385 3143 V WindowManager: nav_input_consumer created. 08-29 00:32:33.286 1385 1385 I TransportClient: ConfigurableBackupTransportService#2: Notifying [BH/MSG_RUN_GET_RESTORE_SETS] transport = IBackupTransport 08-29 00:32:36.039 0 0 I aw8898_mute: mute state=1 08-29 00:32:36.039 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:32:36.803 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:32:42.318 0 0 W healthd : battery l=90 v=4166 t=32.9 h=2 st=4 c=-326385 fc=3060000 cc=0 chg= 08-29 00:32:42.373 0 0 W healthd : battery l=90 v=4173 t=32.9 h=2 st=4 c=-195922 fc=3060000 cc=0 chg= 08-29 00:32:42.684 0 0 W healthd : battery l=90 v=4172 t=32.8 h=2 st=2 c=-204315 fc=3060000 cc=0 chg= 08-29 00:32:42.737 0 0 W healthd : battery l=90 v=4228 t=32.8 h=2 st=2 c=188903 fc=3060000 cc=0 chg= -- 08-29 00:38:53.074 3268 3368 D ULocClient: Set update interval to 86400000 08-29 00:38:53.074 3268 3368 D ULocService: setUpdateInterval[com.google.android.gms] interval: 86400000 08-29 00:38:53.074 3268 3368 D ULocClient: updateBinding - current: true, refs: 2, reqs: 1, avail: true 08-29 00:38:53.288 3936 4243 D InstalledAppProviderSer: insertAppIntoDb org.mozilla.firefox 08-29 00:38:56.238 3251 6907 W MediaProvider: Ignoring mutation of _data from com.nextcloud.client 08-29 00:38:56.409 800 3418 I chatty : uid=1027(nfc) vendor.nxp.hard expire 12 lines 08-29 00:38:56.410 3130 3397 I chatty : uid=1027(nfc) AsyncTask #1 expire 42 lines 08-29 00:38:56.413 800 3415 I chatty : uid=1027(nfc) vendor.nxp.hard expire 32 lines 08-29 00:38:56.556 6292 6321 I chatty : uid=10158(com.nextcloud.client) Binder:6292_1 expire 286 lines 08-29 00:38:56.590 3251 7092 W MediaProvider: Forgot to handle a top level directory in getContentUriForFile? 08-29 00:38:56.954 4606 6226 W PackageParsing: Unknown element under : meta-data at /data/user/0/com.stevesoltys.seedvault/cache/de.markusfisch.android.binaryeye5521525559693772238.apk Binary XML file line #40 08-29 00:38:57.453 1385 1722 W PackageParsing: Unknown element under : meta-data at /data/app/vmdl994786356.tmp/base.apk Binary XML file line #40 08-29 00:38:57.468 1385 1722 W RuleEvaluation: Integrity rule files are not available. 08-29 00:38:57.469 1385 1556 I PackageManager: Integrity check passed for file:///data/app/vmdl994786356.tmp 08-29 00:38:57.486 1385 1556 W PackageParsing: Unknown element under : meta-data at /data/app/vmdl994786356.tmp/base.apk Binary XML file line #40 08-29 00:38:57.522 1385 1556 D CompatibilityChangeReporter: Compat change id reported: 135549675; UID 10191; state: ENABLED 08-29 00:38:57.643 1385 1556 D PackageManager: Instant App installer not found with android.intent.action.INSTALL_INSTANT_APP_PACKAGE 08-29 00:38:57.643 1385 1556 D PackageManager: Clear ephemeral installer activity 08-29 00:38:57.644 1385 1488 W Looper : Slow dispatch took 117ms android.fg h=android.os.Handler c= m=0 08-29 00:38:57.648 1385 1488 V StorageManagerService: Package null does not have legacy storage 08-29 00:38:57.650 3251 3760 I MediaProvider: Invalidating LocalCallingIdentity cache for package de.markusfisch.android.binaryeye. Reason: op android:read_external_storage -- 08-29 00:40:13.584 3268 3370 D ULocService: setUpdateInterval[com.google.android.gms] interval: 86400000 08-29 00:40:13.584 3268 3370 D ULocClient: updateBinding - current: true, refs: 2, reqs: 1, avail: true 08-29 00:40:13.585 3268 3370 D ULocClient: Set update interval to 86400000 08-29 00:40:13.585 3268 3370 D ULocService: setUpdateInterval[com.google.android.gms] interval: 86400000 08-29 00:40:13.585 3268 3370 D ULocClient: updateBinding - current: true, refs: 2, reqs: 1, avail: true 08-29 00:40:14.639 800 3415 I chatty : uid=1027(nfc) vendor.nxp.hard expire 40 lines 08-29 00:40:14.640 800 3418 I chatty : uid=1027(nfc) vendor.nxp.hard expire 10 lines 08-29 00:40:14.640 3130 3397 I chatty : uid=1027(nfc) AsyncTask #1 expire 48 lines 08-29 00:40:16.316 3251 7287 W MediaProvider: Ignoring mutation of _data from com.nextcloud.client 08-29 00:40:16.553 3251 7290 W MediaProvider: Forgot to handle a top level directory in getContentUriForFile? 08-29 00:40:16.827 4606 6939 W PackageParsing: No actions in intent-filter at /data/user/0/com.stevesoltys.seedvault/cache/com.vrem.wifianalyzer6108457626850908318.apk Binary XML file line #73 08-29 00:40:17.090 1385 1722 W PackageParsing: No actions in intent-filter at /data/app/vmdl1151518038.tmp/base.apk Binary XML file line #73 08-29 00:40:17.098 1385 1722 W RuleEvaluation: Integrity rule files are not available. 08-29 00:40:17.099 1385 1556 I PackageManager: Integrity check passed for file:///data/app/vmdl1151518038.tmp 08-29 00:40:17.113 1385 1556 W PackageParsing: No actions in intent-filter at /data/app/vmdl1151518038.tmp/base.apk Binary XML file line #73 08-29 00:40:17.135 1385 1556 D CompatibilityChangeReporter: Compat change id reported: 135549675; UID 10199; state: ENABLED 08-29 00:40:17.262 1385 1556 D PackageManager: Instant App installer not found with android.intent.action.INSTALL_INSTANT_APP_PACKAGE 08-29 00:40:17.263 1385 1556 D PackageManager: Clear ephemeral installer activity 08-29 00:40:17.263 1385 1488 W Looper : Slow dispatch took 125ms android.fg h=android.os.Handler c= m=0 08-29 00:40:17.292 1385 1556 I chatty : uid=1000(system) PackageManager expire 7 lines 08-29 00:40:17.297 1009 1700 V installd: DexInv: --- BEGIN '/data/app/~~43h15OJc2Mpj8JqBblUPyQ==/com.vrem.wifianalyzer-EPxrJU3DMQww_SRYy8OBYw==/base.apk' --- -- 08-29 00:41:58.543 4606 4606 D StrictMode: at android.os.Parcel.readExceptionCode(Parcel.java:2318) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:139) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.content.ContentProviderProxy.query(ContentProviderNative.java:472) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1183) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1115) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1071) 08-29 00:41:58.543 4606 4606 D StrictMode: at androidx.documentfile.provider.DocumentsContractApi19.queryForString(DocumentsContractApi19.java:163) 08-29 00:41:58.543 4606 4606 D StrictMode: at androidx.documentfile.provider.DocumentsContractApi19.getRawType(DocumentsContractApi19.java:54) 08-29 00:41:58.543 4606 4606 D StrictMode: at androidx.documentfile.provider.DocumentsContractApi19.isDirectory(DocumentsContractApi19.java:72) 08-29 00:41:58.543 4606 4606 D StrictMode: at androidx.documentfile.provider.TreeDocumentFile.isDirectory(TreeDocumentFile.java:88) 08-29 00:41:58.543 4606 4606 D StrictMode: at com.stevesoltys.seedvault.ui.storage.StorageViewModel$Companion$validLocationIsSet$1.invoke(StorageViewModel.kt:54) 08-29 00:41:58.543 4606 4606 D StrictMode: at com.stevesoltys.seedvault.ui.storage.StorageViewModel$Companion$validLocationIsSet$1.invoke(StorageViewModel.kt:46) 08-29 00:41:58.543 4606 4606 D StrictMode: at com.stevesoltys.seedvault.AppKt.permitDiskReads(App.kt:128) 08-29 00:41:58.543 4606 4606 D StrictMode: at com.stevesoltys.seedvault.ui.storage.StorageViewModel$Companion.validLocationIsSet$packages__apps__Seedvault__android_common__Seedvault(StorageViewModel.kt:53) 08-29 00:41:58.543 4606 4606 D StrictMode: at com.stevesoltys.seedvault.ui.RequireProvisioningViewModel.validLocationIsSet$packages__apps__Seedvault__android_common__Seedvault(RequireProvisioningViewModel.kt:21) 08-29 00:41:58.543 4606 4606 D StrictMode: at com.stevesoltys.seedvault.restore.RestoreActivity.onStart(RestoreActivity.kt:46) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.Instrumentation.callActivityOnStart(Instrumentation.java:1435) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.Activity.performStart(Activity.java:8018) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.ActivityThread.handleStartActivity(ActivityThread.java:3457) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.servertransaction.TransactionExecutor.performLifecycleSequence(TransactionExecutor.java:221) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.servertransaction.TransactionExecutor.cycleToPath(TransactionExecutor.java:201) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.servertransaction.TransactionExecutor.executeLifecycleState(TransactionExecutor.java:173) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:97) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2066) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.os.Handler.dispatchMessage(Handler.java:106) 08-29 00:41:58.543 4606 4606 D StrictMode: at android.os.Looper.loop(Looper.java:223) -- 08-29 00:41:59.521 800 3418 D NxpHal : read successful status = 0x0 08-29 00:41:59.523 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:41:59.524 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:41:59.524 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:41:59.524 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:41:59.525 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:41:59.525 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 08-29 00:41:59.526 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2565)] new state: DISCOVERY (1), disc_flags: 0x1 08-29 00:41:59.526 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:41:59.783 4606 6227 D RestoreViewModel: Starting new restore session to restore backup 1630109228740 08-29 00:41:59.784 1385 6197 V BackupManagerService: [UserID:0] beginRestoreSession: pkg=null transport=com.stevesoltys.seedvault.transport.ConfigurableBackupTransport 08-29 00:41:59.790 1385 1749 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4 08-29 00:41:59.831 1385 6197 V BackupManagerService: [UserID:0] Acquired wakelock:*backup*-0-3099 08-29 00:41:59.874 4606 4606 D ConfigurableBackupTransportService: Service created. 08-29 00:41:59.875 4606 4606 D ConfigurableBackupTransportService: Transport bound. 08-29 00:41:59.878 1385 1385 I TransportClient: ConfigurableBackupTransportService#3: Notifying [BH/MSG_RUN_GET_RESTORE_SETS] transport = IBackupTransport 08-29 00:41:59.947 817 1049 W APM::AudioPolicyEngine: getDevicesForStrategy() unknown strategy: -1 08-29 00:41:59.949 1385 3143 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4 08-29 00:41:59.974 6292 7517 I chatty : uid=10158(com.nextcloud.client) pool-6-thread-2 expire 7 lines 08-29 00:41:59.977 4606 7266 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:00.003 1816 1816 D KeyguardClockSwitch: Updating clock: 00:42 08-29 00:42:00.464 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/ changed - starting update of local data 08-29 00:42:00.465 0 0 W healthd : battery l=93 v=4401 t=35.8 h=2 st=2 c=638275 fc=3060000 cc=0 chg=a 08-29 00:42:00.524 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/ with 2 children and 0 files to remove 08-29 00:42:00.545 6292 7517 D FileDataStorageManager: Sending 3 operations to FileContentProvider 08-29 00:42:00.546 6292 7517 D FileContentProvider: applying batch in provider com.owncloud.android.providers.FileContentProvider@978883 (temporary: false) 08-29 00:42:00.586 6292 7517 D FileContentProvider: applied batch in provider com.owncloud.android.providers.FileContentProvider@978883 08-29 00:42:00.640 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED 08-29 00:42:00.641 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED 08-29 00:42:00.649 4606 5885 D DocumentsStorage: Children loaded. Continue... 08-29 00:42:00.654 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/2 08-29 00:42:00.694 6292 6450 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/2 08-29 00:42:00.797 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/42 08-29 00:42:00.843 6292 6450 D DocumentsStorageProvider: queryDocument(), id=1824776246/42 -- 08-29 00:42:00.940 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/43 08-29 00:42:00.978 6292 6450 D DocumentsStorageProvider: queryDocument(), id=1824776246/43 08-29 00:42:00.998 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/43 08-29 00:42:01.041 6292 6450 D DocumentsStorageProvider: queryDocument(), id=1824776246/43 08-29 00:42:01.057 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/43 08-29 00:42:01.093 6292 6450 D DocumentsStorageProvider: queryDocument(), id=1824776246/43 08-29 00:42:01.115 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/43 08-29 00:42:01.144 6292 6450 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/43 08-29 00:42:01.656 6292 7517 D DocumentsStorageProvider: run ReloadFolderDocumentTask(), id=1824776246/43 08-29 00:42:01.663 4606 7266 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:01.670 6292 7517 D RefreshFolderOperation: Checking changes in **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/ 08-29 00:42:01.680 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/ 08-29 00:42:01.748 800 3415 D NxpTml : PN54X - I2C Read successful.....len = 4 08-29 00:42:01.748 800 3415 D NxpTml : PN54X - Posting read message..... 08-29 00:42:01.748 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:01.750 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:01.751 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:01.751 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:01.751 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:01.751 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:42:01.752 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 08-29 00:42:01.752 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2565)] new state: DISCOVERY (1), disc_flags: 0x1 08-29 00:42:01.753 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:42:01.753 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:42:01.842 6292 7517 I RefreshFolderOperation: Checked **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/ : changed 08-29 00:42:01.849 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/ 08-29 00:42:02.147 1385 3083 E TaskPersister: File error accessing recents directory (directory doesn't exist?). 08-29 00:42:02.751 6292 7517 I ReadFolderRemoteOperation: Synchronized /.SeedVaultAndroidBackup/1630109228740/: Operation finished with HTTP status code 207 (success) 08-29 00:42:02.752 6292 7517 D RefreshFolderOperation: Synchronizing **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/ 08-29 00:42:02.759 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/1630109228740/ changed - starting update of local data 08-29 00:42:02.876 0 0 I aw8898_mute: mute state=1 08-29 00:42:02.876 0 0 I : aw8898_smartpa_cfg, flag = 0 08-29 00:42:02.879 762 794 D audio_hw_primary: out_standby: enter: stream (0xe71064c0) usecase(1: low-latency-playback) 08-29 00:42:02.928 762 794 D audio_hw_primary: disable_audio_route: reset and update mixer path: low-latency-playback 08-29 00:42:02.931 762 794 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 1 of type 0 for Event 2, with Raise=0 08-29 00:42:02.931 762 794 D msm8916_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0) 08-29 00:42:02.931 762 794 I msm8916_platform: platform_get_custom_mtmx_params: no matching param with id 0 ip_ch 2 op_ch 1 uc_id 1 snd_dev 2 08-29 00:42:02.931 762 794 D hardware_info: hw_info_append_hw_type : device_name = speaker 08-29 00:42:02.932 762 794 D audio_hw_primary: disable_snd_device: snd_device(2: speaker) 08-29 00:42:02.932 762 794 D msm8916_platform: platform_split_snd_device: snd_device(2) num devices(0) new_snd_devices(0) -- 08-29 00:42:03.951 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:03.951 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:03.953 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:42:03.953 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:03.953 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:42:03.953 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:03.954 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:03.954 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:42:03.954 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 08-29 00:42:03.954 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2565)] new state: DISCOVERY (1), disc_flags: 0x1 08-29 00:42:04.171 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/1630109228740/ with 56 children and 0 files to remove 08-29 00:42:04.447 6292 7517 D FileDataStorageManager: Sending 57 operations to FileContentProvider 08-29 00:42:04.447 6292 7517 D FileContentProvider: applying batch in provider com.owncloud.android.providers.FileContentProvider@978883 (temporary: false) 08-29 00:42:04.536 6292 7517 D FileContentProvider: applied batch in provider com.owncloud.android.providers.FileContentProvider@978883 08-29 00:42:04.574 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED 08-29 00:42:04.575 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED 08-29 00:42:04.580 4606 6247 D DocumentsStorage: Children loaded. Continue... 08-29 00:42:04.586 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/43 08-29 00:42:04.613 6292 6450 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/43 08-29 00:42:05.110 1385 1986 W system_server: Long monitor contention with owner PackageManager (1556) at void com.android.server.pm.permission.PermissionSettings.writePermissionTrees(org.xmlpull.v1.XmlSerializer)(PermissionSettings.java:147) waiters=0 in android.content.pm.ProviderInfo com.android.server.pm.ComponentResolver.queryProvider(java.lang.String, int, int) for 203ms 08-29 00:42:05.274 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/44 08-29 00:42:05.297 6292 6450 D DocumentsStorageProvider: queryDocument(), id=1824776246/44 08-29 00:42:05.311 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/44 08-29 00:42:05.345 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/44 08-29 00:42:05.382 6292 6450 D DocumentsStorageProvider: openDocument(), id=1824776246/44 08-29 00:42:05.407 6292 6450 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/.backup.metadata 08-29 00:42:05.641 4606 7266 I RestoreCoordinator: Got available restore sets: [android.app.backup.RestoreSet@e915ae1] 08-29 00:42:05.656 4606 4606 D ConfigurableBackupTransportService: Service destroyed. 08-29 00:42:05.658 1385 3143 D RestoreSession: restoreAll token=17b8a14dec4 observer=android.app.backup.IRestoreObserver$Stub$Proxy@b46f9fa 08-29 00:42:05.662 1385 3099 V BackupManagerService: [UserID:0] Released wakelock:*backup*-0-3099 08-29 00:42:05.687 1385 3143 V BackupManagerService: [UserID:0] Acquired wakelock:*backup*-0-3099 08-29 00:42:05.690 1385 3099 D BackupManagerService: MSG_RUN_RESTORE observer=android.app.backup.IRestoreObserver$Stub$Proxy@b46f9fa 08-29 00:42:05.718 1385 3099 I BackupManagerService: Full restore; asking about 169 apps 08-29 00:42:05.732 1385 3099 D BackupManagerService: Starting restore. 08-29 00:42:05.764 1385 3099 I BackupRestoreController: Restore starting for user: 0 08-29 00:42:05.793 4606 4606 D ConfigurableBackupTransportService: Service created. -- 08-29 00:42:08.423 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:08.423 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:42:08.423 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:42:08.423 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:08.424 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:08.424 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:42:08.424 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 08-29 00:42:08.424 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2565)] new state: DISCOVERY (1), disc_flags: 0x1 08-29 00:42:08.474 6292 7517 D DocumentsStorageProvider: run ReloadFolderDocumentTask(), id=1824776246/83 08-29 00:42:08.480 4606 6247 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:08.498 6292 7517 D RefreshFolderOperation: Checking changes in **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/ 08-29 00:42:08.504 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/ 08-29 00:42:08.675 6292 7517 I RefreshFolderOperation: Checked **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/ : changed 08-29 00:42:08.685 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/ 08-29 00:42:08.987 6292 7517 I ReadFolderRemoteOperation: Synchronized /.SeedVaultAndroidBackup/1630109228740/kv/: Operation finished with HTTP status code 207 (success) 08-29 00:42:08.988 6292 7517 D RefreshFolderOperation: Synchronizing **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/ 08-29 00:42:09.008 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/1630109228740/kv/ changed - starting update of local data 08-29 00:42:09.124 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/1630109228740/kv/ with 8 children and 0 files to remove 08-29 00:42:09.215 6292 7517 D FileDataStorageManager: Sending 9 operations to FileContentProvider 08-29 00:42:09.215 6292 7517 D FileContentProvider: applying batch in provider com.owncloud.android.providers.FileContentProvider@978883 (temporary: false) 08-29 00:42:09.319 6292 7517 D FileContentProvider: applied batch in provider com.owncloud.android.providers.FileContentProvider@978883 08-29 00:42:09.409 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED 08-29 00:42:09.409 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED 08-29 00:42:09.414 4606 6695 D DocumentsStorage: Children loaded. Continue... 08-29 00:42:09.420 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/83 08-29 00:42:09.453 6292 6450 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/83 08-29 00:42:09.814 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/100 08-29 00:42:09.874 6292 6450 D DocumentsStorageProvider: queryDocument(), id=1824776246/100 08-29 00:42:09.898 6292 6450 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/100 08-29 00:42:09.937 6292 6450 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/100 08-29 00:42:09.995 6292 7517 D DocumentsStorageProvider: run ReloadFolderDocumentTask(), id=1824776246/100 08-29 00:42:10.004 4606 6247 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:10.027 6292 7517 D RefreshFolderOperation: Checking changes in **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/@pm@/ 08-29 00:42:10.034 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/%40pm%40/ 08-29 00:42:10.177 6292 7517 I RefreshFolderOperation: Checked **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/@pm@/ : changed 08-29 00:42:10.186 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/%40pm%40/ 08-29 00:42:12.011 6292 7517 I ReadFolderRemoteOperation: Synchronized /.SeedVaultAndroidBackup/1630109228740/kv/@pm@/: Operation finished with HTTP status code 207 (success) 08-29 00:42:12.011 6292 7517 D RefreshFolderOperation: Synchronizing **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/@pm@/ 08-29 00:42:12.025 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/1630109228740/kv/@pm@/ changed - starting update of local data 08-29 00:42:12.080 6292 6316 I extcloud.clien: Background concurrent copying GC freed 311419(13MB) AllocSpace objects, 10(200KB) LOS objects, 67% free, 5833KB/17MB, paused 67us total 124.795ms 08-29 00:42:12.886 800 3415 D NxpTml : PN54X - I2C Read successful.....len = 4 08-29 00:42:12.886 800 3415 D NxpTml : PN54X - Posting read message..... 08-29 00:42:12.886 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:12.888 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:12.888 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:12.889 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:12.889 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:12.889 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:42:12.889 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 -- 08-29 00:42:15.110 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:15.111 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:15.112 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:15.112 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:15.112 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:15.113 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:42:15.113 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 08-29 00:42:15.113 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2565)] new state: DISCOVERY (1), disc_flags: 0x1 08-29 00:42:15.114 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:42:15.114 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:42:16.205 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/1630109228740/kv/@pm@/ with 172 children and 0 files to remove 08-29 00:42:17.328 800 3415 D NxpTml : PN54X - I2C Read successful.....len = 4 08-29 00:42:17.328 800 3415 D NxpTml : PN54X - Posting read message..... 08-29 00:42:17.328 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:17.329 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:17.330 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:17.330 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:42:17.330 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:42:17.330 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:17.331 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:17.331 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback -- 08-29 00:42:27.661 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/76 08-29 00:42:27.694 6292 6334 D DocumentsStorageProvider: queryDocument(), id=1824776246/76 08-29 00:42:27.713 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/77 08-29 00:42:27.744 6292 6334 D DocumentsStorageProvider: queryDocument(), id=1824776246/77 08-29 00:42:27.762 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/78 08-29 00:42:27.819 6292 6334 D DocumentsStorageProvider: queryDocument(), id=1824776246/78 08-29 00:42:27.864 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/78 08-29 00:42:27.916 6292 6334 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/78 08-29 00:42:27.967 6292 7517 D DocumentsStorageProvider: run ReloadFolderDocumentTask(), id=1824776246/78 08-29 00:42:27.973 4606 6247 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:27.994 6292 7517 D RefreshFolderOperation: Checking changes in **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/full/ 08-29 00:42:28.000 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/full/ 08-29 00:42:28.166 6292 7517 I RefreshFolderOperation: Checked **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/full/ : changed 08-29 00:42:28.174 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/full/ 08-29 00:42:28.717 6292 7517 I ReadFolderRemoteOperation: Synchronized /.SeedVaultAndroidBackup/1630109228740/full/: Operation finished with HTTP status code 207 (success) 08-29 00:42:28.717 6292 7517 D RefreshFolderOperation: Synchronizing **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/full/ 08-29 00:42:28.733 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/1630109228740/full/ changed - starting update of local data 08-29 00:42:29.543 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/1630109228740/full/ with 31 children and 0 files to remove 08-29 00:42:29.829 6292 7517 D FileDataStorageManager: Sending 32 operations to FileContentProvider 08-29 00:42:29.830 6292 7517 D FileContentProvider: applying batch in provider com.owncloud.android.providers.FileContentProvider@978883 (temporary: false) 08-29 00:42:30.071 6292 7517 D FileContentProvider: applied batch in provider com.owncloud.android.providers.FileContentProvider@978883 08-29 00:42:30.128 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED 08-29 00:42:30.129 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED 08-29 00:42:30.138 4606 6198 D DocumentsStorage: Children loaded. Continue... 08-29 00:42:30.143 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/78 08-29 00:42:30.188 6292 6334 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/78 08-29 00:42:30.706 800 3415 D NxpTml : PN54X - I2C Read successful.....len = 4 08-29 00:42:30.706 800 3415 D NxpTml : PN54X - Posting read message..... -- 08-29 00:42:34.224 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/83 08-29 00:42:34.267 6292 6334 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/83 08-29 00:42:34.699 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/100 08-29 00:42:34.739 6292 6334 D DocumentsStorageProvider: queryDocument(), id=1824776246/100 08-29 00:42:34.772 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/101 08-29 00:42:34.828 6292 6334 D DocumentsStorageProvider: queryDocument(), id=1824776246/101 08-29 00:42:34.868 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/101 08-29 00:42:34.917 6292 6334 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/101 08-29 00:42:34.982 6292 7517 D DocumentsStorageProvider: run ReloadFolderDocumentTask(), id=1824776246/101 08-29 00:42:34.990 4606 6247 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:35.009 6292 7517 D RefreshFolderOperation: Checking changes in **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.014 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.239 6292 7517 I RefreshFolderOperation: Checked **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/android/ : changed 08-29 00:42:35.247 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.968 6292 7517 I ReadFolderRemoteOperation: Synchronized /.SeedVaultAndroidBackup/1630109228740/kv/android/: Operation finished with HTTP status code 207 (success) 08-29 00:42:35.969 6292 7517 D RefreshFolderOperation: Synchronizing **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.989 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/1630109228740/kv/android/ changed - starting update of local data 08-29 00:42:36.312 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/1630109228740/kv/android/ with 10 children and 0 files to remove 08-29 00:42:36.478 6292 7517 D FileDataStorageManager: Sending 11 operations to FileContentProvider 08-29 00:42:36.478 6292 7517 D FileContentProvider: applying batch in provider com.owncloud.android.providers.FileContentProvider@978883 (temporary: false) 08-29 00:42:36.581 6292 7517 D FileContentProvider: applied batch in provider com.owncloud.android.providers.FileContentProvider@978883 08-29 00:42:36.640 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED 08-29 00:42:36.641 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED 08-29 00:42:36.646 4606 4625 D DocumentsStorage: Children loaded. Continue... 08-29 00:42:36.654 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/101 08-29 00:42:36.703 6292 6334 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/101 08-29 00:42:37.140 4606 6247 I RestoreCoordinator: Found K/V data for android. 08-29 00:42:37.141 1385 3099 E BackupManagerService: Required package metadata but got android -- 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Daemons$FinalizerDaemon.runInternal(Daemons.java:278) 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Daemons$Daemon.run(Daemons.java:139) 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Thread.run(Thread.java:923) 08-29 00:42:38.103 4606 4622 D StrictMode: Caused by: java.lang.Throwable: Explicit termination method 'close' not called 08-29 00:42:38.103 4606 4622 D StrictMode: at dalvik.system.CloseGuard.openWithCallSite(CloseGuard.java:259) 08-29 00:42:38.103 4606 4622 D StrictMode: at dalvik.system.CloseGuard.open(CloseGuard.java:230) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver$CursorWrapperInner.(ContentResolver.java:3753) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1209) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1115) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1071) 08-29 00:42:38.103 4606 4622 D StrictMode: at com.stevesoltys.seedvault.plugins.saf.DocumentsStorageKt$listFilesBlocking$2.invoke(DocumentsStorage.kt:229) 08-29 00:42:38.103 4606 4622 D StrictMode: at com.stevesoltys.seedvault.plugins.saf.DocumentsStorageKt$listFilesBlocking$2.invoke(Unknown Source:0) 08-29 00:42:38.103 4606 4622 D StrictMode: at com.stevesoltys.seedvault.plugins.saf.DocumentsStorageKt$getLoadedCursor$2$invokeSuspend$$inlined$suspendCancellableCoroutine$lambda$1.onChange(DocumentsStorage.kt:314) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:169) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:187) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:202) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:279) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:272) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:254) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:236) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObservable.dispatchChange(ContentObservable.java:70) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.AbstractCursor.onChange(AbstractCursor.java:393) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.AbstractCursor$SelfContentObserver.onChange(AbstractCursor.java:548) ```

Here is the last error stacktrace:

``` 08-29 00:42:34.990 4606 6247 D DocumentsStorage: Wait for children to get loaded... 08-29 00:42:35.009 6292 7517 D RefreshFolderOperation: Checking changes in **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.014 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.239 6292 7517 I RefreshFolderOperation: Checked **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/android/ : changed 08-29 00:42:35.247 6292 7517 D OwnCloudClient #0: REQUEST PROPFIND /remote.php/webdav/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.968 6292 7517 I ReadFolderRemoteOperation: Synchronized /.SeedVaultAndroidBackup/1630109228740/kv/android/: Operation finished with HTTP status code 207 (success) 08-29 00:42:35.969 6292 7517 D RefreshFolderOperation: Synchronizing **NEXTCLOUD_USER**@*******NEXTCLOUD_SERVER*******/.SeedVaultAndroidBackup/1630109228740/kv/android/ 08-29 00:42:35.989 6292 7517 D RefreshFolderOperation: Remote folder /.SeedVaultAndroidBackup/1630109228740/kv/android/ changed - starting update of local data 08-29 00:42:36.312 6292 7517 D FileDataStorageManager: Saving folder /.SeedVaultAndroidBackup/1630109228740/kv/android/ with 10 children and 0 files to remove 08-29 00:42:36.478 6292 7517 D FileDataStorageManager: Sending 11 operations to FileContentProvider 08-29 00:42:36.478 6292 7517 D FileContentProvider: applying batch in provider com.owncloud.android.providers.FileContentProvider@978883 (temporary: false) 08-29 00:42:36.581 6292 7517 D FileContentProvider: applied batch in provider com.owncloud.android.providers.FileContentProvider@978883 08-29 00:42:36.640 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_CONTENTS_SYNCED 08-29 00:42:36.641 6292 7517 D RefreshFolderOperation: Send broadcast com.owncloud.android.operations.RefreshFolderOperation.EVENT_SINGLE_FOLDER_SHARES_SYNCED 08-29 00:42:36.646 4606 4625 D DocumentsStorage: Children loaded. Continue... 08-29 00:42:36.654 6292 6334 D DocumentsStorageProvider: isChildDocument(), parent=1824776246/1, id=1824776246/101 08-29 00:42:36.703 6292 6334 D DocumentsStorageProvider: queryChildDocuments(), id=1824776246/101 08-29 00:42:37.140 4606 6247 I RestoreCoordinator: Found K/V data for android. 08-29 00:42:37.141 1385 3099 E BackupManagerService: Required package metadata but got android 08-29 00:42:37.143 4606 4625 D RestoreCoordinator: finishRestore 08-29 00:42:37.144 1385 3099 I BackupRestoreController: restoreFinished for 0 08-29 00:42:37.144 1385 3099 I BackupManagerService: Restore complete. 08-29 00:42:37.152 4606 4606 D ConfigurableBackupTransportService: Service destroyed. 08-29 00:42:37.154 1385 3099 V BackupManagerService: [UserID:0] Released wakelock:*backup*-0-3099 08-29 00:42:37.394 800 3415 D NxpTml : PN54X - I2C Read successful.....len = 4 08-29 00:42:37.394 800 3415 D NxpTml : PN54X - Posting read message..... 08-29 00:42:37.394 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:37.395 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:37.395 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:37.396 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 08-29 00:42:37.396 800 3415 D NxpTml : PN54X - Read requested..... 08-29 00:42:37.396 800 3415 D NxpTml : PN54X - Invoking I2C Read..... 08-29 00:42:37.397 3130 3397 I libnfc_nci: [INFO:nfa_dm_act.cc(269)] NFC_GEN_ERROR_REVT(0x500d) 08-29 00:42:37.397 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(1663)] nfa_dm_disc_data_cback 08-29 00:42:37.397 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2512)] state: DISCOVERY (1), event: INTF_ERROR_NTF(10) disc_flags: 0x1 08-29 00:42:37.397 3130 3397 I libnfc_nci: [INFO:nfa_dm_discover.cc(2565)] new state: DISCOVERY (1), disc_flags: 0x1 08-29 00:42:37.967 1385 1749 D RestoreSession: endRestoreSession 08-29 00:42:37.976 1385 3099 V BackupManagerService: [UserID:0] Clearing restore session and halting timeout 08-29 00:42:38.103 4606 4622 D StrictMode: StrictMode policy violation: android.os.strictmode.LeakedClosableViolation: A resource was acquired at attached stack trace but never released. See java.io.Closeable for information on avoiding resource leaks. 08-29 00:42:38.103 4606 4622 D StrictMode: at android.os.StrictMode$AndroidCloseGuardReporter.report(StrictMode.java:1924) 08-29 00:42:38.103 4606 4622 D StrictMode: at dalvik.system.CloseGuard.warnIfOpen(CloseGuard.java:303) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver$CursorWrapperInner.finalize(ContentResolver.java:3770) 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Daemons$FinalizerDaemon.doFinalize(Daemons.java:291) 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Daemons$FinalizerDaemon.runInternal(Daemons.java:278) 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Daemons$Daemon.run(Daemons.java:139) 08-29 00:42:38.103 4606 4622 D StrictMode: at java.lang.Thread.run(Thread.java:923) 08-29 00:42:38.103 4606 4622 D StrictMode: Caused by: java.lang.Throwable: Explicit termination method 'close' not called 08-29 00:42:38.103 4606 4622 D StrictMode: at dalvik.system.CloseGuard.openWithCallSite(CloseGuard.java:259) 08-29 00:42:38.103 4606 4622 D StrictMode: at dalvik.system.CloseGuard.open(CloseGuard.java:230) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver$CursorWrapperInner.(ContentResolver.java:3753) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1209) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1115) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.content.ContentResolver.query(ContentResolver.java:1071) 08-29 00:42:38.103 4606 4622 D StrictMode: at com.stevesoltys.seedvault.plugins.saf.DocumentsStorageKt$listFilesBlocking$2.invoke(DocumentsStorage.kt:229) 08-29 00:42:38.103 4606 4622 D StrictMode: at com.stevesoltys.seedvault.plugins.saf.DocumentsStorageKt$listFilesBlocking$2.invoke(Unknown Source:0) 08-29 00:42:38.103 4606 4622 D StrictMode: at com.stevesoltys.seedvault.plugins.saf.DocumentsStorageKt$getLoadedCursor$2$invokeSuspend$$inlined$suspendCancellableCoroutine$lambda$1.onChange(DocumentsStorage.kt:314) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:169) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:187) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:202) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:279) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:272) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:254) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:236) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObservable.dispatchChange(ContentObservable.java:70) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.AbstractCursor.onChange(AbstractCursor.java:393) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.AbstractCursor$SelfContentObserver.onChange(AbstractCursor.java:548) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:153) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:169) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:187) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.onChange(ContentObserver.java:202) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver.dispatchChange(ContentObserver.java:279) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.ContentObserver$Transport.onChangeEtc(ContentObserver.java:305) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.database.IContentObserver$Stub.onTransact(IContentObserver.java:124) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.os.Binder.execTransactInternal(Binder.java:1159) 08-29 00:42:38.103 4606 4622 D StrictMode: at android.os.Binder.execTransact(Binder.java:1123) 08-29 00:42:39.623 800 3415 D NxpTml : PN54X - I2C Read successful.....len = 4 08-29 00:42:39.623 800 3415 D NxpTml : PN54X - Posting read message..... 08-29 00:42:39.624 800 3415 D NxpNciR : len = 4 <= 600701A1 08-29 00:42:39.624 800 3418 D NxpHal : read successful status = 0x0 08-29 00:42:39.626 3130 3397 I libnfc_nci: [INFO:nfc_ncif.cc(501)] NFC received ntf gid:0 08-29 00:42:39.626 3130 3397 I libnfc_nci: [INFO:nci_hrcv.cc(128)] nci_proc_core_ntf opcode:0x7 ```

I can provide you with the full logcat on request in some more private way. :slightly_smiling_face:

System

v11-1.1 LineageOS for microG 18.1 2021-08-11 FP3 (Fairphone 3)

grote commented 3 years ago

Not seeing any issues in the log. Only strange thing might be:

08-29 00:42:37.140  4606  6247 I RestoreCoordinator: Found K/V data for android.
08-29 00:42:37.141  1385  3099 E BackupManagerService: Required package metadata but got android
rugk commented 3 years ago

…whcih I don't understand what that means, :sweat_smile: but anyway if you want the full logcat I still have that available.

rugk commented 3 years ago

Also what about that strange exception there?

StrictMode policy violation: android.os.strictmode.LeakedClosableViolation: A resource was acquired at attached stack trace but never released. See java.io.Closeable for information on avoiding resource leaks.

rugk commented 2 years ago

Note in a new restore try the successful thing was to actually wait. Simple as that. It just took very very loong to restore that dubious package manager, which I even have no idea what that is about/what data it restores.

So this could probably be more of a UX problem. Things like a loading indicator or other things proposed in https://github.com/seedvault-app/seedvault/issues/202 could fix that… :slightly_smiling_face:

grote commented 2 years ago

It just took very very loong to restore that dubious package manager

On Seedvault 3 this should be way faster now, so hopefully resolved.