"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/11 08:01:38 DEBUG : Creating backend with remote "TestBox:rclone-test-bunisuc3rugo"
2025/03/11 08:01:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/11 08:01:39 DEBUG : box root 'rclone-test-bunisuc3rugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/11 08:01:40 DEBUG : box root 'rclone-test-bunisuc3rugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/11 08:01:41 INFO  : dir: Making directory
2025/03/11 08:01:42 INFO  : dir/subdir1: Making directory
2025/03/11 08:01:43 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164849342")
2025/03/11 08:01:43 INFO  : dir/subdir3: Making directory
2025/03/11 08:01:44 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164850586")
2025/03/11 08:01:45 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164851598")
2025/03/11 08:01:45 INFO  : dir/subdir2: Making directory
2025/03/11 08:01:46 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164852606")
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164853637")
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(0)|file|ITEM_UPLOAD|1799748645022|0ea1a00070715d8ddea48703115dadc15a164b9a] new parent not found
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1799736964231|c7fa5b7f1220df7a81636b3b0f23661483612b1a] new parent not found
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311111655762|c7ac7b273c3e891b0caff9b5ad5eccd1d1792e5c] new parent not found
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(0)|folder|ITEM_CREATE|311110717668|a670ced6edd4c060a2e04a967e317c4c9ab2df34] new parent not found
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/11 08:01:47 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164854632")
2025/03/11 08:01:48 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(0)|file|ITEM_UPLOAD|1799748645022|0ea1a00070715d8ddea48703115dadc15a164b9a] ignored due to duplicate EventID
2025/03/11 08:01:48 DEBUG : box root 'rclone-test-bunisuc3rugo': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1799736964231|c7fa5b7f1220df7a81636b3b0f23661483612b1a] ignored due to duplicate EventID
2025/03/11 08:01:48 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311111655762|c7ac7b273c3e891b0caff9b5ad5eccd1d1792e5c] ignored due to duplicate EventID
2025/03/11 08:01:48 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(0)|folder|ITEM_CREATE|311110717668|a670ced6edd4c060a2e04a967e317c4c9ab2df34] ignored due to duplicate EventID
2025/03/11 08:01:48 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164855008")
2025/03/11 08:01:49 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164855807")
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(0)|file|ITEM_UPLOAD|1799748645022|0ea1a00070715d8ddea48703115dadc15a164b9a] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1799736964231|c7fa5b7f1220df7a81636b3b0f23661483612b1a] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311111655762|c7ac7b273c3e891b0caff9b5ad5eccd1d1792e5c] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(0)|folder|ITEM_CREATE|311110717668|a670ced6edd4c060a2e04a967e317c4c9ab2df34] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(0)|folder|ITEM_CREATE|311112058883|2c867a8437ee9f3cb2bd213cb8b152324017c885] new parent not found
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164856891")
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["unknown-size-update.txt"(1)|file|ITEM_UPLOAD|1799736964231|c7fa5b7f1220df7a81636b3b0f23661483612b1a] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311111655762|c7ac7b273c3e891b0caff9b5ad5eccd1d1792e5c] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(0)|folder|ITEM_CREATE|311110717668|a670ced6edd4c060a2e04a967e317c4c9ab2df34] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(0)|folder|ITEM_CREATE|311112058883|2c867a8437ee9f3cb2bd213cb8b152324017c885] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164857175")
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311111655762|c7ac7b273c3e891b0caff9b5ad5eccd1d1792e5c] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(0)|folder|ITEM_CREATE|311110717668|a670ced6edd4c060a2e04a967e317c4c9ab2df34] ignored due to duplicate EventID
2025/03/11 08:01:50 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(0)|folder|ITEM_CREATE|311112058883|2c867a8437ee9f3cb2bd213cb8b152324017c885] ignored due to duplicate EventID
2025/03/11 08:01:51 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164857622")
2025/03/11 08:01:51 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311111655762|c7ac7b273c3e891b0caff9b5ad5eccd1d1792e5c] ignored due to duplicate EventID
2025/03/11 08:01:51 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(0)|folder|ITEM_CREATE|311110717668|a670ced6edd4c060a2e04a967e317c4c9ab2df34] ignored due to duplicate EventID
2025/03/11 08:01:52 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164858689")
2025/03/11 08:01:53 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164859640")
2025/03/11 08:01:53 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(0)|folder|ITEM_CREATE|311112058883|2c867a8437ee9f3cb2bd213cb8b152324017c885] ignored due to duplicate EventID
2025/03/11 08:01:54 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164860602")
2025/03/11 08:01:55 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164861608")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/11 08:01:56 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164862602")
2025/03/11 08:01:56 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/11 08:01:56 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164863611")
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164863949")
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir1"(1)|folder|ITEM_TRASH|311109990837|f400bb53a58ab85af7a65954784b4b5d489569f4] ignored due to duplicate EventID
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(1)|folder|ITEM_TRASH|311107616520|9254a0da6ff5c44f70b687644431b162bb1256dc] ignored due to duplicate EventID
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file3"(1)|file|ITEM_TRASH|1799744283759|9cca39049a7183aaa094d545ef950d3ee33c0cc8] ignored due to duplicate EventID
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file2"(0)|file|ITEM_UPLOAD|1799749525890|f84d01d10a35a600f7d9eb7e952da7e0e75642f0] added new path ("dir/file2") for notify
2025/03/11 08:01:57 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/11 08:01:57 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164864605")
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir1"(1)|folder|ITEM_TRASH|311109990837|f400bb53a58ab85af7a65954784b4b5d489569f4] ignored due to duplicate EventID
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(1)|folder|ITEM_TRASH|311107616520|9254a0da6ff5c44f70b687644431b162bb1256dc] ignored due to duplicate EventID
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file3"(1)|file|ITEM_TRASH|1799744283759|9cca39049a7183aaa094d545ef950d3ee33c0cc8] ignored due to duplicate EventID
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file2"(0)|file|ITEM_UPLOAD|1799749525890|f84d01d10a35a600f7d9eb7e952da7e0e75642f0] ignored due to duplicate EventID
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164864867")
2025/03/11 08:01:58 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164865130")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/11 08:01:59 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164865625")
2025/03/11 08:01:59 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir1"(1)|folder|ITEM_TRASH|311109990837|f400bb53a58ab85af7a65954784b4b5d489569f4] ignored due to duplicate EventID
2025/03/11 08:01:59 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(1)|folder|ITEM_TRASH|311107616520|9254a0da6ff5c44f70b687644431b162bb1256dc] ignored due to duplicate EventID
2025/03/11 08:02:00 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164866634")
2025/03/11 08:02:00 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file3"(1)|file|ITEM_TRASH|1799744283759|9cca39049a7183aaa094d545ef950d3ee33c0cc8] ignored due to duplicate EventID
2025/03/11 08:02:00 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file2"(0)|file|ITEM_UPLOAD|1799749525890|f84d01d10a35a600f7d9eb7e952da7e0e75642f0] ignored due to duplicate EventID
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164867710")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(1)|folder|ITEM_TRASH|311110717668|8cb9d9762e99e875b50b9cf419daa1f1d36d5d33] ignored due to duplicate EventID
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-bunisuc3rugo"(0)|folder|ITEM_CREATE|311111819004|a54149ccff8cc77ae1d37bc38713d09582125657] new parent not found
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir1"(0)|folder|ITEM_CREATE|311108269725|33b68d8be1ecc3d9e5c068cef609f67043a774f3] added new path ("dir/subdir1") for notify
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(0)|folder|ITEM_CREATE|311108615810|c865849ba69e59929c2811bcb2e3effd26c8db4b] added new path ("dir/subdir3") for notify
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311112139173|9ba7edd0cb84cc6477fe544efae2058927475965] added new path ("dir") for notify
2025/03/11 08:02:01 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/11 08:02:01 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/11 08:02:01 DEBUG : doChangeNotify("dir", 0)
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 9 events, resulting in 3 paths and 3 notifications
2025/03/11 08:02:01 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164868603")
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-filucas7zuke"(1)|folder|ITEM_TRASH|311110717668|8cb9d9762e99e875b50b9cf419daa1f1d36d5d33] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["rclone-test-bunisuc3rugo"(0)|folder|ITEM_CREATE|311111819004|a54149ccff8cc77ae1d37bc38713d09582125657] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir1"(0)|folder|ITEM_CREATE|311108269725|33b68d8be1ecc3d9e5c068cef609f67043a774f3] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file2"(1)|file|ITEM_TRASH|1799746285282|af96aeb230985b689b48059ca5959201e2c201a7] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(1)|folder|ITEM_TRASH|311112058883|2c9a0fdab2acf7e7d7d3542223e634d79a853612] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(0)|folder|ITEM_CREATE|311108615810|c865849ba69e59929c2811bcb2e3effd26c8db4b] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311112139173|9ba7edd0cb84cc6477fe544efae2058927475965] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(1)|file|ITEM_TRASH|1799748645022|3f6d47bee6e0e2b1e7fe0d2c12f286264f0f37e1] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(1)|folder|ITEM_TRASH|311111655762|5090a954eea29b2449e910f97bb42a786159abe3] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164870215")
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir1"(0)|folder|ITEM_CREATE|311108269725|33b68d8be1ecc3d9e5c068cef609f67043a774f3] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file2"(1)|file|ITEM_TRASH|1799746285282|af96aeb230985b689b48059ca5959201e2c201a7] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(1)|folder|ITEM_TRASH|311112058883|2c9a0fdab2acf7e7d7d3542223e634d79a853612] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(0)|folder|ITEM_CREATE|311108615810|c865849ba69e59929c2811bcb2e3effd26c8db4b] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311112139173|9ba7edd0cb84cc6477fe544efae2058927475965] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(1)|file|ITEM_TRASH|1799748645022|3f6d47bee6e0e2b1e7fe0d2c12f286264f0f37e1] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(1)|folder|ITEM_TRASH|311111655762|5090a954eea29b2449e910f97bb42a786159abe3] ignored due to duplicate EventID
2025/03/11 08:02:03 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164870510")
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(1)|folder|ITEM_TRASH|311112058883|2c9a0fdab2acf7e7d7d3542223e634d79a853612] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(0)|folder|ITEM_CREATE|311108615810|c865849ba69e59929c2811bcb2e3effd26c8db4b] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311112139173|9ba7edd0cb84cc6477fe544efae2058927475965] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(1)|file|ITEM_TRASH|1799748645022|3f6d47bee6e0e2b1e7fe0d2c12f286264f0f37e1] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(1)|folder|ITEM_TRASH|311111655762|5090a954eea29b2449e910f97bb42a786159abe3] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164870801")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(1)|folder|ITEM_TRASH|311112058883|2c9a0fdab2acf7e7d7d3542223e634d79a853612] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(0)|folder|ITEM_CREATE|311108615810|c865849ba69e59929c2811bcb2e3effd26c8db4b] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311112139173|9ba7edd0cb84cc6477fe544efae2058927475965] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(1)|file|ITEM_TRASH|1799748645022|3f6d47bee6e0e2b1e7fe0d2c12f286264f0f37e1] ignored due to duplicate EventID
2025/03/11 08:02:04 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(1)|folder|ITEM_TRASH|311111655762|5090a954eea29b2449e910f97bb42a786159abe3] ignored due to duplicate EventID
2025/03/11 08:02:05 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164871605")
2025/03/11 08:02:05 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir3"(0)|folder|ITEM_CREATE|311108615810|c865849ba69e59929c2811bcb2e3effd26c8db4b] ignored due to duplicate EventID
2025/03/11 08:02:05 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(0)|folder|ITEM_CREATE|311112139173|9ba7edd0cb84cc6477fe544efae2058927475965] ignored due to duplicate EventID
2025/03/11 08:02:05 DEBUG : box root 'rclone-test-bunisuc3rugo': ["file4"(1)|file|ITEM_TRASH|1799748645022|3f6d47bee6e0e2b1e7fe0d2c12f286264f0f37e1] ignored due to duplicate EventID
2025/03/11 08:02:05 DEBUG : box root 'rclone-test-bunisuc3rugo': ["dir"(1)|folder|ITEM_TRASH|311111655762|5090a954eea29b2449e910f97bb42a786159abe3] ignored due to duplicate EventID
2025/03/11 08:02:06 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164872578")
2025/03/11 08:02:07 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164873585")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/11 08:02:08 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164874650")
2025/03/11 08:02:09 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164875573")
2025/03/11 08:02:10 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164876598")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/11 08:02:11 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164877582")
2025/03/11 08:02:12 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164878585")
2025/03/11 08:02:13 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164879589")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/11 08:02:13 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(0)|folder|ITEM_CREATE|311110186886|ce0fe50e80e9b86793bd8dc2fbe584477b2c1039] added new path ("dir/subdir2") for notify
2025/03/11 08:02:13 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/11 08:02:13 DEBUG : box root 'rclone-test-bunisuc3rugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/11 08:02:13 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164880579")
2025/03/11 08:02:14 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164880815")
2025/03/11 08:02:15 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164881569")
2025/03/11 08:02:16 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164882569")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/11 08:02:17 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164883575")
2025/03/11 08:02:17 DEBUG : box root 'rclone-test-bunisuc3rugo': ["subdir2"(0)|folder|ITEM_CREATE|311110186886|ce0fe50e80e9b86793bd8dc2fbe584477b2c1039] ignored due to duplicate EventID
2025/03/11 08:02:18 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164884571")
2025/03/11 08:02:19 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164885585")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/11 08:02:20 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164886568")
2025/03/11 08:02:21 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164887575")
2025/03/11 08:02:22 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164888571")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file2:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/11 08:02:23 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164889569")
2025/03/11 08:02:24 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164890566")
2025/03/11 08:02:25 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164891569")
2025/03/11 08:02:26 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164892568")
2025/03/11 08:02:27 DEBUG : box root 'rclone-test-bunisuc3rugo': Checking for changes on remote (next_stream_position: "30401039164893570")
2025/03/11 08:02:29 DEBUG : box root 'rclone-test-bunisuc3rugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/11 08:02:29 ERROR : : error listing: directory not found
2025/03/11 08:02:30 DEBUG : box root 'rclone-test-bunisuc3rugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (51.33s)
    --- FAIL: TestIntegration/FsMkdir (49.14s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (46.98s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	51.342s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 51.861835663s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]