"go test -v -timeout 1h0m0s -remote TestShade: -verbose" - Starting (try 2/5)
=== RUN   TestIntegration
2026/07/27 04:35:03 DEBUG : Using config file from "/home/rclone/.rclone.conf"
    fstests.go:457: Using remote "TestShade:"
2026/07/27 04:35:03 DEBUG : Creating backend with remote "TestShade:rclone-test-lomoqih6bafa"
2026/07/27 04:35:03 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
=== RUN   TestIntegration/FsCheckWrap
    fstests.go:498: Not a wrapping Fs
=== RUN   TestIntegration/FsCommand
    fstests.go:526: No commands in this remote
=== RUN   TestIntegration/FsRmdirNotFound
=== RUN   TestIntegration/FsString
=== RUN   TestIntegration/FsName
=== RUN   TestIntegration/FsRoot
=== RUN   TestIntegration/FsRmdirEmpty
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsMkdirRmdirSubdir
2026/07/27 04:35:04 INFO  : dir/subdir: Making directory
2026/07/27 04:35:05 INFO  : dir/subdir: Removing directory
2026/07/27 04:35:06 INFO  : dir: Removing directory
=== RUN   TestIntegration/FsMkdir/FsListEmpty
=== RUN   TestIntegration/FsMkdir/FsListDirEmpty
=== RUN   TestIntegration/FsMkdir/FsListRDirEmpty
    fstests.go:418: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsListDirNotFound
2026/07/27 04:35:07 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-lomoqih6bafa: Error from List call: HTTP error 404 (404 Not Found) returned body: "can't derive ino for path"
2026/07/27 04:35:07 ERROR : does not exist: error listing: directory not found
=== RUN   TestIntegration/FsMkdir/FsListRDirNotFound
    fstests.go:418: FS has no ListR interface
=== RUN   TestIntegration/FsMkdir/FsEncoding
=== RUN   TestIntegration/FsMkdir/FsEncoding/control_chars
    fstests.go:727: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡"
2026/07/27 04:35:07 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: multipart upload: starting chunk 0 size 100 offset 0/100
2026/07/27 04:35:09 DEBUG : pacer: low level retry 1/10 (error EOF)
2026/07/27 04:35:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/07/27 04:35:09 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:09 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/07/27 04:35:09 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:09 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/07/27 04:35:09 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:09 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/07/27 04:35:10 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:10 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/07/27 04:35:10 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:10 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/07/27 04:35:10 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/07/27 04:35:11 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:11 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/07/27 04:35:12 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/07/27 04:35:15 DEBUG : pacer: low level retry 10/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:15 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/27 04:35:15 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Cancelling multipart upload
2026/07/27 04:35:20 DEBUG : pacer: Reducing sleep to 5.12s
    fstests.go:143: Put error: multipart upload: failed to finalise: failed to complete multipart upload: HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error" - low level retry 1/10
2026/07/27 04:35:30 DEBUG : pacer: Reducing sleep to 2.56s
2026/07/27 04:35:30 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: multipart upload: starting chunk 0 size 100 offset 0/100
2026/07/27 04:35:35 DEBUG : pacer: Reducing sleep to 1.28s
2026/07/27 04:35:38 DEBUG : pacer: Reducing sleep to 640ms
2026/07/27 04:35:40 DEBUG : pacer: low level retry 1/10 (error EOF)
2026/07/27 04:35:40 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/07/27 04:35:40 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:40 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/07/27 04:35:41 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:41 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/07/27 04:35:44 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:44 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/27 04:35:49 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:49 DEBUG : pacer: Rate limited, increasing sleep to 20.48s
2026/07/27 04:35:59 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:35:59 DEBUG : pacer: Rate limited, increasing sleep to 40.96s
2026/07/27 04:36:20 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:36:20 DEBUG : pacer: Rate limited, increasing sleep to 1m21.92s
2026/07/27 04:37:01 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:37:01 DEBUG : pacer: Rate limited, increasing sleep to 2m43.84s
2026/07/27 04:38:23 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:38:23 DEBUG : pacer: Rate limited, increasing sleep to 5m0s
2026/07/27 04:41:07 DEBUG : pacer: low level retry 10/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:41:07 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Cancelling multipart upload
2026/07/27 04:46:07 DEBUG : pacer: Reducing sleep to 2m30s
    fstests.go:143: Put error: multipart upload: failed to finalise: failed to complete multipart upload: HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error" - low level retry 2/10
