"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 2/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestBox:"
2025/03/12 04:44:48 DEBUG : Creating backend with remote "TestBox:rclone-test-quwuzop4kugi"
2025/03/12 04:44:48 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/12 04:44:48 DEBUG : box root 'rclone-test-quwuzop4kugi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/12 04:44:49 DEBUG : box root 'rclone-test-quwuzop4kugi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/12 04:44:52 INFO  : dir: Making directory
2025/03/12 04:44:53 INFO  : dir/subdir1: Making directory
2025/03/12 04:44:54 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239440265")
2025/03/12 04:44:54 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test"(1)|file|ITEM_RENAME|1800645736782|2a9e40c79ba2221c6defe1d68e57064215b568c7] new parent not found
2025/03/12 04:44:54 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/12 04:44:54 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239441523")
2025/03/12 04:44:54 INFO  : dir/subdir3: Making directory
2025/03/12 04:44:55 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239441759")
2025/03/12 04:44:55 DEBUG : box root 'rclone-test-quwuzop4kugi': ["piped data.txt"(0)|file|ITEM_UPLOAD|1800655602876|31fe210c156eeb9d3127ffd777eea5e0317df422] new parent not found
2025/03/12 04:44:55 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_003rx5"(0)|file|ITEM_UPLOAD|1800656123479|46ed784ac873dee004763e126cd95316085b371e] new parent not found
2025/03/12 04:44:55 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1800661319604|662383703af4f9617e612034c2fc7102c2ecd781] new parent not found
2025/03/12 04:44:55 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 9 events, resulting in 0 paths and 0 notifications
2025/03/12 04:44:55 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239442550")
2025/03/12 04:44:55 INFO  : dir/subdir2: Making directory
2025/03/12 04:44:56 DEBUG : box root 'rclone-test-quwuzop4kugi': ["piped data.txt"(1)|file|ITEM_TRASH|1800649809080|fbf461a20af11f45d5f17c8f9d5a2c05d0f4481c] ignored due to duplicate EventID
2025/03/12 04:44:56 DEBUG : box root 'rclone-test-quwuzop4kugi': ["somefile"(1)|file|ITEM_TRASH|1800652617531|f57431de3c6003e6b41a4f1a8d9bc1256a4e1eda] ignored due to duplicate EventID
2025/03/12 04:44:56 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239442858")
2025/03/12 04:44:56 DEBUG : box root 'rclone-test-quwuzop4kugi': ["created from root.txt"(1)|file|ITEM_TRASH|1800648760123|601d470971d71640bb9ee07ca3308c19c6c597bb] ignored due to duplicate EventID
2025/03/12 04:44:56 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_003rx5"(0)|file|ITEM_UPLOAD|1800656123479|46ed784ac873dee004763e126cd95316085b371e] ignored due to duplicate EventID
2025/03/12 04:44:56 DEBUG : box root 'rclone-test-quwuzop4kugi': ["rclone-test-nepiwiy9bowo"(1)|folder|ITEM_TRASH|311283122019|9ee8398464a1ec2fdd8b310f9b5b002c75e3518c] ignored due to duplicate EventID
2025/03/12 04:44:57 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239443527")
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239444540")
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(2)|file|ITEM_TRASH|1800654290286|5bbfa1a6257cf8d2b0df4805b26dbf8e447d1c38] ignored due to duplicate EventID
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1800661319604|662383703af4f9617e612034c2fc7102c2ecd781] ignored due to duplicate EventID
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["piped data.txt"(0)|file|ITEM_UPLOAD|1800649809080|2037fd88b3ca7d631c61d21b0e49daf43e3dcd21] new parent not found
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800653903517|d48fd518b777df6bdd87ceaf292691b69ab4d789] new parent not found
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1800661319604|9ef448ec8df0ad9644c2e3721dbd893396133a7e] new parent not found
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_003nlw"(0)|file|ITEM_UPLOAD|1800653903517|152401b4e89459fc89116fe85b4fff0df169b590] new parent not found
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_0048cb"(0)|file|ITEM_UPLOAD|1800651082864|477e894e05e5f81be1e19608f320a3f67077063c] new parent not found
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 9 events, resulting in 0 paths and 0 notifications
2025/03/12 04:44:58 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239445549")
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(2)|file|ITEM_TRASH|1800654290286|5bbfa1a6257cf8d2b0df4805b26dbf8e447d1c38] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1800661319604|662383703af4f9617e612034c2fc7102c2ecd781] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(2)|file|ITEM_TRASH|1800653903517|6b49acc6d9268587e68f881cc5048f1c0b0b7d08] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["piped data.txt"(0)|file|ITEM_UPLOAD|1800649809080|2037fd88b3ca7d631c61d21b0e49daf43e3dcd21] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800653903517|d48fd518b777df6bdd87ceaf292691b69ab4d789] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1800661319604|9ef448ec8df0ad9644c2e3721dbd893396133a7e] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_003nlw"(0)|file|ITEM_UPLOAD|1800653903517|152401b4e89459fc89116fe85b4fff0df169b590] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_0048cb"(0)|file|ITEM_UPLOAD|1800651082864|477e894e05e5f81be1e19608f320a3f67077063c] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-put.txt"(1)|file|ITEM_TRASH|1800663349913|77b275e2bd1724e04f6ca4855cf13aac6c5fc22f] ignored due to duplicate EventID
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239445861")
2025/03/12 04:44:59 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1800661319604|662383703af4f9617e612034c2fc7102c2ecd781] ignored due to duplicate EventID
2025/03/12 04:45:00 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239446545")
2025/03/12 04:45:00 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(2)|file|ITEM_TRASH|1800653903517|6b49acc6d9268587e68f881cc5048f1c0b0b7d08] ignored due to duplicate EventID
2025/03/12 04:45:00 DEBUG : box root 'rclone-test-quwuzop4kugi': ["piped data.txt"(0)|file|ITEM_UPLOAD|1800649809080|2037fd88b3ca7d631c61d21b0e49daf43e3dcd21] ignored due to duplicate EventID
2025/03/12 04:45:00 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800653903517|d48fd518b777df6bdd87ceaf292691b69ab4d789] ignored due to duplicate EventID
2025/03/12 04:45:01 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239447718")
2025/03/12 04:45:01 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1800661319604|9ef448ec8df0ad9644c2e3721dbd893396133a7e] ignored due to duplicate EventID
2025/03/12 04:45:01 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_003nlw"(0)|file|ITEM_UPLOAD|1800653903517|152401b4e89459fc89116fe85b4fff0df169b590] ignored due to duplicate EventID
2025/03/12 04:45:02 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239448541")
2025/03/12 04:45:02 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.rclone_chunk.001_0048cb"(0)|file|ITEM_UPLOAD|1800651082864|477e894e05e5f81be1e19608f320a3f67077063c] ignored due to duplicate EventID
2025/03/12 04:45:02 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-put.txt"(1)|file|ITEM_TRASH|1800663349913|77b275e2bd1724e04f6ca4855cf13aac6c5fc22f] ignored due to duplicate EventID
2025/03/12 04:45:03 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239449541")
2025/03/12 04:45:03 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 04:45:03 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239450572")
2025/03/12 04:45:04 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239450831")
2025/03/12 04:45:04 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800656123479|f283a9a9fd21b56b6d6b08a2ea0ee899df09d12a] new parent not found
2025/03/12 04:45:04 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/12 04:45:04 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239451550")
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test"(1)|file|ITEM_RENAME|1800646355561|307644fe4780444607590063fa4edb6d9e005708] new parent not found
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239451810")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir1"(0)|folder|ITEM_CREATE|311283301813|b7151de4a283d7b2e8d34094b94b6e8bf599f28c] added new path ("dir/subdir1") for notify
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1800663349913|be289abacad27126069b6af2fa2550be9ba4be70] new parent not found
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test.rclone_chunk.001_004ea9"(0)|file|ITEM_UPLOAD|1800652628989|e4fe1022568948edcb9b5af31cf8b45a118d9cca] new parent not found
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800651082864|5f1833b59afd0918d916081a618e0397c597d234] new parent not found
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir2"(0)|folder|ITEM_CREATE|311282606315|9f48f783ca2995f0a88771688c33b7bfb3e62786] added new path ("dir/subdir2") for notify
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1.rclone_chunk.001_00445h"(0)|file|ITEM_UPLOAD|1800647879280|88f690aff141db0b89ac84815a8cb1771727e3de] new parent not found
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1"(1)|file|ITEM_RENAME|1800647879280|f4010a842627fd8876f1ac8b9bea122e60590827] new parent not found
2025/03/12 04:45:05 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/12 04:45:05 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 13 events, resulting in 2 paths and 2 notifications
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239452067")
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir1"(0)|folder|ITEM_CREATE|311283301813|b7151de4a283d7b2e8d34094b94b6e8bf599f28c] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["piped data.txt"(1)|file|ITEM_TRASH|1800655602876|31718d4ed38b3aa601de2ed79368b2f5bb118f31] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["z.txt"(4)|file|ITEM_TRASH|1800650668041|6ea49443791676db5afa61e4070daa17c8a8698b] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1800663349913|be289abacad27126069b6af2fa2550be9ba4be70] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': [" \" ' @ < > & ? + ≠"(1)|folder|ITEM_TRASH|311283995949|3b4c92710cf81c5d55cf0b90d39fb668bb6d1c77] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["êé"(1)|folder|ITEM_TRASH|311279644484|5be85120b8cf50c4f6ced4d0727f0a5a327db995] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test.rclone_chunk.001_004ea9"(0)|file|ITEM_UPLOAD|1800652628989|e4fe1022568948edcb9b5af31cf8b45a118d9cca] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800651082864|5f1833b59afd0918d916081a618e0397c597d234] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir2"(0)|folder|ITEM_CREATE|311282606315|9f48f783ca2995f0a88771688c33b7bfb3e62786] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1.rclone_chunk.001_00445h"(0)|file|ITEM_UPLOAD|1800647879280|88f690aff141db0b89ac84815a8cb1771727e3de] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(2)|file|ITEM_TRASH|1800656123479|a63a5b618c4c7b9383817b2a812e4bad590bce07] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1"(1)|file|ITEM_RENAME|1800647879280|f4010a842627fd8876f1ac8b9bea122e60590827] ignored due to duplicate EventID
2025/03/12 04:45:05 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239452481")
2025/03/12 04:45:06 DEBUG : box root 'rclone-test-quwuzop4kugi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1800663349913|be289abacad27126069b6af2fa2550be9ba4be70] ignored due to duplicate EventID
2025/03/12 04:45:06 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239452811")
2025/03/12 04:45:07 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239453531")
2025/03/12 04:45:07 DEBUG : box root 'rclone-test-quwuzop4kugi': [" \" ' @ < > & ? + ≠"(1)|folder|ITEM_TRASH|311283995949|3b4c92710cf81c5d55cf0b90d39fb668bb6d1c77] ignored due to duplicate EventID
2025/03/12 04:45:07 DEBUG : box root 'rclone-test-quwuzop4kugi': ["êé"(1)|folder|ITEM_TRASH|311279644484|5be85120b8cf50c4f6ced4d0727f0a5a327db995] ignored due to duplicate EventID
2025/03/12 04:45:07 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test.rclone_chunk.001_004ea9"(0)|file|ITEM_UPLOAD|1800652628989|e4fe1022568948edcb9b5af31cf8b45a118d9cca] ignored due to duplicate EventID
2025/03/12 04:45:07 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800651082864|5f1833b59afd0918d916081a618e0397c597d234] ignored due to duplicate EventID
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239454545")
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir2"(0)|folder|ITEM_CREATE|311282606315|9f48f783ca2995f0a88771688c33b7bfb3e62786] ignored due to duplicate EventID
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1.rclone_chunk.001_00445h"(0)|file|ITEM_UPLOAD|1800647879280|88f690aff141db0b89ac84815a8cb1771727e3de] ignored due to duplicate EventID
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(2)|file|ITEM_TRASH|1800656123479|a63a5b618c4c7b9383817b2a812e4bad590bce07] ignored due to duplicate EventID
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1"(1)|file|ITEM_RENAME|1800647879280|f4010a842627fd8876f1ac8b9bea122e60590827] ignored due to duplicate EventID
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir3"(0)|folder|ITEM_CREATE|311283289362|90fe95369a9b4afa11f247ccf82feb68d28c8ad7] added new path ("dir/subdir3") for notify
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file name.txt"(4)|file|ITEM_UPLOAD|1800645169381|071e088ff06eb7b92906bb57e7d15eaae2f7549d] new parent not found
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.apple2"(2)|file|ITEM_RENAME|1800651082864|02a401909843fec9e7277b9d49c0a93444f18597] new parent not found
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': ["rclone-test-quwuzop4kugi"(0)|folder|ITEM_CREATE|311281375185|ac73a5cf08d83aaa8efd0ccbef0612280c469b15] new parent not found
2025/03/12 04:45:08 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 8 events, resulting in 1 paths and 1 notifications
2025/03/12 04:45:08 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239455573")
2025/03/12 04:45:09 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.dinosaur1"(1)|file|ITEM_RENAME|1800647879280|f4010a842627fd8876f1ac8b9bea122e60590827] ignored due to duplicate EventID
2025/03/12 04:45:09 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239455922")
2025/03/12 04:45:09 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir3"(0)|folder|ITEM_CREATE|311283289362|90fe95369a9b4afa11f247ccf82feb68d28c8ad7] ignored due to duplicate EventID
2025/03/12 04:45:09 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file name.txt"(4)|file|ITEM_UPLOAD|1800645169381|071e088ff06eb7b92906bb57e7d15eaae2f7549d] ignored due to duplicate EventID
2025/03/12 04:45:09 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.apple2"(2)|file|ITEM_RENAME|1800651082864|02a401909843fec9e7277b9d49c0a93444f18597] ignored due to duplicate EventID
2025/03/12 04:45:09 DEBUG : box root 'rclone-test-quwuzop4kugi': ["rclone-test-quwuzop4kugi"(0)|folder|ITEM_CREATE|311281375185|ac73a5cf08d83aaa8efd0ccbef0612280c469b15] ignored due to duplicate EventID
2025/03/12 04:45:10 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239456538")
2025/03/12 04:45:10 DEBUG : box root 'rclone-test-quwuzop4kugi': ["subdir3"(0)|folder|ITEM_CREATE|311283289362|90fe95369a9b4afa11f247ccf82feb68d28c8ad7] ignored due to duplicate EventID
2025/03/12 04:45:10 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file name.txt"(4)|file|ITEM_UPLOAD|1800645169381|071e088ff06eb7b92906bb57e7d15eaae2f7549d] ignored due to duplicate EventID
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/12 04:45:11 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239457537")
2025/03/12 04:45:11 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.apple2"(2)|file|ITEM_RENAME|1800651082864|02a401909843fec9e7277b9d49c0a93444f18597] ignored due to duplicate EventID
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239458536")
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': ["rclone-test-quwuzop4kugi"(0)|folder|ITEM_CREATE|311281375185|ac73a5cf08d83aaa8efd0ccbef0612280c469b15] ignored due to duplicate EventID
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800654290286|afd805ebf3f30c9a09e1592639698c47d111a5e5] new parent not found
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': ["dir"(0)|folder|ITEM_CREATE|311284770810|8fee81b34ff5204ae4af00d0848ac2d0e49fc0f4] added new path ("dir") for notify
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test"(1)|file|ITEM_RENAME|1800652628989|a6ad8415817c29e0e97ed50e292a56b2e0077771] new parent not found
2025/03/12 04:45:12 DEBUG : doChangeNotify("dir", 0)
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 6 events, resulting in 1 paths and 1 notifications
2025/03/12 04:45:12 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239459536")
2025/03/12 04:45:13 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239459800")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/12 04:45:14 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239460529")
2025/03/12 04:45:14 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt"(1)|file|ITEM_RENAME|1800654290286|afd805ebf3f30c9a09e1592639698c47d111a5e5] ignored due to duplicate EventID
2025/03/12 04:45:14 DEBUG : box root 'rclone-test-quwuzop4kugi': ["dir"(0)|folder|ITEM_CREATE|311284770810|8fee81b34ff5204ae4af00d0848ac2d0e49fc0f4] ignored due to duplicate EventID
2025/03/12 04:45:15 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239461627")
2025/03/12 04:45:15 DEBUG : box root 'rclone-test-quwuzop4kugi': ["rclone-test-lalupik0fifu"(3)|folder|ITEM_TRASH|311282240771|ceec2b00ae8674a36de60ee3ef70af5d16c306a6] ignored due to duplicate EventID
2025/03/12 04:45:16 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239462546")
2025/03/12 04:45:16 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test"(2)|file|ITEM_TRASH|1800652628989|ba1c3bd4049c47b4f892f3ee60eb77ce40fa42aa] ignored due to duplicate EventID
2025/03/12 04:45:16 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test"(1)|file|ITEM_RENAME|1800652628989|a6ad8415817c29e0e97ed50e292a56b2e0077771] ignored due to duplicate EventID
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239463527")
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.apple1.rclone_chunk.001_004rs6"(0)|file|ITEM_UPLOAD|1800653402399|f52765267608ad34dd53aaaf5dac414fabcc08bf] new parent not found
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file2"(0)|file|ITEM_UPLOAD|1800648349981|2000410bcf0148be1bff07c004e2c1141b048f87] added new path ("dir/file2") for notify
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.apple1"(1)|file|ITEM_RENAME|1800653402399|a8c0bc532db201d612145aa3c45ad2dba8a1e972] new parent not found
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file3"(0)|file|ITEM_UPLOAD|1800650240626|604feffe0685d97db55e7a7370455b3859928226] added new path ("dir/file3") for notify
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file4"(0)|file|ITEM_UPLOAD|1800650409313|4ba83eef1867b698fe3b6e6710e651156643f24a] added new path ("dir/file4") for notify
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test.rclone_chunk.001_003wx7"(0)|file|ITEM_UPLOAD|1800646355561|4bfdb3eee3c1b1f0e5924eee2b64774eaba10b44] new parent not found
2025/03/12 04:45:17 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/12 04:45:17 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/12 04:45:17 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': Received 6 events, resulting in 3 paths and 3 notifications
2025/03/12 04:45:17 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239464521")
2025/03/12 04:45:18 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239464804")
2025/03/12 04:45:18 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file1.txt.apple1"(1)|file|ITEM_RENAME|1800653402399|a8c0bc532db201d612145aa3c45ad2dba8a1e972] ignored due to duplicate EventID
2025/03/12 04:45:19 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239465527")
2025/03/12 04:45:19 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file3"(0)|file|ITEM_UPLOAD|1800650240626|604feffe0685d97db55e7a7370455b3859928226] ignored due to duplicate EventID
2025/03/12 04:45:19 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file4"(0)|file|ITEM_UPLOAD|1800650409313|4ba83eef1867b698fe3b6e6710e651156643f24a] ignored due to duplicate EventID
2025/03/12 04:45:19 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test.rclone_chunk.001_003wx7"(0)|file|ITEM_UPLOAD|1800646355561|4bfdb3eee3c1b1f0e5924eee2b64774eaba10b44] ignored due to duplicate EventID
2025/03/12 04:45:20 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239466536")
2025/03/12 04:45:20 DEBUG : box root 'rclone-test-quwuzop4kugi': ["file4"(0)|file|ITEM_UPLOAD|1800650409313|4ba83eef1867b698fe3b6e6710e651156643f24a] ignored due to duplicate EventID
2025/03/12 04:45:20 DEBUG : box root 'rclone-test-quwuzop4kugi': ["modtime_write_test.rclone_chunk.001_003wx7"(0)|file|ITEM_UPLOAD|1800646355561|4bfdb3eee3c1b1f0e5924eee2b64774eaba10b44] ignored due to duplicate EventID
2025/03/12 04:45:21 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239467562")
2025/03/12 04:45:22 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239468544")
2025/03/12 04:45:23 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239469516")
2025/03/12 04:45:24 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239470523")
2025/03/12 04:45:25 DEBUG : box root 'rclone-test-quwuzop4kugi': Checking for changes on remote (next_stream_position: "30401039239471512")
2025/03/12 04:45:27 DEBUG : box root 'rclone-test-quwuzop4kugi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/12 04:45:27 ERROR : : error listing: directory not found
2025/03/12 04:45:27 DEBUG : box root 'rclone-test-quwuzop4kugi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- PASS: TestIntegration (39.63s)
    --- PASS: TestIntegration/FsMkdir (36.40s)
        --- PASS: TestIntegration/FsMkdir/FsChangeNotify (34.09s)
PASS
ok  	github.com/rclone/rclone/backend/box	39.640s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished OK in 40.189771346s (try 2/5)