{"id":7456,"date":"2024-03-12T14:36:47","date_gmt":"2024-03-12T12:36:47","guid":{"rendered":"https:\/\/mummila.net\/kommentit\/?p=7456"},"modified":"2024-03-12T14:38:45","modified_gmt":"2024-03-12T12:38:45","slug":"optional-feature-not-implemented-with-crypt-on-sftp-in-1-66","status":"publish","type":"post","link":"https:\/\/mummila.net\/kommentit\/2024\/03\/12\/optional-feature-not-implemented-with-crypt-on-sftp-in-1-66\/","title":{"rendered":"&#8221;optional feature not implemented&#8221; with crypt-on-sftp in 1.66"},"content":{"rendered":"<h4>What is the problem you are having with rclone?<\/h4>\n<p>I&#8217;m using a crypt-on-sftp remote, which was working just fine up until v1.65.2. With v1.66, attempts to sync to it now fail with &#8221;optional feature not implemented&#8221;. I&#8217;ve not narrowed down whether it&#8217;s the crypt or the sftp layer, so I&#8217;m just reporting my (failing) test case below.<\/p>\n<p>I did a bisect, and apparently this issue came in at\u00a0<a href=\"https:\/\/github.com\/rclone\/rclone\/commit\/f5f86786b27290964d3b6b29790f1d245f66aa1d\"><tt>f5f8678<\/tt><\/a>\u00a0(which was a fix for\u00a0<a href=\"https:\/\/github.com\/rclone\/rclone\/issues\/6685\">#6685<\/a>).<\/p>\n<h4>What is your rclone version (output from\u00a0<code>rclone version<\/code>)<\/h4>\n<p>1.66.0<\/p>\n<h4>Which OS you are using and how many bits (e.g. Windows 7, 64 bit)<\/h4>\n<p>Ubuntu 22.04 (64-bit)<\/p>\n<h4>Which cloud storage system are you using? (e.g. Google Drive)<\/h4>\n<p>sftp<\/p>\n<h4>The command you were trying to run (e.g.\u00a0<code>rclone copy \/tmp remote:tmp<\/code>)<\/h4>\n<p><code>rclone sync a\/ C:<\/code><\/p>\n<h4>Steps to reproduce<\/h4>\n<ol>\n<li><code>rclone --config test.conf config<\/code><\/li>\n<li>set up sftp (44) remote called S\n<ul>\n<li>for test purposes here I&#8217;m using localhost as the underlying remote server<\/li>\n<li>set user &amp; password<\/li>\n<li>leave everything else at defaults<\/li>\n<\/ul>\n<\/li>\n<li>set up crypt remote C, with\n<ul>\n<li>&#8221;S:ftp&#8221; as the underlying remote<\/li>\n<li>Very simple filename obfuscation (obfuscate)<\/li>\n<li>Encrypt directory names (true)<\/li>\n<li>set password, no password2<\/li>\n<\/ul>\n<\/li>\n<li><code>mkdir -p ftp a\/b # on the sftp remote; here I'm using localhost<\/code><\/li>\n<li><code>touch a\/test1 # on the sftp remote<\/code><\/li>\n<li><code>rclone --config test.conf sync a\/ C: -vv # no problems<\/code><\/li>\n<li><code>touch a\/b\/test2 # on the sftp remote<\/code><\/li>\n<li><code>rclone --config test.conf sync a\/ C: -vv<\/code><\/li>\n<\/ol>\n<h4>A log from the command with the\u00a0<code>-vv<\/code>\u00a0flag (e.g. output from\u00a0<code>rclone -vv copy \/tmp remote:tmp<\/code>)<\/h4>\n<div>\n<pre><code>2024\/03\/12 14:33:36 DEBUG : rclone: Version \"v1.66.0\" starting with parameters [\"rclone\" \"--config\" \"test.conf\" \"sync\" \"a\/\" \"C:\" \"-vv\"]\r\n2024\/03\/12 14:33:36 DEBUG : Creating backend with remote \"a\/\"\r\n2024\/03\/12 14:33:36 DEBUG : Using config file from \"\/home\/jani\/test.conf\"\r\n2024\/03\/12 14:33:36 DEBUG : fs cache: renaming cache item \"a\/\" to be canonical \"\/home\/jani\/a\"\r\n2024\/03\/12 14:33:36 DEBUG : Creating backend with remote \"C:\"\r\n2024\/03\/12 14:33:37 DEBUG : Creating backend with remote \"S:ftp\"\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: New connection 127.0.0.1:48814-&gt;127.0.0.1:22 to \"SSH-2.0-OpenSSH_8.9p1 Ubuntu-3ubuntu0.6\"\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Shell type \"unix\" from config\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Relative path resolved to \"\/home\/jani\/ftp\"\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Using root directory \"\/home\/jani\/ftp\"\r\n2024\/03\/12 14:33:37 DEBUG : test1: Size and modification time the same (differ by -995.288851ms, within tolerance 1s)\r\n2024\/03\/12 14:33:37 DEBUG : test1: Unchanged skipping\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2: Need to transfer - File not found at Destination\r\n2024\/03\/12 14:33:37 DEBUG : Encrypted drive 'C:': Waiting for checks to finish\r\n2024\/03\/12 14:33:37 DEBUG : Encrypted drive 'C:': Waiting for transfers to finish\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Shell path \"\/home\/jani\/ftp\/98.g\/149.zkyz4.EACoEuq1.vgxzogr\"\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Running remote command: md5sum \/home\/jani\/ftp\/98.g\/149.zkyz4.EACoEuq1.vgxzogr\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Remote command result: ac83867dee807f03ee4f8f307cda1257  \/home\/jani\/ftp\/98.g\/149.zkyz4.EACoEuq1.vgxzogr\r\n2024\/03\/12 14:33:37 DEBUG : 98.g\/149.zkyz4.EACoEuq1.vgxzogr: Parsed hash: ac83867dee807f03ee4f8f307cda1257\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2.yuwiyok9.partial: md5 = ac83867dee807f03ee4f8f307cda1257 OK\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2.yuwiyok9.partial: renamed to: b\/test2\r\n2024\/03\/12 14:33:37 INFO  : b\/test2: Copied (new)\r\n2024\/03\/12 14:33:37 ERROR : Encrypted drive 'C:': not deleting files as there were IO errors\r\n2024\/03\/12 14:33:37 ERROR : Encrypted drive 'C:': not deleting directories as there were IO errors\r\n2024\/03\/12 14:33:37 ERROR : Attempt 1\/3 failed with 1 errors and: optional feature not implemented\r\n2024\/03\/12 14:33:37 DEBUG : test1: Size and modification time the same (differ by -995.288851ms, within tolerance 1s)\r\n2024\/03\/12 14:33:37 DEBUG : test1: Unchanged skipping\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2: Size and modification time the same (differ by -116.891668ms, within tolerance 1s)\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2: Unchanged skipping\r\n2024\/03\/12 14:33:37 DEBUG : Encrypted drive 'C:': Waiting for checks to finish\r\n2024\/03\/12 14:33:37 DEBUG : Encrypted drive 'C:': Waiting for transfers to finish\r\n2024\/03\/12 14:33:37 ERROR : Encrypted drive 'C:': not deleting files as there were IO errors\r\n2024\/03\/12 14:33:37 ERROR : Encrypted drive 'C:': not deleting directories as there were IO errors\r\n2024\/03\/12 14:33:37 ERROR : Attempt 2\/3 failed with 1 errors and: optional feature not implemented\r\n2024\/03\/12 14:33:37 DEBUG : test1: Size and modification time the same (differ by -995.288851ms, within tolerance 1s)\r\n2024\/03\/12 14:33:37 DEBUG : test1: Unchanged skipping\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2: Size and modification time the same (differ by -116.891668ms, within tolerance 1s)\r\n2024\/03\/12 14:33:37 DEBUG : b\/test2: Unchanged skipping\r\n2024\/03\/12 14:33:37 DEBUG : Encrypted drive 'C:': Waiting for checks to finish\r\n2024\/03\/12 14:33:37 DEBUG : Encrypted drive 'C:': Waiting for transfers to finish\r\n2024\/03\/12 14:33:37 ERROR : Encrypted drive 'C:': not deleting files as there were IO errors\r\n2024\/03\/12 14:33:37 ERROR : Encrypted drive 'C:': not deleting directories as there were IO errors\r\n2024\/03\/12 14:33:37 ERROR : Attempt 3\/3 failed with 1 errors and: optional feature not implemented\r\n2024\/03\/12 14:33:37 INFO  : \r\nTransferred:   \t         32 B \/ 32 B, 100%, 0 B\/s, ETA -\r\nErrors:                 1 (retrying may help)\r\nChecks:                 5 \/ 5, 100%\r\nTransferred:            1 \/ 1, 100%\r\nElapsed time:         0.3s\r\n\r\n2024\/03\/12 14:33:37 DEBUG : 15 go routines active\r\n2024\/03\/12 14:33:37 DEBUG : sftp:\/\/jani@localhost:22\/ftp: Closing 1 unused connections\r\n2024\/03\/12 14:33:37 Failed to sync: optional feature not implemented\r\n<\/code><\/pre>\n<div><\/div>\n<\/div>\n<h4>How to use GitHub<\/h4>\n<ul>\n<li>Please use the \ud83d\udc4d\u00a0<a href=\"https:\/\/blog.github.com\/2016-03-10-add-reactions-to-pull-requests-issues-and-comments\/\">reaction<\/a>\u00a0to show that you are affected by the same issue.<\/li>\n<li>Please don&#8217;t comment if you have no relevant information to add. It&#8217;s just extra noise for everyone subscribed to this issue.<\/li>\n<li>Subscribe to receive notifications on status change and new comments.<\/li>\n<\/ul>\n","protected":false},"excerpt":{"rendered":"<p>I&#8217;m using a crypt-on-sftp remote, which was working until v1.65.2. With 1.66, attempts to sync to it now fail with &#8221;optional feature not implemented&#8221;.<\/p>\n","protected":false},"author":2,"featured_media":0,"comment_status":"closed","ping_status":"closed","sticky":false,"template":"","format":"standard","meta":{"footnotes":""},"categories":[8860],"tags":[22982],"class_list":["post-7456","post","type-post","status-publish","format-standard","hentry","category-vianhallintajarjestelmat","tag-rclone","sijainti-github"],"_links":{"self":[{"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/posts\/7456","targetHints":{"allow":["GET"]}}],"collection":[{"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/posts"}],"about":[{"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/types\/post"}],"author":[{"embeddable":true,"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/users\/2"}],"replies":[{"embeddable":true,"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/comments?post=7456"}],"version-history":[{"count":9,"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/posts\/7456\/revisions"}],"predecessor-version":[{"id":7467,"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/posts\/7456\/revisions\/7467"}],"wp:attachment":[{"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/media?parent=7456"}],"wp:term":[{"taxonomy":"category","embeddable":true,"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/categories?post=7456"},{"taxonomy":"post_tag","embeddable":true,"href":"https:\/\/mummila.net\/kommentit\/wp-json\/wp\/v2\/tags?post=7456"}],"curies":[{"name":"wp","href":"https:\/\/api.w.org\/{rel}","templated":true}]}}