{"action":"created","issue":{"url":"https://api.github.com/repos/duplicati/duplicati/issues/3205","repository_url":"https://api.github.com/repos/duplicati/duplicati","labels_url":"https://api.github.com/repos/duplicati/duplicati/issues/3205/labels{/name}","comments_url":"https://api.github.com/repos/duplicati/duplicati/issues/3205/comments","events_url":"https://api.github.com/repos/duplicati/duplicati/issues/3205/events","html_url":"https://github.com/duplicati/duplicati/issues/3205","id":320452980,"number":3205,"title":"Duplicati stuck on verifying backend data","user":{"login":"Rubyjunk","id":26909616,"avatar_url":"https://avatars2.githubusercontent.com/u/26909616?v=4","gravatar_id":"","url":"https://api.github.com/users/Rubyjunk","html_url":"https://github.com/Rubyjunk","followers_url":"https://api.github.com/users/Rubyjunk/followers","following_url":"https://api.github.com/users/Rubyjunk/following{/other_user}","gists_url":"https://api.github.com/users/Rubyjunk/gists{/gist_id}","starred_url":"https://api.github.com/users/Rubyjunk/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/Rubyjunk/subscriptions","organizations_url":"https://api.github.com/users/Rubyjunk/orgs","repos_url":"https://api.github.com/users/Rubyjunk/repos","events_url":"https://api.github.com/users/Rubyjunk/events{/privacy}","received_events_url":"https://api.github.com/users/Rubyjunk/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":3,"created_at":"2018-05-04T22:57:10Z","updated_at":"2018-05-09T13:36:08Z","closed_at":null,"author_association":"NONE","body":"- [x] I have searched open and closed issues for duplicates.\r\n\r\n----------------------------------------\r\n\r\n## Environment info\r\n\r\n- **Duplicati version**: 2.0.3.3_beta_2018-04-02\r\n- **Operating system**: Windows 10 Pro Version 1709 Build 16299.371 \r\n- **Backend**: Google Drive\r\n\r\n## Description\r\nDuplicati is stuck on \"Verifying Backend Data\". This occurs even after I enable the \"no-backend-verification\" option. This only started happening after the update from the previous beta version.\r\n\r\n## Steps to reproduce\r\n1. Start duplicati\r\n2. Start a backup\r\n\r\n- **Actual result**:\r\n\r\nDuplicati never begins the backup and remains on the \"Verifying Backend Data\" stage\r\n\r\n- **Expected result**:\r\n\r\nDuplicati starts a backup.\r\n\r\n## Debug log\r\n\r\n```May 4, 2018 6:55 PM: Delete operation failed for duplicati-i5a41f5ee55f14c6ea2b3ad7ff2c17191.dindex.zip.aes with FileNotFound, listing contents\r\nMay 4, 2018 6:55 PM: Backend event: Delete - Started: duplicati-i5a41f5ee55f14c6ea2b3ad7ff2c17191.dindex.zip.aes ()\r\nMay 4, 2018 6:55 PM: Starting - RemoteOperationDelete\r\nMay 4, 2018 6:55 PM: removing remote file listed as Temporary: duplicati-i5a41f5ee55f14c6ea2b3ad7ff2c17191.dindex.zip.aes\r\nMay 4, 2018 6:55 PM: RemoteOperationDelete took 00:00:47.292\r\nMay 4, 2018 6:55 PM: Listing indicates file duplicati-i6f313f495c1c4970b5aed4929bfbb415.dindex.zip.aes is deleted correctly\r\nMay 4, 2018 6:54 PM: Delete operation failed for duplicati-i6f313f495c1c4970b5aed4929bfbb415.dindex.zip.aes with FileNotFound, listing contents\r\nMay 4, 2018 6:54 PM: Backend event: Delete - Started: duplicati-i6f313f495c1c4970b5aed4929bfbb415.dindex.zip.aes ()\r\nMay 4, 2018 6:54 PM: Starting - RemoteOperationDelete\r\nMay 4, 2018 6:54 PM: removing remote file listed as Deleting: duplicati-i6f313f495c1c4970b5aed4929bfbb415.dindex.zip.aes\r\nMay 4, 2018 6:54 PM: RemoteOperationDelete took 00:00:48.182\r\nMay 4, 2018 6:54 PM: Listing indicates file duplicati-i5cc05a8e8b91473dbdf6c53bb152cc9e.dindex.zip.aes is deleted correctly\r\nMay 4, 2018 6:54 PM: Delete operation failed for duplicati-i5cc05a8e8b91473dbdf6c53bb152cc9e.dindex.zip.aes with FileNotFound, listing contents\r\nMay 4, 2018 6:54 PM: Backend event: Delete - Started: duplicati-i5cc05a8e8b91473dbdf6c53bb152cc9e.dindex.zip.aes ()\r\nMay 4, 2018 6:54 PM: Starting - RemoteOperationDelete\r\nMay 4, 2018 6:54 PM: removing remote file listed as Deleting: duplicati-i5cc05a8e8b91473dbdf6c53bb152cc9e.dindex.zip.aes\r\nMay 4, 2018 6:54 PM: RemoteOperationDelete took 00:01:06.145\r\nMay 4, 2018 6:54 PM: Listing indicates file duplicati-i08dfbc2aa8de4208b9d35ddbb5f7f502.dindex.zip.aes is deleted correctly\r\nMay 4, 2018 6:53 PM: Delete operation failed for duplicati-i08dfbc2aa8de4208b9d35ddbb5f7f502.dindex.zip.aes with FileNotFound, listing contents\r\nMay 4, 2018 6:52 PM: Backend event: Delete - Started: duplicati-i08dfbc2aa8de4208b9d35ddbb5f7f502.dindex.zip.aes ()\r\nMay 4, 2018 6:52 PM: Starting - RemoteOperationDelete\r\nMay 4, 2018 6:52 PM: removing remote file listed as Deleting: duplicati-i08dfbc2aa8de4208b9d35ddbb5f7f502.dindex.zip.aes\r\nMay 4, 2018 6:52 PM: Starting - PreBackupVerify\r\nMay 4, 2018 6:52 PM: ExecuteReader: SELECT DISTINCT \"Fileset\".\"ID\", \"Fileset\".\"Timestamp\" FROM \"Fileset\", \"RemoteVolume\" WHERE \"RemoteVolume\".\"ID\" = \"Fileset\".\"VolumeID\" AND \"Fileset\".\"ID\" IN (SELECT \"FilesetID\" FROM \"FilesetEntry\") AND (\"RemoteVolume\".\"State\" = \"Uploading\" OR \"RemoteVolume\".\"State\" = \"Temporary\") took 00:00:00.260\r\nMay 4, 2018 6:52 PM: Starting - ExecuteReader: SELECT DISTINCT \"Fileset\".\"ID\", \"Fileset\".\"Timestamp\" FROM \"Fileset\", \"RemoteVolume\" WHERE \"RemoteVolume\".\"ID\" = \"Fileset\".\"VolumeID\" AND \"Fileset\".\"ID\" IN (SELECT \"FilesetID\" FROM \"FilesetEntry\") AND (\"RemoteVolume\".\"State\" = \"Uploading\" OR \"RemoteVolume\".\"State\" = \"Temporary\")\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT SUM(\"Blockset\".\"Length\") FROM \"File\", \"FilesetEntry\", \"Blockset\" WHERE \"File\".\"ID\" = \"FilesetEntry\".\"FileID\" AND \"File\".\"BlocksetID\" = \"Blockset\".\"ID\" AND \"FilesetEntry\".\"FilesetID\" = ? AND \"File\".\"BlocksetID\" NOT IN (?, ?) took 00:00:00.308\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT SUM(\"Blockset\".\"Length\") FROM \"File\", \"FilesetEntry\", \"Blockset\" WHERE \"File\".\"ID\" = \"FilesetEntry\".\"FileID\" AND \"File\".\"BlocksetID\" = \"Blockset\".\"ID\" AND \"FilesetEntry\".\"FilesetID\" = ? AND \"File\".\"BlocksetID\" NOT IN (?, ?)\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT COUNT(*) FROM \"File\" INNER JOIN \"FilesetEntry\" ON \"File\".\"ID\" = \"FilesetEntry\".\"FileID\" WHERE \"FilesetEntry\".\"FilesetID\" = ? AND \"File\".\"BlocksetID\" NOT IN (?, ?) took 00:00:00.333\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM \"File\" INNER JOIN \"FilesetEntry\" ON \"File\".\"ID\" = \"FilesetEntry\".\"FileID\" WHERE \"FilesetEntry\".\"FilesetID\" = ? AND \"File\".\"BlocksetID\" NOT IN (?, ?)\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT \"ID\" FROM \"Fileset\" ORDER BY \"Timestamp\" DESC LIMIT 1 took 00:00:00.000\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT \"ID\" FROM \"Fileset\" ORDER BY \"Timestamp\" DESC LIMIT 1\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT COUNT(*) FROM \"File\" WHERE \"BlocksetID\" != ? AND \"BlocksetID\" != ? AND NOT \"BlocksetID\" IN (SELECT \"BlocksetID\" FROM \"BlocksetEntry\") took 00:00:01.898\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM \"File\" WHERE \"BlocksetID\" != ? AND \"BlocksetID\" != ? AND NOT \"BlocksetID\" IN (SELECT \"BlocksetID\" FROM \"BlocksetEntry\")\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT COUNT(*) FROM (SELECT * FROM (SELECT \"N\".\"BlocksetID\", ((\"N\".\"BlockCount\" + 3200 - 1) / 3200) AS \"BlocklistHashCountExpected\", CASE WHEN \"G\".\"BlocklistHashCount\" IS NULL THEN 0 ELSE \"G\".\"BlocklistHashCount\" END AS \"BlocklistHashCountActual\" FROM (SELECT \"BlocksetID\", COUNT(*) AS \"BlockCount\" FROM \"BlocksetEntry\" GROUP BY \"BlocksetID\") \"N\" LEFT OUTER JOIN (SELECT \"BlocksetID\", COUNT(*) AS \"BlocklistHashCount\" FROM \"BlocklistHash\" GROUP BY \"BlocksetID\") \"G\" ON \"N\".\"BlocksetID\" = \"G\".\"BlocksetID\" WHERE \"N\".\"BlockCount\" > 1) WHERE \"BlocklistHashCountExpected\" != \"BlocklistHashCountActual\") took 00:00:02.189\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM (SELECT * FROM (SELECT \"N\".\"BlocksetID\", ((\"N\".\"BlockCount\" + 3200 - 1) / 3200) AS \"BlocklistHashCountExpected\", CASE WHEN \"G\".\"BlocklistHashCount\" IS NULL THEN 0 ELSE \"G\".\"BlocklistHashCount\" END AS \"BlocklistHashCountActual\" FROM (SELECT \"BlocksetID\", COUNT(*) AS \"BlockCount\" FROM \"BlocksetEntry\" GROUP BY \"BlocksetID\") \"N\" LEFT OUTER JOIN (SELECT \"BlocksetID\", COUNT(*) AS \"BlocklistHashCount\" FROM \"BlocklistHash\" GROUP BY \"BlocksetID\") \"G\" ON \"N\".\"BlocksetID\" = \"G\".\"BlocksetID\" WHERE \"N\".\"BlockCount\" > 1) WHERE \"BlocklistHashCountExpected\" != \"BlocklistHashCountActual\")\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT Count(*) FROM (SELECT DISTINCT \"BlocksetID\", \"Index\" FROM \"BlocklistHash\") took 00:00:00.009\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT Count(*) FROM (SELECT DISTINCT \"BlocksetID\", \"Index\" FROM \"BlocklistHash\")\r\nMay 4, 2018 6:52 PM: ExecuteScalarInt64: SELECT Count(*) FROM \"BlocklistHash\" took 00:00:00.052\r\nMay 4, 2018 6:52 PM: Starting - ExecuteScalarInt64: SELECT Count(*) FROM \"BlocklistHash\"\r\nMay 4, 2018 6:52 PM: ExecuteReader: SELECT \"CalcLen\", \"Length\", \"A\".\"BlocksetID\", \"File\".\"Path\" FROM (\r\n SELECT \r\n \"A\".\"ID\" AS \"BlocksetID\", \r\n IFNULL(\"B\".\"CalcLen\", 0) AS \"CalcLen\", \r\n \"A\".\"Length\"\r\n FROM\r\n \"Blockset\" A\r\n LEFT OUTER JOIN\r\n (\r\n SELECT \r\n \"BlocksetEntry\".\"BlocksetID\",\r\n SUM(\"Block\".\"Size\") AS \"CalcLen\"\r\n FROM\r\n \"BlocksetEntry\"\r\n LEFT OUTER JOIN\r\n \"Block\"\r\n ON\r\n \"Block\".\"ID\" = \"BlocksetEntry\".\"BlockID\"\r\n GROUP BY \"BlocksetEntry\".\"BlocksetID\"\r\n ) B\r\n ON\r\n \"A\".\"ID\" = \"B\".\"BlocksetID\"\r\n \r\n ) A, \"File\" WHERE \"A\".\"BlocksetID\" = \"File\".\"BlocksetID\" AND \"A\".\"CalcLen\" != \"A\".\"Length\" took 00:00:42.644\r\nMay 4, 2018 6:50 PM: Server has started and is listening on 127.0.0.1, port 8200```\r\n"},"comment":{"url":"https://api.github.com/repos/duplicati/duplicati/issues/comments/387740644","html_url":"https://github.com/duplicati/duplicati/issues/3205#issuecomment-387740644","issue_url":"https://api.github.com/repos/duplicati/duplicati/issues/3205","id":387740644,"user":{"login":"BlackEdder0815","id":25084261,"avatar_url":"https://avatars3.githubusercontent.com/u/25084261?v=4","gravatar_id":"","url":"https://api.github.com/users/BlackEdder0815","html_url":"https://github.com/BlackEdder0815","followers_url":"https://api.github.com/users/BlackEdder0815/followers","following_url":"https://api.github.com/users/BlackEdder0815/following{/other_user}","gists_url":"https://api.github.com/users/BlackEdder0815/gists{/gist_id}","starred_url":"https://api.github.com/users/BlackEdder0815/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/BlackEdder0815/subscriptions","organizations_url":"https://api.github.com/users/BlackEdder0815/orgs","repos_url":"https://api.github.com/users/BlackEdder0815/repos","events_url":"https://api.github.com/users/BlackEdder0815/events{/privacy}","received_events_url":"https://api.github.com/users/BlackEdder0815/received_events","type":"User","site_admin":false},"created_at":"2018-05-09T13:36:08Z","updated_at":"2018-05-09T13:36:08Z","author_association":"NONE","body":"Here is my log entry (I restarted server with enabled debug-log):\r\n\r\n```\r\n2018-05-09 11:48:22Z - Profiling: Starting - Running Backup\r\n2018-05-09 11:48:22Z - Profiling: Starting - ExecuteScalarInt64: INSERT INTO \"Operation\" (\"Description\", \"Timestamp\") VALUES (?, ?); SELECT last_insert_rowid();\r\n2018-05-09 11:48:22Z - Profiling: ExecuteScalarInt64: INSERT INTO \"Operation\" (\"Description\", \"Timestamp\") VALUES (?, ?); SELECT last_insert_rowid(); took 00:00:00.006\r\n2018-05-09 11:48:22Z - Profiling: Starting - ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"\r\n2018-05-09 11:48:22Z - Profiling: ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"  took 00:00:00.000\r\n2018-05-09 11:48:22Z - Profiling: Starting - ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"\r\n2018-05-09 11:48:22Z - Profiling: ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"  took 00:00:00.000\r\n2018-05-09 11:48:22Z - Profiling: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM \"Block\" WHERE \"Size\" > ?\r\n2018-05-09 11:48:24Z - Profiling: ExecuteScalarInt64: SELECT COUNT(*) FROM \"Block\" WHERE \"Size\" > ? took 00:00:01.499\r\n2018-05-09 11:48:24Z - Profiling: Starting - ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"\r\n2018-05-09 11:48:24Z - Profiling: ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"  took 00:00:00.000\r\n2018-05-09 11:48:24Z - Profiling: Starting - ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"\r\n2018-05-09 11:48:24Z - Profiling: ExecuteReader: SELECT \"Key\", \"Value\" FROM \"Configuration\"  took 00:00:00.000\r\n2018-05-09 11:48:24Z - Profiling: Starting - ExecuteReader: SELECT \"CalcLen\", \"Length\", \"A\".\"BlocksetID\", \"File\".\"Path\" FROM (\r\nSELECT\r\n    \"A\".\"ID\" AS \"BlocksetID\",\r\n    IFNULL(\"B\".\"CalcLen\", 0) AS \"CalcLen\",\r\n    \"A\".\"Length\"\r\nFROM\r\n    \"Blockset\" A\r\nLEFT OUTER JOIN\r\n    (\r\n        SELECT\r\n            \"BlocksetEntry\".\"BlocksetID\",\r\n            SUM(\"Block\".\"Size\") AS \"CalcLen\"\r\n        FROM\r\n            \"BlocksetEntry\"\r\n        LEFT OUTER JOIN\r\n            \"Block\"\r\n        ON\r\n            \"Block\".\"ID\" = \"BlocksetEntry\".\"BlockID\"\r\n        GROUP BY \"BlocksetEntry\".\"BlocksetID\"\r\n    ) B\r\nON\r\n    \"A\".\"ID\" = \"B\".\"BlocksetID\"\r\n\r\n) A, \"File\" WHERE \"A\".\"BlocksetID\" = \"File\".\"BlocksetID\" AND \"A\".\"CalcLen\" != \"A\".\"Length\"\r\n2018-05-09 11:48:25Z - Profiling: ExecuteReader: SELECT \"CalcLen\", \"Length\", \"A\".\"BlocksetID\", \"File\".\"Path\" FROM (\r\nSELECT\r\n    \"A\".\"ID\" AS \"BlocksetID\",\r\n    IFNULL(\"B\".\"CalcLen\", 0) AS \"CalcLen\",\r\n    \"A\".\"Length\"\r\nFROM\r\n    \"Blockset\" A\r\nLEFT OUTER JOIN\r\n    (\r\n        SELECT\r\n            \"BlocksetEntry\".\"BlocksetID\",\r\n            SUM(\"Block\".\"Size\") AS \"CalcLen\"\r\n        FROM\r\n            \"BlocksetEntry\"\r\n        LEFT OUTER JOIN\r\n            \"Block\"\r\n        ON\r\n            \"Block\".\"ID\" = \"BlocksetEntry\".\"BlockID\"\r\n        GROUP BY \"BlocksetEntry\".\"BlocksetID\"\r\n    ) B\r\nON\r\n    \"A\".\"ID\" = \"B\".\"BlocksetID\"\r\n\r\n) A, \"File\" WHERE \"A\".\"BlocksetID\" = \"File\".\"BlocksetID\" AND \"A\".\"CalcLen\" != \"A\".\"Length\"  took 00:00:00.467\r\n2018-05-09 11:48:25Z - Profiling: Starting - ExecuteScalarInt64: SELECT Count(*) FROM \"BlocklistHash\"\r\n2018-05-09 11:48:25Z - Profiling: ExecuteScalarInt64: SELECT Count(*) FROM \"BlocklistHash\" took 00:00:00.000\r\n2018-05-09 11:48:25Z - Profiling: Starting - ExecuteScalarInt64: SELECT Count(*) FROM (SELECT DISTINCT \"BlocksetID\", \"Index\" FROM \"BlocklistHash\")\r\n2018-05-09 11:48:25Z - Profiling: ExecuteScalarInt64: SELECT Count(*) FROM (SELECT DISTINCT \"BlocksetID\", \"Index\" FROM \"BlocklistHash\") took 00:00:00.000\r\n2018-05-09 11:48:25Z - Profiling: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM (SELECT * FROM (SELECT \"N\".\"BlocksetID\", ((\"N\".\"BlockCount\" + 3200 - 1) / 3200) AS \"BlocklistHashCountExpected\", CASE WHEN \"G\".\"BlocklistHashCount\" IS NULL THEN 0 ELSE \"G\".\"BlocklistHashCount\" END AS \"BlocklistHashCountActual\" FROM (SELECT \"BlocksetID\", COUNT(*) AS \"BlockCount\" FROM \"BlocksetEntry\" GROUP BY \"BlocksetID\") \"N\" LEFT OUTER JOIN (SELECT \"BlocksetID\", COUNT(*) AS \"BlocklistHashCount\" FROM \"BlocklistHash\" GROUP BY \"BlocksetID\") \"G\" ON \"N\".\"BlocksetID\" = \"G\".\"BlocksetID\" WHERE \"N\".\"BlockCount\" > 1) WHERE \"BlocklistHashCountExpected\" != \"BlocklistHashCountActual\")\r\n2018-05-09 11:48:25Z - Profiling: ExecuteScalarInt64: SELECT COUNT(*) FROM (SELECT * FROM (SELECT \"N\".\"BlocksetID\", ((\"N\".\"BlockCount\" + 3200 - 1) / 3200) AS \"BlocklistHashCountExpected\", CASE WHEN \"G\".\"BlocklistHashCount\" IS NULL THEN 0 ELSE \"G\".\"BlocklistHashCount\" END AS \"BlocklistHashCountActual\" FROM (SELECT \"BlocksetID\", COUNT(*) AS \"BlockCount\" FROM \"BlocksetEntry\" GROUP BY \"BlocksetID\") \"N\" LEFT OUTER JOIN (SELECT \"BlocksetID\", COUNT(*) AS \"BlocklistHashCount\" FROM \"BlocklistHash\" GROUP BY \"BlocksetID\") \"G\" ON \"N\".\"BlocksetID\" = \"G\".\"BlocksetID\" WHERE \"N\".\"BlockCount\" > 1) WHERE \"BlocklistHashCountExpected\" != \"BlocklistHashCountActual\") took 00:00:00.132\r\n2018-05-09 11:48:25Z - Profiling: Starting - ExecuteScalarInt64: SELECT COUNT(*) FROM \"File\" WHERE \"BlocksetID\" != ? AND \"BlocksetID\" != ? AND NOT \"BlocksetID\" IN (SELECT \"BlocksetID\" FROM \"BlocksetEntry\")\r\n2018-05-09 11:48:25Z - Profiling: ExecuteScalarInt64: SELECT COUNT(*) FROM \"File\" WHERE \"BlocksetID\" != ? AND \"BlocksetID\" != ? AND NOT \"BlocksetID\" IN (SELECT \"BlocksetID\" FROM \"BlocksetEntry\") took 00:00:00.075\r\n2018-05-09 11:48:25Z - Profiling: Starting - ExecuteReader: SELECT DISTINCT \"Fileset\".\"ID\", \"Fileset\".\"Timestamp\" FROM \"Fileset\", \"RemoteVolume\" WHERE \"RemoteVolume\".\"ID\" = \"Fileset\".\"VolumeID\" AND \"Fileset\".\"ID\" IN (SELECT \"FilesetID\" FROM \"FilesetEntry\")  AND (\"RemoteVolume\".\"State\" = \"Uploading\" OR \"RemoteVolume\".\"State\" = \"Temporary\")\r\n2018-05-09 11:48:25Z - Profiling: ExecuteReader: SELECT DISTINCT \"Fileset\".\"ID\", \"Fileset\".\"Timestamp\" FROM \"Fileset\", \"RemoteVolume\" WHERE \"RemoteVolume\".\"ID\" = \"Fileset\".\"VolumeID\" AND \"Fileset\".\"ID\" IN (SELECT \"FilesetID\" FROM \"FilesetEntry\")  AND (\"RemoteVolume\".\"State\" = \"Uploading\" OR \"RemoteVolume\".\"State\" = \"Temporary\") took 00:00:00.343\r\n2018-05-09 11:48:25Z - Profiling: Starting - PreBackupVerify\r\n2018-05-09 11:48:25Z - Profiling: Starting - RemoteOperationList\r\n2018-05-09 11:48:25Z - Information: Backend event: List - Started:  ()\r\n2018-05-09 11:48:29Z - Information: Backend event: List - Completed:  (98 Bytes)\r\n2018-05-09 11:48:29Z - Profiling: RemoteOperationList took 00:00:03.490\r\n2018-05-09 11:48:29Z - Profiling: Starting - ExecuteReader: SELECT DISTINCT \"Name\", \"State\" FROM \"Remotevolume\" WHERE \"Name\" IN (SELECT \"Name\" FROM \"Remotevolume\" WHERE \"State\" IN (\"Deleted\", \"Deleting\")) AND NOT \"State\" IN (\"Deleted\", \"Deleting\")\r\n2018-05-09 11:48:29Z - Profiling: ExecuteReader: SELECT DISTINCT \"Name\", \"State\" FROM \"Remotevolume\" WHERE \"Name\" IN (SELECT \"Name\" FROM \"Remotevolume\" WHERE \"State\" IN (\"Deleted\", \"Deleting\")) AND NOT \"State\" IN (\"Deleted\", \"Deleting\") took 00:00:00.003\r\n2018-05-09 11:48:29Z - Information: removing remote file listed as Deleting: duplicati-20180406T230001Z.dlist.zip.aes\r\n2018-05-09 11:48:29Z - Profiling: Starting - RemoteOperationDelete\r\n2018-05-09 11:48:29Z - Information: Backend event: Delete - Started: duplicati-20180406T230001Z.dlist.zip.aes (1,42 MB)\r\n```\r\n\r\nAnd form this point on, duplicati is doing ... nothing.\r\nCPU is at 0%."}}
{"actor":{"display_login":"BlackEdder0815"}}