"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 3/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestBox:"
2025/03/03 06:54:20 DEBUG : Creating backend with remote "TestBox:rclone-test-hamuzoq3hedi"
2025/03/03 06:54:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/03 06:54:21 DEBUG : box root 'rclone-test-hamuzoq3hedi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/03 06:54:22 DEBUG : box root 'rclone-test-hamuzoq3hedi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/03 06:54:23 INFO  : dir: Making directory
2025/03/03 06:54:24 INFO  : dir/subdir1: Making directory
2025/03/03 06:54:25 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469611597")
2025/03/03 06:54:25 INFO  : dir/subdir3: Making directory
2025/03/03 06:54:26 INFO  : dir/subdir2: Making directory
2025/03/03 06:54:26 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469612826")
2025/03/03 06:54:27 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469613820")
2025/03/03 06:54:28 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469614823")
2025/03/03 06:54:29 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469615822")
2025/03/03 06:54:30 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469616823")
2025/03/03 06:54:31 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469617824")
2025/03/03 06:54:32 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469618827")
2025/03/03 06:54:33 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469619822")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/03 06:54:34 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469620820")
2025/03/03 06:54:35 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469621826")
2025/03/03 06:54:36 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469622826")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/03 06:54:37 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469623823")
2025/03/03 06:54:38 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469624819")
2025/03/03 06:54:39 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469625822")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/03 06:54:40 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469626827")
2025/03/03 06:54:41 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469627823")
2025/03/03 06:54:42 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469628824")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/03 06:54:43 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469629844")
2025/03/03 06:54:44 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469630877")
2025/03/03 06:54:45 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["created from root.txt"(0)|file|ITEM_UPLOAD|1792101515804|f25d0d1d1345217d340606a8ef92643ff4411a58] new parent not found
2025/03/03 06:54:45 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:45 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469631876")
2025/03/03 06:54:45 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469632145")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/03 06:54:46 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469632868")
2025/03/03 06:54:47 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469633879")
2025/03/03 06:54:48 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469634925")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/03 06:54:49 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["created from root.txt"(0)|file|ITEM_UPLOAD|1792101515804|f25d0d1d1345217d340606a8ef92643ff4411a58] ignored due to duplicate EventID
2025/03/03 06:54:49 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469635843")
2025/03/03 06:54:50 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["e.txt"(0)|file|ITEM_UPLOAD|1792089779811|7e7ee1e1d9d45c23f63857f15799ed7c542cd691] new parent not found
2025/03/03 06:54:50 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-jiqijoz2lali"(2)|folder|ITEM_MOVE|309875239149|325c08e97401131b2a4f4278a3230d26a1752fe4] new parent not found
2025/03/03 06:54:50 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:50 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469636853")
2025/03/03 06:54:50 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-jiqijoz2lali"(2)|folder|ITEM_MOVE|309875239149|325c08e97401131b2a4f4278a3230d26a1752fe4] ignored due to duplicate EventID
2025/03/03 06:54:50 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469637104")
2025/03/03 06:54:51 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469637842")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-jiqijoz2lali"(2)|folder|ITEM_MOVE|309875239149|325c08e97401131b2a4f4278a3230d26a1752fe4] ignored due to duplicate EventID
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1792089895051|7904779625c64360b78402fd1f187298f08da24f] new parent not found
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["other.txt"(2)|file|ITEM_MOVE|1792080167383|4564e277d5980772542ba258f99eee2916f97ddb] new parent not found
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469638840")
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1792089895051|7904779625c64360b78402fd1f187298f08da24f] ignored due to duplicate EventID
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["other.txt"(2)|file|ITEM_MOVE|1792080167383|4564e277d5980772542ba258f99eee2916f97ddb] ignored due to duplicate EventID
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["d"(1)|folder|ITEM_TRASH|309877792546|4a23c5a93bf3d480242e5a6f1952ec0854338e41] ignored due to duplicate EventID
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469639101")
2025/03/03 06:54:52 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469639357")
2025/03/03 06:54:53 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469639842")
2025/03/03 06:54:54 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469640851")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/03 06:54:55 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1792089895051|7904779625c64360b78402fd1f187298f08da24f] ignored due to duplicate EventID
2025/03/03 06:54:55 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469641848")
2025/03/03 06:54:56 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["other.txt"(2)|file|ITEM_MOVE|1792080167383|4564e277d5980772542ba258f99eee2916f97ddb] ignored due to duplicate EventID
2025/03/03 06:54:56 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["d"(1)|folder|ITEM_TRASH|309877792546|4a23c5a93bf3d480242e5a6f1952ec0854338e41] ignored due to duplicate EventID
2025/03/03 06:54:56 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469642834")
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["moveTest"(1)|folder|ITEM_TRASH|309876475147|b773f3120a667a3c77615241e623914866d027ec] ignored due to duplicate EventID
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["file2"(0)|file|ITEM_UPLOAD|1792092560614|13dfd6633ee1a000d22ba5eff32301323c8df123] new parent not found
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-yuyufif0cera"(0)|folder|ITEM_CREATE|309874180503|614f55b5464ad6ffd2fbae6a68accb7585bfed1a] new parent not found
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469643865")
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["c"(1)|folder|ITEM_TRASH|309874170381|51fad7cae9b01ae965eba15b135ec80b376f96cb] ignored due to duplicate EventID
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["file2"(0)|file|ITEM_UPLOAD|1792092560614|13dfd6633ee1a000d22ba5eff32301323c8df123] ignored due to duplicate EventID
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir1"(1)|folder|ITEM_TRASH|309873776459|7056bb089290dfd9544c9c94515a48a4370b7a17] ignored due to duplicate EventID
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-yuyufif0cera"(0)|folder|ITEM_CREATE|309874180503|614f55b5464ad6ffd2fbae6a68accb7585bfed1a] ignored due to duplicate EventID
2025/03/03 06:54:57 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469644124")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["c"(1)|folder|ITEM_TRASH|309874170381|51fad7cae9b01ae965eba15b135ec80b376f96cb] ignored due to duplicate EventID
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["file2"(0)|file|ITEM_UPLOAD|1792092560614|13dfd6633ee1a000d22ba5eff32301323c8df123] ignored due to duplicate EventID
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir1"(1)|folder|ITEM_TRASH|309873776459|7056bb089290dfd9544c9c94515a48a4370b7a17] ignored due to duplicate EventID
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-yuyufif0cera"(0)|folder|ITEM_CREATE|309874180503|614f55b5464ad6ffd2fbae6a68accb7585bfed1a] ignored due to duplicate EventID
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1792082488717|7df61164f7c841713a75460f40fbf78b7ef7c792] new parent not found
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469644831")
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["file2"(0)|file|ITEM_UPLOAD|1792092560614|13dfd6633ee1a000d22ba5eff32301323c8df123] ignored due to duplicate EventID
2025/03/03 06:54:58 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469645083")
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir1"(1)|folder|ITEM_TRASH|309873776459|7056bb089290dfd9544c9c94515a48a4370b7a17] ignored due to duplicate EventID
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-yuyufif0cera"(0)|folder|ITEM_CREATE|309874180503|614f55b5464ad6ffd2fbae6a68accb7585bfed1a] ignored due to duplicate EventID
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1792082488717|7df61164f7c841713a75460f40fbf78b7ef7c792] ignored due to duplicate EventID
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir3"(0)|folder|ITEM_CREATE|309877698609|9f03d8a46136d23e97bf9cf7b342416eef138cb5] new parent not found
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469645841")
2025/03/03 06:54:59 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469646099")
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir1"(1)|folder|ITEM_TRASH|309873776459|7056bb089290dfd9544c9c94515a48a4370b7a17] ignored due to duplicate EventID
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["rclone-test-yuyufif0cera"(0)|folder|ITEM_CREATE|309874180503|614f55b5464ad6ffd2fbae6a68accb7585bfed1a] ignored due to duplicate EventID
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1792082488717|7df61164f7c841713a75460f40fbf78b7ef7c792] ignored due to duplicate EventID
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir3"(0)|folder|ITEM_CREATE|309877698609|9f03d8a46136d23e97bf9cf7b342416eef138cb5] ignored due to duplicate EventID
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469646838")
2025/03/03 06:55:00 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469647195")
    fstests.go:935: map[] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/03 06:55:01 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469648134")
