"go test -v -timeout 1h0m0s -remote TestBox: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestBox:" 2025/03/25 01:52:29 DEBUG : Creating backend with remote "TestBox:rclone-test-xuputak1yeru" 2025/03/25 01:52:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/25 01:52:29 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2025/03/25 01:52:31 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:52:31 DEBUG : box root 'rclone-test-xuputak1yeru': 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/25 01:52:34 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/03/25 01:52:35 INFO : dir/subdir: Making directory 2025/03/25 01:52:39 INFO : dir/subdir: Removing directory 2025/03/25 01:52:40 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/25 01:52:43 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 "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "．" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing "．．" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:707: testing "trailing CR␍" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:707: testing "trailing LF␊" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:707: testing "trailing HT␉" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:707: testing "trailing VT␋" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:707: testing "trailing dot." === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:707: testing "invalid utf-8\xfe" === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:707: testing "test%46.txt" === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength === 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/25 01:54:58 INFO : dir: Making directory 2025/03/25 01:54:59 INFO : dir/subdir1: Making directory 2025/03/25 01:55:00 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352446291") 2025/03/25 01:55:00 INFO : dir/subdir3: Making directory 2025/03/25 01:55:00 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(1)|folder|ITEM_TRASH|313278004123|55b0953f6c7311d5b458d2c31e377a8102670123] added old path ("test%46.txt") for notify 2025/03/25 01:55:00 DEBUG : doChangeNotify("test%46.txt", 0) 2025/03/25 01:55:00 DEBUG : box root 'rclone-test-xuputak1yeru': Received 1 events, resulting in 1 paths and 1 notifications 2025/03/25 01:55:00 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352447682") 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': ["êé"(0)|folder|ITEM_CREATE|313273926468|c64faf3f472875727000752e9a0abb165a8e3fe8] added new path ("hello? sausage/êé") for notify 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(0)|file|ITEM_UPLOAD|1812787124802|9fc48a6833d769df9384f7b51a85e639a1a8e8b3] added new path ("zero-length-file") for notify 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': [" \" ' @ < > & ? + ≠"(0)|folder|ITEM_CREATE|313277583846|ba3bfb6f035c14127162d2a6797fe92afe7ae88b] added new path ("hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠") for notify 2025/03/25 01:55:01 DEBUG : doChangeNotify("hello? sausage/êé", 0) 2025/03/25 01:55:01 DEBUG : Ignoring notify for file1 or file2: "hello? sausage/êé", 0 2025/03/25 01:55:01 DEBUG : doChangeNotify("zero-length-file", 1) 2025/03/25 01:55:01 DEBUG : doChangeNotify("hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠", 0) 2025/03/25 01:55:01 DEBUG : Ignoring notify for file1 or file2: "hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠", 0 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': Received 4 events, resulting in 3 paths and 3 notifications 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352447974") 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': ["êé"(0)|folder|ITEM_CREATE|313273926468|c64faf3f472875727000752e9a0abb165a8e3fe8] ignored due to duplicate EventID 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(0)|file|ITEM_UPLOAD|1812787124802|9fc48a6833d769df9384f7b51a85e639a1a8e8b3] ignored due to duplicate EventID 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': [" \" ' @ < > & ? + ≠"(0)|folder|ITEM_CREATE|313277583846|ba3bfb6f035c14127162d2a6797fe92afe7ae88b] ignored due to duplicate EventID 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(1)|file|ITEM_TRASH|1812787124802|5f7d15d2433b5eae1bdbc9766e238a414b27da08] ignored due to duplicate EventID 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352448279") 2025/03/25 01:55:01 INFO : dir/subdir2: Making directory 2025/03/25 01:55:01 DEBUG : box root 'rclone-test-xuputak1yeru': ["êé"(0)|folder|ITEM_CREATE|313273926468|c64faf3f472875727000752e9a0abb165a8e3fe8] ignored due to duplicate EventID 2025/03/25 01:55:02 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352448573") 2025/03/25 01:55:02 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(0)|file|ITEM_UPLOAD|1812787124802|9fc48a6833d769df9384f7b51a85e639a1a8e8b3] ignored due to duplicate EventID 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352449523") 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': [" \" ' @ < > & ? + ≠"(0)|folder|ITEM_CREATE|313277583846|ba3bfb6f035c14127162d2a6797fe92afe7ae88b] ignored due to duplicate EventID 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(1)|file|ITEM_TRASH|1812787124802|5f7d15d2433b5eae1bdbc9766e238a414b27da08] ignored due to duplicate EventID 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] added new path ("dir") for notify 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] added new path ("dir/subdir1") for notify 2025/03/25 01:55:03 DEBUG : doChangeNotify("dir", 0) 2025/03/25 01:55:03 DEBUG : doChangeNotify("dir/subdir1", 0) 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': Received 4 events, resulting in 2 paths and 2 notifications 2025/03/25 01:55:03 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352450531") 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(1)|file|ITEM_TRASH|1812787124802|5f7d15d2433b5eae1bdbc9766e238a414b27da08] ignored due to duplicate EventID 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] ignored due to duplicate EventID 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] ignored due to duplicate EventID 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352450788") 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["zero-length-file"(1)|file|ITEM_TRASH|1812787124802|5f7d15d2433b5eae1bdbc9766e238a414b27da08] ignored due to duplicate EventID 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] ignored due to duplicate EventID 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] ignored due to duplicate EventID 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(0)|folder|ITEM_CREATE|313278004123|fe46c6bdc385913d8c59e808bbe57b55a88e08e9] added new path ("test%46.txt") for notify 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir3"(0)|folder|ITEM_CREATE|313275218302|92d8cc2915a54cccbdb23883585ab550fb84636a] added new path ("dir/subdir3") for notify 2025/03/25 01:55:04 DEBUG : doChangeNotify("test%46.txt", 0) 2025/03/25 01:55:04 DEBUG : doChangeNotify("dir/subdir3", 0) 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': Received 5 events, resulting in 2 paths and 2 notifications 2025/03/25 01:55:04 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352451537") 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(0)|folder|ITEM_CREATE|313278004123|fe46c6bdc385913d8c59e808bbe57b55a88e08e9] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir3"(0)|folder|ITEM_CREATE|313275218302|92d8cc2915a54cccbdb23883585ab550fb84636a] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352451807") 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(0)|folder|ITEM_CREATE|313278004123|fe46c6bdc385913d8c59e808bbe57b55a88e08e9] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir3"(0)|folder|ITEM_CREATE|313275218302|92d8cc2915a54cccbdb23883585ab550fb84636a] ignored due to duplicate EventID 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir2"(0)|folder|ITEM_CREATE|313273292786|7778c8b239bfeb7e84e20702b07ca9810212fe78] added new path ("dir/subdir2") for notify 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': ["file2"(0)|file|ITEM_UPLOAD|1812802300190|23dc48df84a7f96b17b5a56a4b2eabe910830a55] added new path ("dir/file2") for notify 2025/03/25 01:55:05 DEBUG : doChangeNotify("dir/subdir2", 0) 2025/03/25 01:55:05 DEBUG : doChangeNotify("dir/file2", 1) 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': Received 6 events, resulting in 2 paths and 2 notifications 2025/03/25 01:55:05 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352452573") 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(0)|folder|ITEM_CREATE|313278004123|fe46c6bdc385913d8c59e808bbe57b55a88e08e9] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir3"(0)|folder|ITEM_CREATE|313275218302|92d8cc2915a54cccbdb23883585ab550fb84636a] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir2"(0)|folder|ITEM_CREATE|313273292786|7778c8b239bfeb7e84e20702b07ca9810212fe78] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["file2"(0)|file|ITEM_UPLOAD|1812802300190|23dc48df84a7f96b17b5a56a4b2eabe910830a55] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352452856") 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["dir"(0)|folder|ITEM_CREATE|313274839048|0e5965dbf9dac7cfbb3509840ecae90d74f005db] ignored due to duplicate EventID 2025/03/25 01:55:06 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir1"(0)|folder|ITEM_CREATE|313272841898|435475a481e6d7e49e8e149a1369bb488c5cf2c7] ignored due to duplicate EventID 2025/03/25 01:55:07 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352453532") 2025/03/25 01:55:07 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(0)|folder|ITEM_CREATE|313278004123|fe46c6bdc385913d8c59e808bbe57b55a88e08e9] ignored due to duplicate EventID 2025/03/25 01:55:07 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir3"(0)|folder|ITEM_CREATE|313275218302|92d8cc2915a54cccbdb23883585ab550fb84636a] ignored due to duplicate EventID 2025/03/25 01:55:07 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir2"(0)|folder|ITEM_CREATE|313273292786|7778c8b239bfeb7e84e20702b07ca9810212fe78] ignored due to duplicate EventID 2025/03/25 01:55:07 DEBUG : box root 'rclone-test-xuputak1yeru': ["file2"(0)|file|ITEM_UPLOAD|1812802300190|23dc48df84a7f96b17b5a56a4b2eabe910830a55] ignored due to duplicate EventID 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352454531") 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': ["test%46.txt"(0)|folder|ITEM_CREATE|313278004123|fe46c6bdc385913d8c59e808bbe57b55a88e08e9] ignored due to duplicate EventID 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir3"(0)|folder|ITEM_CREATE|313275218302|92d8cc2915a54cccbdb23883585ab550fb84636a] ignored due to duplicate EventID 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir2"(0)|folder|ITEM_CREATE|313273292786|7778c8b239bfeb7e84e20702b07ca9810212fe78] ignored due to duplicate EventID 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': ["file2"(0)|file|ITEM_UPLOAD|1812802300190|23dc48df84a7f96b17b5a56a4b2eabe910830a55] ignored due to duplicate EventID 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': ["file4"(0)|file|ITEM_UPLOAD|1812804313224|ebe68654afde111da452f0c0705fd8d9312b0cf7] added new path ("dir/file4") for notify 2025/03/25 01:55:08 DEBUG : doChangeNotify("dir/file4", 1) 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': Received 5 events, resulting in 1 paths and 1 notifications 2025/03/25 01:55:08 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352455525") 2025/03/25 01:55:09 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352455781") fstests.go:931: Try 1/10 waiting for dirChanges and objChanges 2025/03/25 01:55:09 DEBUG : box root 'rclone-test-xuputak1yeru': ["subdir2"(0)|folder|ITEM_CREATE|313273292786|7778c8b239bfeb7e84e20702b07ca9810212fe78] ignored due to duplicate EventID 2025/03/25 01:55:09 DEBUG : box root 'rclone-test-xuputak1yeru': ["file2"(0)|file|ITEM_UPLOAD|1812802300190|23dc48df84a7f96b17b5a56a4b2eabe910830a55] ignored due to duplicate EventID 2025/03/25 01:55:10 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352456537") 2025/03/25 01:55:11 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352457533") 2025/03/25 01:55:11 DEBUG : box root 'rclone-test-xuputak1yeru': ["file4"(0)|file|ITEM_UPLOAD|1812804313224|ebe68654afde111da452f0c0705fd8d9312b0cf7] ignored due to duplicate EventID 2025/03/25 01:55:11 DEBUG : box root 'rclone-test-xuputak1yeru': ["file3"(0)|file|ITEM_UPLOAD|1812791094333|2e90da569ea8d7166b56306b892bf12bd228952c] added new path ("dir/file3") for notify 2025/03/25 01:55:11 DEBUG : doChangeNotify("dir/file3", 1) 2025/03/25 01:55:11 DEBUG : box root 'rclone-test-xuputak1yeru': Received 2 events, resulting in 1 paths and 1 notifications 2025/03/25 01:55:11 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352458524") 2025/03/25 01:55:12 DEBUG : box root 'rclone-test-xuputak1yeru': ["file4"(0)|file|ITEM_UPLOAD|1812804313224|ebe68654afde111da452f0c0705fd8d9312b0cf7] ignored due to duplicate EventID 2025/03/25 01:55:12 DEBUG : box root 'rclone-test-xuputak1yeru': ["file3"(0)|file|ITEM_UPLOAD|1812791094333|2e90da569ea8d7166b56306b892bf12bd228952c] ignored due to duplicate EventID 2025/03/25 01:55:12 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352458768") 2025/03/25 01:55:12 DEBUG : box root 'rclone-test-xuputak1yeru': ["file4"(0)|file|ITEM_UPLOAD|1812804313224|ebe68654afde111da452f0c0705fd8d9312b0cf7] ignored due to duplicate EventID 2025/03/25 01:55:13 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352459536") 2025/03/25 01:55:14 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352460531") 2025/03/25 01:55:15 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352461537") 2025/03/25 01:55:15 DEBUG : box root 'rclone-test-xuputak1yeru': ["file3"(0)|file|ITEM_UPLOAD|1812791094333|2e90da569ea8d7166b56306b892bf12bd228952c] ignored due to duplicate EventID 2025/03/25 01:55:16 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352462530") 2025/03/25 01:55:17 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352463531") 2025/03/25 01:55:18 DEBUG : box root 'rclone-test-xuputak1yeru': Checking for changes on remote (next_stream_position: "30401040352464526") === RUN TestIntegration/FsMkdir/FsPutFiles === 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/25 01:55:31 DEBUG : Creating backend with remote "TestBox:" 2025/03/25 01:55:31 DEBUG : Config file has changed externally - reloading 2025/03/25 01:55:32 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 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2025/03/25 01:55:56 DEBUG : Creating backend with remote "TestBox:rclone-test-xuputak1yeru/dirToPurgeFromRoot" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2025/03/25 01:56:01 DEBUG : Creating backend with remote "TestBox:rclone-test-xuputak1yeru/hello? sausage/êé" === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1289: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1398: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2025/03/25 01:56:38 DEBUG : Creating backend with remote "TestBox:rclone-test-pedimat1vago" 2025/03/25 01:56:39 DEBUG : box root 'rclone-test-pedimat1vago': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:56:41 DEBUG : box root 'rclone-test-pedimat1vago': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:56:48 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:56:53 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:57:00 DEBUG : box root 'rclone-test-pedimat1vago': 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 === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2025/03/25 01:57:21 DEBUG : Creating backend with remote "TestBox:rclone-test-xuputak1yeru/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2025/03/25 01:57:21 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2025/03/25 01:57:29 DEBUG : Creating backend with remote "TestBox:rclone-test-xuputak1yeru/not found.txt" 2025/03/25 01:57:33 ERROR : : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1911: Opening root remote "TestBox:" path "rclone-test-xuputak1yeru" from "TestBox:rclone-test-xuputak1yeru" 2025/03/25 01:57:33 DEBUG : Creating backend with remote "TestBox:" === 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 === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink 2025/03/25 01:57:43 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share single file 'file name.txt_does_not_exist' 2025/03/25 01:57:44 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share single file 'file name.txt' 2025/03/25 01:57:46 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share single file 'hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt' 2025/03/25 01:57:48 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share single file 'file name.txt' 2025/03/25 01:57:49 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share directory 'hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠' 2025/03/25 01:57:49 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share directory 'hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠' 2025/03/25 01:57:49 DEBUG : Creating backend with remote "TestBox:rclone-test-gifenuf4woqi" 2025/03/25 01:57:50 DEBUG : box root 'rclone-test-gifenuf4woqi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:57:52 DEBUG : box root 'rclone-test-gifenuf4woqi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:57:53 DEBUG : box root 'rclone-test-gifenuf4woqi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:57:55 DEBUG : box root 'rclone-test-xuputak1yeru': attempting to share directory '' 2025/03/25 01:57:55 DEBUG : box root 'rclone-test-gifenuf4woqi': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:412: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2109: Not a wrapping Fs === 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 === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2343: *box.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2364: *box.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsCopyChunked fstests.go:2485: *box.Fs does not implement SetCopyCutoff === RUN TestIntegration/FsMkdir/FsUploadUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize === RUN TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize === RUN TestIntegration/FsMkdir/FsRootCollapse 2025/03/25 01:58:20 DEBUG : Creating backend with remote "TestBox:rclone-test-xuputak1yeru/deeper/nonexisting/directory" === 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 fstests.go:2725: FS has no Directory methods and doesn't Wrap 2025/03/25 01:58:26 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/25 01:58:26 ERROR : : error listing: directory not found 2025/03/25 01:58:26 DEBUG : box root 'rclone-test-xuputak1yeru': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsShutdown --- PASS: TestIntegration (357.50s) --- SKIP: TestIntegration/FsCheckWrap (0.00s) --- SKIP: TestIntegration/FsCommand (0.00s) --- PASS: TestIntegration/FsRmdirNotFound (0.57s) --- PASS: TestIntegration/FsString (0.00s) --- PASS: TestIntegration/FsName (0.00s) --- PASS: TestIntegration/FsRoot (0.00s) --- PASS: TestIntegration/FsRmdirEmpty (1.22s) --- PASS: TestIntegration/FsMkdir (352.65s) --- PASS: TestIntegration/FsMkdir/FsMkdirRmdirSubdir (6.26s) --- PASS: TestIntegration/FsMkdir/FsListEmpty (0.38s) --- PASS: TestIntegration/FsMkdir/FsListDirEmpty (0.38s) --- SKIP: TestIntegration/FsMkdir/FsListRDirEmpty (0.00s) --- PASS: TestIntegration/FsMkdir/FsListDirNotFound (0.38s) --- SKIP: TestIntegration/FsMkdir/FsListRDirNotFound (0.00s) --- PASS: TestIntegration/FsMkdir/FsEncoding (124.97s) --- PASS: TestIntegration/FsMkdir/FsEncoding/control_chars (6.53s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot (6.55s) --- PASS: TestIntegration/FsMkdir/FsEncoding/dot_dot (6.51s) --- PASS: TestIntegration/FsMkdir/FsEncoding/punctuation (6.43s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_space (6.54s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_tilde (6.28s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_CR (6.44s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_LF (6.49s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_HT (6.51s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_VT (6.40s) --- PASS: TestIntegration/FsMkdir/FsEncoding/leading_dot (6.64s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_space (6.63s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_CR (6.65s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_LF (6.77s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_HT (6.59s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_VT (6.62s) --- PASS: TestIntegration/FsMkdir/FsEncoding/trailing_dot (6.49s) --- PASS: TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 (6.66s) --- PASS: TestIntegration/FsMkdir/FsEncoding/URL_encoding (6.83s) --- PASS: TestIntegration/FsMkdir/FsNewObjectNotFound (0.76s) --- PASS: TestIntegration/FsMkdir/FsPutError (5.03s) --- PASS: TestIntegration/FsMkdir/FsPutZeroLength (4.26s) --- SKIP: TestIntegration/FsMkdir/FsOpenWriterAt (0.00s) --- SKIP: TestIntegration/FsMkdir/FsOpenChunkWriter (0.00s) --- PASS: TestIntegration/FsMkdir/FsChangeNotify (21.18s) --- PASS: TestIntegration/FsMkdir/FsPutFiles (173.25s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 (1.97s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListR (2.02s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir (1.59s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot (0.62s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListSubdir (0.83s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 (0.82s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1 (1.99s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObject (0.71s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive (2.93s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive/Dir (2.24s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 (2.06s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir (0.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurge (9.07s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot (10.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir (14.35s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsCopy (5.69s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsMove (16.86s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsDirMove (22.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull (0.32s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPrecision (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectString (0.68s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectFs (0.62s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemote (0.63s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectHashes (0.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectModTime (0.63s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType (0.66s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata (0.67s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata (0.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime (1.86s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectSize (0.66s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpen (1.91s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek (1.42s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange (3.85s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead (1.49s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate (3.38s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectStorable (0.62s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile (7.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound (3.76s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot (10.41s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/List (2.53s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries (0.41s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put (7.47s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove (3.20s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/PublicLink (13.64s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/SetTier (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Bucket (0.00s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectRemove (3.96s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/ObjectAbout (0.31s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream (11.17s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/0 (7.86s) --- PASS: TestIntegration/FsMkdir/FsPutFiles/FsPutStream/100 (3.31s) --- SKIP: TestIntegration/FsMkdir/FsPutFiles/Internal (0.00s) --- SKIP: TestIntegration/FsMkdir/FsPutChunked (0.00s) --- SKIP: TestIntegration/FsMkdir/FsCopyChunked (0.00s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize (7.72s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsPutUnknownSize (2.47s) --- PASS: TestIntegration/FsMkdir/FsUploadUnknownSize/FsUpdateUnknownSize (5.25s) --- PASS: TestIntegration/FsMkdir/FsRootCollapse (3.35s) --- SKIP: TestIntegration/FsMkdir/FsDirSetModTime (0.00s) --- SKIP: TestIntegration/FsMkdir/FsMkdirMetadata (0.00s) --- SKIP: TestIntegration/FsMkdir/FsDirectory (0.00s) --- PASS: TestIntegration/FsShutdown (0.00s) PASS ok github.com/rclone/rclone/backend/box 357.515s "go test -v -timeout 1h0m0s -remote TestBox: -verbose" - Finished OK in 5m58.288794507s (try 1/5)