2026/07/27 04:51:07 DEBUG : pacer: Reducing sleep to 1m15s
2026/07/27 04:51:07 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: multipart upload: starting chunk 0 size 100 offset 0/100
2026/07/27 04:53:37 DEBUG : pacer: Reducing sleep to 37.5s
2026/07/27 04:54:52 DEBUG : pacer: Reducing sleep to 18.75s
2026/07/27 04:55:30 DEBUG : pacer: low level retry 1/10 (error EOF)
2026/07/27 04:55:30 DEBUG : pacer: Rate limited, increasing sleep to 37.5s
2026/07/27 04:55:48 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:55:48 DEBUG : pacer: Rate limited, increasing sleep to 1m15s
2026/07/27 04:56:26 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:56:26 DEBUG : pacer: Rate limited, increasing sleep to 2m30s
2026/07/27 04:57:41 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 04:57:41 DEBUG : pacer: Rate limited, increasing sleep to 5m0s
2026/07/27 05:00:11 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:05:10 DEBUG : pacer: Reducing sleep to 2m30s
2026/07/27 05:05:10 DEBUG : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡: Cancelling multipart upload
2026/07/27 05:10:10 DEBUG : pacer: Reducing sleep to 1m15s
2026/07/27 05:10:10 DEBUG : Saving config "token" in section "TestShade" of the config file
2026/07/27 05:10:10 DEBUG : Config file has changed externally - reloading
2026/07/27 05:10:10 DEBUG : Saving config "token_expiry" in section "TestShade" of the config file
2026/07/27 05:12:41 DEBUG : pacer: Reducing sleep to 37.5s
    fstests.go:146: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:177
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:241
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:231
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:733
        	            				/usr/local/go/src/runtime/asm_amd64.s:1771
        	Error:      	Received unexpected error:
        	            	multipart upload: failed to finalise: failed to complete multipart upload: HTTP error 400 (400 Bad Request) returned body: "Invalid token"
        	Test:       	TestIntegration/FsMkdir/FsEncoding/control_chars
        	Messages:   	Put
=== RUN   TestIntegration/FsMkdir/FsEncoding/dot
    fstests.go:727: testing "．"
2026/07/27 05:13:55 DEBUG : pacer: Reducing sleep to 18.75s
2026/07/27 05:14:33 DEBUG : pacer: Reducing sleep to 9.375s
2026/07/27 05:14:33 DEBUG : ．/．: multipart upload: starting chunk 0 size 100 offset 0/100
2026/07/27 05:14:52 DEBUG : pacer: Reducing sleep to 4.6875s
2026/07/27 05:15:01 DEBUG : pacer: Reducing sleep to 2.34375s
2026/07/27 05:15:07 DEBUG : pacer: low level retry 1/10 (error EOF)
2026/07/27 05:15:07 DEBUG : pacer: Rate limited, increasing sleep to 4.6875s
2026/07/27 05:15:08 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:15:08 DEBUG : pacer: Rate limited, increasing sleep to 9.375s
2026/07/27 05:15:13 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:15:13 DEBUG : pacer: Rate limited, increasing sleep to 18.75s
2026/07/27 05:15:22 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:15:22 DEBUG : pacer: Rate limited, increasing sleep to 37.5s
2026/07/27 05:15:41 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:15:41 DEBUG : pacer: Rate limited, increasing sleep to 1m15s
2026/07/27 05:16:19 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:16:19 DEBUG : pacer: Rate limited, increasing sleep to 2m30s
2026/07/27 05:17:34 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:17:34 DEBUG : pacer: Rate limited, increasing sleep to 5m0s
2026/07/27 05:20:04 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:25:04 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:30:04 DEBUG : pacer: low level retry 10/10 (error HTTP error 500 (500 Internal Server Error) returned body: "Internal Server Error")
2026/07/27 05:30:04 DEBUG : ．/．: Cancelling multipart upload
panic: test timed out after 1h0m0s
	running tests:
		TestIntegration (1h0m0s)
		TestIntegration/FsMkdir (59m59s)
		TestIntegration/FsMkdir/FsEncoding (59m56s)
		TestIntegration/FsMkdir/FsEncoding/dot (22m23s)

