"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Starting (try 1/5) === RUN TestIntegration fstests.go:442: Using remote "TestChunkerChunk50bBox:" 2025/02/20 03:33:40 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu" 2025/02/20 03:33:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/20 03:33:40 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu" 2025/02/20 03:33:40 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:33:41 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsCheckWrap === RUN TestIntegration/FsCommand fstests.go:511: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/02/20 03:33:41 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:33:42 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir 2025/02/20 03:33:44 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/02/20 03:33:45 INFO : dir/subdir: Making directory 2025/02/20 03:33:49 INFO : dir/subdir: Removing directory 2025/02/20 03:33:51 INFO : dir: Removing directory === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/02/20 03:33:53 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:711: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2025/02/20 03:33:59 INFO : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.001_yvhsjk: Moved (server-side) to: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.001 2025/02/20 03:34:00 INFO : ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.002_yvhsjk: Moved (server-side) to: ␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡/␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:711: testing "．" 2025/02/20 03:34:15 INFO : ．/．.rclone_chunk.001_yvi7ls: Moved (server-side) to: ．/．.rclone_chunk.001 2025/02/20 03:34:15 INFO : ．/．.rclone_chunk.002_yvi7ls: Moved (server-side) to: ．/．.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:711: testing "．．" 2025/02/20 03:34:30 INFO : ．．/．．.rclone_chunk.001_yvim9j: Moved (server-side) to: ．．/．．.rclone_chunk.001 2025/02/20 03:34:30 INFO : ．．/．．.rclone_chunk.002_yvim9j: Moved (server-side) to: ．．/．．.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:711: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2025/02/20 03:34:44 INFO : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.001_yvj16q: Moved (server-side) to: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.001 2025/02/20 03:34:45 INFO : !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.002_yvj16q: Moved (server-side) to: !"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~/!"#$%&'()*+,-.／:;<=>?@[\]^_`{|}~.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:711: testing " leading space" 2025/02/20 03:35:00 INFO : leading space/ leading space.rclone_chunk.001_yvjgki: Moved (server-side) to: leading space/ leading space.rclone_chunk.001 2025/02/20 03:35:00 INFO : leading space/ leading space.rclone_chunk.002_yvjgki: Moved (server-side) to: leading space/ leading space.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:711: testing "~leading tilde" 2025/02/20 03:35:15 INFO : ~leading tilde/~leading tilde.rclone_chunk.001_yvjwer: Moved (server-side) to: ~leading tilde/~leading tilde.rclone_chunk.001 2025/02/20 03:35:16 INFO : ~leading tilde/~leading tilde.rclone_chunk.002_yvjwer: Moved (server-side) to: ~leading tilde/~leading tilde.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:711: testing "␍leading CR" 2025/02/20 03:35:31 INFO : ␍leading CR/␍leading CR.rclone_chunk.001_yvkbzs: Moved (server-side) to: ␍leading CR/␍leading CR.rclone_chunk.001 2025/02/20 03:35:31 INFO : ␍leading CR/␍leading CR.rclone_chunk.002_yvkbzs: Moved (server-side) to: ␍leading CR/␍leading CR.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:711: testing "␊leading LF" 2025/02/20 03:35:46 INFO : ␊leading LF/␊leading LF.rclone_chunk.001_yvkq9y: Moved (server-side) to: ␊leading LF/␊leading LF.rclone_chunk.001 2025/02/20 03:35:46 INFO : ␊leading LF/␊leading LF.rclone_chunk.002_yvkq9y: Moved (server-side) to: ␊leading LF/␊leading LF.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:711: testing "␉leading HT" 2025/02/20 03:36:01 INFO : ␉leading HT/␉leading HT.rclone_chunk.001_yvl56m: Moved (server-side) to: ␉leading HT/␉leading HT.rclone_chunk.001 2025/02/20 03:36:01 INFO : ␉leading HT/␉leading HT.rclone_chunk.002_yvl56m: Moved (server-side) to: ␉leading HT/␉leading HT.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:711: testing "␋leading VT" 2025/02/20 03:36:16 INFO : ␋leading VT/␋leading VT.rclone_chunk.001_yvlk0h: Moved (server-side) to: ␋leading VT/␋leading VT.rclone_chunk.001 2025/02/20 03:36:16 INFO : ␋leading VT/␋leading VT.rclone_chunk.002_yvlk0h: Moved (server-side) to: ␋leading VT/␋leading VT.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:711: testing ".leading dot" 2025/02/20 03:36:31 INFO : .leading dot/.leading dot.rclone_chunk.001_yvlz20: Moved (server-side) to: .leading dot/.leading dot.rclone_chunk.001 2025/02/20 03:36:31 INFO : .leading dot/.leading dot.rclone_chunk.002_yvlz20: Moved (server-side) to: .leading dot/.leading dot.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:711: testing "trailing space " 2025/02/20 03:36:46 INFO : trailing space /trailing space .rclone_chunk.001_yvmemu: Moved (server-side) to: trailing space /trailing space .rclone_chunk.001 2025/02/20 03:36:47 INFO : trailing space /trailing space .rclone_chunk.002_yvmemu: Moved (server-side) to: trailing space /trailing space .rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:711: testing "trailing CR␍" 2025/02/20 03:37:02 INFO : trailing CR␍/trailing CR␍.rclone_chunk.001_yvmu6q: Moved (server-side) to: trailing CR␍/trailing CR␍.rclone_chunk.001 2025/02/20 03:37:02 INFO : trailing CR␍/trailing CR␍.rclone_chunk.002_yvmu6q: Moved (server-side) to: trailing CR␍/trailing CR␍.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:711: testing "trailing LF␊" 2025/02/20 03:37:17 INFO : trailing LF␊/trailing LF␊.rclone_chunk.001_yvnadq: Moved (server-side) to: trailing LF␊/trailing LF␊.rclone_chunk.001 2025/02/20 03:37:18 INFO : trailing LF␊/trailing LF␊.rclone_chunk.002_yvnadq: Moved (server-side) to: trailing LF␊/trailing LF␊.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:711: testing "trailing HT␉" 2025/02/20 03:37:32 INFO : trailing HT␉/trailing HT␉.rclone_chunk.001_yvnps4: Moved (server-side) to: trailing HT␉/trailing HT␉.rclone_chunk.001 2025/02/20 03:37:33 INFO : trailing HT␉/trailing HT␉.rclone_chunk.002_yvnps4: Moved (server-side) to: trailing HT␉/trailing HT␉.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" 2025/02/20 03:37:47 INFO : trailing VT␋/trailing VT␋.rclone_chunk.001_yvo3fa: Moved (server-side) to: trailing VT␋/trailing VT␋.rclone_chunk.001 2025/02/20 03:37:48 INFO : trailing VT␋/trailing VT␋.rclone_chunk.002_yvo3fa: Moved (server-side) to: trailing VT␋/trailing VT␋.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." 2025/02/20 03:38:03 INFO : trailing dot./trailing dot..rclone_chunk.001_yvoj03: Moved (server-side) to: trailing dot./trailing dot..rclone_chunk.001 2025/02/20 03:38:03 INFO : trailing dot./trailing dot..rclone_chunk.002_yvoj03: Moved (server-side) to: trailing dot./trailing dot..rclone_chunk.002 2025/02/20 03:38:11 DEBUG : Config file has changed externally - reloading 2025/02/20 03:38:11 DEBUG : TestBox: Loaded invalid token from config file - ignoring 2025/02/20 03:38:12 DEBUG : Saving config "token" in section "TestBox" of the config file 2025/02/20 03:38:12 DEBUG : TestBox: Saved new token in config file === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:711: testing "invalid utf-8\xfe" 2025/02/20 03:38:19 INFO : invalid utf-8/invalid utf-8.rclone_chunk.001_yvoz7m: Moved (server-side) to: invalid utf-8/invalid utf-8.rclone_chunk.001 2025/02/20 03:38:20 INFO : invalid utf-8/invalid utf-8.rclone_chunk.002_yvoz7m: Moved (server-side) to: invalid utf-8/invalid utf-8.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" 2025/02/20 03:38:34 INFO : test%46.txt/test%46.txt.rclone_chunk.001_yvpfun: Moved (server-side) to: test%46.txt/test%46.txt.rclone_chunk.001 2025/02/20 03:38:35 INFO : test%46.txt/test%46.txt.rclone_chunk.002_yvpfun: Moved (server-side) to: test%46.txt/test%46.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError fstests.go:751: Reduce file size due to limit 1024 === RUN TestIntegration/FsMkdir/FsPutZeroLength 2025/02/20 03:39:46 INFO : zero-length-file.rclone_chunk.001_yvrgfp: Moved (server-side) to: zero-length-file === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:790: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:823: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify 2025/02/20 03:39:49 INFO : dir: Making directory 2025/02/20 03:39:51 INFO : dir/subdir1: Making directory 2025/02/20 03:39:52 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507538120") 2025/02/20 03:39:52 INFO : dir/subdir3: Making directory 2025/02/20 03:39:52 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 2 events, resulting in 0 paths and 0 notifications 2025/02/20 03:39:52 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507539369") 2025/02/20 03:39:52 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 0 paths and 0 notifications 2025/02/20 03:39:52 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507539618") 2025/02/20 03:39:53 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file"(1)|file|ITEM_RENAME|1782714979148|f98fd7182189446c11e9b1515838b930cf659cbe] ignored due to old SequenceID ('\x01') 2025/02/20 03:39:53 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file.rclone_chunk.001_yvrgfp"(0)|file|ITEM_UPLOAD|1782714979148|fd78b9d6421c998c10f7f569be47e7388b3feb76] ignored due to old SequenceID ('\x01') 2025/02/20 03:39:53 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 6 events, resulting in 0 paths and 0 notifications 2025/02/20 03:39:53 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507539861") 2025/02/20 03:39:53 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file"(1)|file|ITEM_RENAME|1782714979148|f98fd7182189446c11e9b1515838b930cf659cbe] ignored due to duplicate EventID 2025/02/20 03:39:53 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507540140") 2025/02/20 03:39:53 INFO : dir/subdir2: Making directory 2025/02/20 03:39:54 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507540458") 2025/02/20 03:39:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["z.txt.rclone_chunk.017_yvptmw"(1)|file|ITEM_TRASH|1782709614633|11117cf587dd874706601674b33afdcb9f4b0a92] ignored due to duplicate EventID 2025/02/20 03:39:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["z.txt.rclone_chunk.012_yvptmw"(1)|file|ITEM_TRASH|1782715830439|0ee873821fbc98678e7830a6fc48b85a968de90a] ignored due to duplicate EventID 2025/02/20 03:39:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["z.txt.rclone_chunk.011_yvptmw"(1)|file|ITEM_TRASH|1782715384209|4bb7c1b942ce634757ccecf6452245da53f92493] ignored due to duplicate EventID 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507541370") 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file.rclone_chunk.001_yvrgfp"(0)|file|ITEM_UPLOAD|1782714979148|fd78b9d6421c998c10f7f569be47e7388b3feb76] ignored due to duplicate EventID 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': ["z.txt.rclone_chunk.019_yvptmw"(1)|file|ITEM_TRASH|1782715645839|6c7c954425f93eeb928e9ae7d82d12258a71fabe] ignored due to duplicate EventID 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file"(2)|file|ITEM_TRASH|1782714979148|828a11f8e208eb4d84caa415cf278a08a59e07f6] added old path ("zero-length-file") for notify 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': ["dir"(0)|folder|ITEM_CREATE|308300260289|59d5b7bb2ec4587abd27e2c4feb67bcd11dae33a] added new path ("dir") for notify 2025/02/20 03:39:55 DEBUG : doChangeNotify("zero-length-file", 1) 2025/02/20 03:39:55 DEBUG : doChangeNotify("dir", 0) 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 4 events, resulting in 2 paths and 2 notifications 2025/02/20 03:39:55 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507542363") 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507542620") 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': ["z.txt.rclone_chunk.019_yvptmw"(1)|file|ITEM_TRASH|1782715645839|6c7c954425f93eeb928e9ae7d82d12258a71fabe] ignored due to duplicate EventID 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file"(2)|file|ITEM_TRASH|1782714979148|828a11f8e208eb4d84caa415cf278a08a59e07f6] ignored due to duplicate EventID 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': ["dir"(0)|folder|ITEM_CREATE|308300260289|59d5b7bb2ec4587abd27e2c4feb67bcd11dae33a] ignored due to duplicate EventID 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir1"(0)|folder|ITEM_CREATE|308302501310|cecb01c6e39256371c17083d9148247770eb9a25] added new path ("dir/subdir1") for notify 2025/02/20 03:39:56 DEBUG : doChangeNotify("dir/subdir1", 0) 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 4 events, resulting in 1 paths and 1 notifications 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507543366") 2025/02/20 03:39:56 DEBUG : box root 'rclone-test-rumidaj3tibu': ["z.txt.rclone_chunk.019_yvptmw"(1)|file|ITEM_TRASH|1782715645839|6c7c954425f93eeb928e9ae7d82d12258a71fabe] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507543631") 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file"(2)|file|ITEM_TRASH|1782714979148|828a11f8e208eb4d84caa415cf278a08a59e07f6] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["dir"(0)|folder|ITEM_CREATE|308300260289|59d5b7bb2ec4587abd27e2c4feb67bcd11dae33a] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir1"(0)|folder|ITEM_CREATE|308302501310|cecb01c6e39256371c17083d9148247770eb9a25] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir2"(0)|folder|ITEM_CREATE|308302629387|29577420eca47bc2667016fd38a6caa9c8d3a083] added new path ("dir/subdir2") for notify 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir3"(0)|folder|ITEM_CREATE|308298150970|3bf08474deb9fb3220ec5e39bdcaee7ee258e131] added new path ("dir/subdir3") for notify 2025/02/20 03:39:57 DEBUG : doChangeNotify("dir/subdir2", 0) 2025/02/20 03:39:57 DEBUG : doChangeNotify("dir/subdir3", 0) 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 5 events, resulting in 2 paths and 2 notifications 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507544355") 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["zero-length-file"(2)|file|ITEM_TRASH|1782714979148|828a11f8e208eb4d84caa415cf278a08a59e07f6] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["dir"(0)|folder|ITEM_CREATE|308300260289|59d5b7bb2ec4587abd27e2c4feb67bcd11dae33a] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir1"(0)|folder|ITEM_CREATE|308302501310|cecb01c6e39256371c17083d9148247770eb9a25] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir2"(0)|folder|ITEM_CREATE|308302629387|29577420eca47bc2667016fd38a6caa9c8d3a083] ignored due to duplicate EventID 2025/02/20 03:39:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir3"(0)|folder|ITEM_CREATE|308298150970|3bf08474deb9fb3220ec5e39bdcaee7ee258e131] ignored due to duplicate EventID 2025/02/20 03:39:58 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507544621") 2025/02/20 03:39:58 DEBUG : box root 'rclone-test-rumidaj3tibu': ["dir"(0)|folder|ITEM_CREATE|308300260289|59d5b7bb2ec4587abd27e2c4feb67bcd11dae33a] ignored due to duplicate EventID 2025/02/20 03:39:58 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir1"(0)|folder|ITEM_CREATE|308302501310|cecb01c6e39256371c17083d9148247770eb9a25] ignored due to duplicate EventID 2025/02/20 03:39:58 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir2"(0)|folder|ITEM_CREATE|308302629387|29577420eca47bc2667016fd38a6caa9c8d3a083] ignored due to duplicate EventID 2025/02/20 03:39:58 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir3"(0)|folder|ITEM_CREATE|308298150970|3bf08474deb9fb3220ec5e39bdcaee7ee258e131] ignored due to duplicate EventID 2025/02/20 03:39:59 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507545359") 2025/02/20 03:39:59 DEBUG : box root 'rclone-test-rumidaj3tibu': ["dir"(0)|folder|ITEM_CREATE|308300260289|59d5b7bb2ec4587abd27e2c4feb67bcd11dae33a] ignored due to duplicate EventID 2025/02/20 03:39:59 INFO : dir/file2.rclone_chunk.001_yvrsqy: Moved (server-side) to: dir/file2.rclone_chunk.001 2025/02/20 03:40:00 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507546354") 2025/02/20 03:40:00 INFO : dir/file2.rclone_chunk.002_yvrsqy: Moved (server-side) to: dir/file2.rclone_chunk.002 2025/02/20 03:40:00 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir1"(0)|folder|ITEM_CREATE|308302501310|cecb01c6e39256371c17083d9148247770eb9a25] ignored due to duplicate EventID 2025/02/20 03:40:01 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507547373") 2025/02/20 03:40:01 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir2"(0)|folder|ITEM_CREATE|308302629387|29577420eca47bc2667016fd38a6caa9c8d3a083] ignored due to duplicate EventID 2025/02/20 03:40:01 DEBUG : box root 'rclone-test-rumidaj3tibu': ["subdir3"(0)|folder|ITEM_CREATE|308298150970|3bf08474deb9fb3220ec5e39bdcaee7ee258e131] ignored due to duplicate EventID 2025/02/20 03:40:02 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507548365") 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507549357") 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001_yvrsqy"(0)|file|ITEM_UPLOAD|1782712264190|526a33d506b2f6b33533ee53d1657a7097cc08b4] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002_yvrsqy"(0)|file|ITEM_UPLOAD|1782717858920|d0c1b1b323cbb4fca451e540d5166b3c9682a26b] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1782712264190|7cc364f7b3ebde8c68f8942599b2e972c5bc6d37] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 3 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507550368") 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001_yvrsqy"(0)|file|ITEM_UPLOAD|1782712264190|526a33d506b2f6b33533ee53d1657a7097cc08b4] ignored due to duplicate EventID 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002_yvrsqy"(0)|file|ITEM_UPLOAD|1782717858920|d0c1b1b323cbb4fca451e540d5166b3c9682a26b] ignored due to duplicate EventID 2025/02/20 03:40:03 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1782712264190|7cc364f7b3ebde8c68f8942599b2e972c5bc6d37] ignored due to duplicate EventID 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507550640") 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001_yvrsqy"(0)|file|ITEM_UPLOAD|1782712264190|526a33d506b2f6b33533ee53d1657a7097cc08b4] ignored due to duplicate EventID 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002_yvrsqy"(0)|file|ITEM_UPLOAD|1782717858920|d0c1b1b323cbb4fca451e540d5166b3c9682a26b] ignored due to duplicate EventID 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1782712264190|7cc364f7b3ebde8c68f8942599b2e972c5bc6d37] ignored due to duplicate EventID 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2"(0)|file|ITEM_UPLOAD|1782715807099|b84b4fc79a975ecc3ca3b0630965a3e76969f308] ignored due to old SequenceID ('\x00') 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 4 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507551356") 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002_yvrsqy"(0)|file|ITEM_UPLOAD|1782717858920|d0c1b1b323cbb4fca451e540d5166b3c9682a26b] ignored due to duplicate EventID 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1782712264190|7cc364f7b3ebde8c68f8942599b2e972c5bc6d37] ignored due to duplicate EventID 2025/02/20 03:40:04 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2"(0)|file|ITEM_UPLOAD|1782715807099|b84b4fc79a975ecc3ca3b0630965a3e76969f308] ignored due to duplicate EventID 2025/02/20 03:40:05 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507551610") 2025/02/20 03:40:06 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507552358") 2025/02/20 03:40:06 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002_yvrsqy"(0)|file|ITEM_UPLOAD|1782717858920|d0c1b1b323cbb4fca451e540d5166b3c9682a26b] ignored due to duplicate EventID 2025/02/20 03:40:07 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507553364") 2025/02/20 03:40:07 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1782712264190|7cc364f7b3ebde8c68f8942599b2e972c5bc6d37] ignored due to duplicate EventID 2025/02/20 03:40:08 INFO : dir/file4.rclone_chunk.001_yvs09j: Moved (server-side) to: dir/file4.rclone_chunk.001 2025/02/20 03:40:08 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507554360") 2025/02/20 03:40:08 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2"(0)|file|ITEM_UPLOAD|1782715807099|b84b4fc79a975ecc3ca3b0630965a3e76969f308] ignored due to duplicate EventID 2025/02/20 03:40:08 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001_yvs09j"(0)|file|ITEM_UPLOAD|1782723050248|8ee01f25141b1f4ca802958f31fc486deaa0633e] added new path ("dir/file4.rclone_chunk.001_yvs09j") for notify 2025/02/20 03:40:08 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_yvs09j", 1) 2025/02/20 03:40:08 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 2 events, resulting in 1 paths and 1 notifications 2025/02/20 03:40:08 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507555363") 2025/02/20 03:40:08 INFO : dir/file4.rclone_chunk.002_yvs09j: Moved (server-side) to: dir/file4.rclone_chunk.002 2025/02/20 03:40:09 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507555623") 2025/02/20 03:40:10 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507556356") 2025/02/20 03:40:10 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001_yvs09j"(0)|file|ITEM_UPLOAD|1782723050248|8ee01f25141b1f4ca802958f31fc486deaa0633e] ignored due to duplicate EventID 2025/02/20 03:40:10 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.002_yvs09j"(0)|file|ITEM_UPLOAD|1782714980796|5ba049b1e50984845dc359c9a5a9c00dd17b709d] added new path ("dir/file4.rclone_chunk.002_yvs09j") for notify 2025/02/20 03:40:10 DEBUG : doChangeNotify("dir/file4.rclone_chunk.002_yvs09j", 1) 2025/02/20 03:40:10 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 2 events, resulting in 1 paths and 1 notifications 2025/02/20 03:40:10 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507557381") 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507557629") 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001_yvs09j"(0)|file|ITEM_UPLOAD|1782723050248|8ee01f25141b1f4ca802958f31fc486deaa0633e] ignored due to duplicate EventID 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.002_yvs09j"(0)|file|ITEM_UPLOAD|1782714980796|5ba049b1e50984845dc359c9a5a9c00dd17b709d] ignored due to duplicate EventID 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1782723050248|d1c948fc9c6ff5da9930aff00a1dcf93d5162980] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 3 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507558358") 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001_yvs09j"(0)|file|ITEM_UPLOAD|1782723050248|8ee01f25141b1f4ca802958f31fc486deaa0633e] ignored due to duplicate EventID 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.002_yvs09j"(0)|file|ITEM_UPLOAD|1782714980796|5ba049b1e50984845dc359c9a5a9c00dd17b709d] ignored due to duplicate EventID 2025/02/20 03:40:11 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1782723050248|d1c948fc9c6ff5da9930aff00a1dcf93d5162980] ignored due to duplicate EventID 2025/02/20 03:40:12 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507558617") 2025/02/20 03:40:12 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001_yvs09j"(0)|file|ITEM_UPLOAD|1782723050248|8ee01f25141b1f4ca802958f31fc486deaa0633e] ignored due to duplicate EventID 2025/02/20 03:40:13 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507559358") 2025/02/20 03:40:14 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507560368") 2025/02/20 03:40:14 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.002_yvs09j"(0)|file|ITEM_UPLOAD|1782714980796|5ba049b1e50984845dc359c9a5a9c00dd17b709d] ignored due to duplicate EventID 2025/02/20 03:40:15 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507561358") 2025/02/20 03:40:15 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1782723050248|d1c948fc9c6ff5da9930aff00a1dcf93d5162980] ignored due to duplicate EventID 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507562366") 2025/02/20 03:40:16 INFO : dir/file3.rclone_chunk.001_yvs8kp: Moved (server-side) to: dir/file3.rclone_chunk.001 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1782714980796|f93ff57df0ab8e9e093a93b9ae092443cc1e7f4d] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507563365") 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1782717858920|dbe872ee7baee406473fa0590738442a90084d53] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:16 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507563605") 2025/02/20 03:40:16 INFO : dir/file3.rclone_chunk.002_yvs8kp: Moved (server-side) to: dir/file3.rclone_chunk.002 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4"(0)|file|ITEM_UPLOAD|1782716043865|2afc10337eff296e0c86f6a91367c2143a9242d6] ignored due to old SequenceID ('\x00') 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507563843") 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4"(0)|file|ITEM_UPLOAD|1782716043865|2afc10337eff296e0c86f6a91367c2143a9242d6] ignored due to duplicate EventID 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507564085") 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001_yvs8kp"(0)|file|ITEM_UPLOAD|1782719234091|dbcd05b413ddb82496b9ef258090a0eb8a47cdc5] added new path ("dir/file3.rclone_chunk.001_yvs8kp") for notify 2025/02/20 03:40:17 DEBUG : doChangeNotify("dir/file3.rclone_chunk.001_yvs8kp", 1) 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 1 paths and 1 notifications 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507564357") 2025/02/20 03:40:17 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001_yvs8kp"(0)|file|ITEM_UPLOAD|1782719234091|dbcd05b413ddb82496b9ef258090a0eb8a47cdc5] ignored due to duplicate EventID 2025/02/20 03:40:18 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507564592") 2025/02/20 03:40:19 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507565351") fstests.go:935: Try 1/10 waiting for dirChanges and objChanges 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507566362") 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001_yvs8kp"(0)|file|ITEM_UPLOAD|1782719234091|dbcd05b413ddb82496b9ef258090a0eb8a47cdc5] ignored due to duplicate EventID 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002_yvs8kp"(0)|file|ITEM_UPLOAD|1782710917355|4657996f21484c3d738dbe870c63668b682d8fb7] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1782710917355|02693d8ae4db239b5e77acd105a4f94ae8079815] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 3 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507567359") 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001_yvs8kp"(0)|file|ITEM_UPLOAD|1782719234091|dbcd05b413ddb82496b9ef258090a0eb8a47cdc5] ignored due to duplicate EventID 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002_yvs8kp"(0)|file|ITEM_UPLOAD|1782710917355|4657996f21484c3d738dbe870c63668b682d8fb7] ignored due to duplicate EventID 2025/02/20 03:40:20 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1782710917355|02693d8ae4db239b5e77acd105a4f94ae8079815] ignored due to duplicate EventID 2025/02/20 03:40:21 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507567617") 2025/02/20 03:40:21 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001_yvs8kp"(0)|file|ITEM_UPLOAD|1782719234091|dbcd05b413ddb82496b9ef258090a0eb8a47cdc5] ignored due to duplicate EventID 2025/02/20 03:40:22 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507568362") 2025/02/20 03:40:22 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002_yvs8kp"(0)|file|ITEM_UPLOAD|1782710917355|4657996f21484c3d738dbe870c63668b682d8fb7] ignored due to duplicate EventID 2025/02/20 03:40:22 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1782710917355|02693d8ae4db239b5e77acd105a4f94ae8079815] ignored due to duplicate EventID 2025/02/20 03:40:22 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3"(0)|file|ITEM_UPLOAD|1782719060858|161d5d38dd1c8b73c44d9b97ed2329ae4b63a00a] ignored due to old SequenceID ('\x00') 2025/02/20 03:40:22 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 3 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:22 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507569369") fstests.go:935: Try 2/10 waiting for dirChanges and objChanges 2025/02/20 03:40:23 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507569614") 2025/02/20 03:40:23 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1782710917355|02693d8ae4db239b5e77acd105a4f94ae8079815] ignored due to duplicate EventID 2025/02/20 03:40:23 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3"(0)|file|ITEM_UPLOAD|1782719060858|161d5d38dd1c8b73c44d9b97ed2329ae4b63a00a] ignored due to duplicate EventID 2025/02/20 03:40:24 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507570360") 2025/02/20 03:40:25 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507571357") 2025/02/20 03:40:25 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3"(0)|file|ITEM_UPLOAD|1782719060858|161d5d38dd1c8b73c44d9b97ed2329ae4b63a00a] ignored due to duplicate EventID fstests.go:935: Try 3/10 waiting for dirChanges and objChanges 2025/02/20 03:40:26 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507572359") 2025/02/20 03:40:27 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507573360") 2025/02/20 03:40:28 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507574357") 2025/02/20 03:40:28 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1782719234091|2b41ed1cf010de1b4fd88421ee44db124a0e2485] ignored due to old SequenceID ('\x01') 2025/02/20 03:40:28 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 0 paths and 0 notifications 2025/02/20 03:40:28 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507575359") fstests.go:935: Try 4/10 waiting for dirChanges and objChanges 2025/02/20 03:40:29 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507575599") 2025/02/20 03:40:30 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507576370") 2025/02/20 03:40:31 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507577354") 2025/02/20 03:40:31 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1782719234091|2b41ed1cf010de1b4fd88421ee44db124a0e2485] ignored due to duplicate EventID fstests.go:935: Try 5/10 waiting for dirChanges and objChanges 2025/02/20 03:40:32 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507578366") 2025/02/20 03:40:33 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507579367") 2025/02/20 03:40:34 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507580373") fstests.go:935: Try 6/10 waiting for dirChanges and objChanges 2025/02/20 03:40:35 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507581354") 2025/02/20 03:40:36 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507582360") 2025/02/20 03:40:37 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507583353") fstests.go:935: Try 7/10 waiting for dirChanges and objChanges 2025/02/20 03:40:38 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507584351") 2025/02/20 03:40:39 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507585355") 2025/02/20 03:40:40 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507586352") fstests.go:935: Try 8/10 waiting for dirChanges and objChanges 2025/02/20 03:40:41 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507587362") 2025/02/20 03:40:42 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507588357") 2025/02/20 03:40:43 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507589361") fstests.go:935: Try 9/10 waiting for dirChanges and objChanges 2025/02/20 03:40:44 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507590365") 2025/02/20 03:40:45 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507591365") 2025/02/20 03:40:46 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507592371") fstests.go:939: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or map[dir/file3.rclone_chunk.001_yvs8kp:{} dir/file4.rclone_chunk.001_yvs09j:{} dir/file4.rclone_chunk.002_yvs09j:{} zero-length-file:{}] does not contain [dir/file2 dir/file4 dir/file3] 2025/02/20 03:40:47 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507593367") 2025/02/20 03:40:48 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507594374") 2025/02/20 03:40:49 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507595366") 2025/02/20 03:40:50 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507596361") 2025/02/20 03:40:51 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507597365") 2025/02/20 03:40:52 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507598364") 2025/02/20 03:40:53 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507599370") 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507600370") 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1782712264190|727511c2a0a0112c44dfbd59fbbbee337889a185] added old path ("dir/file2.rclone_chunk.001") for notify 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1782717858920|dbf20b4538bb4b79db6e3a435b80ce05dffe4c95] added old path ("dir/file2.rclone_chunk.002") for notify 2025/02/20 03:40:54 DEBUG : doChangeNotify("dir/file2", 1) 2025/02/20 03:40:54 DEBUG : doChangeNotify("dir/file2", 1) 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 2 events, resulting in 2 paths and 2 notifications 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507601379") 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1782712264190|727511c2a0a0112c44dfbd59fbbbee337889a185] ignored due to duplicate EventID 2025/02/20 03:40:54 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1782717858920|dbf20b4538bb4b79db6e3a435b80ce05dffe4c95] ignored due to duplicate EventID 2025/02/20 03:40:55 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507601621") 2025/02/20 03:40:56 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507602370") 2025/02/20 03:40:57 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507603368") 2025/02/20 03:40:57 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1782712264190|727511c2a0a0112c44dfbd59fbbbee337889a185] ignored due to duplicate EventID 2025/02/20 03:40:58 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507604379") 2025/02/20 03:40:58 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1782717858920|dbf20b4538bb4b79db6e3a435b80ce05dffe4c95] ignored due to duplicate EventID 2025/02/20 03:40:59 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507605367") 2025/02/20 03:40:59 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file2"(1)|file|ITEM_TRASH|1782715807099|0eba27995e1013ef2d800ac27437e2c482e2786a] added old path ("dir/file2") for notify 2025/02/20 03:40:59 DEBUG : doChangeNotify("dir/file2", 1) 2025/02/20 03:40:59 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 1 paths and 1 notifications 2025/02/20 03:40:59 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507606366") 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507606608") 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4"(1)|file|ITEM_TRASH|1782716043865|e9e3c1d1bb31e3c225f2e68bd61f30212d28832e] added old path ("dir/file4") for notify 2025/02/20 03:41:00 DEBUG : doChangeNotify("dir/file4", 1) 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 1 paths and 1 notifications 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507607360") 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1782723050248|dfd84a59893882da9da9b3bf3af4a6aa0d7770c5] added old path ("dir/file4.rclone_chunk.001") for notify 2025/02/20 03:41:00 DEBUG : doChangeNotify("dir/file4", 1) 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': Received 1 events, resulting in 1 paths and 1 notifications 2025/02/20 03:41:00 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507607613") 2025/02/20 03:41:01 DEBUG : box root 'rclone-test-rumidaj3tibu': Checking for changes on remote (next_stream_position: "30401037507607855") === RUN TestIntegration/FsMkdir/FsPutFiles 2025/02/20 03:41:06 INFO : file name.txt.rclone_chunk.001_yvtny7: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/02/20 03:41:07 INFO : file name.txt.rclone_chunk.002_yvtny7: Moved (server-side) to: file name.txt.rclone_chunk.002 2025/02/20 03:41:15 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001_yvtvx5: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001 2025/02/20 03:41:15 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002_yvtvx5: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002 2025/02/20 03:41:27 INFO : file name.txt.rclone_chunk.001_yvu5s4: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/02/20 03:41:28 INFO : file name.txt.rclone_chunk.002_yvu5s4: Moved (server-side) to: file name.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2025/02/20 03:41:36 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:" 2025/02/20 03:41:36 DEBUG : Creating backend with remote "TestBox:" 2025/02/20 03:41:36 DEBUG : Reset feature "ListR" 2025/02/20 03:41:37 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:403: 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/02/20 03:41:54 INFO : dirToPurge/fileToPurge.txt.rclone_chunk.001_yvuxzr: Moved (server-side) to: dirToPurge/fileToPurge.txt.rclone_chunk.001 2025/02/20 03:41:54 INFO : dirToPurge/fileToPurge.txt.rclone_chunk.002_yvuxzr: Moved (server-side) to: dirToPurge/fileToPurge.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/02/20 03:42:09 INFO : dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.001_yvvc6m: Moved (server-side) to: dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.001 2025/02/20 03:42:10 INFO : dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.002_yvvc6m: Moved (server-side) to: dirToPurgeFromRoot/fileToPurgeFromRoot.txt.rclone_chunk.002 2025/02/20 03:42:15 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/dirToPurgeFromRoot" 2025/02/20 03:42:15 DEBUG : Config file has changed externally - reloading 2025/02/20 03:42:15 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/dirToPurgeFromRoot" 2025/02/20 03:42:16 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/02/20 03:42:20 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/hello? sausage/êé" 2025/02/20 03:42:20 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/hello? sausage/êé" 2025/02/20 03:42:21 DEBUG : Reset feature "ListR" 2025/02/20 03:42:31 INFO : a/b/c/d/e.txt.rclone_chunk.001_yvvv7f: Moved (server-side) to: a/b/c/d/e.txt.rclone_chunk.001 2025/02/20 03:42:31 INFO : a/b/c/d/e.txt.rclone_chunk.002_yvvv7f: Moved (server-side) to: a/b/c/d/e.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2025/02/20 03:42:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: copy 2 data chunks... === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1293: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2025/02/20 03:42:54 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: move 2 data chunks... 2025/02/20 03:42:55 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001: Moved (server-side) to: other.txt.rclone_chunk.001 2025/02/20 03:42:56 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002: Moved (server-side) to: other.txt.rclone_chunk.002 2025/02/20 03:42:57 INFO : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Moved (server-side) to: other.txt 2025/02/20 03:43:02 DEBUG : file name.txt: move 2 data chunks... 2025/02/20 03:43:05 INFO : file name.txt.rclone_chunk.001: Moved (server-side) to: moveTest/other.txt.rclone_chunk.001 2025/02/20 03:43:06 INFO : file name.txt.rclone_chunk.002: Moved (server-side) to: moveTest/other.txt.rclone_chunk.002 2025/02/20 03:43:07 INFO : file name.txt: Moved (server-side) to: moveTest/other.txt 2025/02/20 03:43:12 DEBUG : moveTest/other.txt: move 2 data chunks... 2025/02/20 03:43:13 INFO : moveTest/other.txt.rclone_chunk.001: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/02/20 03:43:14 INFO : moveTest/other.txt.rclone_chunk.002: Moved (server-side) to: file name.txt.rclone_chunk.002 2025/02/20 03:43:16 INFO : moveTest/other.txt: Moved (server-side) to: file name.txt 2025/02/20 03:43:21 DEBUG : other.txt: move 2 data chunks... 2025/02/20 03:43:22 INFO : other.txt.rclone_chunk.001: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.001 2025/02/20 03:43:23 INFO : other.txt.rclone_chunk.002: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt.rclone_chunk.002 2025/02/20 03:43:24 INFO : other.txt: Moved (server-side) to: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1402: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/02/20 03:43:28 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-pabiraj1sune" 2025/02/20 03:43:28 DEBUG : Creating backend with remote "TestBox:rclone-test-pabiraj1sune" 2025/02/20 03:43:29 DEBUG : box root 'rclone-test-pabiraj1sune': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:43:29 DEBUG : Reset feature "ListR" 2025/02/20 03:43:30 DEBUG : box root 'rclone-test-pabiraj1sune': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:43:36 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:43:40 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:43:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-pabiraj1sune': 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:1596: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1627: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1698: 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/02/20 03:44:28 INFO : file name.txt.rclone_chunk.001_yvz2rf: Moved (server-side) to: file name.txt.rclone_chunk.001 2025/02/20 03:44:28 INFO : file name.txt.rclone_chunk.002_yvz2rf: Moved (server-side) to: file name.txt.rclone_chunk.002 2025/02/20 03:44:29 INFO : file name.txt.rclone_chunk.003_yvz2rf: Moved (server-side) to: file name.txt.rclone_chunk.003 2025/02/20 03:44:30 INFO : file name.txt.rclone_chunk.004_yvz2rf: Moved (server-side) to: file name.txt.rclone_chunk.004 === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/02/20 03:44:38 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/02/20 03:44:38 DEBUG : Config file has changed externally - reloading 2025/02/20 03:44:38 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/02/20 03:44:45 DEBUG : fs cache: renaming child cache item "TestBox:rclone-test-rumidaj3tibu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" to be canonical for parent "TestBox:rclone-test-rumidaj3tibu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠" 2025/02/20 03:44:45 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/02/20 03:44:46 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/not found.txt" 2025/02/20 03:44:46 DEBUG : Config file has changed externally - reloading 2025/02/20 03:44:46 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/not found.txt" 2025/02/20 03:44:49 DEBUG : Reset feature "ListR" 2025/02/20 03:44:49 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1915: Opening root remote "TestChunkerChunk50bBox:" path "rclone-test-rumidaj3tibu" from "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu" 2025/02/20 03:44:49 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:" 2025/02/20 03:44:49 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:1949: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2025/02/20 03:44:57 INFO : rclone-test-rumidaj3tibu/created from root.txt.rclone_chunk.001_yw01sq: Moved (server-side) to: rclone-test-rumidaj3tibu/created from root.txt.rclone_chunk.001 2025/02/20 03:44:57 INFO : rclone-test-rumidaj3tibu/created from root.txt.rclone_chunk.002_yw01sq: Moved (server-side) to: rclone-test-rumidaj3tibu/created from root.txt.rclone_chunk.002 === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2008: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:416: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2129: 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/02/20 03:45:22 INFO : piped data.txt.rclone_chunk.001_yw0sck: Moved (server-side) to: piped data.txt === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 2025/02/20 03:45:31 INFO : piped data.txt.rclone_chunk.001_yw0yem: Moved (server-side) to: piped data.txt.rclone_chunk.001 2025/02/20 03:45:31 INFO : piped data.txt.rclone_chunk.002_yw0yem: Moved (server-side) to: piped data.txt.rclone_chunk.002 2025/02/20 03:45:32 INFO : piped data.txt.rclone_chunk.003_yw0yem: 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/02/20 03:45:39 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rumidaj3tibu': invalid data chunk number in file "fish.chunk.001" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/SmallFileInternals 2025/02/20 03:45:44 INFO : small/emptyfile.rclone_chunk.001_yw1dh8: Moved (server-side) to: small/emptyfile 2025/02/20 03:45:52 INFO : small/smallfile.rclone_chunk.001_yw1m2n: Moved (server-side) to: small/smallfile === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/PreventCorruption 2025/02/20 03:46:08 INFO : corrupted/billy.rclone_chunk.001_yw1upw: Moved (server-side) to: corrupted/billy.rclone_chunk.001 2025/02/20 03:46:08 INFO : corrupted/billy.rclone_chunk.002_yw1upw: Moved (server-side) to: corrupted/billy.rclone_chunk.002 2025/02/20 03:46:09 INFO : corrupted/billy.rclone_chunk.003_yw1upw: Moved (server-side) to: corrupted/billy.rclone_chunk.003 2025/02/20 03:46:10 INFO : corrupted/billy.rclone_chunk.004_yw1upw: Moved (server-side) to: corrupted/billy.rclone_chunk.004 2025/02/20 03:46:10 INFO : corrupted/billy.rclone_chunk.005_yw1upw: Moved (server-side) to: corrupted/billy.rclone_chunk.005 2025/02/20 03:46:23 INFO : corrupted/silly1.rclone_chunk.001_yw2ako: Moved (server-side) to: corrupted/silly1.rclone_chunk.001 2025/02/20 03:46:24 INFO : corrupted/silly1.rclone_chunk.002_yw2ako: Moved (server-side) to: corrupted/silly1.rclone_chunk.002 2025/02/20 03:46:24 INFO : corrupted/silly1.rclone_chunk.003_yw2ako: Moved (server-side) to: corrupted/silly1.rclone_chunk.003 2025/02/20 03:46:25 INFO : corrupted/silly1.rclone_chunk.004_yw2ako: Moved (server-side) to: corrupted/silly1.rclone_chunk.004 2025/02/20 03:46:25 INFO : corrupted/silly1.rclone_chunk.005_yw2ako: Moved (server-side) to: corrupted/silly1.rclone_chunk.005 2025/02/20 03:46:39 INFO : corrupted/silly2.rclone_chunk.001_yw2q7g: Moved (server-side) to: corrupted/silly2.rclone_chunk.001 2025/02/20 03:46:40 INFO : corrupted/silly2.rclone_chunk.002_yw2q7g: Moved (server-side) to: corrupted/silly2.rclone_chunk.002 2025/02/20 03:46:40 INFO : corrupted/silly2.rclone_chunk.003_yw2q7g: Moved (server-side) to: corrupted/silly2.rclone_chunk.003 2025/02/20 03:46:41 INFO : corrupted/silly2.rclone_chunk.004_yw2q7g: Moved (server-side) to: corrupted/silly2.rclone_chunk.004 2025/02/20 03:46:42 INFO : corrupted/silly2.rclone_chunk.005_yw2q7g: Moved (server-side) to: corrupted/silly2.rclone_chunk.005 2025/02/20 03:46:58 INFO : corrupted/silly3.rclone_chunk.001_yw39vb: Moved (server-side) to: corrupted/silly3.rclone_chunk.001 2025/02/20 03:46:59 INFO : corrupted/silly3.rclone_chunk.002_yw39vb: Moved (server-side) to: corrupted/silly3.rclone_chunk.002 2025/02/20 03:46:59 INFO : corrupted/silly3.rclone_chunk.003_yw39vb: Moved (server-side) to: corrupted/silly3.rclone_chunk.003 2025/02/20 03:47:00 INFO : corrupted/silly3.rclone_chunk.004_yw39vb: Moved (server-side) to: corrupted/silly3.rclone_chunk.004 2025/02/20 03:47:01 INFO : corrupted/silly3.rclone_chunk.005_yw39vb: Moved (server-side) to: corrupted/silly3.rclone_chunk.005 2025/02/20 03:47:13 INFO : corrupted/willy.rclone_chunk.001_yw3p6m: Moved (server-side) to: corrupted/willy.rclone_chunk.001 2025/02/20 03:47:14 INFO : corrupted/willy.rclone_chunk.002_yw3p6m: Moved (server-side) to: corrupted/willy.rclone_chunk.002 2025/02/20 03:47:15 INFO : corrupted/willy.rclone_chunk.003_yw3p6m: Moved (server-side) to: corrupted/willy.rclone_chunk.003 2025/02/20 03:47:16 INFO : corrupted/willy.rclone_chunk.004_yw3p6m: Moved (server-side) to: corrupted/willy.rclone_chunk.004 2025/02/20 03:47:16 INFO : corrupted/willy.rclone_chunk.005_yw3p6m: Moved (server-side) to: corrupted/willy.rclone_chunk.005 2025/02/20 03:47:31 INFO : corrupted/silly4.rclone_chunk.001_yw46rb: Moved (server-side) to: corrupted/silly4.rclone_chunk.001 2025/02/20 03:47:31 INFO : corrupted/silly4.rclone_chunk.002_yw46rb: Moved (server-side) to: corrupted/silly4.rclone_chunk.002 2025/02/20 03:47:32 INFO : corrupted/silly4.rclone_chunk.003_yw46rb: Moved (server-side) to: corrupted/silly4.rclone_chunk.003 2025/02/20 03:47:32 INFO : corrupted/silly4.rclone_chunk.004_yw46rb: Moved (server-side) to: corrupted/silly4.rclone_chunk.004 2025/02/20 03:47:33 INFO : corrupted/silly4.rclone_chunk.005_yw46rb: Moved (server-side) to: corrupted/silly4.rclone_chunk.005 2025/02/20 03:47:37 ERROR : corrupted/silly4: Failed to copy: can't copy: chunk overlap with "corrupted/willy" 2025/02/20 03:47:47 INFO : corrupted/silly5.rclone_chunk.001_yw4mrq: Moved (server-side) to: corrupted/silly5.rclone_chunk.001 2025/02/20 03:47:48 INFO : corrupted/silly5.rclone_chunk.002_yw4mrq: Moved (server-side) to: corrupted/silly5.rclone_chunk.002 2025/02/20 03:47:49 INFO : corrupted/silly5.rclone_chunk.003_yw4mrq: Moved (server-side) to: corrupted/silly5.rclone_chunk.003 2025/02/20 03:47:49 INFO : corrupted/silly5.rclone_chunk.004_yw4mrq: Moved (server-side) to: corrupted/silly5.rclone_chunk.004 2025/02/20 03:47:50 INFO : corrupted/silly5.rclone_chunk.005_yw4mrq: Moved (server-side) to: corrupted/silly5.rclone_chunk.005 2025/02/20 03:47:53 ERROR : corrupted/willy.rclone_chunk.002: Couldn't delete: refuse to corrupt: chunk overlap with "corrupted/willy" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNumberOverflow 2025/02/20 03:48:09 INFO : wreaked/wreaker.rclone_chunk.001_yw5du0: Moved (server-side) to: wreaked/wreaker.rclone_chunk.001 2025/02/20 03:48:10 INFO : wreaked/wreaker.rclone_chunk.002_yw5du0: Moved (server-side) to: wreaked/wreaker.rclone_chunk.002 2025/02/20 03:48:15 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rumidaj3tibu': invalid directory entry "wreaked/wreaker" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/MetadataInput 2025/02/20 03:48:31 ERROR : usermeta/today.rclone_chunk.001_yw6149: Couldn't move: Error "item_name_in_use" (409): Item with the same name already exists ({"conflicts":{"type":"file","id":"1782721917271","file_version":{"type":"file_version","id":"1964380586071","sha1":"7be49e353ee21561206e1da71b883cd5538b9973"},"sequence_id":"0","etag":"0","sha1":"7be49e353ee21561206e1da71b883cd5538b9973","name":"today.rclone_chunk.001_yw6149"}}) 2025/02/20 03:48:32 INFO : usermeta/today.rclone_chunk.001: Deleted 2025/02/20 03:48:33 INFO : usermeta/today.rclone_chunk.001_yw6149: Moved (server-side) to: usermeta/today.rclone_chunk.001 2025/02/20 03:48:45 ERROR : usermeta/past.rclone_chunk.001_yw6f8y: Couldn't move: Error "item_name_in_use" (409): Item with the same name already exists ({"conflicts":{"type":"file","id":"1782717671800","file_version":{"type":"file_version","id":"1964376791800","sha1":"19ce348da906ba7f953608f75d61d9f2d9bb6774"},"sequence_id":"0","etag":"0","sha1":"19ce348da906ba7f953608f75d61d9f2d9bb6774","name":"past.rclone_chunk.001_yw6f8y"}}) 2025/02/20 03:48:47 INFO : usermeta/past.rclone_chunk.001: Deleted 2025/02/20 03:48:48 INFO : usermeta/past.rclone_chunk.001_yw6f8y: Moved (server-side) to: usermeta/past.rclone_chunk.001 2025/02/20 03:49:00 ERROR : usermeta/future.rclone_chunk.001_yw6uf9: Couldn't move: Error "item_name_in_use" (409): Item with the same name already exists ({"conflicts":{"type":"file","id":"1782722471728","file_version":{"type":"file_version","id":"1964381176528","sha1":"4f93e7315127f1e93e7e33b2c1b3cc8be5a6d6ba"},"sequence_id":"0","etag":"0","sha1":"4f93e7315127f1e93e7e33b2c1b3cc8be5a6d6ba","name":"future.rclone_chunk.001_yw6uf9"}}) 2025/02/20 03:49:02 INFO : usermeta/future.rclone_chunk.001: Deleted 2025/02/20 03:49:03 INFO : usermeta/future.rclone_chunk.001_yw6uf9: Moved (server-side) to: usermeta/future.rclone_chunk.001 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/FutureProof 2025/02/20 03:49:25 DEBUG : future/test: Can't move - required hash not found 2025/02/20 03:49:25 DEBUG : future/test: Can't move, switching to copy 2025/02/20 03:49:25 DEBUG : future/test: Can't copy - required hash not found 2025/02/20 03:49:26 ERROR : future/test: Failed to copy: failed to open source object: can't open: unknown metadata, please upgrade rclone 2025/02/20 03:49:26 ERROR : future/test: Not deleting source as copy failed: failed to open source object: can't open: unknown metadata, please upgrade rclone 2025/02/20 03:49:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rumidaj3tibu': File to upload is small (3 bytes), uploading instead of streaming === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/BackwardsCompatibility 2025/02/20 03:50:16 INFO : backcomp/renamefile.rclone_chunk.001_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.001 2025/02/20 03:50:17 INFO : backcomp/renamefile.rclone_chunk.002_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.002 2025/02/20 03:50:17 INFO : backcomp/renamefile.rclone_chunk.003_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.003 2025/02/20 03:50:18 INFO : backcomp/renamefile.rclone_chunk.004_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.004 2025/02/20 03:50:18 INFO : backcomp/renamefile.rclone_chunk.005_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.005 2025/02/20 03:50:19 INFO : backcomp/renamefile.rclone_chunk.006_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.006 2025/02/20 03:50:19 INFO : backcomp/renamefile.rclone_chunk.007_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.007 2025/02/20 03:50:20 INFO : backcomp/renamefile.rclone_chunk.008_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.008 2025/02/20 03:50:21 INFO : backcomp/renamefile.rclone_chunk.009_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.009 2025/02/20 03:50:21 INFO : backcomp/renamefile.rclone_chunk.010_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.010 2025/02/20 03:50:22 INFO : backcomp/renamefile.rclone_chunk.011_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.011 2025/02/20 03:50:22 INFO : backcomp/renamefile.rclone_chunk.012_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.012 2025/02/20 03:50:23 INFO : backcomp/renamefile.rclone_chunk.013_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.013 2025/02/20 03:50:24 INFO : backcomp/renamefile.rclone_chunk.014_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.014 2025/02/20 03:50:24 INFO : backcomp/renamefile.rclone_chunk.015_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.015 2025/02/20 03:50:25 INFO : backcomp/renamefile.rclone_chunk.016_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.016 2025/02/20 03:50:25 INFO : backcomp/renamefile.rclone_chunk.017_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.017 2025/02/20 03:50:26 INFO : backcomp/renamefile.rclone_chunk.018_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.018 2025/02/20 03:50:27 INFO : backcomp/renamefile.rclone_chunk.019_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.019 2025/02/20 03:50:27 INFO : backcomp/renamefile.rclone_chunk.020_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.020 2025/02/20 03:50:28 INFO : backcomp/renamefile.rclone_chunk.021_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.021 2025/02/20 03:50:29 INFO : backcomp/renamefile.rclone_chunk.022_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.022 2025/02/20 03:50:29 INFO : backcomp/renamefile.rclone_chunk.023_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.023 2025/02/20 03:50:30 INFO : backcomp/renamefile.rclone_chunk.024_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.024 2025/02/20 03:50:31 INFO : backcomp/renamefile.rclone_chunk.025_yw7uqr: Moved (server-side) to: backcomp/renamefile.rclone_chunk.025 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkerServerSideMove 2025/02/20 03:51:07 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/servermovetest/subdir1" 2025/02/20 03:51:07 DEBUG : Config file has changed externally - reloading 2025/02/20 03:51:07 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/servermovetest/subdir1" 2025/02/20 03:51:10 DEBUG : Reset feature "ListR" 2025/02/20 03:51:10 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/servermovetest/subdir2" 2025/02/20 03:51:10 DEBUG : Config file has changed externally - reloading 2025/02/20 03:51:10 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/servermovetest/subdir2" 2025/02/20 03:51:13 DEBUG : Reset feature "ListR" 2025/02/20 03:51:22 INFO : movefile.rclone_chunk.001_ywan2x: Moved (server-side) to: movefile.rclone_chunk.001 2025/02/20 03:51:22 INFO : movefile.rclone_chunk.002_ywan2x: Moved (server-side) to: movefile.rclone_chunk.002 2025/02/20 03:51:27 DEBUG : movefile: move 2 data chunks... 2025/02/20 03:51:30 INFO : movefile.rclone_chunk.001: Moved (server-side) 2025/02/20 03:51:31 INFO : movefile.rclone_chunk.002: Moved (server-side) 2025/02/20 03:51:32 INFO : movefile: Moved (server-side) 2025/02/20 03:51:33 INFO : movefile: Moved (server-side) === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/MD5AllSlow 2025/02/20 03:51:39 DEBUG : Creating backend with remote "TestChunkerChunk50bBox,chunk_size='1P',hash_type='md5all',meta_format='simplejson',name_format='*.#',transactions='rename':rclone-test-rumidaj3tibu/md5all" 2025/02/20 03:51:39 DEBUG : Config file has changed externally - reloading 2025/02/20 03:51:39 DEBUG : TestChunkerChunk50bBox: detected overridden config - adding "{r7Izf}" suffix to name 2025/02/20 03:51:39 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/md5all" 2025/02/20 03:51:40 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:2368: *chunker.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2495: *chunker.Fs does not implement SetCopyCutoff === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize 2025/02/20 03:51:47 INFO : unknown-size-put.txt.rclone_chunk.001_ywbeb6: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.001 2025/02/20 03:51:48 INFO : unknown-size-put.txt.rclone_chunk.002_ywbeb6: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.002 2025/02/20 03:51:48 INFO : unknown-size-put.txt.rclone_chunk.003_ywbeb6: Moved (server-side) to: unknown-size-put.txt.rclone_chunk.003 === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize 2025/02/20 03:52:00 INFO : unknown-size-update.txt.rclone_chunk.001_ywbsyy: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.001 2025/02/20 03:52:00 INFO : unknown-size-update.txt.rclone_chunk.002_ywbsyy: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.002 2025/02/20 03:52:18 INFO : unknown-size-update.txt.rclone_chunk.001_ywc2fh: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.001 2025/02/20 03:52:19 INFO : unknown-size-update.txt.rclone_chunk.002_ywc2fh: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.002 2025/02/20 03:52:19 INFO : unknown-size-update.txt.rclone_chunk.003_ywc2fh: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.003 2025/02/20 03:52:20 INFO : unknown-size-update.txt.rclone_chunk.004_ywc2fh: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.004 2025/02/20 03:52:20 INFO : unknown-size-update.txt.rclone_chunk.005_ywc2fh: Moved (server-side) to: unknown-size-update.txt.rclone_chunk.005 === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/02/20 03:52:29 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rumidaj3tibu/deeper/nonexisting/directory" 2025/02/20 03:52:29 DEBUG : Config file has changed externally - reloading 2025/02/20 03:52:29 DEBUG : Creating backend with remote "TestBox:rclone-test-rumidaj3tibu/deeper/nonexisting/directory" 2025/02/20 03:52:32 DEBUG : Reset feature "ListR" === RUN TestIntegration/FsMkdir/FsDirSetModTime fstests.go:2638: FS has no DirSetModTime interface === RUN TestIntegration/FsMkdir/FsMkdirMetadata fstests.go:2681: FS has no MkdirMetadata interface === RUN TestIntegration/FsMkdir/FsDirectory === RUN TestIntegration/FsMkdir/FsDirectory/ReadDirMetadata fstests.go:2755: Directories don't support ReadDirMetadata === RUN TestIntegration/FsMkdir/FsDirectory/WriteDirMetadata fstests.go:2767: Directories don't support WriteDirMetadata === RUN TestIntegration/FsMkdir/FsDirectory/WriteDirSetModTime fstests.go:2781: Directories don't support WriteDirSetModTime === RUN TestIntegration/FsMkdir/FsDirectory/DirectoryCheckWrap 2025/02/20 03:52:37 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/20 03:52:37 ERROR : : error listing: directory not found 2025/02/20 03:52:38 DEBUG : box root 'rclone-test-rumidaj3tibu': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsShutdown --- FAIL: TestIntegration (1138.05s) --- PASS: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.54s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (0.99s) --- FAIL: TestIntegration/FsMkdir (1133.83s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (6.29s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.37s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.39s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.39s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (290.14s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (15.19s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (15.39s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (14.78s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (15.16s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (15.44s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (15.38s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (14.86s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (15.23s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (15.22s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (15.08s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (14.59s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (15.83s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (15.85s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (15.02s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (14.73s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (15.72s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (16.29s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (15.30s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (14.66s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.74s) --- PASS: TestIntegration/FsMkdir/FsPutError (58.47s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (7.10s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (72.11s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (639.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (2.06s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (1.94s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.61s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.56s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.79s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.81s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.83s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (1.04s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (3.77s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (2.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (2.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (15.43s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (16.65s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (18.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (13.52s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (36.73s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (19.12s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.34s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (1.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (1.12s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (1.05s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (1.15s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (1.13s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (1.05s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (1.12s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (1.13s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (3.60s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (1.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (3.54s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (2.81s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (5.30s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (2.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (21.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (1.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (7.21s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (3.50s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (19.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (2.01s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.43s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (16.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (5.83s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/PublicLink (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (1.07s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (8.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (20.60s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (5.78s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (14.82s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (361.35s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal/PutLarge (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNameFormat (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/SmallFileInternals (17.97s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/PreventCorruption (126.30s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkNumberOverflow (20.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/MetadataInput (46.27s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/FutureProof (22.48s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/BackwardsCompatibility (93.88s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/ChunkerServerSideMove (31.91s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal/MD5AllSlow (1.90s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (48.66s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (14.44s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (34.22s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (3.35s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsDirectory (2.33s) --- 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 1138.073s "go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Finished ERROR in 19m0.825633066s (try 1/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]