"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose" - Starting (try 1/5) 2025/01/13 02:37:55 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi" 2025/01/13 02:37:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/13 02:37:55 DEBUG : TestPikPak: Loaded invalid token from config file - ignoring 2025/01/13 02:37:56 DEBUG : Saving config "token" in section "TestPikPak" of the config file 2025/01/13 02:37:56 DEBUG : TestPikPak: Saved new token in config file 2025/01/13 02:37:57 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:37:59 DEBUG : Creating backend with remote "/tmp/rclone3732950820" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === 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 "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:00 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:00 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/01/13 02:38:00 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestCopyWithDryRun (2.18s) === RUN TestCopy run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:01 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/13 02:38:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:03 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:03 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:03 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:04 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/01/13 02:38:05 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopy (5.98s) === RUN TestCopyMetadata run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.53s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.52s) === RUN TestCopyMissingDirectory run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:08 DEBUG : Creating backend with remote "/non-existing" 2025/01/13 02:38:09 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/01/13 02:38:09 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:09 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestCopyMissingDirectory (1.34s) === RUN TestCopyNoTraverse run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:11 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:11 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:11 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:14 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyNoTraverse (6.01s) === RUN TestCopyCheckFirst run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:16 INFO : PikPak root 'rclone-test-sexoqam7nobi': Running all checks before starting transfers 2025/01/13 02:38:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:16 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:16 INFO : PikPak root 'rclone-test-sexoqam7nobi': Checks finished, now starting transfers 2025/01/13 02:38:16 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:17 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:17 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:17 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:20 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyCheckFirst (5.96s) === RUN TestSyncNoTraverse run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:22 ERROR : Ignoring --no-traverse with sync 2025/01/13 02:38:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:22 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:22 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:23 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:23 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:23 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:38:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:26 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestSyncNoTraverse (5.98s) === RUN TestCopyWithDepth run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:28 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/01/13 02:38:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:28 DEBUG : hello world2: gcid = 6DA9FD6C87C80028F5856A686420DB4F1D06C429 2025/01/13 02:38:29 DEBUG : hello world2: Dst hash empty - aborting Src hash check 2025/01/13 02:38:29 INFO : hello world2: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestCopyWithDepth (3.53s) === RUN TestCopyWithFilesFrom run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:31 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:38:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:32 DEBUG : potato2: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:32 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:38:32 INFO : potato2: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestCopyWithFilesFrom (3.42s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:38:35 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:35 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:35 DEBUG : potato2: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:36 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:38:36 INFO : potato2: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestCopyWithFilesFromAndNoTraverse (3.43s) === RUN TestCopyEmptyDirectories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:38 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/01/13 02:38:38 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/13 02:38:38 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/01/13 02:38:38 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/13 02:38:38 INFO : sub dir: Making directory 2025/01/13 02:38:39 INFO : sub dir2: Making directory 2025/01/13 02:38:39 INFO : sub dir2/sub sub dir2: Making directory 2025/01/13 02:38:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:40 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:40 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:40 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:41 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:41 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:42 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/01/13 02:38:45 DEBUG : sub dir2: Rmdir: contains trashed file: "sub sub dir2" 2025/01/13 02:38:47 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyEmptyDirectories (10.34s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:48 INFO : sub dir2: Making directory 2025/01/13 02:38:48 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/01/13 02:38:48 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/13 02:38:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:50 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:50 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:50 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:38:52 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyNoEmptyDirectories (5.26s) === RUN TestMoveEmptyDirectories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:54 DEBUG : sub dir2: Making directory with metadata 2025/01/13 02:38:54 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/13 02:38:54 INFO : sub dir: Making directory 2025/01/13 02:38:54 INFO : sub dir2: Making directory 2025/01/13 02:38:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:38:55 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:38:55 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:38:55 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:38:56 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:38:56 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:38:56 INFO : sub dir/hello world: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:38:56 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/01/13 02:38:57 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/01/13 02:39:00 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestMoveEmptyDirectories (8.11s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.49s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:02 INFO : sub dir2: Making directory 2025/01/13 02:39:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:39:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:39:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:39:04 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:39:04 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:39:04 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:39:04 INFO : sub dir/hello world: Deleted 2025/01/13 02:39:06 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestMoveNoEmptyDirectories (5.33s) === RUN TestSyncEmptyDirectories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:08 DEBUG : sub dir2: Making directory with metadata 2025/01/13 02:39:08 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/13 02:39:08 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/13 02:39:08 INFO : sub dir: Making directory 2025/01/13 02:39:08 INFO : sub dir2: Making directory 2025/01/13 02:39:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:39:09 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:39:09 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:39:10 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:39:10 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:39:10 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:39:10 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:11 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/01/13 02:39:15 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestSyncEmptyDirectories (8.87s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.54s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:17 INFO : sub dir2: Making directory 2025/01/13 02:39:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:39:17 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:39:17 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:39:18 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:39:19 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:39:19 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:39:19 DEBUG : Waiting for deletions to finish 2025/01/13 02:39:21 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestSyncNoEmptyDirectories (5.50s) === RUN TestServerSideCopy run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:23 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:39:23 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:39:24 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bosugux4koxi" sync_test.go:590: Server side copy (if possible) PikPak root 'rclone-test-sexoqam7nobi' -> PikPak root 'rclone-test-bosugux4koxi' 2025/01/13 02:39:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:39:26 DEBUG : PikPak root 'rclone-test-bosugux4koxi': Waiting for checks to finish 2025/01/13 02:39:26 DEBUG : PikPak root 'rclone-test-bosugux4koxi': Waiting for transfers to finish 2025/01/13 02:39:29 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:39:29 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check 2025/01/13 02:39:29 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:29 DEBUG : PikPak root 'rclone-test-bosugux4koxi': Purge remote 2025/01/13 02:39:32 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestServerSideCopy (11.49s) === RUN TestCopyOverSelf run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:35 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:39:35 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:39:36 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/13 02:39:36 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:39:36 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:39:37 DEBUG : sub dir/hello world: gcid = A1D34F87709D3556F7ABEA2F38B387D82A25FA1B 2025/01/13 02:39:38 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:39:38 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:41 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:39:41 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyOverSelf (9.39s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:44 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:39:44 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:39:45 DEBUG : Creating backend with remote "TestPikPak:rclone-test-johuvep0bebu" sync_test.go:626: Server side copy (if possible) PikPak root 'rclone-test-sexoqam7nobi' -> PikPak root 'rclone-test-johuvep0bebu' 2025/01/13 02:39:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:39:46 DEBUG : PikPak root 'rclone-test-johuvep0bebu': Waiting for checks to finish 2025/01/13 02:39:46 DEBUG : PikPak root 'rclone-test-johuvep0bebu': Waiting for transfers to finish 2025/01/13 02:39:49 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:39:49 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check 2025/01/13 02:39:49 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:39:49 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:39:49 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b 2025/01/13 02:39:53 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/13 02:39:53 DEBUG : PikPak root 'rclone-test-johuvep0bebu': Waiting for checks to finish 2025/01/13 02:39:53 DEBUG : PikPak root 'rclone-test-johuvep0bebu': Waiting for transfers to finish 2025/01/13 02:39:54 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(PikPak root 'rclone-test-sexoqam7nobi') 17 vs dst(PikPak root 'rclone-test-johuvep0bebu') 11 2025/01/13 02:39:54 INFO : sub dir/hello world: Removing failed copy sync_test.go:639: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639 Error: Received unexpected error: corrupted on transfer: sizes differ src(PikPak root 'rclone-test-sexoqam7nobi') 17 vs dst(PikPak root 'rclone-test-johuvep0bebu') 11 Test: TestServerSideCopyOverSelf 2025/01/13 02:39:55 DEBUG : PikPak root 'rclone-test-johuvep0bebu': Purge remote 2025/01/13 02:39:58 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:39:58 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- FAIL: TestServerSideCopyOverSelf (16.51s) === RUN TestMoveOverSelf run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:01 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:40:01 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:40:02 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/13 02:40:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:02 DEBUG : sub dir/hello world: gcid = A1D34F87709D3556F7ABEA2F38B387D82A25FA1B 2025/01/13 02:40:04 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:40:04 INFO : sub dir/hello world: Copied (replaced existing) 2025/01/13 02:40:04 INFO : sub dir/hello world: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:06 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:40:06 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestMoveOverSelf (8.22s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:09 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:40:09 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:40:10 DEBUG : Creating backend with remote "TestPikPak:rclone-test-hifohex9jepo" sync_test.go:671: Server side copy (if possible) PikPak root 'rclone-test-sexoqam7nobi' -> PikPak root 'rclone-test-hifohex9jepo' 2025/01/13 02:40:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:40:11 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Waiting for checks to finish 2025/01/13 02:40:11 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Waiting for transfers to finish 2025/01/13 02:40:14 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:40:14 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check 2025/01/13 02:40:14 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:14 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:40:14 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b 2025/01/13 02:40:17 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Using server-side directory move 2025/01/13 02:40:17 INFO : PikPak root 'rclone-test-hifohex9jepo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/13 02:40:17 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/13 02:40:17 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Waiting for checks to finish 2025/01/13 02:40:17 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Waiting for transfers to finish 2025/01/13 02:40:18 INFO : sub dir/hello world: Deleted 2025/01/13 02:40:19 INFO : sub dir/hello world: Moved (server-side) 2025/01/13 02:40:20 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:40:20 DEBUG : sub dir/hello world: gcid = 56e41ddbaec00ad6eb8e58ba6df699946a8e84e2 2025/01/13 02:40:21 DEBUG : testing file moves 2025/01/13 02:40:22 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/01/13 02:40:22 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Waiting for checks to finish 2025/01/13 02:40:22 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Waiting for transfers to finish 2025/01/13 02:40:23 INFO : sub dir/hello world: Deleted 2025/01/13 02:40:24 INFO : sub dir/hello world: Moved (server-side) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:25 DEBUG : PikPak root 'rclone-test-hifohex9jepo': Purge remote 2025/01/13 02:40:27 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestServerSideMoveOverSelf (21.08s) === RUN TestCopyAfterDelete run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:30 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:40:30 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:40:30 ERROR : : error listing: directory not found 2025/01/13 02:40:31 INFO : Local file system at /tmp/rclone3732950820: Making directory 2025/01/13 02:40:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:34 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyAfterDelete (7.13s) === RUN TestCopyRedownload run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:37 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world 2025/01/13 02:40:37 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05 2025/01/13 02:40:38 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/13 02:40:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:40:39 DEBUG : Local file system at /tmp/rclone3732950820: Waiting for checks to finish 2025/01/13 02:40:39 DEBUG : Local file system at /tmp/rclone3732950820: Waiting for transfers to finish 2025/01/13 02:40:40 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check 2025/01/13 02:40:40 DEBUG : sub dir/hello world.2e6d3de4.partial: renamed to: sub dir/hello world 2025/01/13 02:40:40 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:40:40 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:42 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestCopyRedownload (7.77s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:44 DEBUG : check sum: Need to transfer - File not found at Destination 2025/01/13 02:40:44 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:44 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:45 DEBUG : check sum: gcid = 09F49478AF9C899F0E74BF1A8E2EC667345A178B 2025/01/13 02:40:45 DEBUG : check sum: Dst hash empty - aborting Src hash check 2025/01/13 02:40:45 INFO : check sum: Copied (new) 2025/01/13 02:40:45 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:46 DEBUG : check sum: Dst hash empty - aborting Src hash check 2025/01/13 02:40:46 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:46 DEBUG : check sum: Size of src and dst objects identical 2025/01/13 02:40:46 DEBUG : check sum: Unchanged skipping 2025/01/13 02:40:46 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:46 DEBUG : Waiting for deletions to finish 2025/01/13 02:40:46 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncBasedOnCheckSum (4.66s) === RUN TestSyncSizeOnly run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:49 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/01/13 02:40:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:49 DEBUG : sizeonly: gcid = 2A54A2DADF85005832B477A2CF6B042FBE28D421 2025/01/13 02:40:50 DEBUG : sizeonly: Dst hash empty - aborting Src hash check 2025/01/13 02:40:50 INFO : sizeonly: Copied (new) 2025/01/13 02:40:50 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:50 DEBUG : sizeonly: Sizes identical 2025/01/13 02:40:50 DEBUG : sizeonly: Unchanged skipping 2025/01/13 02:40:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:50 DEBUG : Waiting for deletions to finish 2025/01/13 02:40:50 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncSizeOnly (4.61s) === RUN TestSyncIgnoreSize run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:54 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/01/13 02:40:54 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:54 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:54 DEBUG : ignore-size: gcid = 14DCE568A9271185D6D5154BDBF31DD6CA2B4496 2025/01/13 02:40:54 DEBUG : ignore-size: Dst hash empty - aborting Src hash check 2025/01/13 02:40:54 INFO : ignore-size: Copied (new) 2025/01/13 02:40:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:55 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:55 DEBUG : ignore-size: Sizes identical 2025/01/13 02:40:55 DEBUG : ignore-size: Unchanged skipping 2025/01/13 02:40:55 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:55 DEBUG : Waiting for deletions to finish 2025/01/13 02:40:55 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreSize (4.62s) === RUN TestSyncIgnoreTimes run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:58 DEBUG : existing: calculating gcid: failed to unwrap object from src: existing 2025/01/13 02:40:58 DEBUG : existing: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:40:59 DEBUG : existing: Sizes identical 2025/01/13 02:40:59 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:59 DEBUG : existing: Unchanged skipping 2025/01/13 02:40:59 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:40:59 DEBUG : Waiting for deletions to finish 2025/01/13 02:40:59 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:40:59 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:40:59 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/01/13 02:40:59 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:00 DEBUG : existing: gcid = 2A54A2DADF85005832B477A2CF6B042FBE28D421 2025/01/13 02:41:01 DEBUG : existing: Dst hash empty - aborting Src hash check 2025/01/13 02:41:01 INFO : existing: Copied (replaced existing) 2025/01/13 02:41:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreTimes (6.33s) === RUN TestSyncIgnoreExisting run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:05 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/13 02:41:05 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:05 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:05 DEBUG : existing: gcid = 2A54A2DADF85005832B477A2CF6B042FBE28D421 2025/01/13 02:41:05 DEBUG : existing: Dst hash empty - aborting Src hash check 2025/01/13 02:41:05 INFO : existing: Copied (new) 2025/01/13 02:41:05 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:06 DEBUG : existing: Destination exists, skipping 2025/01/13 02:41:06 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:06 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:06 DEBUG : Waiting for deletions to finish 2025/01/13 02:41:06 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreExisting (5.11s) === RUN TestSyncIgnoreErrors run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:10 DEBUG : b/potato: calculating gcid: failed to unwrap object from src: b/potato 2025/01/13 02:41:10 DEBUG : b/potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:41:11 DEBUG : c/non empty space: calculating gcid: failed to unwrap object from src: c/non empty space 2025/01/13 02:41:11 DEBUG : c/non empty space: gcid = 38650f9e5ef1421ecffb4d8870fac603ce07a319 2025/01/13 02:41:12 INFO : d: Making directory 2025/01/13 02:41:14 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/01/13 02:41:14 DEBUG : c/non empty space: Sizes identical 2025/01/13 02:41:14 DEBUG : c/non empty space: Unchanged skipping 2025/01/13 02:41:14 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:14 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:16 DEBUG : a/potato2: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:41:16 DEBUG : a/potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:41:16 INFO : a/potato2: Copied (new) 2025/01/13 02:41:16 DEBUG : Waiting for deletions to finish 2025/01/13 02:41:17 INFO : b/potato: Deleted 2025/01/13 02:41:17 INFO : d: Removing directory 2025/01/13 02:41:18 INFO : b: Removing directory 2025/01/13 02:41:19 DEBUG : b: Rmdir: contains trashed file: "potato" 2025/01/13 02:41:20 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': deleted 2 directories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:23 DEBUG : c: Rmdir: contains trashed file: "non empty space" 2025/01/13 02:41:24 DEBUG : a: Rmdir: contains trashed file: "potato2" --- PASS: TestSyncIgnoreErrors (16.76s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:26 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:41:26 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:41:27 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:27 DEBUG : empty space: Sizes identical 2025/01/13 02:41:27 DEBUG : empty space: Unchanged skipping 2025/01/13 02:41:27 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:27 DEBUG : Waiting for deletions to finish 2025/01/13 02:41:27 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:28 DEBUG : empty space: Sizes identical 2025/01/13 02:41:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:28 DEBUG : empty space: Unchanged skipping 2025/01/13 02:41:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:28 DEBUG : Waiting for deletions to finish 2025/01/13 02:41:28 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingModtimeOnly (4.52s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:31 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:41:31 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:41:32 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:32 DEBUG : empty space: Sizes identical 2025/01/13 02:41:32 DEBUG : empty space: Unchanged skipping 2025/01/13 02:41:32 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:32 DEBUG : Waiting for deletions to finish 2025/01/13 02:41:32 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (3.37s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1042: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncDoesntUpdateModtime (0.49s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:35 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:41:35 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:41:36 DEBUG : potato: Need to transfer - File not found at Destination 2025/01/13 02:41:36 DEBUG : empty space: Sizes identical 2025/01/13 02:41:36 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:36 DEBUG : empty space: Unchanged skipping 2025/01/13 02:41:36 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:36 DEBUG : potato: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:41:36 DEBUG : potato: Dst hash empty - aborting Src hash check 2025/01/13 02:41:36 INFO : potato: Copied (new) 2025/01/13 02:41:36 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterAddingAFile (5.22s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:40 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:41:40 DEBUG : potato: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:41:41 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/01/13 02:41:41 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:41 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:41 DEBUG : potato: gcid = 0B2BF6BE338377A62FA7636AD169C4B56486C1B1 2025/01/13 02:41:43 DEBUG : potato: Dst hash empty - aborting Src hash check 2025/01/13 02:41:43 INFO : potato: Copied (replaced existing) 2025/01/13 02:41:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingFilesSizeOnly (5.28s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1106: ModTimeNotSupported so forcing file to be a different size 2025/01/13 02:41:45 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:41:45 DEBUG : potato: gcid = 728d2aee5ac530a2abb4d2d01a698f859f44fbf1 2025/01/13 02:41:47 DEBUG : potato: Sizes differ (src 21 vs dst 36) 2025/01/13 02:41:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:47 DEBUG : potato: gcid = 4610B0F211BE9069C0AAAC2F7EC34B5E164C9556 2025/01/13 02:41:49 DEBUG : potato: Dst hash empty - aborting Src hash check 2025/01/13 02:41:49 INFO : potato: Copied (replaced existing) 2025/01/13 02:41:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingContentsOnly (6.21s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:51 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:41:51 DEBUG : potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:41:52 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:41:52 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:41:53 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:41:53 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/01/13 02:41:53 DEBUG : empty space: Sizes identical 2025/01/13 02:41:53 DEBUG : empty space: Unchanged skipping 2025/01/13 02:41:53 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:53 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:53 DEBUG : Waiting for deletions to finish 2025/01/13 02:41:53 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (4.95s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:41:56 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:41:56 DEBUG : potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:41:57 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:41:57 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:41:58 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:41:58 DEBUG : empty space: Sizes identical 2025/01/13 02:41:58 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:41:58 DEBUG : empty space: Unchanged skipping 2025/01/13 02:41:58 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:41:58 DEBUG : potato2: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:41:59 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:41:59 INFO : potato2: Copied (new) 2025/01/13 02:41:59 DEBUG : Waiting for deletions to finish 2025/01/13 02:42:00 INFO : potato: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (7.06s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:04 DEBUG : b/potato: calculating gcid: failed to unwrap object from src: b/potato 2025/01/13 02:42:04 DEBUG : b/potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:42:05 DEBUG : c/non empty space: calculating gcid: failed to unwrap object from src: c/non empty space 2025/01/13 02:42:05 DEBUG : c/non empty space: gcid = 38650f9e5ef1421ecffb4d8870fac603ce07a319 2025/01/13 02:42:06 INFO : d: Making directory 2025/01/13 02:42:06 INFO : d/e: Making directory 2025/01/13 02:42:09 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/01/13 02:42:09 DEBUG : c/non empty space: Sizes identical 2025/01/13 02:42:09 DEBUG : c/non empty space: Unchanged skipping 2025/01/13 02:42:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:42:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:42:10 DEBUG : a/potato2: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:42:11 DEBUG : a/potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:42:11 INFO : a/potato2: Copied (new) 2025/01/13 02:42:11 DEBUG : Waiting for deletions to finish 2025/01/13 02:42:12 INFO : b/potato: Deleted 2025/01/13 02:42:12 INFO : d/e: Removing directory 2025/01/13 02:42:13 INFO : d: Removing directory 2025/01/13 02:42:13 DEBUG : d: Rmdir: contains trashed file: "e" 2025/01/13 02:42:14 INFO : b: Removing directory 2025/01/13 02:42:15 DEBUG : b: Rmdir: contains trashed file: "potato" 2025/01/13 02:42:16 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': deleted 3 directories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:19 DEBUG : c: Rmdir: contains trashed file: "non empty space" 2025/01/13 02:42:21 DEBUG : a: Rmdir: contains trashed file: "potato2" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (19.20s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:23 DEBUG : b/potato: calculating gcid: failed to unwrap object from src: b/potato 2025/01/13 02:42:23 DEBUG : b/potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:42:24 DEBUG : c/non empty space: calculating gcid: failed to unwrap object from src: c/non empty space 2025/01/13 02:42:24 DEBUG : c/non empty space: gcid = 38650f9e5ef1421ecffb4d8870fac603ce07a319 2025/01/13 02:42:25 INFO : d: Making directory 2025/01/13 02:42:27 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/01/13 02:42:27 DEBUG : c/non empty space: Sizes identical 2025/01/13 02:42:27 DEBUG : c/non empty space: Unchanged skipping 2025/01/13 02:42:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:42:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:42:29 DEBUG : a/potato2: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:42:29 DEBUG : a/potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:42:29 INFO : a/potato2: Copied (new) 2025/01/13 02:42:29 ERROR : PikPak root 'rclone-test-sexoqam7nobi': not deleting files as there were IO errors 2025/01/13 02:42:29 ERROR : PikPak root 'rclone-test-sexoqam7nobi': not deleting directories as there were IO errors run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:35 DEBUG : c: Rmdir: contains trashed file: "non empty space" 2025/01/13 02:42:37 DEBUG : b: Rmdir: contains trashed file: "potato" 2025/01/13 02:42:38 DEBUG : a: Rmdir: contains trashed file: "potato2" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (17.17s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:40 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:42:40 DEBUG : potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:42:40 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:42:40 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:42:41 DEBUG : Waiting for deletions to finish 2025/01/13 02:42:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:42:41 DEBUG : empty space: Sizes identical 2025/01/13 02:42:41 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:42:41 DEBUG : empty space: Unchanged skipping 2025/01/13 02:42:41 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:42:42 DEBUG : potato2: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:42:42 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:42:42 INFO : potato2: Copied (new) 2025/01/13 02:42:42 INFO : potato: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncDeleteDuring (6.12s) === RUN TestSyncDeleteBefore run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:46 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:42:46 DEBUG : potato: gcid = 4610b0f211be9069c0aaac2f7ec34b5e164c9556 2025/01/13 02:42:47 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:42:47 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:42:47 DEBUG : Waiting for deletions to finish 2025/01/13 02:42:49 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:42:49 DEBUG : Config file has changed externally - reloading 2025/01/13 02:42:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:42:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:42:50 INFO : potato: Deleted 2025/01/13 02:42:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:42:50 DEBUG : empty space: Sizes identical 2025/01/13 02:42:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:42:50 DEBUG : empty space: Unchanged skipping 2025/01/13 02:42:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:42:51 DEBUG : potato2: gcid = F284CA644CF64403B777451495CA659CC12415AF 2025/01/13 02:42:51 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:42:51 INFO : potato2: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncDeleteBefore (8.64s) === RUN TestCopyDeleteBefore run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:42:55 DEBUG : potato: calculating gcid: failed to unwrap object from src: potato 2025/01/13 02:42:55 DEBUG : potato: gcid = 2b96e3e0cf87879486cc850d3aebb91b6cc80acd 2025/01/13 02:42:55 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:42:55 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:42:55 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:42:56 DEBUG : potato2: gcid = 5609D3871BAF3D9C2C2D6FF299B3E0D4DCDFEA12 2025/01/13 02:42:56 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/01/13 02:42:56 INFO : potato2: Copied (new) run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestCopyDeleteBefore (5.24s) === RUN TestSyncWithExclude run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:00 DEBUG : potato2: calculating gcid: failed to unwrap object from src: potato2 2025/01/13 02:43:00 DEBUG : potato2: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:43:00 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:43:00 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:43:01 DEBUG : enormous: Excluded (Size Filter) 2025/01/13 02:43:01 DEBUG : enormous: Excluded 2025/01/13 02:43:01 DEBUG : potato2: Excluded (Size Filter) 2025/01/13 02:43:01 DEBUG : potato2: Excluded 2025/01/13 02:43:01 DEBUG : potato2: Excluded (Size Filter) 2025/01/13 02:43:01 DEBUG : potato2: Excluded 2025/01/13 02:43:01 DEBUG : empty space: Sizes identical 2025/01/13 02:43:01 DEBUG : empty space: Unchanged skipping 2025/01/13 02:43:01 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:01 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:01 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:01 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:02 DEBUG : enormous: Excluded (Size Filter) 2025/01/13 02:43:02 DEBUG : enormous: Excluded 2025/01/13 02:43:02 DEBUG : potato2: Excluded (Size Filter) 2025/01/13 02:43:02 DEBUG : potato2: Excluded 2025/01/13 02:43:02 DEBUG : potato2: Excluded (Size Filter) 2025/01/13 02:43:02 DEBUG : potato2: Excluded 2025/01/13 02:43:02 DEBUG : empty space: Sizes identical 2025/01/13 02:43:02 DEBUG : empty space: Unchanged skipping 2025/01/13 02:43:02 DEBUG : Local file system at /tmp/rclone3732950820: Waiting for checks to finish 2025/01/13 02:43:02 DEBUG : Local file system at /tmp/rclone3732950820: Waiting for transfers to finish 2025/01/13 02:43:02 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:02 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncWithExclude (5.73s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:06 DEBUG : potato2: calculating gcid: failed to unwrap object from src: potato2 2025/01/13 02:43:06 DEBUG : potato2: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:43:06 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:43:06 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:43:07 DEBUG : enormous: calculating gcid: failed to unwrap object from src: enormous 2025/01/13 02:43:07 DEBUG : enormous: gcid = 3263d761482234c1af659712f0f2b3d89f5f7e15 2025/01/13 02:43:07 DEBUG : enormous: Excluded (Size Filter) 2025/01/13 02:43:07 DEBUG : enormous: Excluded 2025/01/13 02:43:07 DEBUG : potato2: Excluded (Size Filter) 2025/01/13 02:43:07 DEBUG : potato2: Excluded 2025/01/13 02:43:08 DEBUG : empty space: Sizes identical 2025/01/13 02:43:08 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:08 DEBUG : empty space: Unchanged skipping 2025/01/13 02:43:08 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:08 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:09 INFO : enormous: Deleted 2025/01/13 02:43:09 INFO : potato2: Deleted 2025/01/13 02:43:09 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:10 DEBUG : empty space: Sizes identical 2025/01/13 02:43:10 DEBUG : empty space: Unchanged skipping 2025/01/13 02:43:10 DEBUG : Local file system at /tmp/rclone3732950820: Waiting for checks to finish 2025/01/13 02:43:10 DEBUG : Local file system at /tmp/rclone3732950820: Waiting for transfers to finish 2025/01/13 02:43:10 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:10 INFO : potato2: Deleted 2025/01/13 02:43:10 INFO : enormous: Deleted 2025/01/13 02:43:10 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.19s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1423: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.51s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1538: Can track renames: true 2025/01/13 02:43:13 INFO : PikPak root 'rclone-test-sexoqam7nobi': Making map for --track-renames 2025/01/13 02:43:13 INFO : PikPak root 'rclone-test-sexoqam7nobi': Finished making map for --track-renames 2025/01/13 02:43:13 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:13 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/13 02:43:13 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for renames to finish 2025/01/13 02:43:13 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/01/13 02:43:13 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:14 DEBUG : potato: gcid = 042F38E8A9BA1C5763703527A7310AA86EE543A1 2025/01/13 02:43:14 DEBUG : yam: gcid = EFF462CAEB44EA6C3FB2CE986E6D7A822418EF2B 2025/01/13 02:43:14 DEBUG : potato: Dst hash empty - aborting Src hash check 2025/01/13 02:43:14 INFO : potato: Copied (new) 2025/01/13 02:43:14 DEBUG : yam: Dst hash empty - aborting Src hash check 2025/01/13 02:43:14 INFO : yam: Copied (new) 2025/01/13 02:43:14 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:15 DEBUG : potato: Sizes identical 2025/01/13 02:43:15 INFO : PikPak root 'rclone-test-sexoqam7nobi': Making map for --track-renames 2025/01/13 02:43:15 INFO : PikPak root 'rclone-test-sexoqam7nobi': Finished making map for --track-renames 2025/01/13 02:43:15 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:15 DEBUG : yaml: Need to transfer - No matching file found at Destination 2025/01/13 02:43:15 DEBUG : potato: Unchanged skipping 2025/01/13 02:43:15 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for renames to finish 2025/01/13 02:43:15 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:15 DEBUG : yaml: gcid = EFF462CAEB44EA6C3FB2CE986E6D7A822418EF2B 2025/01/13 02:43:16 DEBUG : yaml: Dst hash empty - aborting Src hash check 2025/01/13 02:43:16 INFO : yaml: Copied (new) 2025/01/13 02:43:16 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:17 INFO : yam: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1564: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1564 Error: Not equal: expected: true actual : false Test: TestSyncWithTrackRenames Messages: canTrackRenames=true, renames=0 --- FAIL: TestSyncWithTrackRenames (7.31s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1610: Can track renames: false 2025/01/13 02:43:20 ERROR : PikPak root 'rclone-test-sexoqam7nobi': Ignoring --track-renames as either the source or destination do not support modtime 2025/01/13 02:43:21 DEBUG : potato: Need to transfer - File not found at Destination 2025/01/13 02:43:21 DEBUG : yam: Need to transfer - File not found at Destination 2025/01/13 02:43:21 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:21 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:21 DEBUG : yam: gcid = EFF462CAEB44EA6C3FB2CE986E6D7A822418EF2B 2025/01/13 02:43:21 DEBUG : potato: gcid = 042F38E8A9BA1C5763703527A7310AA86EE543A1 2025/01/13 02:43:21 DEBUG : yam: Dst hash empty - aborting Src hash check 2025/01/13 02:43:21 INFO : yam: Copied (new) 2025/01/13 02:43:21 DEBUG : potato: Dst hash empty - aborting Src hash check 2025/01/13 02:43:21 INFO : potato: Copied (new) 2025/01/13 02:43:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:22 ERROR : PikPak root 'rclone-test-sexoqam7nobi': Ignoring --track-renames as either the source or destination do not support modtime 2025/01/13 02:43:22 DEBUG : yaml: Need to transfer - File not found at Destination 2025/01/13 02:43:22 DEBUG : potato: Sizes identical 2025/01/13 02:43:22 DEBUG : potato: Unchanged skipping 2025/01/13 02:43:22 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:22 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:23 DEBUG : yaml: gcid = EFF462CAEB44EA6C3FB2CE986E6D7A822418EF2B 2025/01/13 02:43:23 DEBUG : yaml: Dst hash empty - aborting Src hash check 2025/01/13 02:43:23 INFO : yaml: Copied (new) 2025/01/13 02:43:23 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:24 INFO : yam: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncWithTrackRenamesStrategyModtime (7.27s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1649: Can track renames: false 2025/01/13 02:43:28 INFO : PikPak root 'rclone-test-sexoqam7nobi': Making map for --track-renames 2025/01/13 02:43:28 INFO : PikPak root 'rclone-test-sexoqam7nobi': Finished making map for --track-renames 2025/01/13 02:43:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:28 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/13 02:43:28 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/01/13 02:43:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for renames to finish 2025/01/13 02:43:28 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:29 DEBUG : potato: gcid = 042F38E8A9BA1C5763703527A7310AA86EE543A1 2025/01/13 02:43:29 DEBUG : sub/yam: gcid = EFF462CAEB44EA6C3FB2CE986E6D7A822418EF2B 2025/01/13 02:43:29 DEBUG : potato: Dst hash empty - aborting Src hash check 2025/01/13 02:43:29 INFO : potato: Copied (new) 2025/01/13 02:43:29 DEBUG : sub/yam: Dst hash empty - aborting Src hash check 2025/01/13 02:43:29 INFO : sub/yam: Copied (new) 2025/01/13 02:43:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:30 DEBUG : potato: Sizes identical 2025/01/13 02:43:30 DEBUG : potato: Unchanged skipping 2025/01/13 02:43:31 INFO : PikPak root 'rclone-test-sexoqam7nobi': Making map for --track-renames 2025/01/13 02:43:31 INFO : PikPak root 'rclone-test-sexoqam7nobi': Finished making map for --track-renames 2025/01/13 02:43:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for renames to finish 2025/01/13 02:43:32 INFO : sub/yam: Moved (server-side) to: yam 2025/01/13 02:43:32 INFO : yam: Renamed from "sub/yam" 2025/01/13 02:43:32 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:32 DEBUG : Waiting for deletions to finish 2025/01/13 02:43:32 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (9.81s) === RUN TestServerSideMoveLocal run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:37 DEBUG : Creating backend with remote "/tmp/rclone3732950820/dir1" 2025/01/13 02:43:37 DEBUG : Creating backend with remote "/tmp/rclone3732950820/dir2" 2025/01/13 02:43:37 DEBUG : Local file system at /tmp/rclone3732950820/dir2: Using server-side directory move 2025/01/13 02:43:37 INFO : Local file system at /tmp/rclone3732950820/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/13 02:43:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/01/13 02:43:37 INFO : file1.txt: Moved (server-side) 2025/01/13 02:43:37 DEBUG : Local file system at /tmp/rclone3732950820/dir2: Waiting for checks to finish 2025/01/13 02:43:37 DEBUG : Local file system at /tmp/rclone3732950820/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.51s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:43:38 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/01/13 02:43:38 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:38 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:39 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:43:40 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:43:40 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:43:40 INFO : sub dir/hello world: Deleted 2025/01/13 02:43:41 DEBUG : nested/sub dir/file: gcid = 82E1BE46AF6810D05CE889DB14236009AF874EB5 2025/01/13 02:43:41 DEBUG : nested/sub dir/file: Dst hash empty - aborting Src hash check 2025/01/13 02:43:41 INFO : nested/sub dir/file: Copied (new) 2025/01/13 02:43:41 INFO : nested/sub dir/file: Deleted 2025/01/13 02:43:41 INFO : sub dir: Removing directory 2025/01/13 02:43:41 INFO : nested/sub dir: Removing directory 2025/01/13 02:43:41 INFO : nested: Removing directory 2025/01/13 02:43:41 DEBUG : Local file system at /tmp/rclone3732950820: deleted 3 directories run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:45 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:43:46 DEBUG : nested/sub dir: Rmdir: contains trashed file: "file" 2025/01/13 02:43:48 DEBUG : nested: Rmdir: contains trashed file: "sub dir" --- PASS: TestMoveWithDeleteEmptySrcDirs (11.44s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/13 02:43:50 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/01/13 02:43:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:43:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:43:52 DEBUG : sub dir/hello world: gcid = 67BECF85308ACF0261750DA1075681EE5C412F05 2025/01/13 02:43:53 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/01/13 02:43:53 INFO : sub dir/hello world: Copied (new) 2025/01/13 02:43:53 INFO : sub dir/hello world: Deleted 2025/01/13 02:43:53 DEBUG : nested/sub dir/file: gcid = 82E1BE46AF6810D05CE889DB14236009AF874EB5 2025/01/13 02:43:53 DEBUG : nested/sub dir/file: Dst hash empty - aborting Src hash check 2025/01/13 02:43:53 INFO : nested/sub dir/file: Copied (new) 2025/01/13 02:43:53 INFO : nested/sub dir/file: Deleted run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:43:57 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:43:58 DEBUG : nested/sub dir: Rmdir: contains trashed file: "file" 2025/01/13 02:44:00 DEBUG : nested: Rmdir: contains trashed file: "sub dir" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (11.99s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:44:02 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/13 02:44:02 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/01/13 02:44:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:44:02 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:44:02 DEBUG : existing: gcid = 2A54A2DADF85005832B477A2CF6B042FBE28D421 2025/01/13 02:44:02 DEBUG : existing-b: gcid = F5E2F430F70ADB8C5D5CD97AC2C56CCE089FB65B 2025/01/13 02:44:02 DEBUG : existing: Dst hash empty - aborting Src hash check 2025/01/13 02:44:02 INFO : existing: Copied (new) 2025/01/13 02:44:02 INFO : existing: Deleted 2025/01/13 02:44:03 DEBUG : existing-b: Dst hash empty - aborting Src hash check 2025/01/13 02:44:03 INFO : existing-b: Copied (new) 2025/01/13 02:44:03 INFO : existing-b: Deleted 2025/01/13 02:44:03 DEBUG : existing: Destination exists, skipping 2025/01/13 02:44:03 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:44:03 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/01/13 02:44:03 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:44:03 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestMoveWithIgnoreExisting (4.98s) === RUN TestServerSideMove run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:44:06 DEBUG : Creating backend with remote "TestPikPak:rclone-test-rufideq2jaja" 2025/01/13 02:44:08 DEBUG : potato2: calculating gcid: failed to unwrap object from src: potato2 2025/01/13 02:44:08 DEBUG : potato2: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:44:09 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:44:09 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:44:09 DEBUG : potato3: calculating gcid: failed to unwrap object from src: potato3 2025/01/13 02:44:09 DEBUG : potato3: gcid = 9dee7de1c6ec137b072da7e579dcc66439cb22bb sync_test.go:1705: Server side move (if possible) PikPak root 'rclone-test-sexoqam7nobi' -> PikPak root 'rclone-test-rufideq2jaja' 2025/01/13 02:44:11 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:44:11 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:44:11 DEBUG : potato3: calculating gcid: failed to unwrap object from src: potato3 2025/01/13 02:44:11 DEBUG : potato3: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:44:12 DEBUG : PikPak root 'rclone-test-rufideq2jaja': Using server-side directory move 2025/01/13 02:44:12 INFO : PikPak root 'rclone-test-rufideq2jaja': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/13 02:44:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:44:12 DEBUG : empty space: Sizes identical 2025/01/13 02:44:12 DEBUG : empty space: Unchanged skipping 2025/01/13 02:44:12 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/01/13 02:44:12 DEBUG : PikPak root 'rclone-test-rufideq2jaja': Waiting for checks to finish 2025/01/13 02:44:13 INFO : empty space: Deleted 2025/01/13 02:44:13 DEBUG : PikPak root 'rclone-test-rufideq2jaja': Waiting for transfers to finish 2025/01/13 02:44:13 INFO : potato2: Moved (server-side) 2025/01/13 02:44:14 INFO : potato3: Deleted 2025/01/13 02:44:15 INFO : potato3: Moved (server-side) 2025/01/13 02:44:16 DEBUG : Creating backend with remote "TestPikPak:rclone-test-nadufuc8joda" 2025/01/13 02:44:17 DEBUG : PikPak root 'rclone-test-nadufuc8joda': Using server-side directory move 2025/01/13 02:44:17 INFO : PikPak root 'rclone-test-nadufuc8joda': Server side directory move succeeded 2025/01/13 02:44:18 ERROR : : error listing: directory not found 2025/01/13 02:44:18 DEBUG : PikPak root 'rclone-test-nadufuc8joda': Purge remote 2025/01/13 02:44:19 DEBUG : PikPak root 'rclone-test-rufideq2jaja': Purge remote 2025/01/13 02:44:20 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (13.78s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:44:20 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bemoyuc6qovu" 2025/01/13 02:44:21 DEBUG : potato2: calculating gcid: failed to unwrap object from src: potato2 2025/01/13 02:44:21 DEBUG : potato2: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:44:22 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:44:22 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:44:22 DEBUG : potato3: calculating gcid: failed to unwrap object from src: potato3 2025/01/13 02:44:22 DEBUG : potato3: gcid = 9dee7de1c6ec137b072da7e579dcc66439cb22bb sync_test.go:1705: Server side move (if possible) PikPak root 'rclone-test-sexoqam7nobi' -> PikPak root 'rclone-test-bemoyuc6qovu' 2025/01/13 02:44:24 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:44:24 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:44:25 DEBUG : potato3: calculating gcid: failed to unwrap object from src: potato3 2025/01/13 02:44:25 DEBUG : potato3: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:44:26 DEBUG : empty space: Excluded (Size Filter) 2025/01/13 02:44:26 DEBUG : empty space: Excluded 2025/01/13 02:44:26 DEBUG : empty space: Excluded (Size Filter) 2025/01/13 02:44:26 DEBUG : empty space: Excluded 2025/01/13 02:44:26 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:44:26 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/01/13 02:44:26 DEBUG : PikPak root 'rclone-test-bemoyuc6qovu': Waiting for checks to finish 2025/01/13 02:44:26 DEBUG : PikPak root 'rclone-test-bemoyuc6qovu': Waiting for transfers to finish 2025/01/13 02:44:27 INFO : potato2: Moved (server-side) 2025/01/13 02:44:27 INFO : potato3: Deleted 2025/01/13 02:44:28 INFO : potato3: Moved (server-side) 2025/01/13 02:44:28 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sehihim3xuda" 2025/01/13 02:44:29 DEBUG : empty space: Excluded (Size Filter) 2025/01/13 02:44:29 DEBUG : empty space: Excluded 2025/01/13 02:44:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:44:29 DEBUG : potato3: Need to transfer - File not found at Destination 2025/01/13 02:44:29 DEBUG : PikPak root 'rclone-test-sehihim3xuda': Waiting for checks to finish 2025/01/13 02:44:29 DEBUG : PikPak root 'rclone-test-sehihim3xuda': Waiting for transfers to finish 2025/01/13 02:44:31 INFO : potato2: Moved (server-side) 2025/01/13 02:44:31 INFO : potato3: Moved (server-side) 2025/01/13 02:44:31 DEBUG : PikPak root 'rclone-test-sehihim3xuda': Purge remote 2025/01/13 02:44:32 DEBUG : PikPak root 'rclone-test-bemoyuc6qovu': Purge remote --- PASS: TestServerSideMoveWithFilter (14.52s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:44:35 DEBUG : Creating backend with remote "TestPikPak:rclone-test-hiconid5qisa" 2025/01/13 02:44:37 DEBUG : potato2: calculating gcid: failed to unwrap object from src: potato2 2025/01/13 02:44:37 DEBUG : potato2: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:44:38 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:44:38 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:44:38 DEBUG : potato3: calculating gcid: failed to unwrap object from src: potato3 2025/01/13 02:44:38 DEBUG : potato3: gcid = 9dee7de1c6ec137b072da7e579dcc66439cb22bb 2025/01/13 02:44:39 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) PikPak root 'rclone-test-sexoqam7nobi' -> PikPak root 'rclone-test-hiconid5qisa' 2025/01/13 02:44:41 DEBUG : empty space: calculating gcid: failed to unwrap object from src: empty space 2025/01/13 02:44:41 DEBUG : empty space: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:44:41 DEBUG : potato3: calculating gcid: failed to unwrap object from src: potato3 2025/01/13 02:44:41 DEBUG : potato3: gcid = f284ca644cf64403b777451495ca659cc12415af 2025/01/13 02:44:42 DEBUG : PikPak root 'rclone-test-hiconid5qisa': Using server-side directory move 2025/01/13 02:44:42 INFO : PikPak root 'rclone-test-hiconid5qisa': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/13 02:44:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/13 02:44:42 DEBUG : empty space: Sizes identical 2025/01/13 02:44:42 DEBUG : empty space: Unchanged skipping 2025/01/13 02:44:42 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/01/13 02:44:42 DEBUG : PikPak root 'rclone-test-hiconid5qisa': Waiting for checks to finish 2025/01/13 02:44:43 INFO : potato2: Moved (server-side) 2025/01/13 02:44:44 INFO : empty space: Deleted 2025/01/13 02:44:44 DEBUG : PikPak root 'rclone-test-hiconid5qisa': Waiting for transfers to finish 2025/01/13 02:44:44 INFO : potato3: Deleted 2025/01/13 02:44:45 INFO : potato3: Moved (server-side) 2025/01/13 02:44:45 INFO : tomatoDir: Removing directory 2025/01/13 02:44:46 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': deleted 1 directories 2025/01/13 02:44:47 DEBUG : Creating backend with remote "TestPikPak:rclone-test-goyoqet7sugo" 2025/01/13 02:44:49 INFO : tomatoDir: Making directory 2025/01/13 02:44:49 DEBUG : PikPak root 'rclone-test-goyoqet7sugo': Using server-side directory move 2025/01/13 02:44:50 INFO : PikPak root 'rclone-test-goyoqet7sugo': Server side directory move succeeded 2025/01/13 02:44:51 ERROR : : error listing: directory not found 2025/01/13 02:44:51 ERROR : : error listing: directory not found 2025/01/13 02:44:51 DEBUG : PikPak root 'rclone-test-goyoqet7sugo': Purge remote 2025/01/13 02:44:52 DEBUG : PikPak root 'rclone-test-hiconid5qisa': Purge remote 2025/01/13 02:44:53 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (18.53s) === RUN TestServerSideMoveOverlap run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:1910: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.50s) === RUN TestSyncOverlap run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:44:54 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/rclone-sync-test" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncOverlap (4.23s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:44:58 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/rclone-sync-test" 2025/01/13 02:44:58 DEBUG : Config file has changed externally - reloading 2025/01/13 02:45:00 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/rclone-sync-test-include/layer2" 2025/01/13 02:45:03 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/rclone-sync-test-ignore-file" 2025/01/13 02:45:06 DEBUG : rclone-sync-test-ignore-file/.ignore: calculating gcid: failed to unwrap object from src: rclone-sync-test-ignore-file/.ignore 2025/01/13 02:45:06 DEBUG : rclone-sync-test-ignore-file/.ignore: gcid = 09f49478af9c899f0e74bf1a8e2ec667345a178b 2025/01/13 02:45:07 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/13 02:45:08 DEBUG : rclone-sync-test: Excluded 2025/01/13 02:45:08 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/13 02:45:08 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/rclone-sync-test': Waiting for checks to finish 2025/01/13 02:45:08 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/rclone-sync-test': Waiting for transfers to finish 2025/01/13 02:45:08 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:08 INFO : There was nothing to transfer 2025/01/13 02:45:09 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/13 02:45:10 DEBUG : rclone-sync-test: Excluded 2025/01/13 02:45:10 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/13 02:45:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:45:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:45:10 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:10 INFO : rclone-sync-test-include: Removing directory 2025/01/13 02:45:10 DEBUG : rclone-sync-test-include: Rmdir: contains file: "layer2" 2025/01/13 02:45:10 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/01/13 02:45:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': failed to delete 1 directories 2025/01/13 02:45:10 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:45:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/13 02:45:12 DEBUG : rclone-sync-test: Excluded 2025/01/13 02:45:12 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/13 02:45:12 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/01/13 02:45:12 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/01/13 02:45:12 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:12 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:45:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/13 02:45:13 DEBUG : rclone-sync-test: Excluded 2025/01/13 02:45:14 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/13 02:45:14 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:45:14 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:45:14 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:14 INFO : rclone-sync-test-include: Removing directory 2025/01/13 02:45:14 DEBUG : rclone-sync-test-include: Rmdir: contains file: "layer2" 2025/01/13 02:45:14 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/01/13 02:45:14 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': failed to delete 1 directories 2025/01/13 02:45:14 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:45:15 DEBUG : : Excluded 2025/01/13 02:45:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/13 02:45:15 DEBUG : rclone-sync-test: Excluded 2025/01/13 02:45:16 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/13 02:45:16 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/01/13 02:45:16 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/01/13 02:45:16 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:16 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:45:20 DEBUG : rclone-sync-test-include: Rmdir: contains trashed file: "layer2" 2025/01/13 02:45:21 DEBUG : rclone-sync-test-ignore-file: Rmdir: contains trashed file: ".ignore" --- PASS: TestSyncOverlapWithFilter (34.06s) === RUN TestSyncCompareDest run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:45:32 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:45:34 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/CompareDest" 2025/01/13 02:45:37 DEBUG : one: Need to transfer - File not found at Destination 2025/01/13 02:45:37 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:45:37 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:45:38 DEBUG : one: gcid = 74445CD742AF268FD22866DA0D12758951D638F7 2025/01/13 02:45:38 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:45:38 INFO : one: Copied (new) 2025/01/13 02:45:38 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:45:39 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:45:40 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:45:40 DEBUG : one: gcid = 9F917A3EA1B24AF2E77A328BEA3B8D3CD96D53A7 2025/01/13 02:45:41 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:45:41 INFO : one: Copied (replaced existing) 2025/01/13 02:45:41 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:42 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:45:42 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:45:45 DEBUG : CompareDest/one: calculating gcid: failed to unwrap object from src: CompareDest/one 2025/01/13 02:45:45 DEBUG : CompareDest/one: gcid = 9f917a3ea1b24af2e77a328bea3b8d3cd96d53a7 2025/01/13 02:45:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:45:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:45:47 DEBUG : one: Sizes identical 2025/01/13 02:45:47 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/13 02:45:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:45:47 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:47 INFO : There was nothing to transfer 2025/01/13 02:45:48 DEBUG : CompareDest/two: calculating gcid: failed to unwrap object from src: CompareDest/two 2025/01/13 02:45:48 DEBUG : CompareDest/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:45:50 DEBUG : two: Sizes identical 2025/01/13 02:45:50 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/13 02:45:50 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:45:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:45:50 DEBUG : one: Sizes identical 2025/01/13 02:45:50 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/13 02:45:50 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:45:50 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:50 INFO : There was nothing to transfer 2025/01/13 02:45:51 DEBUG : two: Sizes identical 2025/01/13 02:45:51 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/13 02:45:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:45:51 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:45:52 DEBUG : one: Sizes identical 2025/01/13 02:45:52 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/13 02:45:52 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:45:52 DEBUG : Waiting for deletions to finish 2025/01/13 02:45:52 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/01/13 02:45:54 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/01/13 02:45:54 DEBUG : two: Need to transfer - File not found at Destination 2025/01/13 02:45:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:45:54 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:45:54 DEBUG : one: Sizes identical 2025/01/13 02:45:54 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/13 02:45:54 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:45:54 DEBUG : two: gcid = AB96B6D2950AA019FA94C28BB7DBD117D049F997 2025/01/13 02:45:54 DEBUG : two: Dst hash empty - aborting Src hash check 2025/01/13 02:45:54 INFO : two: Copied (new) 2025/01/13 02:45:54 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:00 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:46:00 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:46:00 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:46:00 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:46:01 DEBUG : CompareDest: Rmdir: contains trashed file: "two" 2025/01/13 02:46:01 DEBUG : CompareDest: Rmdir: contains trashed file: "one" --- PASS: TestSyncCompareDest (30.73s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:46:03 DEBUG : pre-dest1/1: calculating gcid: failed to unwrap object from src: pre-dest1/1 2025/01/13 02:46:03 DEBUG : pre-dest1/1: gcid = e6cc90b878b948c35e92b003c792c46c58c4af40 2025/01/13 02:46:05 DEBUG : pre-dest2/2: calculating gcid: failed to unwrap object from src: pre-dest2/2 2025/01/13 02:46:05 DEBUG : pre-dest2/2: gcid = 12033b78389744f3f39ac4ce4ccfcad6960d8ea0 2025/01/13 02:46:06 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dest" 2025/01/13 02:46:08 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/pre-dest1" 2025/01/13 02:46:09 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/pre-dest2" 2025/01/13 02:46:11 DEBUG : 1: Sizes identical 2025/01/13 02:46:11 DEBUG : 1: Destination found in --compare-dest, skipping 2025/01/13 02:46:11 DEBUG : 2: Sizes identical 2025/01/13 02:46:11 DEBUG : 2: Destination found in --compare-dest, skipping 2025/01/13 02:46:12 DEBUG : 3: Need to transfer - File not found at Destination 2025/01/13 02:46:12 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dest': Waiting for checks to finish 2025/01/13 02:46:12 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dest': Waiting for transfers to finish 2025/01/13 02:46:13 DEBUG : 3: gcid = C4E74DDDC9CC9E2FDCDB7F63B127FB638831262E 2025/01/13 02:46:13 DEBUG : 3: Dst hash empty - aborting Src hash check 2025/01/13 02:46:13 INFO : 3: Copied (new) 2025/01/13 02:46:13 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:19 DEBUG : pre-dest2: Rmdir: contains trashed file: "2" 2025/01/13 02:46:20 DEBUG : pre-dest1: Rmdir: contains trashed file: "1" 2025/01/13 02:46:21 DEBUG : dest: Rmdir: contains trashed file: "3" --- PASS: TestSyncMultipleCompareDest (19.80s) === RUN TestSyncCopyDest run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:46:22 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:46:24 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/CopyDest" 2025/01/13 02:46:26 DEBUG : one: Need to transfer - File not found at Destination 2025/01/13 02:46:26 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:46:26 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:46:27 DEBUG : one: gcid = 74445CD742AF268FD22866DA0D12758951D638F7 2025/01/13 02:46:28 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:46:28 INFO : one: Copied (new) 2025/01/13 02:46:28 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:46:29 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:46:29 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:46:29 DEBUG : one: gcid = 9F917A3EA1B24AF2E77A328BEA3B8D3CD96D53A7 2025/01/13 02:46:31 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:46:31 INFO : one: Copied (replaced existing) 2025/01/13 02:46:31 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:32 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:46:32 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:46:34 DEBUG : CopyDest/one: calculating gcid: failed to unwrap object from src: CopyDest/one 2025/01/13 02:46:34 DEBUG : CopyDest/one: gcid = 9f917a3ea1b24af2e77a328bea3b8d3cd96d53a7 2025/01/13 02:46:36 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/BackupDir" 2025/01/13 02:46:38 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:46:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:46:39 DEBUG : one: Sizes identical 2025/01/13 02:46:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/13 02:46:41 INFO : one: Moved (server-side) 2025/01/13 02:46:42 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:46:42 DEBUG : one: Src hash empty - aborting Dst hash check 2025/01/13 02:46:42 INFO : one: Copied (server-side copy) 2025/01/13 02:46:42 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/01/13 02:46:42 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:46:42 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:44 DEBUG : CopyDest/two: calculating gcid: failed to unwrap object from src: CopyDest/two 2025/01/13 02:46:44 DEBUG : CopyDest/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:46:46 DEBUG : two: Sizes identical 2025/01/13 02:46:47 DEBUG : two: Dst hash empty - aborting Src hash check 2025/01/13 02:46:47 DEBUG : two: Src hash empty - aborting Dst hash check 2025/01/13 02:46:47 INFO : two: Copied (server-side copy) 2025/01/13 02:46:47 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/01/13 02:46:47 DEBUG : one: Sizes identical 2025/01/13 02:46:47 DEBUG : one: Unchanged skipping 2025/01/13 02:46:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:46:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:46:47 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:48 DEBUG : one: Sizes identical 2025/01/13 02:46:48 DEBUG : one: Unchanged skipping 2025/01/13 02:46:48 DEBUG : two: Sizes identical 2025/01/13 02:46:48 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:46:48 DEBUG : two: Unchanged skipping 2025/01/13 02:46:48 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:46:48 DEBUG : Waiting for deletions to finish 2025/01/13 02:46:48 INFO : There was nothing to transfer 2025/01/13 02:46:49 DEBUG : CopyDest/three: calculating gcid: failed to unwrap object from src: CopyDest/three 2025/01/13 02:46:49 DEBUG : CopyDest/three: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:46:51 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/01/13 02:46:51 DEBUG : three: Destination not found in --copy-dest 2025/01/13 02:46:51 DEBUG : three: Need to transfer - File not found at Destination 2025/01/13 02:46:51 DEBUG : two: Sizes identical 2025/01/13 02:46:51 DEBUG : two: Unchanged skipping 2025/01/13 02:46:51 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:46:51 DEBUG : one: Sizes identical 2025/01/13 02:46:51 DEBUG : one: Unchanged skipping 2025/01/13 02:46:51 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:46:52 DEBUG : three: gcid = C50301B336BB04A6BB73A9CEEB02478ED3C3EFC9 2025/01/13 02:46:52 DEBUG : three: Dst hash empty - aborting Src hash check 2025/01/13 02:46:52 INFO : three: Copied (new) 2025/01/13 02:46:52 DEBUG : Waiting for deletions to finish 2025/01/13 02:47:00 DEBUG : dst: Rmdir: contains trashed file: "three" 2025/01/13 02:47:00 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:47:00 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:47:00 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:47:00 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:47:02 DEBUG : CopyDest: Rmdir: contains trashed file: "three" 2025/01/13 02:47:02 DEBUG : CopyDest: Rmdir: contains trashed file: "two" 2025/01/13 02:47:02 DEBUG : CopyDest: Rmdir: contains trashed file: "one" 2025/01/13 02:47:03 DEBUG : BackupDir: Rmdir: contains trashed file: "one" --- PASS: TestSyncCopyDest (41.69s) === RUN TestSyncBackupDir run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:47:05 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:47:05 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:47:05 DEBUG : dst/two: calculating gcid: failed to unwrap object from src: dst/two 2025/01/13 02:47:05 DEBUG : dst/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:47:06 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:47:06 DEBUG : dst/three.txt: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:47:07 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:47:08 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/backup" 2025/01/13 02:47:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/13 02:47:10 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:47:10 DEBUG : two: Sizes identical 2025/01/13 02:47:10 DEBUG : two: Unchanged skipping 2025/01/13 02:47:12 INFO : one: Moved (server-side) 2025/01/13 02:47:12 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:47:13 DEBUG : one: gcid = 1509FA1BC93541E3447991ED8D91D1CC4B981646 2025/01/13 02:47:13 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:47:13 INFO : one: Copied (new) 2025/01/13 02:47:13 DEBUG : Waiting for deletions to finish 2025/01/13 02:47:14 INFO : three.txt: Moved (server-side) 2025/01/13 02:47:14 INFO : three.txt: Moved into backup dir 2025/01/13 02:47:16 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:47:16 DEBUG : dst/three.txt: gcid = 9b610ba42676fd6554d876b3a13b097c3df6aa10 2025/01/13 02:47:17 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/13 02:47:17 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:47:17 DEBUG : two: Sizes identical 2025/01/13 02:47:17 DEBUG : two: Unchanged skipping 2025/01/13 02:47:18 INFO : one: Deleted 2025/01/13 02:47:19 INFO : one: Moved (server-side) 2025/01/13 02:47:19 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:47:20 DEBUG : one: gcid = 3617585BC057C02ADC7E14FBEC9617C3D8FDFD4E 2025/01/13 02:47:20 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:47:20 INFO : one: Copied (new) 2025/01/13 02:47:20 DEBUG : Waiting for deletions to finish 2025/01/13 02:47:21 INFO : three.txt: Deleted 2025/01/13 02:47:23 INFO : three.txt: Moved (server-side) 2025/01/13 02:47:23 INFO : three.txt: Moved into backup dir 2025/01/13 02:47:28 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:47:28 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:47:29 DEBUG : backup: Rmdir: contains trashed file: "three.txt" 2025/01/13 02:47:29 DEBUG : backup: Rmdir: contains trashed file: "one" 2025/01/13 02:47:29 DEBUG : backup: Rmdir: contains trashed file: "three.txt" 2025/01/13 02:47:29 DEBUG : backup: Rmdir: contains trashed file: "one" --- PASS: TestSyncBackupDir (26.39s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:47:31 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:47:31 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:47:32 DEBUG : dst/two: calculating gcid: failed to unwrap object from src: dst/two 2025/01/13 02:47:32 DEBUG : dst/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:47:33 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:47:33 DEBUG : dst/three.txt: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:47:33 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:47:35 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/backup" 2025/01/13 02:47:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/13 02:47:37 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:47:37 DEBUG : two: Sizes identical 2025/01/13 02:47:37 DEBUG : two: Unchanged skipping 2025/01/13 02:47:39 INFO : one: Moved (server-side) to: one.bak 2025/01/13 02:47:39 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:47:40 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:47:40 DEBUG : one: gcid = 1509FA1BC93541E3447991ED8D91D1CC4B981646 2025/01/13 02:47:41 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:47:41 INFO : one: Copied (new) 2025/01/13 02:47:41 DEBUG : Waiting for deletions to finish 2025/01/13 02:47:42 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:47:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/13 02:47:43 INFO : three.txt: Moved into backup dir 2025/01/13 02:47:45 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:47:45 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:47:45 DEBUG : dst/three.txt: gcid = 9b610ba42676fd6554d876b3a13b097c3df6aa10 2025/01/13 02:47:47 DEBUG : two: Sizes identical 2025/01/13 02:47:47 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:47:47 DEBUG : two: Unchanged skipping 2025/01/13 02:47:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/13 02:47:48 INFO : one.bak: Deleted 2025/01/13 02:47:49 INFO : one: Moved (server-side) to: one.bak 2025/01/13 02:47:49 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:47:50 DEBUG : one: gcid = 3617585BC057C02ADC7E14FBEC9617C3D8FDFD4E 2025/01/13 02:47:50 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:47:50 INFO : one: Copied (new) 2025/01/13 02:47:50 DEBUG : Waiting for deletions to finish 2025/01/13 02:47:51 INFO : three.txt.bak: Deleted 2025/01/13 02:47:53 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/13 02:47:53 INFO : three.txt: Moved into backup dir 2025/01/13 02:47:58 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:47:58 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:47:59 DEBUG : backup: Rmdir: contains trashed file: "three.txt.bak" 2025/01/13 02:47:59 DEBUG : backup: Rmdir: contains trashed file: "one.bak" 2025/01/13 02:47:59 DEBUG : backup: Rmdir: contains trashed file: "three.txt.bak" 2025/01/13 02:47:59 DEBUG : backup: Rmdir: contains trashed file: "one.bak" --- PASS: TestSyncBackupDirWithSuffix (30.12s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:48:01 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:48:01 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:48:02 DEBUG : dst/two: calculating gcid: failed to unwrap object from src: dst/two 2025/01/13 02:48:02 DEBUG : dst/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:48:03 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:48:03 DEBUG : dst/three.txt: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:48:04 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:48:05 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/backup" 2025/01/13 02:48:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/13 02:48:06 DEBUG : two: Sizes identical 2025/01/13 02:48:06 DEBUG : two: Unchanged skipping 2025/01/13 02:48:06 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:48:09 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/13 02:48:09 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:48:09 DEBUG : one: gcid = 1509FA1BC93541E3447991ED8D91D1CC4B981646 2025/01/13 02:48:09 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:48:09 INFO : one: Copied (new) 2025/01/13 02:48:09 DEBUG : Waiting for deletions to finish 2025/01/13 02:48:11 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/13 02:48:11 INFO : three.txt: Moved into backup dir 2025/01/13 02:48:12 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:48:12 DEBUG : dst/three.txt: gcid = 9b610ba42676fd6554d876b3a13b097c3df6aa10 2025/01/13 02:48:13 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/13 02:48:13 DEBUG : two: Sizes identical 2025/01/13 02:48:13 DEBUG : two: Unchanged skipping 2025/01/13 02:48:13 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:48:15 INFO : one-2019-01-01: Deleted 2025/01/13 02:48:16 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/13 02:48:16 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:48:17 DEBUG : one: gcid = 3617585BC057C02ADC7E14FBEC9617C3D8FDFD4E 2025/01/13 02:48:17 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:48:17 INFO : one: Copied (new) 2025/01/13 02:48:17 DEBUG : Waiting for deletions to finish 2025/01/13 02:48:18 INFO : three-2019-01-01.txt: Deleted 2025/01/13 02:48:20 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/13 02:48:20 INFO : three.txt: Moved into backup dir 2025/01/13 02:48:25 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:48:25 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:48:26 DEBUG : backup: Rmdir: contains trashed file: "three-2019-01-01.txt" 2025/01/13 02:48:26 DEBUG : backup: Rmdir: contains trashed file: "one-2019-01-01" 2025/01/13 02:48:26 DEBUG : backup: Rmdir: contains trashed file: "three-2019-01-01.txt" 2025/01/13 02:48:26 DEBUG : backup: Rmdir: contains trashed file: "one-2019-01-01" --- PASS: TestSyncBackupDirWithSuffixKeepExtension (26.78s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:48:28 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:48:28 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:48:29 DEBUG : dst/two: calculating gcid: failed to unwrap object from src: dst/two 2025/01/13 02:48:29 DEBUG : dst/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:48:29 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:48:29 DEBUG : dst/three.txt: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:48:30 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:48:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/13 02:48:31 DEBUG : two: Sizes identical 2025/01/13 02:48:31 DEBUG : two: Unchanged skipping 2025/01/13 02:48:31 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:48:32 INFO : one: Moved (server-side) to: one.bak 2025/01/13 02:48:32 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:48:32 DEBUG : one: gcid = 1509FA1BC93541E3447991ED8D91D1CC4B981646 2025/01/13 02:48:33 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:48:33 INFO : one: Copied (new) 2025/01/13 02:48:33 DEBUG : Waiting for deletions to finish 2025/01/13 02:48:34 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/13 02:48:34 INFO : three.txt: Moved into backup dir 2025/01/13 02:48:35 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:48:35 DEBUG : dst/three.txt: gcid = 9b610ba42676fd6554d876b3a13b097c3df6aa10 2025/01/13 02:48:36 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/01/13 02:48:36 DEBUG : three.txt.bak: Excluded 2025/01/13 02:48:36 DEBUG : one.bak: Excluded (Path Filter) 2025/01/13 02:48:36 DEBUG : one.bak: Excluded 2025/01/13 02:48:36 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/13 02:48:36 DEBUG : two: Sizes identical 2025/01/13 02:48:36 DEBUG : two: Unchanged skipping 2025/01/13 02:48:36 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for checks to finish 2025/01/13 02:48:38 INFO : one.bak: Deleted 2025/01/13 02:48:38 INFO : one: Moved (server-side) to: one.bak 2025/01/13 02:48:38 DEBUG : PikPak root 'rclone-test-sexoqam7nobi/dst': Waiting for transfers to finish 2025/01/13 02:48:39 DEBUG : one: gcid = 3617585BC057C02ADC7E14FBEC9617C3D8FDFD4E 2025/01/13 02:48:39 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:48:39 INFO : one: Copied (new) 2025/01/13 02:48:39 DEBUG : Waiting for deletions to finish 2025/01/13 02:48:40 INFO : three.txt.bak: Deleted 2025/01/13 02:48:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/13 02:48:41 INFO : three.txt: Moved into backup dir 2025/01/13 02:48:46 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:48:46 DEBUG : dst: Rmdir: contains trashed file: "three.txt.bak" 2025/01/13 02:48:46 DEBUG : dst: Rmdir: contains trashed file: "one.bak" 2025/01/13 02:48:46 DEBUG : dst: Rmdir: contains trashed file: "three.txt.bak" 2025/01/13 02:48:46 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:48:46 DEBUG : dst: Rmdir: contains trashed file: "one.bak" --- PASS: TestSyncBackupDirSuffixOnly (20.26s) === RUN TestSyncSuffix run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:48:49 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:48:49 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:48:49 DEBUG : dst/two: calculating gcid: failed to unwrap object from src: dst/two 2025/01/13 02:48:49 DEBUG : dst/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:48:50 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:48:50 DEBUG : dst/three.txt: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:48:51 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:48:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/13 02:48:53 INFO : one: Moved (server-side) to: one.bak 2025/01/13 02:48:53 DEBUG : one: gcid = 1509FA1BC93541E3447991ED8D91D1CC4B981646 2025/01/13 02:48:54 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:48:54 INFO : one: Copied (new) 2025/01/13 02:48:54 DEBUG : two: Sizes identical 2025/01/13 02:48:54 DEBUG : two: Unchanged skipping 2025/01/13 02:48:54 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/13 02:48:55 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/13 02:48:55 DEBUG : three.txt: gcid = 9B610BA42676FD6554D876B3A13B097C3DF6AA10 2025/01/13 02:48:56 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2025/01/13 02:48:56 INFO : three.txt: Copied (new) 2025/01/13 02:48:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/13 02:48:58 INFO : one.bak: Deleted 2025/01/13 02:48:59 INFO : one: Moved (server-side) to: one.bak 2025/01/13 02:48:59 DEBUG : one: gcid = 3617585BC057C02ADC7E14FBEC9617C3D8FDFD4E 2025/01/13 02:49:00 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:49:00 INFO : one: Copied (new) 2025/01/13 02:49:00 DEBUG : two: Sizes identical 2025/01/13 02:49:00 DEBUG : two: Unchanged skipping 2025/01/13 02:49:00 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/01/13 02:49:01 INFO : three.txt.bak: Deleted 2025/01/13 02:49:02 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/13 02:49:02 DEBUG : three.txt: gcid = 85A73BA0DA83ED61A5F41795C6C6DC6F4C4191C3 2025/01/13 02:49:03 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2025/01/13 02:49:03 INFO : three.txt: Copied (new) 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "three.txt" 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "three.txt.bak" 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "one.bak" 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "three.txt.bak" 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:49:09 DEBUG : dst: Rmdir: contains trashed file: "one.bak" --- PASS: TestSyncSuffix (22.90s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:49:11 DEBUG : dst/one: calculating gcid: failed to unwrap object from src: dst/one 2025/01/13 02:49:11 DEBUG : dst/one: gcid = 74445cd742af268fd22866da0d12758951d638f7 2025/01/13 02:49:12 DEBUG : dst/two: calculating gcid: failed to unwrap object from src: dst/two 2025/01/13 02:49:12 DEBUG : dst/two: gcid = a41bee51488264c01b96de959a9142af1a75205e 2025/01/13 02:49:13 DEBUG : dst/three.txt: calculating gcid: failed to unwrap object from src: dst/three.txt 2025/01/13 02:49:13 DEBUG : dst/three.txt: gcid = aa5f498bee9d8e3a091610f6b4456e1a09763c27 2025/01/13 02:49:14 DEBUG : Creating backend with remote "TestPikPak:rclone-test-sexoqam7nobi/dst" 2025/01/13 02:49:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/13 02:49:15 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/13 02:49:16 DEBUG : one: gcid = 1509FA1BC93541E3447991ED8D91D1CC4B981646 2025/01/13 02:49:16 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:49:16 INFO : one: Copied (new) 2025/01/13 02:49:16 DEBUG : two: Sizes identical 2025/01/13 02:49:16 DEBUG : two: Unchanged skipping 2025/01/13 02:49:17 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/13 02:49:17 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/13 02:49:18 DEBUG : three.txt: gcid = 9B610BA42676FD6554D876B3A13B097C3DF6AA10 2025/01/13 02:49:18 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2025/01/13 02:49:18 INFO : three.txt: Copied (new) 2025/01/13 02:49:20 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/13 02:49:21 INFO : one-2019-01-01: Deleted 2025/01/13 02:49:21 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/13 02:49:22 DEBUG : one: gcid = 3617585BC057C02ADC7E14FBEC9617C3D8FDFD4E 2025/01/13 02:49:22 DEBUG : one: Dst hash empty - aborting Src hash check 2025/01/13 02:49:22 INFO : one: Copied (new) 2025/01/13 02:49:23 DEBUG : two: Sizes identical 2025/01/13 02:49:23 DEBUG : two: Unchanged skipping 2025/01/13 02:49:23 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/01/13 02:49:24 INFO : three-2019-01-01.txt: Deleted 2025/01/13 02:49:24 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/13 02:49:25 DEBUG : three.txt: gcid = 85A73BA0DA83ED61A5F41795C6C6DC6F4C4191C3 2025/01/13 02:49:25 DEBUG : three.txt: Dst hash empty - aborting Src hash check 2025/01/13 02:49:25 INFO : three.txt: Copied (new) 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "three.txt" 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "one" 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "three-2019-01-01.txt" 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "one-2019-01-01" 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "three-2019-01-01.txt" 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "two" 2025/01/13 02:49:32 DEBUG : dst: Rmdir: contains trashed file: "one-2019-01-01" --- PASS: TestSyncSuffixKeepExtension (22.69s) === RUN TestSyncUTFNorm run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:49:34 DEBUG : Testêé: calculating gcid: failed to unwrap object from src: Testêé 2025/01/13 02:49:34 DEBUG : Testêé: gcid = 7978fbde6e1fdd66e5d336113561e46f70fd02d8 2025/01/13 02:49:35 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/01/13 02:49:35 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:49:35 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:49:35 DEBUG : Testêé: gcid = 2B464B6C47A527D98033F9AF6D0D677E37002C45 2025/01/13 02:49:36 DEBUG : Testêé: Dst hash empty - aborting Src hash check 2025/01/13 02:49:36 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/01/13 02:49:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (5.03s) === RUN TestSyncImmutable run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:49:39 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/13 02:49:39 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:49:39 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:49:40 DEBUG : existing: gcid = 2A54A2DADF85005832B477A2CF6B042FBE28D421 2025/01/13 02:49:40 DEBUG : existing: Dst hash empty - aborting Src hash check 2025/01/13 02:49:40 INFO : existing: Copied (new) 2025/01/13 02:49:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:49:41 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/01/13 02:49:41 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/01/13 02:49:41 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:49:41 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:49:41 ERROR : PikPak root 'rclone-test-sexoqam7nobi': not deleting files as there were IO errors 2025/01/13 02:49:41 ERROR : PikPak root 'rclone-test-sexoqam7nobi': not deleting directories as there were IO errors run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncImmutable (5.20s) === RUN TestSyncIgnoreCase run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:49:44 DEBUG : EXISTING: calculating gcid: failed to unwrap object from src: EXISTING 2025/01/13 02:49:44 DEBUG : EXISTING: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:45 DEBUG : existing: Sizes identical 2025/01/13 02:49:45 DEBUG : existing: Unchanged skipping 2025/01/13 02:49:45 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:49:45 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:49:45 DEBUG : Waiting for deletions to finish 2025/01/13 02:49:45 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (2.82s) === RUN TestFixCase run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.64s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (1.41s) --- SKIP: TestMaxTransfer/Hard (0.48s) --- SKIP: TestMaxTransfer/Soft (0.47s) --- SKIP: TestMaxTransfer/Cautious (0.46s) === RUN TestSyncConcurrentDelete run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:49:49 DEBUG : both0: calculating gcid: failed to unwrap object from src: both0 2025/01/13 02:49:49 DEBUG : both0: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:49 DEBUG : only0: calculating gcid: failed to unwrap object from src: only0 2025/01/13 02:49:49 DEBUG : only0: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:50 DEBUG : both1: calculating gcid: failed to unwrap object from src: both1 2025/01/13 02:49:50 DEBUG : both1: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:50 DEBUG : only1: calculating gcid: failed to unwrap object from src: only1 2025/01/13 02:49:50 DEBUG : only1: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:51 DEBUG : both2: calculating gcid: failed to unwrap object from src: both2 2025/01/13 02:49:51 DEBUG : both2: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:52 DEBUG : only2: calculating gcid: failed to unwrap object from src: only2 2025/01/13 02:49:52 DEBUG : only2: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:52 DEBUG : both3: calculating gcid: failed to unwrap object from src: both3 2025/01/13 02:49:52 DEBUG : both3: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:53 DEBUG : only3: calculating gcid: failed to unwrap object from src: only3 2025/01/13 02:49:53 DEBUG : only3: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:53 DEBUG : both4: calculating gcid: failed to unwrap object from src: both4 2025/01/13 02:49:53 DEBUG : both4: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:54 DEBUG : only4: calculating gcid: failed to unwrap object from src: only4 2025/01/13 02:49:54 DEBUG : only4: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:55 DEBUG : both5: calculating gcid: failed to unwrap object from src: both5 2025/01/13 02:49:55 DEBUG : both5: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:55 DEBUG : only5: calculating gcid: failed to unwrap object from src: only5 2025/01/13 02:49:55 DEBUG : only5: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:56 DEBUG : both6: calculating gcid: failed to unwrap object from src: both6 2025/01/13 02:49:56 DEBUG : both6: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:57 DEBUG : only6: calculating gcid: failed to unwrap object from src: only6 2025/01/13 02:49:57 DEBUG : only6: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:57 DEBUG : both7: calculating gcid: failed to unwrap object from src: both7 2025/01/13 02:49:57 DEBUG : both7: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:58 DEBUG : only7: calculating gcid: failed to unwrap object from src: only7 2025/01/13 02:49:58 DEBUG : only7: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:59 DEBUG : both8: calculating gcid: failed to unwrap object from src: both8 2025/01/13 02:49:59 DEBUG : both8: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:49:59 DEBUG : only8: calculating gcid: failed to unwrap object from src: only8 2025/01/13 02:49:59 DEBUG : only8: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:00 DEBUG : both9: calculating gcid: failed to unwrap object from src: both9 2025/01/13 02:50:00 DEBUG : both9: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:01 DEBUG : only9: calculating gcid: failed to unwrap object from src: only9 2025/01/13 02:50:01 DEBUG : only9: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:01 DEBUG : both10: calculating gcid: failed to unwrap object from src: both10 2025/01/13 02:50:01 DEBUG : both10: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:02 DEBUG : only10: calculating gcid: failed to unwrap object from src: only10 2025/01/13 02:50:02 DEBUG : only10: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:03 DEBUG : both11: calculating gcid: failed to unwrap object from src: both11 2025/01/13 02:50:03 DEBUG : both11: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:03 DEBUG : only11: calculating gcid: failed to unwrap object from src: only11 2025/01/13 02:50:03 DEBUG : only11: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:04 DEBUG : both12: calculating gcid: failed to unwrap object from src: both12 2025/01/13 02:50:04 DEBUG : both12: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:05 DEBUG : only12: calculating gcid: failed to unwrap object from src: only12 2025/01/13 02:50:05 DEBUG : only12: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:05 DEBUG : both13: calculating gcid: failed to unwrap object from src: both13 2025/01/13 02:50:05 DEBUG : both13: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:06 DEBUG : only13: calculating gcid: failed to unwrap object from src: only13 2025/01/13 02:50:06 DEBUG : only13: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:07 DEBUG : both14: calculating gcid: failed to unwrap object from src: both14 2025/01/13 02:50:07 DEBUG : both14: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:07 DEBUG : only14: calculating gcid: failed to unwrap object from src: only14 2025/01/13 02:50:07 DEBUG : only14: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:08 DEBUG : both15: calculating gcid: failed to unwrap object from src: both15 2025/01/13 02:50:08 DEBUG : both15: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:09 DEBUG : only15: calculating gcid: failed to unwrap object from src: only15 2025/01/13 02:50:09 DEBUG : only15: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:10 DEBUG : both16: calculating gcid: failed to unwrap object from src: both16 2025/01/13 02:50:10 DEBUG : both16: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:11 DEBUG : only16: calculating gcid: failed to unwrap object from src: only16 2025/01/13 02:50:11 DEBUG : only16: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:12 DEBUG : both17: calculating gcid: failed to unwrap object from src: both17 2025/01/13 02:50:12 DEBUG : both17: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:13 DEBUG : only17: calculating gcid: failed to unwrap object from src: only17 2025/01/13 02:50:13 DEBUG : only17: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:13 DEBUG : both18: calculating gcid: failed to unwrap object from src: both18 2025/01/13 02:50:13 DEBUG : both18: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:14 DEBUG : only18: calculating gcid: failed to unwrap object from src: only18 2025/01/13 02:50:14 DEBUG : only18: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:15 DEBUG : both19: calculating gcid: failed to unwrap object from src: both19 2025/01/13 02:50:15 DEBUG : both19: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:16 DEBUG : only19: calculating gcid: failed to unwrap object from src: only19 2025/01/13 02:50:16 DEBUG : only19: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:17 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:50:17 DEBUG : both0: Sizes identical 2025/01/13 02:50:17 DEBUG : both10: Sizes identical 2025/01/13 02:50:17 DEBUG : both1: Sizes identical 2025/01/13 02:50:17 DEBUG : both11: Sizes identical 2025/01/13 02:50:17 DEBUG : both0: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both12: Sizes identical 2025/01/13 02:50:17 DEBUG : both10: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both13: Sizes identical 2025/01/13 02:50:17 DEBUG : both1: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both14: Sizes identical 2025/01/13 02:50:17 DEBUG : both14: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both15: Sizes identical 2025/01/13 02:50:17 DEBUG : both15: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both16: Sizes identical 2025/01/13 02:50:17 DEBUG : both16: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both17: Sizes identical 2025/01/13 02:50:17 DEBUG : both11: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both18: Sizes identical 2025/01/13 02:50:17 DEBUG : both18: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both19: Sizes identical 2025/01/13 02:50:17 DEBUG : both12: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both13: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both3: Sizes identical 2025/01/13 02:50:17 DEBUG : both2: Sizes identical 2025/01/13 02:50:17 DEBUG : both3: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both4: Sizes identical 2025/01/13 02:50:17 DEBUG : both4: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both5: Sizes identical 2025/01/13 02:50:17 DEBUG : both5: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both6: Sizes identical 2025/01/13 02:50:17 DEBUG : both6: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both7: Sizes identical 2025/01/13 02:50:17 DEBUG : both2: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both8: Sizes identical 2025/01/13 02:50:17 DEBUG : both8: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both9: Sizes identical 2025/01/13 02:50:17 DEBUG : both9: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both19: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both17: Unchanged skipping 2025/01/13 02:50:17 DEBUG : both7: Unchanged skipping 2025/01/13 02:50:17 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:50:17 DEBUG : Waiting for deletions to finish 2025/01/13 02:50:18 INFO : only7: Deleted 2025/01/13 02:50:18 INFO : only14: Deleted 2025/01/13 02:50:18 INFO : only12: Deleted 2025/01/13 02:50:19 INFO : only1: Deleted 2025/01/13 02:50:19 INFO : only17: Deleted 2025/01/13 02:50:19 INFO : only5: Deleted 2025/01/13 02:50:19 INFO : only13: Deleted 2025/01/13 02:50:20 INFO : only16: Deleted 2025/01/13 02:50:20 INFO : only2: Deleted 2025/01/13 02:50:20 INFO : only3: Deleted 2025/01/13 02:50:20 INFO : only8: Deleted 2025/01/13 02:50:21 INFO : only9: Deleted 2025/01/13 02:50:21 INFO : only0: Deleted 2025/01/13 02:50:21 INFO : only11: Deleted 2025/01/13 02:50:22 INFO : only15: Deleted 2025/01/13 02:50:22 INFO : only19: Deleted 2025/01/13 02:50:22 INFO : only10: Deleted 2025/01/13 02:50:22 INFO : only18: Deleted 2025/01/13 02:50:23 INFO : only4: Deleted 2025/01/13 02:50:23 INFO : only6: Deleted 2025/01/13 02:50:23 INFO : There was nothing to transfer run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" --- PASS: TestSyncConcurrentDelete (55.43s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:50:44 DEBUG : both0: calculating gcid: failed to unwrap object from src: both0 2025/01/13 02:50:44 DEBUG : both0: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:45 DEBUG : only0: calculating gcid: failed to unwrap object from src: only0 2025/01/13 02:50:45 DEBUG : only0: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:45 DEBUG : both1: calculating gcid: failed to unwrap object from src: both1 2025/01/13 02:50:45 DEBUG : both1: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:46 DEBUG : only1: calculating gcid: failed to unwrap object from src: only1 2025/01/13 02:50:46 DEBUG : only1: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:46 DEBUG : both2: calculating gcid: failed to unwrap object from src: both2 2025/01/13 02:50:46 DEBUG : both2: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:47 DEBUG : only2: calculating gcid: failed to unwrap object from src: only2 2025/01/13 02:50:47 DEBUG : only2: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:47 DEBUG : both3: calculating gcid: failed to unwrap object from src: both3 2025/01/13 02:50:47 DEBUG : both3: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:48 DEBUG : only3: calculating gcid: failed to unwrap object from src: only3 2025/01/13 02:50:48 DEBUG : only3: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:49 DEBUG : both4: calculating gcid: failed to unwrap object from src: both4 2025/01/13 02:50:49 DEBUG : both4: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:49 DEBUG : only4: calculating gcid: failed to unwrap object from src: only4 2025/01/13 02:50:49 DEBUG : only4: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:50 DEBUG : both5: calculating gcid: failed to unwrap object from src: both5 2025/01/13 02:50:50 DEBUG : both5: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:51 DEBUG : only5: calculating gcid: failed to unwrap object from src: only5 2025/01/13 02:50:51 DEBUG : only5: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:51 DEBUG : both6: calculating gcid: failed to unwrap object from src: both6 2025/01/13 02:50:51 DEBUG : both6: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:52 DEBUG : only6: calculating gcid: failed to unwrap object from src: only6 2025/01/13 02:50:52 DEBUG : only6: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:53 DEBUG : both7: calculating gcid: failed to unwrap object from src: both7 2025/01/13 02:50:53 DEBUG : both7: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:53 DEBUG : only7: calculating gcid: failed to unwrap object from src: only7 2025/01/13 02:50:53 DEBUG : only7: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:54 DEBUG : both8: calculating gcid: failed to unwrap object from src: both8 2025/01/13 02:50:54 DEBUG : both8: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:55 DEBUG : only8: calculating gcid: failed to unwrap object from src: only8 2025/01/13 02:50:55 DEBUG : only8: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:55 DEBUG : both9: calculating gcid: failed to unwrap object from src: both9 2025/01/13 02:50:55 DEBUG : both9: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:56 DEBUG : only9: calculating gcid: failed to unwrap object from src: only9 2025/01/13 02:50:56 DEBUG : only9: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:57 DEBUG : both10: calculating gcid: failed to unwrap object from src: both10 2025/01/13 02:50:57 DEBUG : both10: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:57 DEBUG : only10: calculating gcid: failed to unwrap object from src: only10 2025/01/13 02:50:57 DEBUG : only10: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:50:59 DEBUG : both11: calculating gcid: failed to unwrap object from src: both11 2025/01/13 02:50:59 DEBUG : both11: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:00 DEBUG : only11: calculating gcid: failed to unwrap object from src: only11 2025/01/13 02:51:00 DEBUG : only11: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:00 DEBUG : both12: calculating gcid: failed to unwrap object from src: both12 2025/01/13 02:51:00 DEBUG : both12: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:01 DEBUG : only12: calculating gcid: failed to unwrap object from src: only12 2025/01/13 02:51:01 DEBUG : only12: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:01 DEBUG : both13: calculating gcid: failed to unwrap object from src: both13 2025/01/13 02:51:01 DEBUG : both13: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:02 DEBUG : only13: calculating gcid: failed to unwrap object from src: only13 2025/01/13 02:51:02 DEBUG : only13: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:03 DEBUG : both14: calculating gcid: failed to unwrap object from src: both14 2025/01/13 02:51:03 DEBUG : both14: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:04 DEBUG : only14: calculating gcid: failed to unwrap object from src: only14 2025/01/13 02:51:04 DEBUG : only14: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:05 DEBUG : both15: calculating gcid: failed to unwrap object from src: both15 2025/01/13 02:51:05 DEBUG : both15: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:05 DEBUG : only15: calculating gcid: failed to unwrap object from src: only15 2025/01/13 02:51:05 DEBUG : only15: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:06 DEBUG : both16: calculating gcid: failed to unwrap object from src: both16 2025/01/13 02:51:06 DEBUG : both16: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:06 DEBUG : only16: calculating gcid: failed to unwrap object from src: only16 2025/01/13 02:51:06 DEBUG : only16: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:07 DEBUG : both17: calculating gcid: failed to unwrap object from src: both17 2025/01/13 02:51:07 DEBUG : both17: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:08 DEBUG : only17: calculating gcid: failed to unwrap object from src: only17 2025/01/13 02:51:08 DEBUG : only17: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:08 DEBUG : both18: calculating gcid: failed to unwrap object from src: both18 2025/01/13 02:51:08 DEBUG : both18: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:09 DEBUG : only18: calculating gcid: failed to unwrap object from src: only18 2025/01/13 02:51:09 DEBUG : only18: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:10 DEBUG : both19: calculating gcid: failed to unwrap object from src: both19 2025/01/13 02:51:10 DEBUG : both19: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:10 DEBUG : only19: calculating gcid: failed to unwrap object from src: only19 2025/01/13 02:51:10 DEBUG : only19: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421 2025/01/13 02:51:11 DEBUG : both0: Sizes identical 2025/01/13 02:51:11 DEBUG : both1: Sizes identical 2025/01/13 02:51:11 DEBUG : both10: Sizes identical 2025/01/13 02:51:11 DEBUG : both11: Sizes identical 2025/01/13 02:51:11 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for checks to finish 2025/01/13 02:51:11 DEBUG : both0: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both12: Sizes identical 2025/01/13 02:51:11 DEBUG : both12: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both13: Sizes identical 2025/01/13 02:51:11 DEBUG : both13: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both14: Sizes identical 2025/01/13 02:51:11 DEBUG : both1: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both15: Sizes identical 2025/01/13 02:51:11 DEBUG : both15: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both16: Sizes identical 2025/01/13 02:51:11 DEBUG : both10: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both17: Sizes identical 2025/01/13 02:51:11 DEBUG : both17: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both18: Sizes identical 2025/01/13 02:51:11 DEBUG : both18: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both19: Sizes identical 2025/01/13 02:51:11 DEBUG : both19: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both2: Sizes identical 2025/01/13 02:51:11 DEBUG : both2: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both3: Sizes identical 2025/01/13 02:51:11 DEBUG : both3: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both4: Sizes identical 2025/01/13 02:51:11 DEBUG : both4: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both5: Sizes identical 2025/01/13 02:51:11 DEBUG : both5: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both6: Sizes identical 2025/01/13 02:51:11 DEBUG : both6: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both7: Sizes identical 2025/01/13 02:51:11 DEBUG : both7: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both8: Sizes identical 2025/01/13 02:51:11 DEBUG : both8: Unchanged skipping 2025/01/13 02:51:11 DEBUG : both9: Sizes identical 2025/01/13 02:51:11 DEBUG : both9: Unchanged skipping 2025/01/13 02:51:11 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only0: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:11 DEBUG : only1: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:11 DEBUG : both11: Unchanged skipping 2025/01/13 02:51:11 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only11: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:11 DEBUG : only10: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:11 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:11 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:12 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:12 DEBUG : both14: Unchanged skipping 2025/01/13 02:51:12 DEBUG : both16: Unchanged skipping 2025/01/13 02:51:12 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:12 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/01/13 02:51:12 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Waiting for transfers to finish 2025/01/13 02:51:12 DEBUG : pacer: low level retry 1/10 (error Error "captcha_invalid" (9): Verification code is invalid) 2025/01/13 02:51:12 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/13 02:51:13 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:51:13 DEBUG : pacer: low level retry 1/10 (error Error "captcha_invalid" (9): Verification code is invalid) 2025/01/13 02:51:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/01/13 02:51:13 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:51:13 DEBUG : pacer: low level retry 1/10 (error Error "captcha_invalid" (9): Verification code is invalid) 2025/01/13 02:51:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/01/13 02:51:14 DEBUG : pacer: Reducing sleep to 600ms 2025/01/13 02:51:14 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/01/13 02:51:14 DEBUG : pacer: Reducing sleep to 450ms 2025/01/13 02:51:14 DEBUG : pacer: Reducing sleep to 337.5ms 2025/01/13 02:51:14 DEBUG : pacer: Reducing sleep to 253.125ms 2025/01/13 02:51:14 DEBUG : pacer: Reducing sleep to 189.84375ms 2025/01/13 02:51:15 DEBUG : pacer: Reducing sleep to 142.382812ms 2025/01/13 02:51:15 DEBUG : pacer: Reducing sleep to 106.787109ms 2025/01/13 02:51:15 DEBUG : pacer: Reducing sleep to 100ms 2025/01/13 02:51:16 DEBUG : only11: Dst hash empty - aborting Src hash check 2025/01/13 02:51:16 INFO : only11: Copied (replaced existing) 2025/01/13 02:51:16 DEBUG : only12: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:16 DEBUG : only10: Dst hash empty - aborting Src hash check 2025/01/13 02:51:16 INFO : only10: Copied (replaced existing) 2025/01/13 02:51:16 DEBUG : only13: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:16 DEBUG : only1: Dst hash empty - aborting Src hash check 2025/01/13 02:51:16 INFO : only1: Copied (replaced existing) 2025/01/13 02:51:16 DEBUG : only14: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:16 DEBUG : only0: Dst hash empty - aborting Src hash check 2025/01/13 02:51:16 INFO : only0: Copied (replaced existing) 2025/01/13 02:51:16 DEBUG : only15: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:17 DEBUG : only12: Dst hash empty - aborting Src hash check 2025/01/13 02:51:17 INFO : only12: Copied (replaced existing) 2025/01/13 02:51:17 DEBUG : only16: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:18 DEBUG : only13: Dst hash empty - aborting Src hash check 2025/01/13 02:51:18 INFO : only13: Copied (replaced existing) 2025/01/13 02:51:18 DEBUG : only17: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:18 DEBUG : only14: Dst hash empty - aborting Src hash check 2025/01/13 02:51:18 INFO : only14: Copied (replaced existing) 2025/01/13 02:51:18 DEBUG : only18: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:18 DEBUG : only15: Dst hash empty - aborting Src hash check 2025/01/13 02:51:18 INFO : only15: Copied (replaced existing) 2025/01/13 02:51:18 DEBUG : only19: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:19 DEBUG : only16: Dst hash empty - aborting Src hash check 2025/01/13 02:51:19 INFO : only16: Copied (replaced existing) 2025/01/13 02:51:19 DEBUG : only2: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:20 DEBUG : only17: Dst hash empty - aborting Src hash check 2025/01/13 02:51:20 INFO : only17: Copied (replaced existing) 2025/01/13 02:51:20 DEBUG : only4: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:20 DEBUG : only19: Dst hash empty - aborting Src hash check 2025/01/13 02:51:20 INFO : only19: Copied (replaced existing) 2025/01/13 02:51:20 DEBUG : only5: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:20 DEBUG : only18: Dst hash empty - aborting Src hash check 2025/01/13 02:51:20 INFO : only18: Copied (replaced existing) 2025/01/13 02:51:20 DEBUG : only6: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:21 DEBUG : only2: Dst hash empty - aborting Src hash check 2025/01/13 02:51:21 INFO : only2: Copied (replaced existing) 2025/01/13 02:51:21 DEBUG : only3: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:21 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:21 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/13 02:51:22 DEBUG : pacer: Reducing sleep to 150ms 2025/01/13 02:51:22 DEBUG : only4: Dst hash empty - aborting Src hash check 2025/01/13 02:51:22 INFO : only4: Copied (replaced existing) 2025/01/13 02:51:22 DEBUG : only7: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:22 DEBUG : pacer: Reducing sleep to 112.5ms 2025/01/13 02:51:22 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:22 DEBUG : pacer: Rate limited, increasing sleep to 225ms 2025/01/13 02:51:22 DEBUG : pacer: Reducing sleep to 168.75ms 2025/01/13 02:51:22 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/01/13 02:51:22 DEBUG : pacer: low level retry 3/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:22 DEBUG : pacer: Rate limited, increasing sleep to 253.125ms 2025/01/13 02:51:22 DEBUG : pacer: Reducing sleep to 189.84375ms 2025/01/13 02:51:23 DEBUG : pacer: Reducing sleep to 142.382812ms 2025/01/13 02:51:23 DEBUG : only6: Dst hash empty - aborting Src hash check 2025/01/13 02:51:23 INFO : only6: Copied (replaced existing) 2025/01/13 02:51:23 DEBUG : only9: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:23 DEBUG : pacer: Reducing sleep to 106.787109ms 2025/01/13 02:51:23 DEBUG : pacer: low level retry 4/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:23 DEBUG : pacer: Rate limited, increasing sleep to 213.574218ms 2025/01/13 02:51:23 DEBUG : pacer: Reducing sleep to 160.180663ms 2025/01/13 02:51:23 DEBUG : pacer: Reducing sleep to 120.135497ms 2025/01/13 02:51:23 DEBUG : only3: Dst hash empty - aborting Src hash check 2025/01/13 02:51:23 INFO : only3: Copied (replaced existing) 2025/01/13 02:51:23 DEBUG : only8: gcid = DA39A3EE5E6B4B0D3255BFEF95601890AFD80709 2025/01/13 02:51:23 DEBUG : pacer: Reducing sleep to 100ms 2025/01/13 02:51:24 DEBUG : pacer: low level retry 5/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:24 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/13 02:51:24 DEBUG : pacer: Reducing sleep to 150ms 2025/01/13 02:51:24 DEBUG : pacer: Reducing sleep to 112.5ms 2025/01/13 02:51:24 DEBUG : pacer: Reducing sleep to 100ms 2025/01/13 02:51:24 DEBUG : only7: Dst hash empty - aborting Src hash check 2025/01/13 02:51:24 INFO : only7: Copied (replaced existing) 2025/01/13 02:51:24 DEBUG : pacer: low level retry 6/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:24 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/01/13 02:51:24 DEBUG : pacer: Reducing sleep to 150ms 2025/01/13 02:51:24 DEBUG : pacer: low level retry 7/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:24 DEBUG : pacer: Rate limited, increasing sleep to 300ms 2025/01/13 02:51:25 DEBUG : pacer: Reducing sleep to 225ms 2025/01/13 02:51:25 DEBUG : pacer: low level retry 8/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:25 DEBUG : pacer: Rate limited, increasing sleep to 450ms 2025/01/13 02:51:25 DEBUG : pacer: Reducing sleep to 337.5ms 2025/01/13 02:51:25 DEBUG : only9: Dst hash empty - aborting Src hash check 2025/01/13 02:51:25 INFO : only9: Copied (replaced existing) 2025/01/13 02:51:25 DEBUG : pacer: low level retry 9/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:25 DEBUG : pacer: Rate limited, increasing sleep to 675ms 2025/01/13 02:51:26 DEBUG : pacer: Reducing sleep to 506.25ms 2025/01/13 02:51:26 DEBUG : pacer: low level retry 10/10 (error trash (trash) is still in PHASE_TYPE_RUNNING) 2025/01/13 02:51:26 DEBUG : pacer: Rate limited, increasing sleep to 1.0125s 2025/01/13 02:51:26 ERROR : only5: Failed to copy: failed to remove old object: delete object failed: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOGSZejRVrJFLLLIj-kmJAW1o1", Name:"trash", Type:"trash", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000967400), FileID:"", FileName:"", FileSize:"0", Message:"Saving", CreatedTime:api.Time{wall:0x37131f00, ext:63872333480, loc:(*time.Location)(0xc000403030)}, UpdatedTime:api.Time{wall:0x38be5e00, ext:63872333480, loc:(*time.Location)(0xc000403030)}, ThirdTaskID:"", Phase:"PHASE_TYPE_RUNNING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} 2025/01/13 02:51:27 DEBUG : pacer: Reducing sleep to 759.375ms 2025/01/13 02:51:27 DEBUG : only8: Dst hash empty - aborting Src hash check 2025/01/13 02:51:27 INFO : only8: Copied (replaced existing) 2025/01/13 02:51:27 ERROR : PikPak root 'rclone-test-sexoqam7nobi': not deleting files as there were IO errors 2025/01/13 02:51:27 ERROR : PikPak root 'rclone-test-sexoqam7nobi': not deleting directories as there were IO errors run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" sync_test.go:2752: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2752 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Received unexpected error: failed to remove old object: delete object failed: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOGSZejRVrJFLLLIj-kmJAW1o1", Name:"trash", Type:"trash", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0xc000967400), FileID:"", FileName:"", FileSize:"0", Message:"Saving", CreatedTime:api.Time{wall:0x37131f00, ext:63872333480, loc:(*time.Location)(0xc000403030)}, UpdatedTime:api.Time{wall:0x38be5e00, ext:63872333480, loc:(*time.Location)(0xc000403030)}, ThirdTaskID:"", Phase:"PHASE_TYPE_RUNNING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""} Test: TestSyncConcurrentTruncate Messages: Sync must not return a error sync_test.go:2753: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2753 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be false Test: TestSyncConcurrentTruncate Messages: Low level errors must not have happened 2025/01/13 02:51:28 DEBUG : pacer: Reducing sleep to 569.53125ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2025/01/13 02:51:30 DEBUG : pacer: Reducing sleep to 427.148437ms 2025/01/13 02:51:31 DEBUG : pacer: Reducing sleep to 320.361327ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2025/01/13 02:51:33 DEBUG : pacer: Reducing sleep to 240.270995ms 2025/01/13 02:51:34 DEBUG : pacer: Reducing sleep to 180.203246ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (6), only6 (0), only7 (0), only8 (0), only9 (0), rcloneTempdogudas2 (0) fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Not equal: expected: 0 actual : 6 Test: TestSyncConcurrentTruncate Messages: only5: size incorrect file=0 vs obj=6 fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be true Test: TestSyncConcurrentTruncate Messages: Unexpected file "rcloneTempdogudas2" 2025/01/13 02:51:38 DEBUG : pacer: Reducing sleep to 135.152434ms 2025/01/13 02:51:39 DEBUG : pacer: Reducing sleep to 101.364325ms 2025/01/13 02:51:39 DEBUG : pacer: Reducing sleep to 100ms --- FAIL: TestSyncConcurrentTruncate (96.47s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:52:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/13 02:52:20 INFO : sub dir: Making directory 2025/01/13 02:52:21 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:52:39 DEBUG : sub dir2/very/very/very/very/very/nested/subdir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:52:40 DEBUG : sub dir2/very/very/very/very/very/nested: Rmdir: contains trashed file: "subdir" 2025/01/13 02:52:41 DEBUG : sub dir2/very/very/very/very/very: Rmdir: contains trashed file: "nested" 2025/01/13 02:52:43 DEBUG : sub dir2/very/very/very/very: Rmdir: contains trashed file: "very" 2025/01/13 02:52:44 DEBUG : sub dir2/very/very/very: Rmdir: contains trashed file: "very" 2025/01/13 02:52:45 DEBUG : sub dir2/very/very: Rmdir: contains trashed file: "very" 2025/01/13 02:52:47 DEBUG : sub dir2/very: Rmdir: contains trashed file: "very" 2025/01/13 02:52:48 DEBUG : sub dir2: Rmdir: contains trashed file: "very" 2025/01/13 02:52:49 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestNothingToTransferWithEmptyDirs (31.16s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:52:51 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/13 02:52:51 INFO : sub dir: Making directory 2025/01/13 02:52:52 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" run.go:180: Remote "PikPak root 'rclone-test-sexoqam7nobi'", Local "Local file system at /tmp/rclone3732950820", Modify Window "876000h0m0s" 2025/01/13 02:53:11 DEBUG : sub dir2/very/very/very/very/very/nested/subdir: Rmdir: contains trashed file: "hello world" 2025/01/13 02:53:12 DEBUG : sub dir2/very/very/very/very/very/nested: Rmdir: contains trashed file: "subdir" 2025/01/13 02:53:14 DEBUG : sub dir2/very/very/very/very/very: Rmdir: contains trashed file: "nested" 2025/01/13 02:53:15 DEBUG : sub dir2/very/very/very/very: Rmdir: contains trashed file: "very" 2025/01/13 02:53:16 DEBUG : sub dir2/very/very/very: Rmdir: contains trashed file: "very" 2025/01/13 02:53:18 DEBUG : sub dir2/very/very: Rmdir: contains trashed file: "very" 2025/01/13 02:53:19 DEBUG : sub dir2/very: Rmdir: contains trashed file: "very" 2025/01/13 02:53:20 DEBUG : sub dir2: Rmdir: contains trashed file: "very" 2025/01/13 02:53:21 DEBUG : sub dir: Rmdir: contains trashed file: "hello world" --- PASS: TestNothingToTransferWithoutEmptyDirs (32.28s) FAIL 2025/01/13 02:53:24 DEBUG : PikPak root 'rclone-test-sexoqam7nobi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose" - Finished ERROR in 15m30.268044915s (try 1/5): exit status 1: Failed [TestServerSideCopyOverSelf TestSyncConcurrentTruncate]