2025/03/03 06:55:02 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["unknown-size-put.txt"(0)|file|ITEM_UPLOAD|1792082488717|7df61164f7c841713a75460f40fbf78b7ef7c792] ignored due to duplicate EventID
2025/03/03 06:55:02 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469648839")
2025/03/03 06:55:03 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["subdir3"(0)|folder|ITEM_CREATE|309877698609|9f03d8a46136d23e97bf9cf7b342416eef138cb5] ignored due to duplicate EventID
2025/03/03 06:55:03 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469649838")
2025/03/03 06:55:04 DEBUG : box root 'rclone-test-hamuzoq3hedi': ["created from root.txt"(1)|file|ITEM_TRASH|1792101515804|aae5aa9e1f037a56a95dbfa34149a6d070eebeb4] ignored due to duplicate EventID
2025/03/03 06:55:04 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469650858")
2025/03/03 06:55:05 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469651835")
2025/03/03 06:55:06 DEBUG : box root 'rclone-test-hamuzoq3hedi': Checking for changes on remote (next_stream_position: "30401038469652844")
2025/03/03 06:55:09 DEBUG : box root 'rclone-test-hamuzoq3hedi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/03 06:55:09 ERROR : : error listing: directory not found
2025/03/03 06:55:09 DEBUG : box root 'rclone-test-hamuzoq3hedi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (48.62s)
    --- FAIL: TestIntegration/FsMkdir (46.33s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (43.89s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	48.633s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 49.17248637s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]