goroutine 482 [running]:
testing.(*M).startAlarm.func1()
	/usr/local/go/src/testing/testing.go:2802 +0x34b
created by time.goFunc
	/usr/local/go/src/time/sleep.go:215 +0x2d

goroutine 1 [chan receive, 59 minutes]:
testing.(*T).Run(0x36257caf5688, {0xc9046f?, 0x36257cafbb30?}, 0xcf5208)
	/usr/local/go/src/testing/testing.go:2109 +0x4e5
testing.runTests.func1(0x36257caf5688)
	/usr/local/go/src/testing/testing.go:2585 +0x37
testing.tRunner(0x36257caf5688, 0x36257cafbc58)
	/usr/local/go/src/testing/testing.go:2036 +0xea
testing.runTests({0xc99822, 0x18}, {0xca9759, 0x26}, 0x36257cd6ebd0, {0x1313950, 0x1, 0x1}, {0xc291d961e6269783, 0x346313132b1, ...})
	/usr/local/go/src/testing/testing.go:2583 +0x505
testing.(*M).Run(0x36257cdc2be0)
	/usr/local/go/src/testing/testing.go:2443 +0x6ac
main.main()
	_testmain.go:48 +0x9b

goroutine 10 [chan receive, 59 minutes]:
testing.(*T).Run(0x36257caf58c8, {0xc88812?, 0x36257cdae280?}, 0x36257caea180)
	/usr/local/go/src/testing/testing.go:2109 +0x4e5
github.com/rclone/rclone/fstest/fstests.Run(0x36257caf58c8, 0x36257cc34b60)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:603 +0x1025
github.com/rclone/rclone/backend/shade_test.TestIntegration(0x36257caf58c8)
	/home/rclone/go/src/github.com/rclone/rclone/backend/shade/shade_test.go:13 +0x10f
testing.tRunner(0x36257caf58c8, 0xcf5208)
	/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 1
	/usr/local/go/src/testing/testing.go:2101 +0x4c5

goroutine 12 [syscall, 59 minutes]:
os/signal.signal_recv()
	/usr/local/go/src/runtime/sigqueue.go:152 +0x98
os/signal.loop()
	/usr/local/go/src/os/signal/signal_unix.go:23 +0x13
created by os/signal.Notify.func1.1 in goroutine 10
	/usr/local/go/src/os/signal/signal.go:152 +0x1f

goroutine 13 [chan receive, 59 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 10
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7

goroutine 70 [chan receive, 59 minutes]:
testing.(*T).Run(0x36257caf4488, {0xc8bcc9?, 0x0?}, 0x36257cd62750)
	/usr/local/go/src/testing/testing.go:2109 +0x4e5
github.com/rclone/rclone/fstest/fstests.Run.func13(0x36257caf4488)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:682 +0x609
testing.tRunner(0x36257caf4488, 0x36257caea180)
	/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 10
	/usr/local/go/src/testing/testing.go:2101 +0x4c5

goroutine 167 [chan receive, 22 minutes]:
testing.(*T).Run(0x36257cc8e488, {0xc8544c?, 0x0?}, 0x36257cdaaf50)
	/usr/local/go/src/testing/testing.go:2109 +0x4e5
github.com/rclone/rclone/fstest/fstests.Run.func13.7(0x36257cc8e488)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:716 +0x215
testing.tRunner(0x36257cc8e488, 0x36257cd62750)
	/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 70
	/usr/local/go/src/testing/testing.go:2101 +0x4c5

goroutine 189 [chan receive, 59 minutes]:
github.com/rclone/rclone/lib/atexit.Register.func1.1()
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29
created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 147
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68

goroutine 382 [chan receive, 5 minutes]:
github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0x36257caafd40, 0x0)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:168 +0xa7
github.com/rclone/rclone/lib/pacer.(*Pacer).call(0x36257caafd40, 0x36257cf19890, 0xa)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:227 +0xb2
github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0x36257caafd40, 0x36257cf19890)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x92
github.com/rclone/rclone/backend/shade.(*shadeChunkWriter).Abort(0x36257cdaa0f0, {0xd06e58, 0x1370440})
	/home/rclone/go/src/github.com/rclone/rclone/backend/shade/upload.go:309 +0x34b
