"./sync.test -test.v -test.timeout 1h0m0s -remote TestSugarSync:Test -verbose" - Starting (try 4/5) 2025/09/13 06:49:24 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-hobokok0zuya" 2025/09/13 06:49:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/13 06:49:27 DEBUG : Creating backend with remote "/tmp/rclone3191238256" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:49:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 06:49:29 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/09/13 06:49:29 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:49:29 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (6.22s) === RUN TestCopy run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:49:33 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/13 06:49:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 06:49:34 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:49:34 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:49:38 INFO : sub dir/hello world: Copied (new) 2025/09/13 06:49:39 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (9.95s) === RUN TestCopyMetadata run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (1.48s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (1.48s) === RUN TestCopyMissingDirectory run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:49:46 DEBUG : Creating backend with remote "/non-existing" 2025/09/13 06:49:47 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/09/13 06:49:47 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:49:47 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (2.21s) === RUN TestCopyNoTraverse run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:49:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 06:49:53 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:49:53 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:49:56 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (13.74s) === RUN TestCopyNoTraverseDeadlock run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" sync_test.go:222: Only runs on local --- SKIP: TestCopyNoTraverseDeadlock (1.46s) === RUN TestCopyCheckFirst run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:50:03 INFO : sugarsync root 'Test/rclone-test-hobokok0zuya': Running all checks before starting transfers 2025/09/13 06:50:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 06:50:04 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:50:04 INFO : sugarsync root 'Test/rclone-test-hobokok0zuya': Checks finished, now starting transfers 2025/09/13 06:50:04 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:50:14 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (16.19s) === RUN TestSyncNoTraverse run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:50:20 ERROR : Ignoring --no-traverse with sync 2025/09/13 06:50:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 06:50:20 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:50:20 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:50:24 INFO : sub dir/hello world: Copied (new) 2025/09/13 06:50:24 DEBUG : Waiting for deletions to finish 2025/09/13 06:55:28 DEBUG : pacer: low level retry 1/10 (error Get "https://api.sugarsync.com/folder/:sc:9068489:215_6563739870/contents?max=500&start=0": net/http: timeout awaiting response headers) 2025/09/13 06:55:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 06:55:29 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 06:55:30 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 06:55:30 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestSyncNoTraverse (310.42s) === RUN TestCopyWithDepth run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:55:31 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/09/13 06:55:31 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:55:31 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:55:33 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (11.55s) === RUN TestCopyWithFilesFrom run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:55:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/13 06:55:42 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:55:42 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:55:45 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (12.05s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:55:54 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/13 06:55:54 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:55:54 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:55:57 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (6.34s) === RUN TestCopyEmptyDirectories run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 06:56:00 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/09/13 06:56:00 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/13 06:56:00 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/09/13 06:56:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/13 06:56:01 INFO : sub dir2: Making directory 2025/09/13 06:56:04 INFO : sub dir: Making directory 2025/09/13 06:56:05 INFO : sub dir2/sub sub dir2: Making directory 2025/09/13 06:56:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 06:56:13 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 06:56:13 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 06:56:15 INFO : sub dir/hello world: Copied (new) 2025/09/13 06:56:17 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/09/13 07:01:21 DEBUG : pacer: low level retry 1/10 (error Delete "https://api.sugarsync.com/folder/:sc:9068489:215_6563744338": net/http: timeout awaiting response headers) 2025/09/13 07:01:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:01:21 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:01:22 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:01:23 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestCopyEmptyDirectories (323.73s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:01:24 INFO : sub dir2: Making directory 2025/09/13 07:01:24 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/09/13 07:01:24 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/13 07:01:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:01:25 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:01:25 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:01:34 INFO : sub dir/hello world: Copied (new) 2025/09/13 07:06:41 DEBUG : pacer: low level retry 1/10 (error Get "https://api.sugarsync.com/folder/:sc:9068489:215_6563749499/contents?max=500&start=0": net/http: timeout awaiting response headers) 2025/09/13 07:06:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:06:42 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:06:43 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:06:44 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestCopyNoEmptyDirectories (320.88s) === RUN TestMoveEmptyDirectories run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:06:45 DEBUG : sub dir2: Making directory with metadata 2025/09/13 07:06:45 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/13 07:06:45 INFO : sub dir2: Making directory 2025/09/13 07:06:47 INFO : sub dir: Making directory 2025/09/13 07:06:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:06:48 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:06:48 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:07:04 INFO : sub dir/hello world: Copied (new) 2025/09/13 07:07:04 INFO : sub dir/hello world: Deleted 2025/09/13 07:07:05 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/09/13 07:07:06 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (32.79s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (1.49s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:07:19 INFO : sub dir2: Making directory 2025/09/13 07:07:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:07:20 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:07:20 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:07:36 INFO : sub dir/hello world: Copied (new) 2025/09/13 07:07:36 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (27.44s) === RUN TestSyncEmptyDirectories run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:07:46 DEBUG : sub dir2: Making directory with metadata 2025/09/13 07:07:46 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/13 07:07:46 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/13 07:07:47 INFO : sub dir2: Making directory 2025/09/13 07:12:48 DEBUG : pacer: low level retry 1/10 (error Get "https://api.sugarsync.com/folder/:sc:9068489:215_6563739362/contents?max=500&start=0": net/http: timeout awaiting response headers) 2025/09/13 07:12:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:12:48 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:12:49 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:12:49 INFO : sub dir: Making directory 2025/09/13 07:12:52 DEBUG : pacer: Reducing sleep to 10ms 2025/09/13 07:12:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:12:53 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:12:53 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:12:55 INFO : sub dir/hello world: Copied (new) 2025/09/13 07:12:55 DEBUG : Waiting for deletions to finish 2025/09/13 07:12:57 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (315.59s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (1.50s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:13:03 INFO : sub dir2: Making directory 2025/09/13 07:13:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:13:04 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:13:04 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:13:10 INFO : sub dir/hello world: Copied (new) 2025/09/13 07:13:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (12.12s) === RUN TestServerSideCopy run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:13:35 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-ruquqix7yayo" sync_test.go:620: Server side copy (if possible) sugarsync root 'Test/rclone-test-hobokok0zuya' -> sugarsync root 'Test/rclone-test-ruquqix7yayo' 2025/09/13 07:13:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:13:53 DEBUG : sugarsync root 'Test/rclone-test-ruquqix7yayo': Waiting for checks to finish 2025/09/13 07:13:53 DEBUG : sugarsync root 'Test/rclone-test-ruquqix7yayo': Waiting for transfers to finish 2025/09/13 07:14:04 INFO : sub dir/hello world: Copied (server-side copy) 2025/09/13 07:14:06 DEBUG : sugarsync root 'Test/rclone-test-ruquqix7yayo': Purge remote --- PASS: TestServerSideCopy (56.21s) === RUN TestCopyOverSelf run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:14:27 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/13 07:14:27 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:14:27 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:14:48 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (41.75s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:19:56 DEBUG : pacer: low level retry 1/10 (error Post "https://api.sugarsync.com/folder/:sc:9068489:215_6563757782": net/http: timeout awaiting response headers) 2025/09/13 07:19:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:19:56 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Renewing token 2025/09/13 07:19:57 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:19:57 DEBUG : Saving config "authorization" in section "TestSugarSync" of the config file 2025/09/13 07:19:57 DEBUG : Config file has changed externally - reloading 2025/09/13 07:19:57 DEBUG : Saving config "authorization_expiry" in section "TestSugarSync" of the config file 2025/09/13 07:19:57 DEBUG : Saving config "user" in section "TestSugarSync" of the config file 2025/09/13 07:19:57 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:19:58 DEBUG : pacer: Reducing sleep to 10ms 2025/09/13 07:20:00 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-riwujob1guge" sync_test.go:656: Server side copy (if possible) sugarsync root 'Test/rclone-test-hobokok0zuya' -> sugarsync root 'Test/rclone-test-riwujob1guge' 2025/09/13 07:20:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:20:05 DEBUG : sugarsync root 'Test/rclone-test-riwujob1guge': Waiting for checks to finish 2025/09/13 07:20:05 DEBUG : sugarsync root 'Test/rclone-test-riwujob1guge': Waiting for transfers to finish 2025/09/13 07:20:11 INFO : sub dir/hello world: Copied (server-side copy) 2025/09/13 07:25:18 DEBUG : pacer: low level retry 1/1 (error Put "https://api.sugarsync.com/file/:sc:9068489:215_6563760357/data": net/http: timeout awaiting response headers) 2025/09/13 07:25:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms run.go:281: Retry Put of "sub dir/hello world" to sugarsync root 'Test/rclone-test-hobokok0zuya': 1/10 (failed to upload file: Put "https://api.sugarsync.com/file/:sc:9068489:215_6563760357/data": net/http: timeout awaiting response headers) 2025/09/13 07:25:20 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:25:22 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:25:22 DEBUG : pacer: Reducing sleep to 10ms 2025/09/13 07:25:25 DEBUG : sugarsync root 'Test/rclone-test-riwujob1guge': Waiting for checks to finish 2025/09/13 07:25:25 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/13 07:25:25 DEBUG : sugarsync root 'Test/rclone-test-riwujob1guge': Waiting for transfers to finish 2025/09/13 07:25:26 DEBUG : sub dir/hello world: server side copy: renaming existing object to "sub dir/hello world.dehetob4" before starting 2025/09/13 07:25:31 DEBUG : sub dir/hello world.dehetob4: server side copy: removing renamed existing file after operation 2025/09/13 07:25:32 INFO : sub dir/hello world: Copied (server-side copy) 2025/09/13 07:25:33 DEBUG : sugarsync root 'Test/rclone-test-riwujob1guge': Purge remote --- PASS: TestServerSideCopyOverSelf (644.30s) === RUN TestMoveOverSelf run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:25:51 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/13 07:25:51 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:25:51 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:25:52 INFO : sub dir/hello world: Copied (replaced existing) 2025/09/13 07:25:52 INFO : sub dir/hello world: Deleted 2025/09/13 07:30:57 DEBUG : pacer: low level retry 1/10 (error Get "https://api.sugarsync.com/folder/:sc:9068489:215_6563739362/contents?max=500&start=0": net/http: timeout awaiting response headers) 2025/09/13 07:30:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:31:11 DEBUG : pacer: Reducing sleep to 15ms --- PASS: TestMoveOverSelf (332.79s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:31:11 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:31:12 DEBUG : pacer: Reducing sleep to 10ms 2025/09/13 07:31:15 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-nuvijub2dagu" 2025/09/13 07:31:15 DEBUG : Config file has changed externally - reloading sync_test.go:701: Server side copy (if possible) sugarsync root 'Test/rclone-test-hobokok0zuya' -> sugarsync root 'Test/rclone-test-nuvijub2dagu' 2025/09/13 07:31:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:31:29 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Waiting for checks to finish 2025/09/13 07:31:29 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Waiting for transfers to finish 2025/09/13 07:31:33 INFO : sub dir/hello world: Copied (server-side copy) 2025/09/13 07:31:38 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Using server-side directory move 2025/09/13 07:31:38 INFO : sugarsync root 'Test/rclone-test-nuvijub2dagu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/09/13 07:31:40 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/13 07:31:40 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Waiting for checks to finish 2025/09/13 07:31:40 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Waiting for transfers to finish 2025/09/13 07:31:41 INFO : sub dir/hello world: Deleted 2025/09/13 07:31:42 INFO : sub dir/hello world: Moved (server-side) 2025/09/13 07:31:49 DEBUG : testing file moves 2025/09/13 07:31:56 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/09/13 07:31:56 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Waiting for checks to finish 2025/09/13 07:31:56 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Waiting for transfers to finish 2025/09/13 07:31:57 INFO : sub dir/hello world: Deleted 2025/09/13 07:31:58 INFO : sub dir/hello world: Moved (server-side) 2025/09/13 07:32:01 DEBUG : sugarsync root 'Test/rclone-test-nuvijub2dagu': Purge remote --- PASS: TestServerSideMoveOverSelf (54.37s) === RUN TestCopyAfterDelete run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:32:08 ERROR : error listing: directory not found 2025/09/13 07:32:10 INFO : Local file system at /tmp/rclone3191238256: Making directory 2025/09/13 07:32:11 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:32:11 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (13.25s) === RUN TestCopyRedownload run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:32:24 DEBUG : Added delayed dir = "sub dir", newDst= 2025/09/13 07:32:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/13 07:32:25 DEBUG : Local file system at /tmp/rclone3191238256: Waiting for checks to finish 2025/09/13 07:32:25 DEBUG : Local file system at /tmp/rclone3191238256: Waiting for transfers to finish 2025/09/13 07:32:26 DEBUG : sub dir/hello world.9076d4bf.partial: renamed to: sub dir/hello world 2025/09/13 07:32:26 INFO : sub dir/hello world: Copied (new) 2025/09/13 07:32:26 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (31.24s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:32:50 DEBUG : check sum: Need to transfer - File not found at Destination 2025/09/13 07:32:50 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:32:50 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:33:08 INFO : check sum: Copied (new) 2025/09/13 07:33:08 DEBUG : Waiting for deletions to finish 2025/09/13 07:33:17 NOTICE: sugarsync root 'Test/rclone-test-hobokok0zuya': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/09/13 07:33:17 DEBUG : check sum: Size of src and dst objects identical 2025/09/13 07:33:17 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:33:17 DEBUG : check sum: Unchanged skipping 2025/09/13 07:33:17 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:33:17 DEBUG : Waiting for deletions to finish 2025/09/13 07:33:17 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (30.19s) === RUN TestSyncSizeOnly run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:33:20 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/09/13 07:33:20 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:33:20 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:33:23 INFO : sizeonly: Copied (new) 2025/09/13 07:33:23 DEBUG : Waiting for deletions to finish 2025/09/13 07:33:24 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:33:24 DEBUG : sizeonly: Sizes identical 2025/09/13 07:33:24 DEBUG : sizeonly: Unchanged skipping 2025/09/13 07:33:24 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:33:24 DEBUG : Waiting for deletions to finish 2025/09/13 07:33:24 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (7.65s) === RUN TestSyncIgnoreSize run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:33:28 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/09/13 07:33:28 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:33:28 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:33:30 INFO : ignore-size: Copied (new) 2025/09/13 07:33:30 DEBUG : Waiting for deletions to finish 2025/09/13 07:33:32 DEBUG : ignore-size: Sizes identical 2025/09/13 07:33:32 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:33:32 DEBUG : ignore-size: Unchanged skipping 2025/09/13 07:33:32 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:33:32 DEBUG : Waiting for deletions to finish 2025/09/13 07:33:32 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (12.26s) === RUN TestSyncIgnoreTimes run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:38:43 DEBUG : pacer: low level retry 1/10 (error Get "https://api.sugarsync.com/folder/:sc:9068489:215_6563739362/contents?max=500&start=0": read tcp 65.108.87.141:32898->208.94.4.81:443: i/o timeout) 2025/09/13 07:38:43 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:38:43 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:38:44 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:38:44 DEBUG : existing: Sizes identical 2025/09/13 07:38:44 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:38:44 DEBUG : existing: Unchanged skipping 2025/09/13 07:38:44 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:38:44 DEBUG : Waiting for deletions to finish 2025/09/13 07:38:44 INFO : There was nothing to transfer 2025/09/13 07:38:45 DEBUG : pacer: Reducing sleep to 10ms 2025/09/13 07:38:45 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/09/13 07:38:45 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:38:45 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:38:46 INFO : existing: Copied (replaced existing) 2025/09/13 07:38:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (309.71s) === RUN TestSyncIgnoreExisting run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:38:50 DEBUG : existing: Need to transfer - File not found at Destination 2025/09/13 07:38:50 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:38:50 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:40:52 DEBUG : pacer: low level retry 1/1 (error HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server) 2025/09/13 07:40:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:40:52 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:40:52 DEBUG : existing: Received error: failed to upload file: HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server - low level retry 0/10 2025/09/13 07:40:53 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:40:54 DEBUG : pacer: Reducing sleep to 10ms 2025/09/13 07:40:55 INFO : existing: Copied (new) 2025/09/13 07:40:55 DEBUG : Waiting for deletions to finish 2025/09/13 07:41:39 DEBUG : pacer: low level retry 1/10 (error HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server) 2025/09/13 07:41:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:41:40 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:41:40 DEBUG : existing: Destination exists, skipping 2025/09/13 07:41:40 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for checks to finish 2025/09/13 07:41:40 DEBUG : sugarsync root 'Test/rclone-test-hobokok0zuya': Waiting for transfers to finish 2025/09/13 07:41:40 DEBUG : Waiting for deletions to finish 2025/09/13 07:41:40 INFO : There was nothing to transfer 2025/09/13 07:41:40 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:41:41 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestSyncIgnoreExisting (173.29s) === RUN TestSyncIgnoreErrors run.go:180: Remote "sugarsync root 'Test/rclone-test-hobokok0zuya'", Local "Local file system at /tmp/rclone3191238256", Modify Window "876000h0m0s" 2025/09/13 07:42:58 INFO : d: Making directory 2025/09/13 07:44:15 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/09/13 07:44:16 DEBUG : c/non empty space: Sizes identical 2025/09/13 07:44:16 DEBUG : c/non empty space: Unchanged skipping 2025/09/13 07:45:17 DEBUG : pacer: low level retry 1/1 (error HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server) 2025/09/13 07:45:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/13 07:45:17 DEBUG : pacer: Reducing sleep to 15ms 2025/09/13 07:45:17 DEBUG : a/potato2: Received error: failed to upload file: HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server - low level retry 0/10 2025/09/13 07:45:18 DEBUG : pacer: Reducing sleep to 11.25ms 2025/09/13 07:47:07 DEBUG : pacer: low level retry 1/10 (error HTTP error 503 (503 Service Unavailable): The server is currently unable to handle the request due to a temporary overloading or maintenance of the server) 2025/09/13 07:47:07 DEBUG : pacer: Rate limited, increasing sleep to 22.5ms 2025/09/13 07:48:36 DEBUG : pacer: Reducing sleep to 16.875ms 2025/09/13 07:49:16 DEBUG : pacer: low level retry 1/10 (error Get "https://api.sugarsync.com/folder/:sc:9068489:215_6563772712/contents?max=500&start=0": net/http: timeout awaiting response headers) 2025/09/13 07:49:16 DEBUG : pacer: Rate limited, increasing sleep to 33.75ms panic: test timed out after 1h0m0s running tests: TestSyncIgnoreErrors (7m44s) goroutine 7075 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 8 minutes]: testing.(*T).Run(0xc000584540, {0x2772468?, 0xc00006dad8?}, 0x288b080) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000584540) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000584540, 0xc00006dc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0006f2900, {0x3fb0e00, 0x6c, 0x6c}, {0x845ca0?, 0x3feaac0?, 0x3fc6f80?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc00014ce60) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc00014ce60) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:261 +0xa9 goroutine 6946 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x4b) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x4b, 0xc0008c4760?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 19 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 20 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 7005 [chan receive, 5 minutes]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc0005733c0) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 6950 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 6952 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6950 [select]: net/http.(*persistConn).roundTrip(0xc000492360, 0xc0001c4730) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc00012f380, 0xc000334a00) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004001e0, 0xc000334a00) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 net/http.send(0xc000334a00, {0x2baeea0, 0xc0004001e0}, {0x15010100006fbe01?, 0x77931c849dd8?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000701830, 0xc000334a00, {0x148?, 0x23a4fa0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000701830, 0xc000334a00) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc000136000, {0x2bd6388, 0xc0008ae690}, 0xc000334780) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67 github.com/rclone/rclone/backend/sugarsync.(*Object).Update.func2() /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:1294 +0x4d github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0x0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000400240, 0xc0003f80c0, 0x1) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:227 github.com/rclone/rclone/backend/sugarsync.(*Object).Update(0xc000136870, {0x2bd6388, 0xc0008ae690}, {0x2badc20, 0xc000160100}, {0x7792d4323480, 0xc00011b440}, {0xc000974240, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:1293 +0x3bd github.com/rclone/rclone/backend/sugarsync.(*Fs).PutUnchecked(0xc00039dd40, {0x2bd6388, 0xc0008ae690}, {0x2badc20, 0xc000160100}, {0x7792d4323480, 0xc00011b440}, {0xc000974240, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:781 +0x14c github.com/rclone/rclone/backend/sugarsync.(*Fs).Put(0xc00039dd40, {0x2bd6388, 0xc0008ae690}, {0x2badc20, 0xc000160100}, {0x7792d4323480, 0xc00011b440}, {0xc000974240, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:754 +0x12c github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc0007e2480, {0x2bd6388, 0xc0008ae690}, {0x2bc11c8?, 0xc00096e000?}, {0xc000974240, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:221 +0x225 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0007e2480, {0x2bd6388, 0xc0008ae690}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:274 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0007e2480, {0x2bd6388, 0xc0008ae690}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:314 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2bd6388, 0xc0008ae690}, {0x2bede70, 0xc00039dd40}, {0x0, 0x0}, {0xc000590a40, 0x9}, {0x2beca50, 0xc00011b440}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:416 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000294c88, {0x2bd6388, 0xc0008ae690}, 0xc0008ae550, {0x2bede70, 0xc00039dd40}, 0x32, 0xc0005d0480?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 6954 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6949 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae550, {0x2bd6388, 0xc0008ae6e0}, 0x19) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000294c88, {0x2bd6388, 0xc0008ae690}, 0xc0008ae550, {0x2bede70, 0xc00039dd40}, 0x19, 0xc00054e730?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:491 +0xba created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 6958 [chan receive, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).matchListings(0xc0007e2240, 0xc000369ab0, 0xc000369b20, 0xc000907de8, 0xc000907d98, 0xc000907d48) /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:318 +0x26c github.com/rclone/rclone/fs/march.(*March).processJob(0xc0007e2240, {{0x2c2ef60, 0x1}, {0x2c2ef60, 0x1}, 0x0, 0x7ffffffd, 0x1, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:491 +0x61b github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:213 +0x1e5 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6928 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x32) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x32, 0xc0003b4618?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 6947 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x57) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x57, 0xc000960f60?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 5309 [chan receive, 17 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 5397 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 6923 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0002cf4a0, {0x2bd6388, 0xc0008ae4b0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 6953 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6944 [select]: net/http.(*persistConn).roundTrip(0xc0005d05a0, 0xc0001360f0) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc00012f380, 0xc00028c3c0) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004001e0, 0xc00028c3c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 net/http.send(0xc00028c3c0, {0x2baeea0, 0xc0004001e0}, {0x15010100006fbe01?, 0x7792d43cbba0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000701830, 0xc00028c3c0, {0x148?, 0x23a4fa0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000701830, 0xc00028c3c0) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc000136000, {0x2bd6388, 0xc0008ae6e0}, 0xc000415180) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc000136000, {0x2bd6388, 0xc0008ae6e0}, 0x419691?, {0x0?, 0x0?}, {0x2113da0, 0xc0001c4f50}, 0x41373d?, 0x288c930, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:550 +0x3f2 github.com/rclone/rclone/lib/rest.(*Client).CallXML(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:510 github.com/rclone/rclone/backend/sugarsync.(*Fs).listAll.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:644 +0x87 github.com/rclone/rclone/fs.pacerInvoker(0x2, 0xa, 0x2badac0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000400240, 0xc000557c00, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000400240, 0xc000557c00) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/sugarsync.(*Fs).listAll(0xc00039dd40, {0x2bd6388, 0xc0008ae6e0}, {0xc000373780, 0x3b}, 0xc00006ddd0, 0xc00006dda8) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:643 +0x410 github.com/rclone/rclone/backend/sugarsync.(*Fs).List(0xc00039dd40, {0x2bd6388, 0xc0008ae6e0}, {0x2c2ef60, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:693 +0x165 github.com/rclone/rclone/fs/list.listP({0x2bd6388, 0xc0008ae6e0}, {0x2bede70, 0xc00039dd40}, {0x2c2ef60, 0x1}, 0xc000a4e360) /home/rclone/go/src/github.com/rclone/rclone/fs/list/list.go:48 +0x6e github.com/rclone/rclone/fs/list.DirSortedFn({0x2bd6388, 0xc0008ae6e0}, {0x2bede70, 0xc00039dd40}, 0x0, {0x2c2ef60, 0x1}, 0xc000974e10, 0xc000515640) /home/rclone/go/src/github.com/rclone/rclone/fs/list/list.go:75 +0x257 github.com/rclone/rclone/fs/march.(*March).makeListDir.func1({0x2c2ef60, 0x1}, 0xc000974e10) /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:126 +0xb6 github.com/rclone/rclone/fs/march.(*March).processJob.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:410 +0xbb created by github.com/rclone/rclone/fs/march.(*March).processJob in goroutine 6958 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:408 +0x332 goroutine 7063 [IO wait]: internal/poll.runtime_pollWait(0x77931c8785c0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001f0500?, 0xc000acf000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0001f0500, {0xc000acf000, 0x1800, 0x1800}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001f0500, {0xc000acf000?, 0xc00009e8d0?, 0x42b5fc?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008c7d8, {0xc000acf000?, 0x422eb6?, 0x3fece00?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000998648, {0xc000acf000?, 0x7792d4382778?, 0x77931cc1aa78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000998078, {0xc000acf000?, 0xe?, 0xc00009e9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006b37b8, {0x2bb0500, 0xc000998078}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0006b3508, {0x7792d440f718, 0xc000998648}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0006b3508, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0006b3508, {0xc000756000, 0x1000, 0xc000701b00?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000492360, {0xc000756000?, 0x80d2e5?, 0x2bad200?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0008b0ba0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0008b0ba0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000492360) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 7015 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 6959 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6956 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6955 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 7064 [select]: net/http.(*persistConn).writeLoop(0xc000492360) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 7015 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 6948 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae550, {0x2bd6388, 0xc0008ae6e0}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000294c88, {0x2bd6388, 0xc0008ae690}, 0xc0008ae550, {0x2bede70, 0xc00039dd40}, 0x0, 0xc000086f60?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:491 +0xba created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 6925 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0xc) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0xc, 0xc000492360?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 6927 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x25) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x25, 0xc0008c5760?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 7025 [IO wait]: internal/poll.runtime_pollWait(0x77931c8786d8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000344080?, 0xc000acc000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000344080, {0xc000acc000, 0x1800, 0x1800}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000344080, {0xc000acc000?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008c730, {0xc000acc000?, 0x0?, 0x0?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000998198, {0xc000acc000?, 0x7792d44dee08?, 0x77931cc1a108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000558498, {0xc000acc000?, 0x2bd6388?, 0xc000136190?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006b29b8, {0x2bb0500, 0xc000558498}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0006b2708, {0x7792d440f718, 0xc000998198}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0006b2708, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0006b2708, {0xc000c35000, 0x1000, 0x77931cc1a108?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0005d05a0, {0xc000c35000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc00055aea0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc00055aea0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0005d05a0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 7021 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 6926 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x19) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x19, 0xc0005d05a0?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 6957 [select, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:206 +0x149 created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:203 +0x172 goroutine 6786 [sync.WaitGroup.Wait, 5 minutes]: sync.runtime_SemacquireWaitGroup(0xc000912720?) /usr/local/go/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0xc0007e2240?) /usr/local/go/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/march.(*March).Run(0xc0007e2240, {0x2bd6388, 0xc0008ae690}) /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:260 +0x385 github.com/rclone/rclone/fs/sync.(*syncCopyMove).run(0xc000294c88) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:956 +0x23c github.com/rclone/rclone/fs/sync.runSyncCopyMove({0x2bd6350, 0xc0003ed1d0}, {0x2bede70, 0xc00039dd40}, {0x2bec9e0, 0xc000ac23c0}, 0x3, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1369 +0x225 github.com/rclone/rclone/fs/sync.Sync({0x2bd6350, 0xc0003ed1d0}, {0x2bede70, 0xc00039dd40}, {0x2bec9e0, 0xc000ac23c0}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1375 +0x77 github.com/rclone/rclone/fs/sync.TestSyncIgnoreErrors(0xc000b801c0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:980 +0x6ce testing.tRunner(0xc000b801c0, 0x288b080) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 7074 [select]: net/http.(*persistConn).writeLoop(0xc0005d05a0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 7021 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 7006 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc000160100) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 6950 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x436 goroutine 6951 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae550, {0x2bd6388, 0xc0008ae6e0}, 0x4b) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000294c88, {0x2bd6388, 0xc0008ae690}, 0xc0008ae550, {0x2bede70, 0xc00039dd40}, 0x4b, 0xc00008af60?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:491 +0xba created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 6929 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x3e) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x3e, 0xc0005d05a0?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 goroutine 6960 [chan receive, 5 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:253 +0x378 goroutine 6924 [select, 5 minutes]: github.com/rclone/rclone/fs/sync.(*pipe).GetMax(0xc0008ae500, {0x2bd6388, 0xc0008ae6e0}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/pipe.go:126 +0xac github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairChecker(0xc000294c88, 0xc0008ae500, 0xc0008ae550, 0x0, 0xc000085760?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:374 +0xbe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startCheckers in goroutine 6786 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:519 +0x67 "./sync.test -test.v -test.timeout 1h0m0s -remote TestSugarSync:Test -verbose" - Finished ERROR in 1h0m3.380807453s (try 4/5): exit status 2: Failed []