"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestChunkerChunk50bBox:" 2025/03/31 04:45:29 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi" 2025/03/31 04:45:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/31 04:45:29 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi" 2025/03/31 04:45:29 DEBUG : TestBox: Loaded invalid token from config file - ignoring 2025/03/31 04:45:29 DEBUG : Saving config "token" in section "TestBox" of the config file 2025/03/31 04:45:29 DEBUG : TestBox: Saved new token in config file 2025/03/31 04:45:30 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 04:45:31 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsCheckWrap === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/03/31 04:45:32 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 04:45:32 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/03/31 04:45:36 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/03/31 04:45:37 INFO : dir/subdir: Making directory 2025/03/31 04:45:42 INFO : dir/subdir: Removing directory 2025/03/31 04:45:43 INFO : dir: Removing directory === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/03/31 04:45:46 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/03/31 04:45:53 INFO : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.001_z6tz4d: Moved (server-side) to: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.001 2025/03/31 04:45:54 INFO : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.002_z6tz4d: Moved (server-side) to: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" 2025/03/31 04:46:09 INFO : ．/．.rclone_chunk.001_z6ugsl: Moved (server-side) to: ．/．.rclone_chunk.001 2025/03/31 04:46:10 INFO : ．/．.rclone_chunk.002_z6ugsl: Moved (server-side) to: ．/．.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" 2025/03/31 04:46:26 INFO : ．．/．．.rclone_chunk.001_z6uwuu: Moved (server-side) to: ．．/．．.rclone_chunk.001 2025/03/31 04:46:27 INFO : ．．/．．.rclone_chunk.002_z6uwuu: Moved (server-side) to: ．．/．．.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/03/31 04:46:42 INFO : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.001_z6vc0u: Moved (server-side) to: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.001 2025/03/31 04:46:42 INFO : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.002_z6vc0u: Moved (server-side) to: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/03/31 04:46:58 INFO : leading space/ leading space.rclone_chunk.001_z6vsp9: Moved (server-side) to: leading space/ leading space.rclone_chunk.001 2025/03/31 04:46:59 INFO : leading space/ leading space.rclone_chunk.002_z6vsp9: Moved (server-side) to: leading space/ leading space.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/03/31 04:47:14 INFO : ~leading tilde/~leading tilde.rclone_chunk.001_z6w85p: Moved (server-side) to: ~leading tilde/~leading tilde.rclone_chunk.001 2025/03/31 04:47:14 INFO : ~leading tilde/~leading tilde.rclone_chunk.002_z6w85p: Moved (server-side) to: ~leading tilde/~leading tilde.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/03/31 04:47:30 INFO : ␍leading CR/␍leading CR.rclone_chunk.001_z6wo8g: Moved (server-side) to: ␍leading CR/␍leading CR.rclone_chunk.001 2025/03/31 04:47:30 INFO : ␍leading CR/␍leading CR.rclone_chunk.002_z6wo8g: Moved (server-side) to: ␍leading CR/␍leading CR.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" 2025/03/31 04:47:46 INFO : ␊leading LF/␊leading LF.rclone_chunk.001_z6x4a7: Moved (server-side) to: ␊leading LF/␊leading LF.rclone_chunk.001 2025/03/31 04:47:46 INFO : ␊leading LF/␊leading LF.rclone_chunk.002_z6x4a7: Moved (server-side) to: ␊leading LF/␊leading LF.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" 2025/03/31 04:48:02 INFO : ␉leading HT/␉leading HT.rclone_chunk.001_z6xk77: Moved (server-side) to: ␉leading HT/␉leading HT.rclone_chunk.001 2025/03/31 04:48:02 INFO : ␉leading HT/␉leading HT.rclone_chunk.002_z6xk77: Moved (server-side) to: ␉leading HT/␉leading HT.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" 2025/03/31 04:48:18 INFO : ␋leading VT/␋leading VT.rclone_chunk.001_z6y0d5: Moved (server-side) to: ␋leading VT/␋leading VT.rclone_chunk.001 2025/03/31 04:48:19 INFO : ␋leading VT/␋leading VT.rclone_chunk.002_z6y0d5: Moved (server-side) to: ␋leading VT/␋leading VT.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" 2025/03/31 04:48:35 INFO : .leading dot/.leading dot.rclone_chunk.001_z6yggp: Moved (server-side) to: .leading dot/.leading dot.rclone_chunk.001 2025/03/31 04:48:36 INFO : .leading dot/.leading dot.rclone_chunk.002_z6yggp: Moved (server-side) to: .leading dot/.leading dot.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/03/31 04:48:52 INFO : trailing space /trailing space .rclone_chunk.001_z6yyah: Moved (server-side) to: trailing space /trailing space .rclone_chunk.001 2025/03/31 04:48:52 INFO : trailing space /trailing space .rclone_chunk.002_z6yyah: Moved (server-side) to: trailing space /trailing space .rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" 2025/03/31 04:49:08 INFO : trailing CR␍/trailing CR␍.rclone_chunk.001_z6ze3i: Moved (server-side) to: trailing CR␍/trailing CR␍.rclone_chunk.001 2025/03/31 04:49:08 INFO : trailing CR␍/trailing CR␍.rclone_chunk.002_z6ze3i: Moved (server-side) to: trailing CR␍/trailing CR␍.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" 2025/03/31 04:49:23 INFO : trailing LF␊/trailing LF␊.rclone_chunk.001_z6zux1: Moved (server-side) to: trailing LF␊/trailing LF␊.rclone_chunk.001 2025/03/31 04:49:24 INFO : trailing LF␊/trailing LF␊.rclone_chunk.002_z6zux1: Moved (server-side) to: trailing LF␊/trailing LF␊.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" 2025/03/31 04:49:39 INFO : trailing HT␉/trailing HT␉.rclone_chunk.001_z709o3: Moved (server-side) to: trailing HT␉/trailing HT␉.rclone_chunk.001 2025/03/31 04:49:40 INFO : trailing HT␉/trailing HT␉.rclone_chunk.002_z709o3: Moved (server-side) to: trailing HT␉/trailing HT␉.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" 2025/03/31 04:49:55 INFO : trailing VT␋/trailing VT␋.rclone_chunk.001_z70pkt: Moved (server-side) to: trailing VT␋/trailing VT␋.rclone_chunk.001 2025/03/31 04:49:56 INFO : trailing VT␋/trailing VT␋.rclone_chunk.002_z70pkt: Moved (server-side) to: trailing VT␋/trailing VT␋.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." 2025/03/31 04:50:11 INFO : trailing dot./trailing dot..rclone_chunk.001_z716ah: Moved (server-side) to: trailing dot./trailing dot..rclone_chunk.001 2025/03/31 04:50:12 INFO : trailing dot./trailing dot..rclone_chunk.002_z716ah: Moved (server-side) to: trailing dot./trailing dot..rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" 2025/03/31 04:50:28 INFO : invalid utf-8/invalid utf-8.rclone_chunk.001_z71muz: Moved (server-side) to: invalid utf-8/invalid utf-8.rclone_chunk.001 2025/03/31 04:50:29 INFO : invalid utf-8/invalid utf-8.rclone_chunk.002_z71muz: Moved (server-side) to: invalid utf-8/invalid utf-8.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" 2025/03/31 04:50:44 INFO : test%46.txt/test%46.txt.rclone_chunk.001_z722et: Moved (server-side) to: test%46.txt/test%46.txt.rclone_chunk.001 2025/03/31 04:50:44 INFO : test%46.txt/test%46.txt.rclone_chunk.002_z722et: Moved (server-side) to: test%46.txt/test%46.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError fstests.go:747: Reduce file size due to limit 1024 === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/03/31 04:52:00 INFO : zero-length-file.rclone_chunk.001_z748vn: Moved (server-side) to: zero-length-file === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:786: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:819: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify 2025/03/31 04:52:04 INFO : dir: Making directory 2025/03/31 04:52:05 INFO : dir/subdir1: Making directory 2025/03/31 04:52:06 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881472228") 2025/03/31 04:52:06 INFO : dir/subdir3: Making directory 2025/03/31 04:52:06 DEBUG : box root 'rclone-test-logabet6xazi': Received 1 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:06 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881473485") 2025/03/31 04:52:07 DEBUG : box root 'rclone-test-logabet6xazi': ["zero-length-file"(1)|file|ITEM_RENAME|1819077659119|a9227351ba99b5110e96502d65e419c1d36b196c] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:07 DEBUG : box root 'rclone-test-logabet6xazi': Received 2 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:07 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881473746") 2025/03/31 04:52:07 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881473995") 2025/03/31 04:52:07 INFO : dir/subdir2: Making directory 2025/03/31 04:52:08 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881474466") 2025/03/31 04:52:08 DEBUG : box root 'rclone-test-logabet6xazi': ["zero-length-file"(1)|file|ITEM_RENAME|1819077659119|a9227351ba99b5110e96502d65e419c1d36b196c] ignored due to duplicate EventID 2025/03/31 04:52:08 DEBUG : box root 'rclone-test-logabet6xazi': ["z.txt.rclone_chunk.016_z72iy7"(1)|file|ITEM_TRASH|1819074210881|cd109cfa423c9bec33309f7a56d00aead7cb854f] ignored due to duplicate EventID 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881475463") 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir1"(0)|folder|ITEM_CREATE|314270001496|f8d83d03677d0bed35cadd5cf95627a7476d5c17] added new path ("dir/subdir1") for notify 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': ["dir"(0)|folder|ITEM_CREATE|314270603185|e6ed22e50d07ce2f0b235d90c96dd8a8f62f5eef] added new path ("dir") for notify 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': ["zero-length-file"(2)|file|ITEM_TRASH|1819077659119|2f66db05c1977a9be5a75930cf2815c2c570dcf8] added old path ("zero-length-file") for notify 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': ["zero-length-file.rclone_chunk.001_z748vn"(0)|file|ITEM_UPLOAD|1819077659119|2c84c9803a6e5c6a1afbe71adcba93e210c80d70] added new path ("zero-length-file.rclone_chunk.001_z748vn") for notify 2025/03/31 04:52:09 DEBUG : doChangeNotify("dir/subdir1", 0) 2025/03/31 04:52:09 DEBUG : doChangeNotify("dir", 0) 2025/03/31 04:52:09 DEBUG : doChangeNotify("zero-length-file", 1) 2025/03/31 04:52:09 DEBUG : doChangeNotify("zero-length-file.rclone_chunk.001_z748vn", 1) 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': Received 4 events, resulting in 4 paths and 4 notifications 2025/03/31 04:52:09 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881476488") 2025/03/31 04:52:10 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881476805") 2025/03/31 04:52:11 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881477466") 2025/03/31 04:52:12 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881478524") 2025/03/31 04:52:12 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir1"(0)|folder|ITEM_CREATE|314270001496|f8d83d03677d0bed35cadd5cf95627a7476d5c17] ignored due to duplicate EventID 2025/03/31 04:52:12 DEBUG : box root 'rclone-test-logabet6xazi': ["dir"(0)|folder|ITEM_CREATE|314270603185|e6ed22e50d07ce2f0b235d90c96dd8a8f62f5eef] ignored due to duplicate EventID 2025/03/31 04:52:12 DEBUG : box root 'rclone-test-logabet6xazi': ["zero-length-file"(2)|file|ITEM_TRASH|1819077659119|2f66db05c1977a9be5a75930cf2815c2c570dcf8] ignored due to duplicate EventID 2025/03/31 04:52:13 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881479462") 2025/03/31 04:52:13 DEBUG : box root 'rclone-test-logabet6xazi': ["zero-length-file.rclone_chunk.001_z748vn"(0)|file|ITEM_UPLOAD|1819077659119|2c84c9803a6e5c6a1afbe71adcba93e210c80d70] ignored due to duplicate EventID 2025/03/31 04:52:13 INFO : dir/file2.rclone_chunk.001_z74k0y: Moved (server-side) to: dir/file2.rclone_chunk.001 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881480467") 2025/03/31 04:52:14 INFO : dir/file2.rclone_chunk.002_z74k0y: Moved (server-side) to: dir/file2.rclone_chunk.002 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir2"(0)|folder|ITEM_CREATE|314269893053|077f662f24be2bbbbda05c4deb7d64cb8cd9d377] added new path ("dir/subdir2") for notify 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir3"(0)|folder|ITEM_CREATE|314272199692|4a35958619e25081cf3d1bda503e6d1eb24c9dd7] added new path ("dir/subdir3") for notify 2025/03/31 04:52:14 DEBUG : doChangeNotify("dir/subdir2", 0) 2025/03/31 04:52:14 DEBUG : doChangeNotify("dir/subdir3", 0) 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': Received 2 events, resulting in 2 paths and 2 notifications 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881481472") 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir2"(0)|folder|ITEM_CREATE|314269893053|077f662f24be2bbbbda05c4deb7d64cb8cd9d377] ignored due to duplicate EventID 2025/03/31 04:52:14 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir3"(0)|folder|ITEM_CREATE|314272199692|4a35958619e25081cf3d1bda503e6d1eb24c9dd7] ignored due to duplicate EventID 2025/03/31 04:52:15 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881481730") 2025/03/31 04:52:15 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir2"(0)|folder|ITEM_CREATE|314269893053|077f662f24be2bbbbda05c4deb7d64cb8cd9d377] ignored due to duplicate EventID 2025/03/31 04:52:16 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881482456") 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881483467") 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir3"(0)|folder|ITEM_CREATE|314272199692|4a35958619e25081cf3d1bda503e6d1eb24c9dd7] ignored due to duplicate EventID 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002_z74k0y"(0)|file|ITEM_UPLOAD|1819067022563|e0d64c27fc6c4647bbfe4cc85b0c351db924720a] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819062195970|6647f355d59a86443ffef1df67fe5a781bc19011] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(0)|file|ITEM_UPLOAD|1819068445986|3e4907ace299cb4e700c050927359732017f2efe] ignored due to old SequenceID ('\x00') 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': Received 5 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:17 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881484470") 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002_z74k0y"(0)|file|ITEM_UPLOAD|1819067022563|e0d64c27fc6c4647bbfe4cc85b0c351db924720a] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819062195970|6647f355d59a86443ffef1df67fe5a781bc19011] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["z.txt.rclone_chunk.020_z72iy7"(1)|file|ITEM_TRASH|1819079798274|46a380327681b424ae5dd108042cb2f3e858163e] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(0)|file|ITEM_UPLOAD|1819068445986|3e4907ace299cb4e700c050927359732017f2efe] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819067022563|8c92a5d95e167f468f41ea22d552fa457505e2ab] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': Received 5 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881484759") 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881485036") 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002_z74k0y"(0)|file|ITEM_UPLOAD|1819067022563|e0d64c27fc6c4647bbfe4cc85b0c351db924720a] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819062195970|6647f355d59a86443ffef1df67fe5a781bc19011] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["z.txt.rclone_chunk.020_z72iy7"(1)|file|ITEM_TRASH|1819079798274|46a380327681b424ae5dd108042cb2f3e858163e] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(0)|file|ITEM_UPLOAD|1819068445986|3e4907ace299cb4e700c050927359732017f2efe] ignored due to duplicate EventID 2025/03/31 04:52:18 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819067022563|8c92a5d95e167f468f41ea22d552fa457505e2ab] ignored due to duplicate EventID 2025/03/31 04:52:19 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881485475") 2025/03/31 04:52:19 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002_z74k0y"(0)|file|ITEM_UPLOAD|1819067022563|e0d64c27fc6c4647bbfe4cc85b0c351db924720a] ignored due to duplicate EventID 2025/03/31 04:52:20 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881486469") 2025/03/31 04:52:20 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819062195970|6647f355d59a86443ffef1df67fe5a781bc19011] ignored due to duplicate EventID 2025/03/31 04:52:20 DEBUG : box root 'rclone-test-logabet6xazi': ["z.txt.rclone_chunk.020_z72iy7"(1)|file|ITEM_TRASH|1819079798274|46a380327681b424ae5dd108042cb2f3e858163e] ignored due to duplicate EventID 2025/03/31 04:52:20 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(0)|file|ITEM_UPLOAD|1819068445986|3e4907ace299cb4e700c050927359732017f2efe] ignored due to duplicate EventID 2025/03/31 04:52:20 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819067022563|8c92a5d95e167f468f41ea22d552fa457505e2ab] ignored due to duplicate EventID 2025/03/31 04:52:21 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881487467") 2025/03/31 04:52:21 DEBUG : box root 'rclone-test-logabet6xazi': ["z.txt.rclone_chunk.020_z72iy7"(1)|file|ITEM_TRASH|1819079798274|46a380327681b424ae5dd108042cb2f3e858163e] ignored due to duplicate EventID 2025/03/31 04:52:21 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(0)|file|ITEM_UPLOAD|1819068445986|3e4907ace299cb4e700c050927359732017f2efe] ignored due to duplicate EventID 2025/03/31 04:52:22 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881488471") 2025/03/31 04:52:22 INFO : dir/file4.rclone_chunk.001_z74spw: Moved (server-side) to: dir/file4.rclone_chunk.001 2025/03/31 04:52:22 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819067022563|8c92a5d95e167f468f41ea22d552fa457505e2ab] ignored due to duplicate EventID 2025/03/31 04:52:23 INFO : dir/file4.rclone_chunk.002_z74spw: Moved (server-side) to: dir/file4.rclone_chunk.002 2025/03/31 04:52:23 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881489470") 2025/03/31 04:52:24 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881490484") 2025/03/31 04:52:24 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.001_z74spw"(0)|file|ITEM_UPLOAD|1819068675796|f5557db3864d353a54fc6e6b1d44dd4ead115b9b] added new path ("dir/file4.rclone_chunk.001_z74spw") for notify 2025/03/31 04:52:24 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002_z74spw"(0)|file|ITEM_UPLOAD|1819078967206|623ae79276512b8f474cf0175e304ded1c1d1bec] added new path ("dir/file4.rclone_chunk.002_z74spw") for notify 2025/03/31 04:52:24 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_z74spw", 1) 2025/03/31 04:52:24 DEBUG : doChangeNotify("dir/file4.rclone_chunk.002_z74spw", 1) 2025/03/31 04:52:24 DEBUG : box root 'rclone-test-logabet6xazi': Received 2 events, resulting in 2 paths and 2 notifications 2025/03/31 04:52:24 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881491573") 2025/03/31 04:52:25 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.001_z74spw"(0)|file|ITEM_UPLOAD|1819068675796|f5557db3864d353a54fc6e6b1d44dd4ead115b9b] ignored due to duplicate EventID 2025/03/31 04:52:25 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002_z74spw"(0)|file|ITEM_UPLOAD|1819078967206|623ae79276512b8f474cf0175e304ded1c1d1bec] ignored due to duplicate EventID 2025/03/31 04:52:25 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881491845") 2025/03/31 04:52:25 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.001_z74spw"(0)|file|ITEM_UPLOAD|1819068675796|f5557db3864d353a54fc6e6b1d44dd4ead115b9b] ignored due to duplicate EventID 2025/03/31 04:52:25 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002_z74spw"(0)|file|ITEM_UPLOAD|1819078967206|623ae79276512b8f474cf0175e304ded1c1d1bec] ignored due to duplicate EventID 2025/03/31 04:52:26 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881492492") 2025/03/31 04:52:27 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881493490") 2025/03/31 04:52:28 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881494462") 2025/03/31 04:52:28 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002_z74spw"(0)|file|ITEM_UPLOAD|1819078967206|623ae79276512b8f474cf0175e304ded1c1d1bec] ignored due to duplicate EventID 2025/03/31 04:52:28 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001_z74k0y"(0)|file|ITEM_UPLOAD|1819062195970|dc64894eb523532214c5b7b9266ed00e0bc5b3ed] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:28 DEBUG : box root 'rclone-test-logabet6xazi': ["file4"(0)|file|ITEM_UPLOAD|1819079829152|df6fb87a9ff67189119468773d6081c22ab2c30e] ignored due to old SequenceID ('\x00') 2025/03/31 04:52:28 DEBUG : box root 'rclone-test-logabet6xazi': Received 3 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:28 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881495474") 2025/03/31 04:52:29 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881495733") 2025/03/31 04:52:30 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881496471") 2025/03/31 04:52:30 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001_z74k0y"(0)|file|ITEM_UPLOAD|1819062195970|dc64894eb523532214c5b7b9266ed00e0bc5b3ed] ignored due to duplicate EventID 2025/03/31 04:52:30 DEBUG : box root 'rclone-test-logabet6xazi': ["file4"(0)|file|ITEM_UPLOAD|1819079829152|df6fb87a9ff67189119468773d6081c22ab2c30e] ignored due to duplicate EventID 2025/03/31 04:52:31 INFO : dir/file3.rclone_chunk.001_z7515t: Moved (server-side) to: dir/file3.rclone_chunk.001 2025/03/31 04:52:31 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881497464") 2025/03/31 04:52:31 INFO : dir/file3.rclone_chunk.002_z7515t: Moved (server-side) to: dir/file3.rclone_chunk.002 2025/03/31 04:52:31 DEBUG : box root 'rclone-test-logabet6xazi': ["file4"(0)|file|ITEM_UPLOAD|1819079829152|df6fb87a9ff67189119468773d6081c22ab2c30e] ignored due to duplicate EventID 2025/03/31 04:52:32 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881498488") 2025/03/31 04:52:33 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881499465") 2025/03/31 04:52:33 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819068675796|3fb94bdb5f32ebc893f5ce538ab9898349e4fe65] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:33 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819065145271|b0a1cb6b07e81932eb84ddbb327189900044edf0] added new path ("dir/file3.rclone_chunk.001") for notify 2025/03/31 04:52:33 DEBUG : doChangeNotify("dir/file3", 1) 2025/03/31 04:52:33 DEBUG : box root 'rclone-test-logabet6xazi': Received 2 events, resulting in 1 paths and 1 notifications 2025/03/31 04:52:33 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881500470") fstests.go:931: Try 1/10 waiting for dirChanges and objChanges 2025/03/31 04:52:34 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881500746") 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881501470") 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819068675796|3fb94bdb5f32ebc893f5ce538ab9898349e4fe65] ignored due to duplicate EventID 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819065145271|b0a1cb6b07e81932eb84ddbb327189900044edf0] ignored due to duplicate EventID 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001_z7515t"(0)|file|ITEM_UPLOAD|1819065145271|09b255ab063be2109f40f9fe60beba06fa272c4d] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002_z7515t"(0)|file|ITEM_UPLOAD|1819074734083|aabe970bb2db8d97a73c6b4abb4d159fd4959cff] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819074734083|766817f642e66cdbc84bf1fb96eccd4b2ce31b3f] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078967206|1e755206dff1d1db340b06bacc9eab4efd156a46] ignored due to old SequenceID ('\x01') 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': Received 6 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:35 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881502479") 2025/03/31 04:52:36 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881502757") 2025/03/31 04:52:36 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819065145271|b0a1cb6b07e81932eb84ddbb327189900044edf0] ignored due to duplicate EventID 2025/03/31 04:52:36 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001_z7515t"(0)|file|ITEM_UPLOAD|1819065145271|09b255ab063be2109f40f9fe60beba06fa272c4d] ignored due to duplicate EventID 2025/03/31 04:52:36 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002_z7515t"(0)|file|ITEM_UPLOAD|1819074734083|aabe970bb2db8d97a73c6b4abb4d159fd4959cff] ignored due to duplicate EventID 2025/03/31 04:52:36 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819074734083|766817f642e66cdbc84bf1fb96eccd4b2ce31b3f] ignored due to duplicate EventID 2025/03/31 04:52:36 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078967206|1e755206dff1d1db340b06bacc9eab4efd156a46] ignored due to duplicate EventID fstests.go:931: Try 2/10 waiting for dirChanges and objChanges 2025/03/31 04:52:37 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881503482") 2025/03/31 04:52:37 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819065145271|b0a1cb6b07e81932eb84ddbb327189900044edf0] ignored due to duplicate EventID 2025/03/31 04:52:38 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881504470") 2025/03/31 04:52:38 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001_z7515t"(0)|file|ITEM_UPLOAD|1819065145271|09b255ab063be2109f40f9fe60beba06fa272c4d] ignored due to duplicate EventID 2025/03/31 04:52:38 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002_z7515t"(0)|file|ITEM_UPLOAD|1819074734083|aabe970bb2db8d97a73c6b4abb4d159fd4959cff] ignored due to duplicate EventID 2025/03/31 04:52:38 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819074734083|766817f642e66cdbc84bf1fb96eccd4b2ce31b3f] ignored due to duplicate EventID 2025/03/31 04:52:38 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078967206|1e755206dff1d1db340b06bacc9eab4efd156a46] ignored due to duplicate EventID 2025/03/31 04:52:39 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881505461") fstests.go:931: Try 3/10 waiting for dirChanges and objChanges 2025/03/31 04:52:40 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881506463") 2025/03/31 04:52:41 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881507461") 2025/03/31 04:52:42 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881508461") fstests.go:931: Try 4/10 waiting for dirChanges and objChanges 2025/03/31 04:52:43 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881509461") 2025/03/31 04:52:44 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881510546") 2025/03/31 04:52:45 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881511478") fstests.go:931: Try 5/10 waiting for dirChanges and objChanges 2025/03/31 04:52:46 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881512473") 2025/03/31 04:52:47 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881513463") 2025/03/31 04:52:48 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881514473") 2025/03/31 04:52:48 DEBUG : box root 'rclone-test-logabet6xazi': ["file3"(0)|file|ITEM_UPLOAD|1819079903764|99ab285c5071ddae12295c8aa666413d2e31dde3] ignored due to old SequenceID ('\x00') 2025/03/31 04:52:48 DEBUG : box root 'rclone-test-logabet6xazi': Received 1 events, resulting in 0 paths and 0 notifications 2025/03/31 04:52:48 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881515477") fstests.go:931: Try 6/10 waiting for dirChanges and objChanges 2025/03/31 04:52:49 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881515744") 2025/03/31 04:52:50 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881516477") 2025/03/31 04:52:51 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881517480") fstests.go:931: Try 7/10 waiting for dirChanges and objChanges 2025/03/31 04:52:52 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881518465") 2025/03/31 04:52:53 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881519467") 2025/03/31 04:52:54 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881520472") fstests.go:931: Try 8/10 waiting for dirChanges and objChanges 2025/03/31 04:52:55 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881521463") 2025/03/31 04:52:56 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881522499") 2025/03/31 04:52:57 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881523464") fstests.go:931: Try 9/10 waiting for dirChanges and objChanges 2025/03/31 04:52:58 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881524478") 2025/03/31 04:52:59 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881525466") 2025/03/31 04:53:00 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881526480") fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or map[dir/file3:{} dir/file4.rclone_chunk.001_z74spw:{} dir/file4.rclone_chunk.002_z74spw:{} zero-length-file:{} zero-length-file.rclone_chunk.001_z748vn:{}] does not contain [dir/file2 dir/file4 dir/file3] 2025/03/31 04:53:01 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881527579") 2025/03/31 04:53:02 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881528622") 2025/03/31 04:53:03 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881529512") 2025/03/31 04:53:04 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881530497") 2025/03/31 04:53:05 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881531476") 2025/03/31 04:53:06 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881532492") 2025/03/31 04:53:07 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881533472") 2025/03/31 04:53:08 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881534478") 2025/03/31 04:53:08 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(1)|file|ITEM_TRASH|1819068445986|3593bf516fd830a57fee78f51eb69a600adfd12e] added old path ("dir/file2") for notify 2025/03/31 04:53:08 DEBUG : doChangeNotify("dir/file2", 1) 2025/03/31 04:53:08 DEBUG : box root 'rclone-test-logabet6xazi': Received 1 events, resulting in 1 paths and 1 notifications 2025/03/31 04:53:08 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881535476") 2025/03/31 04:53:09 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881535724") 2025/03/31 04:53:09 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(1)|file|ITEM_TRASH|1819068445986|3593bf516fd830a57fee78f51eb69a600adfd12e] ignored due to duplicate EventID 2025/03/31 04:53:09 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819067022563|3345c5586c1ff98a36a06e71b27371ddff441895] added old path ("dir/file2.rclone_chunk.002") for notify 2025/03/31 04:53:09 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819062195970|01fc54e2e68a38f6e792dfff673f1e8f70138bae] added old path ("dir/file2.rclone_chunk.001") for notify 2025/03/31 04:53:09 DEBUG : doChangeNotify("dir/file2", 1) 2025/03/31 04:53:09 DEBUG : doChangeNotify("dir/file2", 1) 2025/03/31 04:53:09 DEBUG : box root 'rclone-test-logabet6xazi': Received 3 events, resulting in 2 paths and 2 notifications 2025/03/31 04:53:09 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881536476") 2025/03/31 04:53:10 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881536720") 2025/03/31 04:53:10 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(1)|file|ITEM_TRASH|1819068445986|3593bf516fd830a57fee78f51eb69a600adfd12e] ignored due to duplicate EventID 2025/03/31 04:53:10 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819067022563|3345c5586c1ff98a36a06e71b27371ddff441895] ignored due to duplicate EventID 2025/03/31 04:53:10 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819062195970|01fc54e2e68a38f6e792dfff673f1e8f70138bae] ignored due to duplicate EventID 2025/03/31 04:53:11 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881537463") 2025/03/31 04:53:12 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881538468") 2025/03/31 04:53:12 DEBUG : box root 'rclone-test-logabet6xazi': ["file2"(1)|file|ITEM_TRASH|1819068445986|3593bf516fd830a57fee78f51eb69a600adfd12e] ignored due to duplicate EventID 2025/03/31 04:53:13 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881539479") 2025/03/31 04:53:13 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819067022563|3345c5586c1ff98a36a06e71b27371ddff441895] ignored due to duplicate EventID 2025/03/31 04:53:13 DEBUG : box root 'rclone-test-logabet6xazi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819062195970|01fc54e2e68a38f6e792dfff673f1e8f70138bae] ignored due to duplicate EventID 2025/03/31 04:53:14 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881540474") 2025/03/31 04:53:15 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881541467") 2025/03/31 04:53:15 DEBUG : box root 'rclone-test-logabet6xazi': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819068675796|df5bf00b56a76a018dc9c3b8ddced71042c482a9] added old path ("dir/file4.rclone_chunk.001") for notify 2025/03/31 04:53:15 DEBUG : doChangeNotify("dir/file4", 1) 2025/03/31 04:53:15 DEBUG : box root 'rclone-test-logabet6xazi': Received 1 events, resulting in 1 paths and 1 notifications 2025/03/31 04:53:15 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881542474") 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881542723") 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["file4"(1)|file|ITEM_TRASH|1819079829152|b5faddcff004f59eec7c592fa38ff849baf25005] added old path ("dir/file4") for notify 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir1"(1)|folder|ITEM_TRASH|314270001496|c8eb04ce30f669a5b5aae6cdfbfc4b54c3bdfc7f] added old path ("dir/subdir1") for notify 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1819065145271|24adfdf3dc6bc19c5be539ac8e5cf0b625f8cd76] added old path ("dir/file3.rclone_chunk.001") for notify 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002"(2)|file|ITEM_TRASH|1819074734083|7700284f0e57fca7f8c40e97c48819caa9314448] added old path ("dir/file3.rclone_chunk.002") for notify 2025/03/31 04:53:16 DEBUG : doChangeNotify("dir/file4", 1) 2025/03/31 04:53:16 DEBUG : doChangeNotify("dir/subdir1", 0) 2025/03/31 04:53:16 DEBUG : doChangeNotify("dir/file3", 1) 2025/03/31 04:53:16 DEBUG : doChangeNotify("dir/file3", 1) 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': Received 4 events, resulting in 4 paths and 4 notifications 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': Checking for changes on remote (next_stream_position: "30401040881543469") 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["file4"(1)|file|ITEM_TRASH|1819079829152|b5faddcff004f59eec7c592fa38ff849baf25005] ignored due to duplicate EventID 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["subdir1"(1)|folder|ITEM_TRASH|314270001496|c8eb04ce30f669a5b5aae6cdfbfc4b54c3bdfc7f] ignored due to duplicate EventID 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1819065145271|24adfdf3dc6bc19c5be539ac8e5cf0b625f8cd76] ignored due to duplicate EventID 2025/03/31 04:53:16 DEBUG : box root 'rclone-test-logabet6xazi': ["file3.rclone_chunk.002"(2)|file|ITEM_TRASH|1819074734083|7700284f0e57fca7f8c40e97c48819caa9314448] ignored due to duplicate EventID === RUN TestIntegration/FsMkdir/FsPutFiles 2025/03/31 04:53:22 INFO : file name.txt.rclone_chunk.001_z76g3u: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/03/31 04:53:23 INFO : file name.txt.rclone_chunk.002_z76g3u: Moved (server-side) to: file name.txt.rclone_chunk.002 2025/03/31 04:53:31 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001_z76pzi: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001 2025/03/31 04:53:32 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002_z76pzi: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002 2025/03/31 04:53:44 INFO : file name.txt.rclone_chunk.001_z770t2: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/03/31 04:53:44 INFO : file name.txt.rclone_chunk.002_z770t2: Moved (server-side) to: file name.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/03/31 04:53:53 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:" 2025/03/31 04:53:53 DEBUG : Config file has changed externally - reloading 2025/03/31 04:53:53 DEBUG : Creating backend with remote "TestBox:" 2025/03/31 04:53:53 DEBUG : Reset feature "ListR" 2025/03/31 04:53:54 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2025/03/31 04:54:11 INFO : dirToPurge/fileToPurge.txt.rclone_chunk.001_z77sgk: Moved (server-side) to: dirToPurge/fileToPurge.txt.rclone_chunk.001 2025/03/31 04:54:11 INFO : dirToPurge/fileToPurge.txt.rclone_chunk.002_z77sgk: Moved (server-side) to: dirToPurge/fileToPurge.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/03/31 04:54:27 INFO : dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.001_z7885j: Moved (server-side) to: dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.001 2025/03/31 04:54:27 INFO : dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.002_z7885j: Moved (server-side) to: dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.002 2025/03/31 04:54:32 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/dirToPurgeFromRoot" 2025/03/31 04:54:32 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/dirToPurgeFromRoot" 2025/03/31 04:54:33 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/03/31 04:54:38 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/hello? sausage/êé" 2025/03/31 04:54:38 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/hello? sausage/êé" 2025/03/31 04:54:40 DEBUG : Reset feature "ListR" 2025/03/31 04:54:49 INFO : a/b/c/d/e.txt.rclone_chunk.001_z78r56: Moved (server-side) to: a/b/c/d/e.txt.rclone_chunk.001 2025/03/31 04:54:50 INFO : a/b/c/d/e.txt.rclone_chunk.002_z78r56: Moved (server-side) to: a/b/c/d/e.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2025/03/31 04:54:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: copy 2 data chunks... === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1289: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/03/31 04:55:13 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: move 2 data chunks... 2025/03/31 04:55:14 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001: Moved (server-side) to: other.txt.rclone_chunk.001 2025/03/31 04:55:15 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002: Moved (server-side) to: other.txt.rclone_chunk.002 2025/03/31 04:55:16 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Moved (server-side) to: other.txt 2025/03/31 04:55:21 DEBUG : file name.txt: move 2 data chunks... 2025/03/31 04:55:23 INFO : file name.txt.rclone_chunk.001: Moved (server-side) to: moveTest/other.txt.rclone_chunk.001 2025/03/31 04:55:24 INFO : file name.txt.rclone_chunk.002: Moved (server-side) to: moveTest/other.txt.rclone_chunk.002 2025/03/31 04:55:25 INFO : file name.txt: Moved (server-side) to: moveTest/other.txt 2025/03/31 04:55:31 DEBUG : moveTest/other.txt: move 2 data chunks... 2025/03/31 04:55:32 INFO : moveTest/other.txt.rclone_chunk.001: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/03/31 04:55:33 INFO : moveTest/other.txt.rclone_chunk.002: Moved (server-side) to: file name.txt.rclone_chunk.002 2025/03/31 04:55:34 INFO : moveTest/other.txt: Moved (server-side) to: file name.txt 2025/03/31 04:55:40 DEBUG : other.txt: move 2 data chunks... 2025/03/31 04:55:41 INFO : other.txt.rclone_chunk.001: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001 2025/03/31 04:55:42 INFO : other.txt.rclone_chunk.002: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002 2025/03/31 04:55:43 INFO : other.txt: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/03/31 04:55:48 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-mufadag0yozu" 2025/03/31 04:55:48 DEBUG : Creating backend with remote "TestBox:rclone-test-mufadag0yozu" 2025/03/31 04:55:48 DEBUG : box root 'rclone-test-mufadag0yozu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 04:55:49 DEBUG : Reset feature "ListR" 2025/03/31 04:55:50 DEBUG : box root 'rclone-test-mufadag0yozu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 04:55:56 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 04:56:00 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 04:56:07 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-mufadag0yozu': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1592: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1623: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1694: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2025/03/31 04:56:50 INFO : file name.txt.rclone_chunk.001_z7c2a1: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/03/31 04:56:51 INFO : file name.txt.rclone_chunk.002_z7c2a1: Moved (server-side) to: file name.txt.rclone_chunk.002 2025/03/31 04:56:51 INFO : file name.txt.rclone_chunk.003_z7c2a1: Moved (server-side) to: file name.txt.rclone_chunk.003 2025/03/31 04:56:52 INFO : file name.txt.rclone_chunk.004_z7c2a1: Moved (server-side) to: file name.txt.rclone_chunk.004 === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/03/31 04:57:00 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/03/31 04:57:00 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/03/31 04:57:08 DEBUG : fs cache: renaming child cache item "TestBox:rclone-test-logabet6xazi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" to be canonical for parent "TestBox:rclone-test-logabet6xazi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠" 2025/03/31 04:57:08 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/03/31 04:57:09 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/not found.txt" 2025/03/31 04:57:09 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/not found.txt" 2025/03/31 04:57:11 DEBUG : Reset feature "ListR" 2025/03/31 04:57:12 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestChunkerChunk50bBox:" path "rclone-test-logabet6xazi" from "TestChunkerChunk50bBox:rclone-test-logabet6xazi" 2025/03/31 04:57:12 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:" 2025/03/31 04:57:12 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1945: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/03/31 04:57:22 INFO : rclone-test-logabet6xazi/created from root.txt.rclone_chunk.001_z7d426: Moved (server-side) to: rclone-test-logabet6xazi/created from root.txt.rclone_chunk.001 2025/03/31 04:57:23 INFO : rclone-test-logabet6xazi/created from root.txt.rclone_chunk.002_z7d426: Moved (server-side) to: rclone-test-logabet6xazi/created from root.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2004: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:412: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2125: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 2025/03/31 04:57:50 INFO : piped data.txt.rclone_chunk.001_z7dxn1: Moved (server-side) to: piped data.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/03/31 04:57:59 INFO : piped data.txt.rclone_chunk.001_z7e3jd: Moved (server-side) to: piped data.txt.rclone_chunk.001 2025/03/31 04:57:59 INFO : piped data.txt.rclone_chunk.002_z7e3jd: Moved (server-side) to: piped data.txt.rclone_chunk.002 2025/03/31 04:58:00 INFO : piped data.txt.rclone_chunk.003_z7e3jd: Moved (server-side) to: piped data.txt.rclone_chunk.003 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/PutLarge chunker_internal_test.go:906: -upload-kilobytes is not set === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNameFormat 2025/03/31 04:58:08 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-logabet6xazi': invalid data chunk number in file "fish.chunk.001" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/SmallFileInternals 2025/03/31 04:58:13 INFO : small/emptyfile.rclone_chunk.001_z7eju1: Moved (server-side) to: small/emptyfile 2025/03/31 04:58:21 INFO : small/smallfile.rclone_chunk.001_z7es9n: Moved (server-side) to: small/smallfile === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/PreventCorruption 2025/03/31 04:58:37 INFO : corrupted/billy.rclone_chunk.001_z7f1rv: Moved (server-side) to: corrupted/billy.rclone_chunk.001 2025/03/31 04:58:38 INFO : corrupted/billy.rclone_chunk.002_z7f1rv: Moved (server-side) to: corrupted/billy.rclone_chunk.002 2025/03/31 04:58:38 INFO : corrupted/billy.rclone_chunk.003_z7f1rv: Moved (server-side) to: corrupted/billy.rclone_chunk.003 2025/03/31 04:58:39 INFO : corrupted/billy.rclone_chunk.004_z7f1rv: Moved (server-side) to: corrupted/billy.rclone_chunk.004 2025/03/31 04:58:40 INFO : corrupted/billy.rclone_chunk.005_z7f1rv: Moved (server-side) to: corrupted/billy.rclone_chunk.005 2025/03/31 04:58:54 INFO : corrupted/silly1.rclone_chunk.001_z7figg: Moved (server-side) to: corrupted/silly1.rclone_chunk.001 2025/03/31 04:58:54 INFO : corrupted/silly1.rclone_chunk.002_z7figg: Moved (server-side) to: corrupted/silly1.rclone_chunk.002 2025/03/31 04:58:55 INFO : corrupted/silly1.rclone_chunk.003_z7figg: Moved (server-side) to: corrupted/silly1.rclone_chunk.003 2025/03/31 04:58:56 INFO : corrupted/silly1.rclone_chunk.004_z7figg: Moved (server-side) to: corrupted/silly1.rclone_chunk.004 2025/03/31 04:58:56 INFO : corrupted/silly1.rclone_chunk.005_z7figg: Moved (server-side) to: corrupted/silly1.rclone_chunk.005 2025/03/31 04:59:11 INFO : corrupted/silly2.rclone_chunk.001_z7g0vm: Moved (server-side) to: corrupted/silly2.rclone_chunk.001 2025/03/31 04:59:12 INFO : corrupted/silly2.rclone_chunk.002_z7g0vm: Moved (server-side) to: corrupted/silly2.rclone_chunk.002 2025/03/31 04:59:12 INFO : corrupted/silly2.rclone_chunk.003_z7g0vm: Moved (server-side) to: corrupted/silly2.rclone_chunk.003 2025/03/31 04:59:13 INFO : corrupted/silly2.rclone_chunk.004_z7g0vm: Moved (server-side) to: corrupted/silly2.rclone_chunk.004 2025/03/31 04:59:13 INFO : corrupted/silly2.rclone_chunk.005_z7g0vm: Moved (server-side) to: corrupted/silly2.rclone_chunk.005 2025/03/31 04:59:30 INFO : corrupted/silly3.rclone_chunk.001_z7gjkj: Moved (server-side) to: corrupted/silly3.rclone_chunk.001 2025/03/31 04:59:31 INFO : corrupted/silly3.rclone_chunk.002_z7gjkj: Moved (server-side) to: corrupted/silly3.rclone_chunk.002 2025/03/31 04:59:31 INFO : corrupted/silly3.rclone_chunk.003_z7gjkj: Moved (server-side) to: corrupted/silly3.rclone_chunk.003 2025/03/31 04:59:32 INFO : corrupted/silly3.rclone_chunk.004_z7gjkj: Moved (server-side) to: corrupted/silly3.rclone_chunk.004 2025/03/31 04:59:32 INFO : corrupted/silly3.rclone_chunk.005_z7gjkj: Moved (server-side) to: corrupted/silly3.rclone_chunk.005 2025/03/31 04:59:46 INFO : corrupted/willy.rclone_chunk.001_z7gzti: Moved (server-side) to: corrupted/willy.rclone_chunk.001 2025/03/31 04:59:47 INFO : corrupted/willy.rclone_chunk.002_z7gzti: Moved (server-side) to: corrupted/willy.rclone_chunk.002 2025/03/31 04:59:47 INFO : corrupted/willy.rclone_chunk.003_z7gzti: Moved (server-side) to: corrupted/willy.rclone_chunk.003 2025/03/31 04:59:48 INFO : corrupted/willy.rclone_chunk.004_z7gzti: Moved (server-side) to: corrupted/willy.rclone_chunk.004 2025/03/31 04:59:48 INFO : corrupted/willy.rclone_chunk.005_z7gzti: Moved (server-side) to: corrupted/willy.rclone_chunk.005 2025/03/31 05:00:03 INFO : corrupted/silly4.rclone_chunk.001_z7hgkq: Moved (server-side) to: corrupted/silly4.rclone_chunk.001 2025/03/31 05:00:04 INFO : corrupted/silly4.rclone_chunk.002_z7hgkq: Moved (server-side) to: corrupted/silly4.rclone_chunk.002 2025/03/31 05:00:04 INFO : corrupted/silly4.rclone_chunk.003_z7hgkq: Moved (server-side) to: corrupted/silly4.rclone_chunk.003 2025/03/31 05:00:05 INFO : corrupted/silly4.rclone_chunk.004_z7hgkq: Moved (server-side) to: corrupted/silly4.rclone_chunk.004 2025/03/31 05:00:06 INFO : corrupted/silly4.rclone_chunk.005_z7hgkq: Moved (server-side) to: corrupted/silly4.rclone_chunk.005 2025/03/31 05:00:09 ERROR : corrupted/silly4: Failed to copy: can't copy: chunk overlap with "corrupted/willy" 2025/03/31 05:00:20 INFO : corrupted/silly5.rclone_chunk.001_z7hxsz: Moved (server-side) to: corrupted/silly5.rclone_chunk.001 2025/03/31 05:00:20 INFO : corrupted/silly5.rclone_chunk.002_z7hxsz: Moved (server-side) to: corrupted/silly5.rclone_chunk.002 2025/03/31 05:00:21 INFO : corrupted/silly5.rclone_chunk.003_z7hxsz: Moved (server-side) to: corrupted/silly5.rclone_chunk.003 2025/03/31 05:00:21 INFO : corrupted/silly5.rclone_chunk.004_z7hxsz: Moved (server-side) to: corrupted/silly5.rclone_chunk.004 2025/03/31 05:00:22 INFO : corrupted/silly5.rclone_chunk.005_z7hxsz: Moved (server-side) to: corrupted/silly5.rclone_chunk.005 2025/03/31 05:00:25 ERROR : corrupted/willy.rclone_chunk.002: Couldn't delete: refuse to corrupt: chunk overlap with "corrupted/willy" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNumberOverflow 2025/03/31 05:00:47 INFO : wreaked/wreaker.rclone_chunk.001_z7isux: Moved (server-side) to: wreaked/wreaker.rclone_chunk.001 2025/03/31 05:00:48 INFO : wreaked/wreaker.rclone_chunk.002_z7isux: Moved (server-side) to: wreaked/wreaker.rclone_chunk.002 2025/03/31 05:00:53 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-logabet6xazi': invalid directory entry "wreaked/wreaker" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/MetadataInput 2025/03/31 05:01:09 ERROR : usermeta/today.rclone_chunk.001_z7jhue: Couldn't move: Error "item_name_in_use" (409): Item with the same name already exists ({"conflicts":{"type":"file","id":"1819092805329","file_version":{"type":"file_version","id":"2005232965329","sha1":"7be49e353ee21561206e1da71b883cd5538b9973"},"sequence_id":"0","etag":"0","sha1":"7be49e353ee21561206e1da71b883cd5538b9973","name":"today.rclone_chunk.001_z7jhue"}}) 2025/03/31 05:01:11 INFO : usermeta/today.rclone_chunk.001: Deleted 2025/03/31 05:01:11 INFO : usermeta/today.rclone_chunk.001_z7jhue: Moved (server-side) to: usermeta/today.rclone_chunk.001 2025/03/31 05:01:24 ERROR : usermeta/past.rclone_chunk.001_z7jwbr: Couldn't move: Error "item_name_in_use" (409): Item with the same name already exists ({"conflicts":{"type":"file","id":"1819088422939","file_version":{"type":"file_version","id":"2005228669339","sha1":"19ce348da906ba7f953608f75d61d9f2d9bb6774"},"sequence_id":"0","etag":"0","sha1":"19ce348da906ba7f953608f75d61d9f2d9bb6774","name":"past.rclone_chunk.001_z7jwbr"}}) 2025/03/31 05:01:26 INFO : usermeta/past.rclone_chunk.001: Deleted 2025/03/31 05:01:26 INFO : usermeta/past.rclone_chunk.001_z7jwbr: Moved (server-side) to: usermeta/past.rclone_chunk.001 2025/03/31 05:01:39 ERROR : usermeta/future.rclone_chunk.001_z7kbqs: Couldn't move: Error "item_name_in_use" (409): Item with the same name already exists ({"conflicts":{"type":"file","id":"1819088154232","file_version":{"type":"file_version","id":"2005228244632","sha1":"4f93e7315127f1e93e7e33b2c1b3cc8be5a6d6ba"},"sequence_id":"0","etag":"0","sha1":"4f93e7315127f1e93e7e33b2c1b3cc8be5a6d6ba","name":"future.rclone_chunk.001_z7kbqs"}}) 2025/03/31 05:01:41 INFO : usermeta/future.rclone_chunk.001: Deleted 2025/03/31 05:01:42 INFO : usermeta/future.rclone_chunk.001_z7kbqs: Moved (server-side) to: usermeta/future.rclone_chunk.001 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/FutureProof 2025/03/31 05:02:03 DEBUG : future/test: Can't move - required hash not found 2025/03/31 05:02:03 DEBUG : future/test: Can't move, switching to copy 2025/03/31 05:02:03 DEBUG : future/test: Can't copy - required hash not found 2025/03/31 05:02:04 ERROR : future/test: Failed to copy: failed to open source object: can't open: unknown metadata, please upgrade rclone 2025/03/31 05:02:04 ERROR : future/test: Not deleting source as copy failed: failed to open source object: can't open: unknown metadata, please upgrade rclone 2025/03/31 05:02:06 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-logabet6xazi': File to upload is small (3 bytes), uploading instead of streaming === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/BackwardsCompatibility 2025/03/31 05:02:55 INFO : backcomp/renamefile.rclone_chunk.001_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.001 2025/03/31 05:02:55 INFO : backcomp/renamefile.rclone_chunk.002_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.002 2025/03/31 05:02:56 INFO : backcomp/renamefile.rclone_chunk.003_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.003 2025/03/31 05:02:57 INFO : backcomp/renamefile.rclone_chunk.004_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.004 2025/03/31 05:02:57 INFO : backcomp/renamefile.rclone_chunk.005_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.005 2025/03/31 05:02:58 INFO : backcomp/renamefile.rclone_chunk.006_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.006 2025/03/31 05:02:58 INFO : backcomp/renamefile.rclone_chunk.007_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.007 2025/03/31 05:02:59 INFO : backcomp/renamefile.rclone_chunk.008_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.008 2025/03/31 05:02:59 INFO : backcomp/renamefile.rclone_chunk.009_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.009 2025/03/31 05:03:00 INFO : backcomp/renamefile.rclone_chunk.010_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.010 2025/03/31 05:03:01 INFO : backcomp/renamefile.rclone_chunk.011_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.011 2025/03/31 05:03:01 INFO : backcomp/renamefile.rclone_chunk.012_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.012 2025/03/31 05:03:02 INFO : backcomp/renamefile.rclone_chunk.013_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.013 2025/03/31 05:03:02 INFO : backcomp/renamefile.rclone_chunk.014_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.014 2025/03/31 05:03:03 INFO : backcomp/renamefile.rclone_chunk.015_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.015 2025/03/31 05:03:03 INFO : backcomp/renamefile.rclone_chunk.016_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.016 2025/03/31 05:03:04 INFO : backcomp/renamefile.rclone_chunk.017_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.017 2025/03/31 05:03:05 INFO : backcomp/renamefile.rclone_chunk.018_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.018 2025/03/31 05:03:05 INFO : backcomp/renamefile.rclone_chunk.019_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.019 2025/03/31 05:03:06 INFO : backcomp/renamefile.rclone_chunk.020_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.020 2025/03/31 05:03:07 INFO : backcomp/renamefile.rclone_chunk.021_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.021 2025/03/31 05:03:07 INFO : backcomp/renamefile.rclone_chunk.022_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.022 2025/03/31 05:03:08 INFO : backcomp/renamefile.rclone_chunk.023_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.023 2025/03/31 05:03:08 INFO : backcomp/renamefile.rclone_chunk.024_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.024 2025/03/31 05:03:09 INFO : backcomp/renamefile.rclone_chunk.025_z7lav6: Moved (server-side) to: backcomp/renamefile.rclone_chunk.025 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkerServerSideMove 2025/03/31 05:03:46 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/servermovetest/subdir1" 2025/03/31 05:03:46 DEBUG : Config file has changed externally - reloading 2025/03/31 05:03:46 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/servermovetest/subdir1" 2025/03/31 05:03:48 DEBUG : Reset feature "ListR" 2025/03/31 05:03:48 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/servermovetest/subdir2" 2025/03/31 05:03:48 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/servermovetest/subdir2" 2025/03/31 05:03:51 DEBUG : Reset feature "ListR" 2025/03/31 05:03:59 INFO : movefile.rclone_chunk.001_z7o3hf: Moved (server-side) to: movefile.rclone_chunk.001 2025/03/31 05:04:00 INFO : movefile.rclone_chunk.002_z7o3hf: Moved (server-side) to: movefile.rclone_chunk.002 2025/03/31 05:04:04 DEBUG : movefile: move 2 data chunks... 2025/03/31 05:04:06 INFO : movefile.rclone_chunk.001: Moved (server-side) 2025/03/31 05:04:07 INFO : movefile.rclone_chunk.002: Moved (server-side) 2025/03/31 05:04:08 INFO : movefile: Moved (server-side) 2025/03/31 05:04:10 INFO : movefile: Moved (server-side) === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/MD5AllSlow 2025/03/31 05:04:16 DEBUG : Creating backend with remote "TestChunkerChunk50bBox,chunk_size='1P',hash_type='md5all',meta_format='simplejson',name_format='*.#',transactions='rename':rclone-test-logabet6xazi/md5all" 2025/03/31 05:04:16 DEBUG : TestChunkerChunk50bBox: detected overridden config - adding "{r7Izf}" suffix to name 2025/03/31 05:04:16 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/md5all" 2025/03/31 05:04:18 DEBUG : Reset feature "ListR" chunker_internal_test.go:878: this test needs a base fs with slow hash, e.g. local === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2364: *chunker.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2485: *chunker.Fs does not implement SetCopyCutoff === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/03/31 05:04:25 INFO : unknown-size-put.txt.rclone_chunk.001_z7ouak: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.001 2025/03/31 05:04:26 INFO : unknown-size-put.txt.rclone_chunk.002_z7ouak: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.002 2025/03/31 05:04:26 INFO : unknown-size-put.txt.rclone_chunk.003_z7ouak: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.003 === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/03/31 05:04:38 INFO : unknown-size-update.txt.rclone_chunk.001_z7p8m3: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.001 2025/03/31 05:04:38 INFO : unknown-size-update.txt.rclone_chunk.002_z7p8m3: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.002 2025/03/31 05:04:56 INFO : unknown-size-update.txt.rclone_chunk.001_z7piil: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.001 2025/03/31 05:04:56 INFO : unknown-size-update.txt.rclone_chunk.002_z7piil: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.002 2025/03/31 05:04:57 INFO : unknown-size-update.txt.rclone_chunk.003_z7piil: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.003 2025/03/31 05:04:58 INFO : unknown-size-update.txt.rclone_chunk.004_z7piil: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.004 2025/03/31 05:04:58 INFO : unknown-size-update.txt.rclone_chunk.005_z7piil: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.005 === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/03/31 05:05:07 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-logabet6xazi/deeper/nonexisting/directory" 2025/03/31 05:05:07 DEBUG : Creating backend with remote "TestBox:rclone-test-logabet6xazi/deeper/nonexisting/directory" 2025/03/31 05:05:10 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2625: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2668: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory === RUN TestIntegration/FsMkdir/FsDirectory/ReadDirMetadata fstests.go:2742: Directories don't support ReadDirMetadata === RUN TestIntegration/FsMkdir/FsDirectory/WriteDirMetadata fstests.go:2754: Directories don't support WriteDirMetadata === RUN TestIntegration/FsMkdir/FsDirectory/WriteDirSetModTime fstests.go:2768: Directories don't support WriteDirSetModTime === RUN TestIntegration/FsMkdir/FsDirectory/DirectoryCheckWrap 2025/03/31 05:05:17 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/31 05:05:17 ERROR : : error listing: directory not found 2025/03/31 05:05:18 DEBUG : box root 'rclone-test-logabet6xazi': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsShutdown --- FAIL: TestIntegration (1188.88s) --- PASS: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (1.06s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.94s) --- FAIL: TestIntegration/FsMkdir (1182.46s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (7.63s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.38s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.36s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.38s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (307.71s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (16.81s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (16.69s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (15.83s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (16.22s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (15.99s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (15.35s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (15.75s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (16.28s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (16.53s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (15.90s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (17.59s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (16.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (15.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (15.84s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (15.69s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (16.50s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (16.45s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (15.94s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (16.04s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.73s) --- PASS: TestIntegration/FsMkdir/FsPutError (61.87s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (7.32s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (73.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (661.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.97s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (2.14s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.46s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (1.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.87s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.76s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (2.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (1.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (3.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (2.73s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (2.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (15.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (17.29s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (19.55s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (13.68s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (36.77s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (20.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.84s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (1.12s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (1.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (1.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (1.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (1.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (1.13s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (1.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (1.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (3.72s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (1.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (3.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (2.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (5.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (3.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (21.75s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (1.09s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (8.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (2.95s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (23.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (3.10s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.90s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (19.03s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (6.68s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (1.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (9.70s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.37s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (21.75s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (6.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (15.71s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (370.74s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal/PutLarge (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNameFormat (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/SmallFileInternals (18.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/PreventCorruption (134.93s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNumberOverflow (20.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/MetadataInput (47.45s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/FutureProof (21.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/BackwardsCompatibility (95.89s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkerServerSideMove (29.57s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal/MD5AllSlow (2.40s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (48.91s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (14.37s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (34.53s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (3.87s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsDirectory (2.63s) --- SKIP: TestIntegration/FsMkdir/FsDirectory/ReadDirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory/WriteDirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory/WriteDirSetModTime (0.00s) --- PASS: TestIntegration/FsMkdir/FsDirectory/DirectoryCheckWrap (0.00s) --- PASS: TestIntegration/FsShutdown (0.00s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/chunker 1188.933s "go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Finished ERROR in 19m52.183372582s (try 1/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]