github.com/rclone/rclone/lib/multipart.UploadMultipart.func1()
	/home/rclone/go/src/github.com/rclone/rclone/lib/multipart/multipart.go:56 +0xc3
sync.(*Once).doSlow(0x36257ca616a0?, 0x520890?)
	/usr/local/go/src/sync/once.go:78 +0xac
sync.(*Once).Do(...)
	/usr/local/go/src/sync/once.go:69
github.com/rclone/rclone/lib/atexit.OnError.func1()
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:123 +0x25
github.com/rclone/rclone/lib/atexit.OnError.func2()
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:129 +0x51
github.com/rclone/rclone/lib/multipart.UploadMultipart({0xd06e58, 0x1370440}, {0xd0ead0, 0x36257ca485b0}, {0xcffd80, 0x36257ce1e3c0}, {{0xcffd00, 0x36257cdae280}, {0x0, 0x0, ...}})
	/home/rclone/go/src/github.com/rclone/rclone/lib/multipart/multipart.go:130 +0xbc5
github.com/rclone/rclone/backend/shade.(*Object).uploadMultipart(0x36257caa3a40, {0xd06e58?, 0x1370440?}, {0xd0ead0?, 0x36257ca485b0?}, {0xcffd80?, 0x36257ce1e3c0?}, {0x0, 0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/shade/upload.go:40 +0xd3
github.com/rclone/rclone/backend/shade.(*Object).Update(0x36257caa3a40, {0xd06e58, 0x1370440}, {0xcffd80, 0x36257ce1e3c0}, {0xd0ead0, 0x36257ca485b0}, {0x0, 0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/shade/shade.go:971 +0x1ac
github.com/rclone/rclone/backend/shade.(*Fs).Put(0x36257cdae280, {0xd06e58, 0x1370440}, {0xcffd80, 0x36257ce1e3c0}, {0xd0ead0, 0x36257ca485b0}, {0x0, 0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/shade/shade.go:555 +0x108
github.com/rclone/rclone/fstest/fstests.PutTestContentsMetadata.func1()
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:202 +0x3ad
github.com/rclone/rclone/fstest/fstests.retry(0x36257cc8efc8, {0xc85440, 0x3}, 0x36257ca61ca0)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:138 +0x117
github.com/rclone/rclone/fstest/fstests.PutTestContentsMetadata({0xd06e58, 0x1370440}, 0x36257cc8efc8, {0xd105b0, 0x36257cdae280}, 0x36257ca61e90, 0x3?, {0x36257cab1c70?, 0x1?}, 0x1, ...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:177 +0x18e
github.com/rclone/rclone/fstest/fstests.testPutMimeType({0xd06e58, 0x1370440}, 0x36257cc8efc8, {0xd105b0, 0x36257cdae280}, 0x36257ce33e90, {0x0, 0x0}, 0x0)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:241 +0x15e
github.com/rclone/rclone/fstest/fstests.testPut(...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:231
github.com/rclone/rclone/fstest/fstests.Run.func13.7.1(0x36257cc8efc8)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:733 +0x3aa
testing.tRunner(0x36257cc8efc8, 0x36257cdaaf50)
	/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 167
	/usr/local/go/src/testing/testing.go:2101 +0x4c5

goroutine 471 [sleep, 5 minutes]:
time.Sleep(0x45d964b800)
	/usr/local/go/src/runtime/time.go:363 +0x165
github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0x36257cb38780?)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:178 +0x1c
created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 382
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:177 +0x17f
exit status 2
FAIL	github.com/rclone/rclone/backend/shade	3600.021s
"go test -v -timeout 1h0m0s -remote TestShade: -verbose" - Finished ERROR in 1h0m1.01795891s (try 2/5): exit status 1: Failed []