"./sync.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^(TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Starting (try 2/5)
2024/12/10 04:43:31 DEBUG : Creating backend with remote "TestBox:rclone-test-desawup3lika"
2024/12/10 04:43:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/10 04:43:31 DEBUG : box root 'rclone-test-desawup3lika': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:43:32 DEBUG : Creating backend with remote "/tmp/rclone4105814917"
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:43:33 DEBUG : box root 'rclone-test-desawup3lika': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:43:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:43:33 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:43:33 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:43:33 DEBUG : box root 'rclone-test-desawup3lika': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:43:34 DEBUG : box root 'rclone-test-desawup3lika': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:43:37 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:43:37 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestCopyNoTraverse (10.56s)
=== RUN   TestCopyCheckFirst
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:43:42 INFO  : box root 'rclone-test-desawup3lika': Running all checks before starting transfers
2024/12/10 04:43:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:43:43 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:43:43 INFO  : box root 'rclone-test-desawup3lika': Checks finished, now starting transfers
2024/12/10 04:43:43 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:43:46 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:43:46 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestCopyCheckFirst (7.96s)
=== RUN   TestSyncNoTraverse
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:43:50 ERROR : Ignoring --no-traverse with sync
2024/12/10 04:43:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:43:51 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:43:51 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:43:54 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:43:54 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:43:54 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestSyncNoTraverse (9.25s)
=== RUN   TestCopyEmptyDirectories
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:00 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2024/12/10 04:44:00 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/10 04:44:00 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2024/12/10 04:44:00 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/10 04:44:00 DEBUG : sub dir: Making directory
2024/12/10 04:44:01 DEBUG : sub dir2: Making directory
2024/12/10 04:44:02 DEBUG : sub dir2/sub sub dir2: Making directory
2024/12/10 04:44:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:44:03 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:44:03 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:44:05 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:44:05 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:06 DEBUG : box root 'rclone-test-desawup3lika': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (12.62s)
=== RUN   TestCopyNoEmptyDirectories
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:12 DEBUG : sub dir2: Making directory
2024/12/10 04:44:12 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2024/12/10 04:44:12 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/10 04:44:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:44:13 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:44:13 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:44:16 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:44:16 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoEmptyDirectories (7.49s)
=== RUN   TestMoveEmptyDirectories
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:20 DEBUG : sub dir2: Making directory with metadata
2024/12/10 04:44:20 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/10 04:44:20 DEBUG : sub dir: Making directory
2024/12/10 04:44:21 DEBUG : sub dir2: Making directory
2024/12/10 04:44:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:44:22 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:44:22 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:44:24 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:44:24 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:44:24 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:25 DEBUG : box root 'rclone-test-desawup3lika': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
2024/12/10 04:44:26 DEBUG : box root 'rclone-test-desawup3lika': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestMoveEmptyDirectories (10.71s)
=== RUN   TestMoveNoEmptyDirectories
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:31 DEBUG : sub dir2: Making directory
2024/12/10 04:44:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:44:31 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:44:31 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:44:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:44:34 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:44:34 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveNoEmptyDirectories (7.31s)
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:38 DEBUG : sub dir2: Making directory with metadata
2024/12/10 04:44:38 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/12/10 04:44:38 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/10 04:44:38 DEBUG : sub dir: Making directory
2024/12/10 04:44:39 DEBUG : sub dir2: Making directory
2024/12/10 04:44:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:44:40 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:44:40 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:44:42 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:44:42 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:44:42 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:43 DEBUG : box root 'rclone-test-desawup3lika': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (10.47s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:44:48 DEBUG : sub dir2: Making directory
2024/12/10 04:44:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:44:49 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:44:49 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:44:52 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:44:52 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:44:52 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (7.81s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:00 DEBUG : Creating backend with remote "TestBox:rclone-test-conuqup5gaxi"
2024/12/10 04:45:00 DEBUG : Config file has changed externally - reloading
2024/12/10 04:45:00 DEBUG : box root 'rclone-test-conuqup5gaxi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
    sync_test.go:590: Server side copy (if possible) box root 'rclone-test-desawup3lika' -> box root 'rclone-test-conuqup5gaxi'
2024/12/10 04:45:02 DEBUG : box root 'rclone-test-conuqup5gaxi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:45:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:45:03 DEBUG : box root 'rclone-test-conuqup5gaxi': Waiting for checks to finish
2024/12/10 04:45:03 DEBUG : box root 'rclone-test-conuqup5gaxi': Waiting for transfers to finish
2024/12/10 04:45:03 DEBUG : box root 'rclone-test-conuqup5gaxi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:45:07 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:45:07 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:08 DEBUG : box root 'rclone-test-conuqup5gaxi': Purge remote
--- PASS: TestServerSideCopy (16.76s)
=== RUN   TestCopyOverSelf
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:17 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/10 04:45:17 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:45:17 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:45:19 DEBUG : sub dir/hello world: sha1 = b84a9b2362aee3556081792f3b7abd3958da8deb OK
2024/12/10 04:45:19 INFO  : sub dir/hello world: Copied (replaced existing)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestCopyOverSelf (10.68s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:27 DEBUG : Creating backend with remote "TestBox:rclone-test-kujabuj9gihi"
2024/12/10 04:45:27 DEBUG : Config file has changed externally - reloading
2024/12/10 04:45:28 DEBUG : box root 'rclone-test-kujabuj9gihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
    sync_test.go:626: Server side copy (if possible) box root 'rclone-test-desawup3lika' -> box root 'rclone-test-kujabuj9gihi'
2024/12/10 04:45:30 DEBUG : box root 'rclone-test-kujabuj9gihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:45:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:45:30 DEBUG : box root 'rclone-test-kujabuj9gihi': Waiting for checks to finish
2024/12/10 04:45:30 DEBUG : box root 'rclone-test-kujabuj9gihi': Waiting for transfers to finish
2024/12/10 04:45:31 DEBUG : box root 'rclone-test-kujabuj9gihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:45:34 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:45:34 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:39 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/10 04:45:39 DEBUG : box root 'rclone-test-kujabuj9gihi': Waiting for checks to finish
2024/12/10 04:45:39 DEBUG : box root 'rclone-test-kujabuj9gihi': Waiting for transfers to finish
2024/12/10 04:45:39 DEBUG : sub dir/hello world: dst already exists, copying to temp name sub dir/hello world-rclone-copy-sojacuf8
2024/12/10 04:45:40 DEBUG : sub dir/hello world-rclone-copy-sojacuf8: moving to real name sub dir/hello world
2024/12/10 04:45:42 DEBUG : sub dir/hello world: sha1 = b84a9b2362aee3556081792f3b7abd3958da8deb OK
2024/12/10 04:45:42 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:43 DEBUG : box root 'rclone-test-kujabuj9gihi': Purge remote
--- PASS: TestServerSideCopyOverSelf (25.39s)
=== RUN   TestMoveOverSelf
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:54 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:45:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/10 04:45:54 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:45:55 DEBUG : sub dir/hello world: sha1 = b84a9b2362aee3556081792f3b7abd3958da8deb OK
2024/12/10 04:45:55 INFO  : sub dir/hello world: Copied (replaced existing)
2024/12/10 04:45:55 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:45:55 DEBUG : TestBox: Loaded invalid token from config file - ignoring
2024/12/10 04:45:56 DEBUG : TestBox: got fatal oauth error: oauth2: "invalid_grant" "Refresh token has expired"
2024/12/10 04:45:56 ERROR : : error listing: couldn't list files: Get "https://api.box.com/2.0/folders/297662197474/items?fields=type%2Cid%2Csequence_id%2Cetag%2Csha1%2Cname%2Csize%2Ccreated_at%2Cmodified_at%2Ccontent_created_at%2Ccontent_modified_at%2Citem_status%2Cshared_link%2Cowned_by&limit=1000&usemarker=true": couldn't fetch token: invalid_grant: maybe token expired? - try refreshing with "rclone config reconnect TestBox:"
    fstest.go:276: Error listing: couldn't list files: Get "https://api.box.com/2.0/folders/297662197474/items?fields=type%2Cid%2Csequence_id%2Cetag%2Csha1%2Cname%2Csize%2Ccreated_at%2Cmodified_at%2Ccontent_created_at%2Ccontent_modified_at%2Citem_status%2Cshared_link%2Cowned_by&limit=1000&usemarker=true": couldn't fetch token: invalid_grant: maybe token expired? - try refreshing with "rclone config reconnect TestBox:"
2024/12/10 04:45:56 DEBUG : Config file has changed externally - reloading
2024/12/10 04:45:56 DEBUG : TestBox: Loaded fresh token from config file
2024/12/10 04:45:56 DEBUG : TestBox: Loaded new refresh token from config file
--- FAIL: TestMoveOverSelf (11.77s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:46:05 DEBUG : Creating backend with remote "TestBox:rclone-test-pogavob0qevi"
2024/12/10 04:46:05 DEBUG : box root 'rclone-test-kujabuj9gihi': Token expired but no uploads in progress - doing nothing
2024/12/10 04:46:05 DEBUG : box root 'rclone-test-conuqup5gaxi': Token expired but no uploads in progress - doing nothing
2024/12/10 04:46:05 DEBUG : box root 'rclone-test-pogavob0qevi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
    sync_test.go:671: Server side copy (if possible) box root 'rclone-test-desawup3lika' -> box root 'rclone-test-pogavob0qevi'
2024/12/10 04:46:07 DEBUG : box root 'rclone-test-pogavob0qevi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:46:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:46:07 DEBUG : box root 'rclone-test-pogavob0qevi': Waiting for checks to finish
2024/12/10 04:46:07 DEBUG : box root 'rclone-test-pogavob0qevi': Waiting for transfers to finish
2024/12/10 04:46:08 DEBUG : box root 'rclone-test-pogavob0qevi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2024/12/10 04:46:11 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:46:11 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:46:15 DEBUG : box root 'rclone-test-pogavob0qevi': Using server-side directory move
2024/12/10 04:46:15 INFO  : box root 'rclone-test-pogavob0qevi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/12/10 04:46:16 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/12/10 04:46:16 DEBUG : box root 'rclone-test-pogavob0qevi': Waiting for checks to finish
2024/12/10 04:46:16 DEBUG : box root 'rclone-test-pogavob0qevi': Waiting for transfers to finish
2024/12/10 04:46:17 INFO  : sub dir/hello world: Deleted
2024/12/10 04:46:18 INFO  : sub dir/hello world: Moved (server-side)
2024/12/10 04:46:22 DEBUG : testing file moves
2024/12/10 04:46:23 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2024/12/10 04:46:23 DEBUG : box root 'rclone-test-pogavob0qevi': Waiting for checks to finish
2024/12/10 04:46:23 DEBUG : box root 'rclone-test-pogavob0qevi': Waiting for transfers to finish
2024/12/10 04:46:24 INFO  : sub dir/hello world: Deleted
2024/12/10 04:46:25 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:46:27 DEBUG : box root 'rclone-test-pogavob0qevi': Purge remote
--- PASS: TestServerSideMoveOverSelf (31.17s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:46:35 ERROR : : error listing: directory not found
2024/12/10 04:46:36 DEBUG : Local file system at /tmp/rclone4105814917: Making directory
2024/12/10 04:46:36 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:46:36 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestCopyAfterDelete (8.98s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:46:45 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/12/10 04:46:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:46:45 DEBUG : Local file system at /tmp/rclone4105814917: Waiting for checks to finish
2024/12/10 04:46:45 DEBUG : Local file system at /tmp/rclone4105814917: Waiting for transfers to finish
2024/12/10 04:46:46 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:46:46 DEBUG : sub dir/hello world.e0e42c92.partial: renamed to: sub dir/hello world
2024/12/10 04:46:46 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:46:46 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestCopyRedownload (9.27s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:46:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:46:51 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/12/10 04:46:51 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:46:51 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:46:56 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK
2024/12/10 04:46:56 INFO  : nested/sub dir/file: Copied (new)
2024/12/10 04:46:56 INFO  : nested/sub dir/file: Deleted
2024/12/10 04:46:56 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:46:56 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:46:56 INFO  : sub dir/hello world: Deleted
2024/12/10 04:46:56 ERROR : Local file system at /tmp/rclone4105814917: not deleting directories as there were IO errors
    sync_test.go:1788: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1788
        	Error:      	Received unexpected error:
        	            	not deleting directories as there were IO errors
        	Test:       	TestMoveWithDeleteEmptySrcDirs
--- FAIL: TestMoveWithDeleteEmptySrcDirs (11.75s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:47:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/12/10 04:47:02 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2024/12/10 04:47:02 DEBUG : box root 'rclone-test-desawup3lika': Waiting for checks to finish
2024/12/10 04:47:02 DEBUG : box root 'rclone-test-desawup3lika': Waiting for transfers to finish
2024/12/10 04:47:08 DEBUG : nested/sub dir/file: sha1 = b4b3e0a278988bc15f2913af3f4153ccef74e465 OK
2024/12/10 04:47:08 INFO  : nested/sub dir/file: Copied (new)
2024/12/10 04:47:08 INFO  : nested/sub dir/file: Deleted
2024/12/10 04:47:08 DEBUG : sub dir/hello world: sha1 = 2aae6c35c94fcfb415dbe95f408b9ce91ee846ed OK
2024/12/10 04:47:08 INFO  : sub dir/hello world: Copied (new)
2024/12/10 04:47:08 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (14.53s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:47:16 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/10 04:47:16 DEBUG : sub dir: Making directory
2024/12/10 04:47:18 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:47:57 DEBUG : Couldn't decode error response: invalid character 'o' in literal null (expecting 'u')
2024/12/10 04:47:57 DEBUG : pacer: low level retry 1/10 (error Error "503 Service Unavailable" (503))
2024/12/10 04:47:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2024/12/10 04:47:58 DEBUG : pacer: Reducing sleep to 15ms
2024/12/10 04:47:59 DEBUG : pacer: Reducing sleep to 11.25ms
2024/12/10 04:48:00 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestNothingToTransferWithEmptyDirs (44.88s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
2024/12/10 04:48:01 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/10 04:48:01 DEBUG : sub dir: Making directory
2024/12/10 04:48:02 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
    run.go:180: Remote "box root 'rclone-test-desawup3lika'", Local "Local file system at /tmp/rclone4105814917", Modify Window "1s"
--- PASS: TestNothingToTransferWithoutEmptyDirs (41.26s)
FAIL
2024/12/10 04:48:43 DEBUG : box root 'rclone-test-desawup3lika': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^(TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Finished ERROR in 5m12.766831977s (try 2/5): exit status 1: Failed [TestMoveOverSelf TestMoveWithDeleteEmptySrcDirs]