"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 4/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestBox:"
2025/03/11 08:02:30 DEBUG : Creating backend with remote "TestBox:rclone-test-momisoq6foni"
2025/03/11 08:02:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/11 08:02:31 DEBUG : box root 'rclone-test-momisoq6foni': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/11 08:02:31 DEBUG : box root 'rclone-test-momisoq6foni': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/11 08:02:33 INFO  : dir: Making directory
2025/03/11 08:02:34 INFO  : dir/subdir1: Making directory
2025/03/11 08:02:35 INFO  : dir/subdir3: Making directory
2025/03/11 08:02:35 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164900988")
2025/03/11 08:02:36 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164902229")
2025/03/11 08:02:36 INFO  : dir/subdir2: Making directory
2025/03/11 08:02:37 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164903207")
2025/03/11 08:02:38 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164904210")
2025/03/11 08:02:39 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164905210")
2025/03/11 08:02:40 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164906209")
2025/03/11 08:02:41 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164907208")
2025/03/11 08:02:42 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164908215")
2025/03/11 08:02:43 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164909219")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/11 08:02:44 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164910223")
2025/03/11 08:02:44 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747562134|df589c36028738254e141be780002ca1854c60f4] new parent not found
2025/03/11 08:02:44 DEBUG : box root 'rclone-test-momisoq6foni': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/11 08:02:44 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164911236")
2025/03/11 08:02:45 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164911496")
2025/03/11 08:02:46 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164912220")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/11 08:02:46 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747562134|df589c36028738254e141be780002ca1854c60f4] ignored due to duplicate EventID
2025/03/11 08:02:47 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164913242")
2025/03/11 08:02:47 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747562134|df589c36028738254e141be780002ca1854c60f4] ignored due to duplicate EventID
2025/03/11 08:02:48 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164914230")
2025/03/11 08:02:48 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747562134|df589c36028738254e141be780002ca1854c60f4] ignored due to duplicate EventID
2025/03/11 08:02:48 DEBUG : box root 'rclone-test-momisoq6foni': ["file4"(0)|file|ITEM_UPLOAD|1799733763104|2fc9eda4dac53524e2d2ef808e9b01fbf70cd4ec] new parent not found
2025/03/11 08:02:48 DEBUG : box root 'rclone-test-momisoq6foni': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/11 08:02:48 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164915253")
2025/03/11 08:02:49 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164915528")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/11 08:02:50 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164916287")
2025/03/11 08:02:51 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164917304")
2025/03/11 08:02:52 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164918251")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/11 08:02:52 DEBUG : box root 'rclone-test-momisoq6foni': ["file4"(0)|file|ITEM_UPLOAD|1799733763104|2fc9eda4dac53524e2d2ef808e9b01fbf70cd4ec] ignored due to duplicate EventID
2025/03/11 08:02:52 DEBUG : box root 'rclone-test-momisoq6foni': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/11 08:02:52 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164919289")
2025/03/11 08:02:52 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir3"(1)|folder|ITEM_TRASH|311108615810|349acb21d77b4b314baf8af93e3bc48f043f09f9] ignored due to duplicate EventID
2025/03/11 08:02:53 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164919574")
2025/03/11 08:02:54 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164920248")
2025/03/11 08:02:55 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164921238")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/11 08:02:55 DEBUG : box root 'rclone-test-momisoq6foni': ["dir"(0)|folder|ITEM_CREATE|311108087090|e09e25cbec3017a393295fa142c7e6ce9b7ddaf7] added new path ("dir") for notify
2025/03/11 08:02:55 DEBUG : doChangeNotify("dir", 0)
2025/03/11 08:02:55 DEBUG : box root 'rclone-test-momisoq6foni': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/11 08:02:55 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164922231")
2025/03/11 08:02:56 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164922532")
2025/03/11 08:02:57 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164923254")
2025/03/11 08:02:58 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164924250")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/11 08:02:59 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164925231")
2025/03/11 08:02:59 DEBUG : box root 'rclone-test-momisoq6foni': ["dir"(0)|folder|ITEM_CREATE|311108087090|e09e25cbec3017a393295fa142c7e6ce9b7ddaf7] ignored due to duplicate EventID
2025/03/11 08:03:00 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164926245")
2025/03/11 08:03:01 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164927436")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/11 08:03:01 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir2"(0)|folder|ITEM_CREATE|311111750342|86b36c5192b19395e9ed8d0991453f690f07860e] added new path ("dir/subdir2") for notify
2025/03/11 08:03:01 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/11 08:03:01 DEBUG : box root 'rclone-test-momisoq6foni': Received 5 events, resulting in 1 paths and 1 notifications
2025/03/11 08:03:01 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164928294")
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164928589")
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir2"(0)|folder|ITEM_CREATE|311111750342|86b36c5192b19395e9ed8d0991453f690f07860e] ignored due to duplicate EventID
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': ["file2"(1)|file|ITEM_TRASH|1799749525890|38eebc71db4abc023c85ec3cb192cccab0c327b0] ignored due to duplicate EventID
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir1"(1)|folder|ITEM_TRASH|311108269725|de5192d77348586cfbc62bd632e01423c493ea53] ignored due to duplicate EventID
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(1)|file|ITEM_TRASH|1799747562134|1ea253934f99d80ba5ef3b33a6a3d7569a6ccbca] ignored due to duplicate EventID
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir1"(0)|folder|ITEM_CREATE|311108725694|2ebffee80a2426f38831f832b056b201a4cc14df] added new path ("dir/subdir1") for notify
2025/03/11 08:03:02 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': Received 5 events, resulting in 1 paths and 1 notifications
2025/03/11 08:03:02 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164929243")
2025/03/11 08:03:03 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164929530")
2025/03/11 08:03:03 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir2"(0)|folder|ITEM_CREATE|311111750342|86b36c5192b19395e9ed8d0991453f690f07860e] ignored due to duplicate EventID
2025/03/11 08:03:04 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164930276")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/11 08:03:04 DEBUG : box root 'rclone-test-momisoq6foni': ["file2"(1)|file|ITEM_TRASH|1799749525890|38eebc71db4abc023c85ec3cb192cccab0c327b0] ignored due to duplicate EventID
2025/03/11 08:03:04 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir1"(1)|folder|ITEM_TRASH|311108269725|de5192d77348586cfbc62bd632e01423c493ea53] ignored due to duplicate EventID
2025/03/11 08:03:05 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164931229")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/11 08:03:07 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(1)|file|ITEM_TRASH|1799747562134|1ea253934f99d80ba5ef3b33a6a3d7569a6ccbca] ignored due to duplicate EventID
2025/03/11 08:03:07 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir1"(0)|folder|ITEM_CREATE|311108725694|2ebffee80a2426f38831f832b056b201a4cc14df] ignored due to duplicate EventID
2025/03/11 08:03:07 DEBUG : box root 'rclone-test-momisoq6foni': ["rclone-test-momisoq6foni"(0)|folder|ITEM_CREATE|311110045382|2eea4eb7e5f62be92c2a2d55b96b56cd6c7fa1e7] new parent not found
2025/03/11 08:03:07 DEBUG : box root 'rclone-test-momisoq6foni': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/11 08:03:07 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164934472")
2025/03/11 08:03:07 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164934731")
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164934988")
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': ["file2"(0)|file|ITEM_UPLOAD|1799740077478|9be68ca725c14cab294a3910c1cf4a9d2bf61c79] added new path ("dir/file2") for notify
2025/03/11 08:03:08 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164935242")
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir3"(0)|folder|ITEM_CREATE|311110167684|cb23db92aa45e4ea3370f4829e83d913dc843dd8] added new path ("dir/subdir3") for notify
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747144940|94212dbece3a4f98ec7d6dab4b13b6513ed91207] added new path ("dir/file3") for notify
2025/03/11 08:03:08 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/11 08:03:08 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/11 08:03:08 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164935492")
2025/03/11 08:03:09 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164935732")
2025/03/11 08:03:10 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164936228")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file2:{} dir/file3:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164937239")
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir3"(0)|folder|ITEM_CREATE|311110167684|cb23db92aa45e4ea3370f4829e83d913dc843dd8] ignored due to duplicate EventID
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747144940|94212dbece3a4f98ec7d6dab4b13b6513ed91207] ignored due to duplicate EventID
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': ["file4"(0)|file|ITEM_UPLOAD|1799747111340|4e75fab73d38a41293e8db2051dfd46cb945a16d] added new path ("dir/file4") for notify
2025/03/11 08:03:11 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164938255")
2025/03/11 08:03:11 DEBUG : box root 'rclone-test-momisoq6foni': ["file3"(0)|file|ITEM_UPLOAD|1799747144940|94212dbece3a4f98ec7d6dab4b13b6513ed91207] ignored due to duplicate EventID
2025/03/11 08:03:12 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164938518")
2025/03/11 08:03:13 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164939232")
2025/03/11 08:03:13 DEBUG : box root 'rclone-test-momisoq6foni': ["subdir2"(1)|folder|ITEM_TRASH|311110186886|20b3a74f25241fab3bb0dde6e8af728c7d370840] ignored due to duplicate EventID
2025/03/11 08:03:13 DEBUG : box root 'rclone-test-momisoq6foni': ["file4"(0)|file|ITEM_UPLOAD|1799747111340|4e75fab73d38a41293e8db2051dfd46cb945a16d] ignored due to duplicate EventID
2025/03/11 08:03:14 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164940227")
2025/03/11 08:03:15 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164941230")
2025/03/11 08:03:16 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164942232")
2025/03/11 08:03:16 DEBUG : box root 'rclone-test-momisoq6foni': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/11 08:03:16 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164943236")
2025/03/11 08:03:17 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164943472")
2025/03/11 08:03:17 DEBUG : box root 'rclone-test-momisoq6foni': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/11 08:03:17 DEBUG : box root 'rclone-test-momisoq6foni': Checking for changes on remote (next_stream_position: "30401039164944227")
2025/03/11 08:03:19 DEBUG : box root 'rclone-test-momisoq6foni': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/11 08:03:19 ERROR : : error listing: directory not found
2025/03/11 08:03:19 DEBUG : box root 'rclone-test-momisoq6foni': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (49.05s)
    --- FAIL: TestIntegration/FsMkdir (46.94s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (44.31s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	49.058s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 49.587600383s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]