core icon indicating copy to clipboard operation
core copied to clipboard

Exporting all accounts at once fails under some circumstances

Open Hocuri opened this issue 3 months ago • 0 comments

  • Operating System (Linux/Mac/Windows/iOS/Android): Android
  • Core Version: 2.11.0
  • Client Version: 2.11.0

Actual behavior

Image

Steps to reproduce the problem

  1. Start with a fresh install of Delta Chat.
  2. Create a new account. Duplicate it by creating a backup, and import it again, so that you have two identical profiles.
  3. Try to export both accounts at once by clicking "Export Backup -> Export all 2 profiles"

Screenshots

Logs

10-07 18:17:41.178 12195 12236 W Parcel  : Expecting binder but got null!
10-07 18:17:41.183  1473  1935 D CoreBackPreview: Window{1579619 u10 chat.delta/org.thoughtcrime.securesms.ApplicationPreferencesActivity}: Setting back callback OnBackInvokedCallbackInfo{mCallback=android.window.IOnBackInvokedCallback$Stub$Proxy@cce99db, mPriority=0}
10-07 18:17:41.185 12195 12225 I DeltaChat: [accId=1] src/scheduler.rs:110: stopping IO
10-07 18:17:41.185 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:110: stopping IO
10-07 18:17:41.186  1473 10096 D CoreBackPreview: Window{66643d u10 chat.delta/org.thoughtcrime.securesms.ApplicationPreferencesActivity}: Setting back callback null
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=1] src/scheduler.rs:437: Shutting down inbox loop.
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:437: Shutting down inbox loop.
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=1] src/scheduler.rs:846: Shutting down SMTP loop.
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=1] src/imap/idle.rs:78: "INBOX": Received interrupt, stopping IDLE.
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:846: Shutting down SMTP loop.
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=2] src/imap/idle.rs:78: "INBOX": Received interrupt, stopping IDLE.
10-07 18:17:41.186 12195 12225 I DeltaChat: [accId=2] src/imex.rs:190: Export path: /storage/emulated/10/Download
10-07 18:17:41.187 12195 12236 D OpenGLRenderer: endAllActiveAnimators on 0x769848df90 (RippleDrawable) with handle 0x75a8288ad0
10-07 18:17:41.188 12195 12225 I DeltaChat: [accId=1] src/imex.rs:190: Export path: /storage/emulated/10/Download
10-07 18:17:41.188  1473 10096 W InputManager-JNI: Input channel object '66643d chat.delta/org.thoughtcrime.securesms.ApplicationPreferencesActivity (client)' was disposed without first being removed with the input manager!
10-07 18:17:41.195 12195 12225 I DeltaChat: [accId=2] src/sql.rs:861: Start housekeeping...
10-07 18:17:41.196 12195 12225 I DeltaChat: [accId=2] src/sql.rs:916: 2 files in use.
10-07 18:17:41.198 12195 12225 I DeltaChat: [accId=2] src/sql.rs:976: Housekeeping: Keeping new unreferenced file #1: "91f9bc261758b09b57844798ebb61f1.jpg".
10-07 18:17:41.199  1473  1621 V WindowManager: Unknown focus tokens, dropping reportFocusChanged
10-07 18:17:41.199 12195 12195 I GenericForegroundService: handleStart() ChannelId: ch_generic  Id: 2 Progress: 0/0 determinate
10-07 18:17:41.200 12195 12225 I DeltaChat: [accId=1] src/sql.rs:861: Start housekeeping...
10-07 18:17:41.200 12195 12225 I DeltaChat: [accId=1] src/sql.rs:916: 3 files in use.
10-07 18:17:41.201 12195 12225 I DeltaChat: [accId=2] src/sql.rs:739: Incremental vacuum freed 0 pages.
10-07 18:17:41.204 12195 12225 I DeltaChat: [accId=1] src/sql.rs:739: Incremental vacuum freed 0 pages.
10-07 18:17:41.217 12195 12236 E OpenGLRenderer: Unable to match the desired swap behavior.
10-07 18:17:41.217  1473  1473 W NotificationHistory: Attempted to add notif for locked/gone/disabled user 10
10-07 18:17:41.225 12195 12195 I GenericForegroundService: handleReplace() ChannelId: ch_generic  Id: 2 Progress: 2/2000 determinate
10-07 18:17:41.327 12195 12225 I DeltaChat: [accId=1] src/sql.rs:842: Housekeeping done.
10-07 18:17:41.330 12195 12225 I DeltaChat: [accId=2] src/sql.rs:842: Housekeeping done.
10-07 18:17:41.388  5232 10744 W MediaProvider: isAppCloneUserPair failed. Users: 0 and 10
10-07 18:17:41.388  5232 10744 W MediaProvider: isAppCloneUserPair for user 10: false
10-07 18:17:41.393  5232 10744 V MediaProvider: Inserted id:1000000294 less than next row id backup:1000001000.
10-07 18:17:41.427  5232 10935 W MediaProvider: isAppCloneUserPair failed. Users: 0 and 10
10-07 18:17:41.427  5232 10935 W MediaProvider: isAppCloneUserPair for user 10: false
10-07 18:17:41.430  5232 10935 V MediaProvider: Inserted id:1000000295 less than next row id backup:1000001000.
10-07 18:17:41.448  5232  9632 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to chat.delta
10-07 18:17:41.451 12195 12225 E DeltaChat: [accId=2] IMEX failed to complete: could not export database: failed to export to attached backup database: database is locked: Error code 1: SQL error or missing database
10-07 18:17:41.451 12195 12225 E DeltaChat: IMEX failed to complete: could not export database: failed to export to attached backup database: database is locked: Error code 1: SQL error or missing database
10-07 18:17:41.451 12195 12225 W DeltaChat: [accId=2] deltachat-ffi/src/lib.rs:2337: IMEX failed: could not export database: failed to export to attached backup database: database is locked: Error code 1: SQL error or missing database
10-07 18:17:41.451 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:69: starting IO
10-07 18:17:41.452 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:392: Starting inbox loop.
10-07 18:17:41.453 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:411: Preparing new IMAP session for inbox.
10-07 18:17:41.453 12195 12225 I DeltaChat: [accId=2] src/imap.rs:327: Connecting to IMAP server.
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:772: Starting SMTP loop.
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:934: scheduler is running
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/location.rs:740: Location loop is waiting for 24h 0m 0s or interrupt
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/contact.rs:2079: Recently seen loop waiting for 24h 0m 0s or interrupt
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/imap.rs:342: IMAP trying to connect to nine.testrun.org:443:tls.
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/net/dns.rs:134: Using memory-cached DNS resolution for nine.testrun.org.
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/net/dns.rs:180: Resolved nine.testrun.org into 2a01:4f8:c014:43c5::1.
10-07 18:17:41.454 12195 12225 I DeltaChat: [accId=2] src/net/dns.rs:180: Resolved nine.testrun.org into 2a01:4f8:241:4ce8::2.
10-07 18:17:41.456 12195 12225 I DeltaChat: [accId=2] src/net/dns.rs:180: Resolved nine.testrun.org into 168.119.244.218.
10-07 18:17:41.456 12195 12225 I DeltaChat: [accId=2] src/net/dns.rs:180: Resolved nine.testrun.org into 37.218.247.14.
10-07 18:17:41.456 12195 12225 I DeltaChat: [accId=2] src/net/dns.rs:180: Resolved nine.testrun.org into 116.202.233.236.
10-07 18:17:41.456 12195 12225 I DeltaChat: [accId=1] src/context.rs:785: Signaling the ongoing process to stop ASAP.
10-07 18:17:41.457 12195 12225 I DeltaChat: [accId=2] src/context.rs:791: No ongoing process to stop.
10-07 18:17:41.457 12195 12225 I DeltaChat: [accId=2] src/ephemeral.rs:605: Ephemeral loop waiting for deletion in 24h 0m 0s or interrupt
10-07 18:17:41.457  1473  6841 D CoreBackPreview: Window{1579619 u10 chat.delta/org.thoughtcrime.securesms.ApplicationPreferencesActivity}: Setting back callback null
10-07 18:17:41.457 12195 12225 I DeltaChat: [accId=2] src/imap/client.rs:121: Attempting IMAP connection to nine.testrun.org ([2a01:4f8:c014:43c5::1]:443).
10-07 18:17:41.459  1473  1935 W InputManager-JNI: Input channel object '1579619 chat.delta/org.thoughtcrime.securesms.ApplicationPreferencesActivity (client)' was disposed without first being removed with the input manager!
10-07 18:17:41.465 12195 12225 I DeltaChat: [accId=2] src/smtp.rs:521: Selected rows from SMTP queue: [].
10-07 18:17:41.465 12195 12225 I DeltaChat: [accId=2] src/smtp.rs:626: Sending MDNs.
10-07 18:17:41.466 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:807: SMTP fake idle started.
10-07 18:17:41.466 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:835: SMTP has no messages to retry, waiting for interrupt.
10-07 18:17:41.468  5232 10744 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to chat.delta
10-07 18:17:41.476 12195 12225 I DeltaChat: [accId=1] src/context.rs:772: Ongoing stopped in 19.204011ms
10-07 18:17:41.476 12195 12225 I DeltaChat: [accId=1] src/scheduler.rs:69: starting IO
10-07 18:17:41.476 12195 12225 E DeltaChat: [accId=1] IMEX failed to complete: could not export database: failed to detach backup database: disk I/O error: Error code 266: Error reading from disk
10-07 18:17:41.476 12195 12225 W DeltaChat: [accId=1] deltachat-ffi/src/lib.rs:2337: IMEX failed: could not export database: failed to detach backup database: disk I/O error: Error code 266: Error reading from disk
10-07 18:17:41.478 12195 12225 E DeltaChat: [accId=1] Failed to start IO: disk I/O error: Error code 266: Error reading from disk
10-07 18:17:41.479  1473  1621 V WindowManager: Unknown focus tokens, dropping reportFocusChanged
10-07 18:17:41.488 12195 12236 W Parcel  : Expecting binder but got null!
10-07 18:17:41.495 12195 12195 I GenericForegroundService: handleStop()
10-07 18:17:41.495 12195 12195 I GenericForegroundService: Last request. Ending foreground service.
10-07 18:17:41.498  1473 10096 D CoreBackPreview: Window{7ac141c u10 chat.delta/org.thoughtcrime.securesms.ApplicationPreferencesActivity}: Setting back callback OnBackInvokedCallbackInfo{mCallback=android.window.IOnBackInvokedCallback$Stub$Proxy@f642fc6, mPriority=0}
10-07 18:17:41.513 12195 12236 E OpenGLRenderer: Unable to match the desired swap behavior.
10-07 18:17:41.535  8729  8729 I GoogleInputMethodService: GoogleInputMethodService.onFinishInput():2085 
10-07 18:17:41.536  8729  8729 I Module  : DeviceLockedStatusModuleProvider$Module.updateDeviceLockedStatus():100 repeatCheckTimes = 0, locked = false
10-07 18:17:41.536  8729  8729 I GoogleInputMethodService: GoogleInputMethodService.onStartInput():1346 onStartInput(EditorInfo{EditorInfo{packageName=chat.delta, inputType=0, inputTypeString=NULL, enableLearning=false, autoCorrection=false, autoComplete=false, imeOptions=0, privateImeOptions=null, actionName=UNSPECIFIED, actionLabel=null, initialSelStart=-1, initialSelEnd=-1, initialCapsMode=0, label=null, fieldId=-1, fieldName=null, extras=null, hintText=null, hintLocales=[]}}, false)
10-07 18:17:41.538  8729  8729 I Module  : DeviceLockedStatusModuleProvider$Module.updateDeviceLockedStatus():100 repeatCheckTimes = 1, locked = false
10-07 18:17:41.552 12195 12225 I DeltaChat: [accId=2] src/imap.rs:380: Logging into IMAP server with LOGIN.
10-07 18:17:41.583  2062  2378 D TelephonyProvider: Using old permission behavior for telephony provider compat
10-07 18:17:41.620  2062  2378 D TelephonyProvider: Using old permission behavior for telephony provider compat
10-07 18:17:41.645 12195 12225 I DeltaChat: [accId=2] src/imap.rs:389: Enabling IMAP compression.
10-07 18:17:41.672 12195 12225 I DeltaChat: [accId=2] src/imap.rs:412: Successfully logged into IMAP server.
10-07 18:17:41.696 12195 12225 I DeltaChat: [accId=2] src/imap/select_folder.rs:79: Selected folder "INBOX".
10-07 18:17:41.697 12195 12225 I DeltaChat: [accId=2] src/imap.rs:553: No new emails in folder "INBOX".
10-07 18:17:41.725 12195 12225 I DeltaChat: [accId=2] src/scheduler.rs:683: IMAP session in folder "INBOX" supports IDLE, using it.
10-07 18:17:41.752 12195 12225 I DeltaChat: [accId=2] src/imap/idle.rs:64: IDLE entering wait-on-remote state in folder "INBOX".
10-07 18:17:42.162  7224  7224 W libsyncthing.so: type=1400 audit(0.0:511): avc: denied { bind } for scontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tcontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tclass=netlink_route_socket permissive=0 bug=b/155595000 app=com.nutomic.syncthingandroid
10-07 18:17:42.162  7224  7224 W libsyncthing.so: type=1400 audit(0.0:510): avc: denied { bind } for scontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tcontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tclass=netlink_route_socket permissive=0 bug=b/155595000 app=com.nutomic.syncthingandroid
10-07 18:17:42.162  7224  7224 W libsyncthing.so: type=1400 audit(0.0:512): avc: denied { bind } for scontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tcontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tclass=netlink_route_socket permissive=0 bug=b/155595000 app=com.nutomic.syncthingandroid
10-07 18:17:42.162  7224  7224 W libsyncthing.so: type=1400 audit(0.0:513): avc: denied { bind } for scontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tcontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tclass=netlink_route_socket permissive=0 bug=b/155595000 app=com.nutomic.syncthingandroid
10-07 18:17:42.162  7224  7224 W libsyncthing.so: type=1400 audit(0.0:514): avc: denied { bind } for scontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tcontext=u:r:untrusted_app:s0:c161,c256,c512,c768 tclass=netlink_route_socket permissive=0 bug=b/155595000 app=com.nutomic.syncthingandroid

10-07 18:17:43.833  2062  2378 D TelephonyProvider: Using old permission behavior for telephony provider compat
10-07 18:17:43.871  2062  2118 D TelephonyProvider: Using old permission behavior for telephony provider compat

Hocuri avatar Oct 07 '25 16:10 Hocuri