"./sync.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose" - Starting (try 5/5) 2025/09/17 05:09:44 DEBUG : Creating backend with remote "TestUlozto:rclone-test-haqahoy5joco" 2025/09/17 05:09:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/17 05:09:46 DEBUG : Creating backend with remote "/tmp/rclone3099255192" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === 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 "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:09:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:09:48 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/09/17 05:09:48 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:09:48 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (2.07s) === RUN TestCopy run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:09:48 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/17 05:09:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:09:49 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:09:49 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:09:51 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:09:51 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:09:51 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (4.25s) === RUN TestCopyMetadata run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.47s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.48s) === RUN TestCopyMissingDirectory run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:09:54 DEBUG : Creating backend with remote "/non-existing" 2025/09/17 05:09:54 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/09/17 05:09:54 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:09:54 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.69s) === RUN TestCopyNoTraverse run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:09:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:09:55 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:09:55 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:09:56 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:09:56 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (3.41s) === RUN TestCopyNoTraverseDeadlock run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" sync_test.go:222: Only runs on local --- SKIP: TestCopyNoTraverseDeadlock (0.46s) === RUN TestCopyCheckFirst run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:09:58 INFO : uloz.to root 'rclone-test-haqahoy5joco': Running all checks before starting transfers 2025/09/17 05:09:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:09:58 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:09:58 INFO : uloz.to root 'rclone-test-haqahoy5joco': Checks finished, now starting transfers 2025/09/17 05:09:58 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:00 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:00 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (3.39s) === RUN TestSyncNoTraverse run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:02 ERROR : Ignoring --no-traverse with sync 2025/09/17 05:10:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:02 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:02 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:03 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:03 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:10:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (3.58s) === RUN TestCopyWithDepth run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:05 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/09/17 05:10:05 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:05 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:06 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2025/09/17 05:10:06 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.94s) === RUN TestCopyWithFilesFrom run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:07 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/17 05:10:07 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:07 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:08 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:08 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.11s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/09/17 05:10:09 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:09 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:10 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:10 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (2.03s) === RUN TestCopyEmptyDirectories run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:11 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/09/17 05:10:11 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/17 05:10:11 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/09/17 05:10:11 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/17 05:10:11 INFO : sub dir2: Making directory 2025/09/17 05:10:12 INFO : sub dir: Making directory 2025/09/17 05:10:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:12 INFO : sub dir2/sub sub dir2: Making directory 2025/09/17 05:10:13 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:13 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:13 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:13 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:10:14 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (4.63s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:16 INFO : sub dir2: Making directory 2025/09/17 05:10:16 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/09/17 05:10:16 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/17 05:10:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:16 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:16 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:17 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (3.16s) === RUN TestMoveEmptyDirectories run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:19 DEBUG : sub dir2: Making directory with metadata 2025/09/17 05:10:19 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/17 05:10:19 INFO : sub dir2: Making directory 2025/09/17 05:10:20 INFO : sub dir: Making directory 2025/09/17 05:10:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:20 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:20 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:21 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:21 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:10:21 INFO : sub dir/hello world: Deleted 2025/09/17 05:10:21 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/09/17 05:10:22 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (4.26s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.46s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:24 INFO : sub dir2: Making directory 2025/09/17 05:10:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:24 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:24 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:25 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:25 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:10:25 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (3.27s) === RUN TestSyncEmptyDirectories run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:27 DEBUG : sub dir2: Making directory with metadata 2025/09/17 05:10:27 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/09/17 05:10:27 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/09/17 05:10:27 INFO : sub dir2: Making directory 2025/09/17 05:10:28 INFO : sub dir: Making directory 2025/09/17 05:10:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:28 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:28 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:29 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:29 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:10:29 DEBUG : Waiting for deletions to finish 2025/09/17 05:10:29 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (3.92s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.48s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:31 INFO : sub dir2: Making directory 2025/09/17 05:10:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:32 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:10:32 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:10:33 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/09/17 05:10:33 INFO : sub dir/hello world: Copied (new) 2025/09/17 05:10:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (3.15s) === RUN TestServerSideCopy run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:10:36 DEBUG : Creating backend with remote "TestUlozto:rclone-test-jugutik2xode" sync_test.go:620: Server side copy (if possible) uloz.to root 'rclone-test-haqahoy5joco' -> uloz.to root 'rclone-test-jugutik2xode' 2025/09/17 05:10:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:10:40 DEBUG : uloz.to root 'rclone-test-jugutik2xode': Waiting for checks to finish 2025/09/17 05:10:40 DEBUG : uloz.to root 'rclone-test-jugutik2xode': Waiting for transfers to finish 2025/09/17 05:11:40 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:11:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:12:40 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:12:40 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/17 05:13:40 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:13:40 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/17 05:14:40 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:14:40 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/09/17 05:16:17 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 05:16:17 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/09/17 05:17:17 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:17:17 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/09/17 05:18:17 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:18:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/09/17 05:19:17 DEBUG : pacer: low level retry 8/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:19:17 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 05:20:18 DEBUG : pacer: low level retry 9/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:21:20 DEBUG : pacer: low level retry 10/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:21:20 DEBUG : sub dir/hello world: Received error: failed to open source object: Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1956947997;tm=1758086440;ut=vd;rs=0;fet=download_cloud;He;ch=88edb6b716b542377042071e24a182db;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout - low level retry 0/10 2025/09/17 05:21:20 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 05:22:23 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:22:23 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 05:23:23 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:24:24 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:25:24 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 05:26:24 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 05:27:24 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 05:28:24 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:29:24 DEBUG : pacer: low level retry 8/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:30:24 DEBUG : pacer: low level retry 9/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:31:25 DEBUG : pacer: low level retry 10/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 05:31:25 DEBUG : sub dir/hello world: Received error: failed to open source object: Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1703502196;tm=1758087080;ut=vd;rs=0;fet=download_cloud;He;ch=7a766ec4b16291107c583c937947a97c;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout - low level retry 1/10 2025/09/17 05:31:26 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 05:32:27 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:32:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 05:33:27 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:34:27 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:35:27 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:36:27 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:37:27 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:38:28 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 05:39:28 DEBUG : pacer: low level retry 8/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:40:28 DEBUG : pacer: low level retry 9/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:41:29 DEBUG : pacer: low level retry 10/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:41:29 DEBUG : sub dir/hello world: Received error: failed to open source object: Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=77055976;tm=1758087686;ut=vd;rs=0;fet=download_cloud;He;ch=79279a0309f81735ea0377accf4618ee;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout - low level retry 2/10 2025/09/17 05:41:29 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 05:42:32 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:42:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 05:43:32 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:44:34 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:45:34 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:46:34 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:47:34 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:53:12 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=BtM4xvMY;fs=lcNv4sbb9JfJ;hid=SKWuC7Kw;rid=1867581176;tm=1758088289;ut=vd;rs=0;fet=download_cloud;He;ch=fe255776186ea628de0653aba199d968;Pe/file/lcNv4sbb9JfJ/hello-world?bD&u=24664897&c=3214735720&De": net/http: timeout awaiting response headers) 2025/09/17 05:53:12 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 05:53:12 ERROR : sub dir/hello world: Failed to copy: failed to open source object: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n
\n \n \n \n \n \n \n \n \n \n
\n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" sync_test.go:624: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:624 Error: Received unexpected error: Test: TestServerSideCopy 2025/09/17 05:53:13 ERROR : error listing: directory not found 2025/09/17 05:53:13 DEBUG : uloz.to root 'rclone-test-jugutik2xode': Purge dir "" 2025/09/17 05:53:14 NOTICE: purge failed to rmdir "": directory not found 2025/09/17 05:53:14 NOTICE: purge failed: directory not found 2025/09/17 05:53:14 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/17 05:53:16 DEBUG : pacer: Reducing sleep to 843.75ms 2025/09/17 05:53:17 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/09/17 05:53:18 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/09/17 05:53:18 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/09/17 05:53:19 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/09/17 05:53:19 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 84.470271ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 63.352703ms --- FAIL: TestServerSideCopy (2565.50s) === RUN TestCopyOverSelf run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 47.514527ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 35.635895ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 26.726921ms 2025/09/17 05:53:20 DEBUG : pacer: Reducing sleep to 20.04519ms 2025/09/17 05:53:21 DEBUG : pacer: Reducing sleep to 15.033892ms 2025/09/17 05:53:21 DEBUG : pacer: Reducing sleep to 11.275419ms 2025/09/17 05:53:22 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 05:53:23 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/17 05:53:23 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 05:53:23 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 05:53:24 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/09/17 05:53:24 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (5.46s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 05:53:27 DEBUG : Creating backend with remote "TestUlozto:rclone-test-panuzid1zuke" 2025/09/17 05:53:27 DEBUG : Config file has changed externally - reloading sync_test.go:656: Server side copy (if possible) uloz.to root 'rclone-test-haqahoy5joco' -> uloz.to root 'rclone-test-panuzid1zuke' 2025/09/17 05:53:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 05:53:31 DEBUG : uloz.to root 'rclone-test-panuzid1zuke': Waiting for checks to finish 2025/09/17 05:53:31 DEBUG : uloz.to root 'rclone-test-panuzid1zuke': Waiting for transfers to finish 2025/09/17 05:54:31 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:54:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:55:31 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:55:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/17 05:56:31 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:56:31 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/17 05:57:32 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:57:32 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/09/17 05:58:33 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:58:33 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/09/17 05:59:33 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:59:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/09/17 06:05:10 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=dUTkZ8Pv;fs=zLj2e43DqYBC;hid=o6oMZajS;rid=1414824133;tm=1758089011;ut=vd;rs=0;fet=download_cloud;He;ch=0f297615b4dfd22dc5d0dc808f8a9b6f;Pe/file/zLj2e43DqYBC/hello-world?bD&u=24664897&c=3214735720&De": net/http: timeout awaiting response headers) 2025/09/17 06:05:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/09/17 06:05:11 DEBUG : pacer: Reducing sleep to 960ms 2025/09/17 06:05:11 ERROR : sub dir/hello world: Failed to copy: failed to open source object: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n
\n \n \n \n \n \n \n \n \n \n
\n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" sync_test.go:660: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:660 Error: Received unexpected error: Test: TestServerSideCopyOverSelf 2025/09/17 06:05:12 ERROR : error listing: directory not found 2025/09/17 06:05:12 DEBUG : uloz.to root 'rclone-test-panuzid1zuke': Purge dir "" 2025/09/17 06:05:13 NOTICE: purge failed to rmdir "": directory not found 2025/09/17 06:05:13 NOTICE: purge failed: directory not found 2025/09/17 06:05:13 DEBUG : pacer: Reducing sleep to 720ms 2025/09/17 06:05:14 DEBUG : pacer: Reducing sleep to 540ms 2025/09/17 06:05:14 DEBUG : pacer: Reducing sleep to 405ms 2025/09/17 06:05:15 DEBUG : pacer: Reducing sleep to 303.75ms 2025/09/17 06:05:15 DEBUG : pacer: Reducing sleep to 227.8125ms 2025/09/17 06:05:16 DEBUG : pacer: Reducing sleep to 170.859375ms 2025/09/17 06:05:16 DEBUG : pacer: Reducing sleep to 128.144531ms 2025/09/17 06:05:16 DEBUG : pacer: Reducing sleep to 96.108398ms 2025/09/17 06:05:16 DEBUG : pacer: Reducing sleep to 72.081298ms 2025/09/17 06:05:16 DEBUG : pacer: Reducing sleep to 54.060973ms 2025/09/17 06:05:16 DEBUG : pacer: Reducing sleep to 40.545729ms --- FAIL: TestServerSideCopyOverSelf (710.95s) === RUN TestMoveOverSelf run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 06:05:17 DEBUG : pacer: Reducing sleep to 30.409296ms 2025/09/17 06:05:17 DEBUG : pacer: Reducing sleep to 22.806972ms 2025/09/17 06:05:17 DEBUG : pacer: Reducing sleep to 17.105229ms 2025/09/17 06:05:17 DEBUG : pacer: Reducing sleep to 12.828921ms 2025/09/17 06:05:17 DEBUG : pacer: Reducing sleep to 10ms 2025/09/17 06:05:21 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/09/17 06:05:21 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for checks to finish 2025/09/17 06:05:21 DEBUG : uloz.to root 'rclone-test-haqahoy5joco': Waiting for transfers to finish 2025/09/17 06:05:21 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/09/17 06:05:21 INFO : sub dir/hello world: Copied (replaced existing) 2025/09/17 06:05:21 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (6.66s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "uloz.to root 'rclone-test-haqahoy5joco'", Local "Local file system at /tmp/rclone3099255192", Modify Window "1µs" 2025/09/17 06:05:27 DEBUG : Creating backend with remote "TestUlozto:rclone-test-wuwitaw8faxe" sync_test.go:701: Server side copy (if possible) uloz.to root 'rclone-test-haqahoy5joco' -> uloz.to root 'rclone-test-wuwitaw8faxe' 2025/09/17 06:05:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/09/17 06:05:30 DEBUG : uloz.to root 'rclone-test-wuwitaw8faxe': Waiting for checks to finish 2025/09/17 06:05:30 DEBUG : uloz.to root 'rclone-test-wuwitaw8faxe': Waiting for transfers to finish 2025/09/17 06:06:30 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=4eP4VRP5;fs=3xRK1MEQPXO2;hid=QJthEmbe;rid=947400896;tm=1758089730;ut=vd;rs=0;fet=download_cloud;He;ch=69886dae95148ecc6b5c42d1b0c326f1;Pe/file/3xRK1MEQPXO2/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:06:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 06:07:30 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=4eP4VRP5;fs=3xRK1MEQPXO2;hid=QJthEmbe;rid=947400896;tm=1758089730;ut=vd;rs=0;fet=download_cloud;He;ch=69886dae95148ecc6b5c42d1b0c326f1;Pe/file/3xRK1MEQPXO2/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:07:30 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/17 06:08:31 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=4eP4VRP5;fs=3xRK1MEQPXO2;hid=QJthEmbe;rid=947400896;tm=1758089730;ut=vd;rs=0;fet=download_cloud;He;ch=69886dae95148ecc6b5c42d1b0c326f1;Pe/file/3xRK1MEQPXO2/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:08:31 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/17 06:09:31 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=4eP4VRP5;fs=3xRK1MEQPXO2;hid=QJthEmbe;rid=947400896;tm=1758089730;ut=vd;rs=0;fet=download_cloud;He;ch=69886dae95148ecc6b5c42d1b0c326f1;Pe/file/3xRK1MEQPXO2/hello-world?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:09:31 DEBUG : pacer: Rate limited, increasing sleep to 160ms panic: test timed out after 1h0m0s running tests: TestServerSideMoveOverSelf (4m23s) goroutine 3856 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 4 minutes]: testing.(*T).Run(0xc00038f6c0, {0x278a484?, 0xc00006dad8?}, 0x288ddc8) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc00038f6c0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc00038f6c0, 0xc00006dc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc000523e00, {0x3fb5e00, 0x6c, 0x6c}, {0xee05c399a?, 0x3fcbf80?, 0x3fcbf80?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc000686f00) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc000686f00) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:261 +0xa9 goroutine 35 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 36 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 3648 [sync.WaitGroup.Wait, 4 minutes]: sync.runtime_SemacquireWaitGroup(0x0?) /usr/local/go/src/runtime/sema.go:110 +0x25 sync.(*WaitGroup).Wait(0xc0001b1207?) /usr/local/go/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/sync.(*syncCopyMove).stopTransfers(0xc00042d188) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:543 +0x6f github.com/rclone/rclone/fs/sync.(*syncCopyMove).run(0xc00042d188) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:978 +0x325 github.com/rclone/rclone/fs/sync.runSyncCopyMove({0x2bd9450, 0xc0003dd560}, {0x2bf11a0, 0xc0009b7400}, {0x2bf11a0, 0xc000686fa0}, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1369 +0x225 github.com/rclone/rclone/fs/sync.CopyDir(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1380 github.com/rclone/rclone/fs/sync.TestServerSideMoveOverSelf(0xc000c41a40) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:704 +0x315 testing.tRunner(0xc000c41a40, 0x288ddc8) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 3851 [IO wait]: internal/poll.runtime_pollWait(0x7c81c1eb6c70, 0x77) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000390280?, 0x0?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitWrite(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 internal/poll.(*FD).WaitWrite(...) /usr/local/go/src/internal/poll/fd_unix.go:692 net.(*netFD).connect(0xc000390280, {0x2bd94f8, 0xc0004c0930}, {0x41b074?, 0xc000490708?}, {0x2bb0700?, 0xc0009b8180?}) /usr/local/go/src/net/fd_unix.go:141 +0x6cb net.(*netFD).dial(0xc000390280, {0x2bd94f8, 0xc0004c0930}, {0x2be06a8?, 0x0?}, {0x2be06a8, 0xc0004da660}, 0xc0002f1210?) /usr/local/go/src/net/sock_posix.go:124 +0x3bc net.socket({0x2bd94f8, 0xc0004c0930}, {0x2743d99, 0x3}, 0x2, 0x1, 0x2?, 0x0, {0x2be06a8, 0x0}, ...) /usr/local/go/src/net/sock_posix.go:70 +0x29b net.internetSocket({0x2bd94f8, 0xc0004c0930}, {0x2743d99, 0x3}, {0x2be06a8, 0x0}, {0x2be06a8, 0xc0004da660}, 0x1, 0x0, ...) /usr/local/go/src/net/ipsock_posix.go:167 +0xf8 net.(*sysDialer).doDialTCPProto(0xc0007f6a80, {0x2bd94f8, 0xc0004c0930}, 0x0, 0xc0004da660, 0x0) /usr/local/go/src/net/tcpsock_posix.go:85 +0xe7 net.(*sysDialer).doDialTCP(...) /usr/local/go/src/net/tcpsock_posix.go:75 net.(*sysDialer).dialTCP(0x0?, {0x2bd94f8?, 0xc0004c0930?}, 0xc0002f1528?, 0xc0002f1420?) /usr/local/go/src/net/tcpsock_posix.go:71 +0x69 net.(*sysDialer).dialSingle(0xc0007f6a80, {0x2bd94f8, 0xc0004c0930}, {0x2bc4660, 0xc0004da660}) /usr/local/go/src/net/dial.go:721 +0x3ce net.(*sysDialer).dialSerial(0xc0007f6a80, {0x2bd94f8, 0xc0004c0930}, {0xc000a6e2b0?, 0x1, 0xc000b24c00?}) /usr/local/go/src/net/dial.go:686 +0x24e net.(*sysDialer).dialParallel(0x0?, {0x2bd94f8?, 0xc0004c0930?}, {0xc000a6e2b0?, 0x23284a0?, 0x2744df6?}, {0x0?, 0x2743d99?, 0xc0003b67f0?}) /usr/local/go/src/net/dial.go:587 +0x3a7 net.(*Dialer).DialContext(0xc000444820, {0x2bd9488, 0xc000850370}, {0x2743d99, 0x3}, {0xc000926150, 0x24}) /usr/local/go/src/net/dial.go:578 +0x6ac github.com/rclone/rclone/fs/fshttp.(*Dialer).DialContext(0xc000444820, {0x2bd9488, 0xc000850370}, {0x2743d99?, 0x7c81c02c4f58?}, {0xc000926150, 0x24}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:62 +0x125 github.com/rclone/rclone/fs/fshttp.NewTransportCustom.func2({0x2bd9488, 0xc000850370}, {0x2743d99, 0x3}, {0xc000926150, 0x24}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:269 +0x5c net/http.(*Transport).dial(0xc0003b6a08?, {0x2bd9488?, 0xc000850370?}, {0x2743d99?, 0xc000af4500?}, {0xc000926150?, 0x105?}) /usr/local/go/src/net/http/transport.go:1278 +0xd2 net/http.(*Transport).dialConn(0xc0003ba000, {0x2bd9488, 0xc000850370}, {{}, 0x0, {0xc0007426e0, 0x5}, {0xc000926150, 0x24}, 0x0}) /usr/local/go/src/net/http/transport.go:1780 +0x7e5 net/http.(*Transport).dialConnFor(0xc0003ba000, 0xc0002e2370) /usr/local/go/src/net/http/transport.go:1615 +0xb8 net/http.(*Transport).startDialConnForLocked.func1() /usr/local/go/src/net/http/transport.go:1597 +0x35 created by net/http.(*Transport).startDialConnForLocked in goroutine 3756 /usr/local/go/src/net/http/transport.go:1596 +0x112 goroutine 3855 [select]: net.(*netFD).connect.func2() /usr/local/go/src/net/fd_unix.go:118 +0x7a created by net.(*netFD).connect in goroutine 3851 /usr/local/go/src/net/fd_unix.go:117 +0x349 goroutine 2956 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc00075c000, {0x2bd9488, 0xc0001b0eb0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 2948 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 3756 [select]: net/http.(*Transport).getConn(0xc0003ba000, 0xc000850320, {{}, 0x0, {0xc0007426e0, 0x5}, {0xc000926150, 0x24}, 0x0}) /usr/local/go/src/net/http/transport.go:1520 +0x4ae net/http.(*Transport).roundTrip(0xc0003ba000, 0xc000bf63c0) /usr/local/go/src/net/http/transport.go:685 +0xb57 net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc00038a9c0, 0xc000bf63c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 net/http.send(0xc000bf63c0, {0x2bb1fa0, 0xc00038a9c0}, {0x479601?, 0xc000beb0f0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000788ba0, 0xc000bf63c0, {0x7c81c04daee8?, 0x3fef560?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000788ba0, 0xc000bf63c0) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc00061c500, {0x2bd9488, 0xc0001b1310}, 0xc000cae780) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67 github.com/rclone/rclone/backend/ulozto.(*Object).Open.func2() /home/rclone/go/src/github.com/rclone/rclone/backend/ulozto/ulozto.go:804 +0x52 github.com/rclone/rclone/fs.pacerInvoker(0x5, 0xa, 0x2bb0bc0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00038aa20, 0xc0008a95c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc00038aa20, 0xc0008a95c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/ulozto.(*Object).Open(0xc00097c540, {0x2bd9488, 0xc0001b1310}, {0xc000587940, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/ulozto/ulozto.go:803 +0x428 github.com/rclone/rclone/fs/operations.(*ReOpen).open(0xc000aec8c0) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/reopen.go:147 +0x109 github.com/rclone/rclone/fs/operations.NewReOpen({0x2bd9488, 0xc0001b1310}, {0x2bf1210, 0xc00097c540}, 0xa, {0xc000587750, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/reopen.go:102 +0x4f5 github.com/rclone/rclone/fs/operations.Open({0x2bd9488, 0xc0001b1310}, {0x2bf1210, 0xc00097c540}, {0xc000587750, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/reopen.go:124 +0x6b github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc000cfe000, {0x2bd9488, 0xc0001b1310}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:265 +0x4dc github.com/rclone/rclone/fs/operations.(*copy).copy(0xc000cfe000, {0x2bd9488, 0xc0001b1310}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:314 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x2bd9488, 0xc0001b1310}, {0x2bf11a0, 0xc0009b7400}, {0x0, 0x0}, {0xc0007e5ba8, 0x13}, {0x2bf1210, 0xc00097c540}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:416 +0x4db github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc00042d188, {0x2bd9488, 0xc0001b1310}, 0xc0001b1270, {0x2bf11a0, 0xc0009b7400}, 0x0, 0xc000940798?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:505 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 3648 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:535 +0x7d goroutine 3769 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0002bb4a0, {0x2bd9488, 0xc000aab8b0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 3756 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 3784 [chan receive, 4 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 3648 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:255 +0x378 "./sync.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose" - Finished ERROR in 1h0m2.535628521s (try 5/5): exit status 2: Failed [TestServerSideCopy TestServerSideCopyOverSelf]