"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/03 06:53:31 DEBUG : Creating backend with remote "TestBox:rclone-test-fufatek1rofo"
2025/03/03 06:53:31 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/03 06:53:31 DEBUG : box root 'rclone-test-fufatek1rofo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/03 06:53:32 DEBUG : box root 'rclone-test-fufatek1rofo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/03 06:53:34 INFO  : dir: Making directory
2025/03/03 06:53:35 INFO  : dir/subdir1: Making directory
2025/03/03 06:53:36 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469561992")
2025/03/03 06:53:36 INFO  : dir/subdir3: Making directory
2025/03/03 06:53:37 INFO  : dir/subdir2: Making directory
2025/03/03 06:53:37 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469563215")
2025/03/03 06:53:38 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469564217")
2025/03/03 06:53:39 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469565211")
2025/03/03 06:53:40 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469566217")
2025/03/03 06:53:41 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469567209")
2025/03/03 06:53:42 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469568213")
2025/03/03 06:53:43 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469569227")
2025/03/03 06:53:44 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469570221")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/03 06:53:45 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469571237")
2025/03/03 06:53:46 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469572250")
2025/03/03 06:53:47 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469573236")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/03 06:53:48 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469574253")
2025/03/03 06:53:49 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469575255")
2025/03/03 06:53:50 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469576260")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/03 06:53:51 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469577240")
2025/03/03 06:53:51 DEBUG : box root 'rclone-test-fufatek1rofo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/03 06:53:51 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469578254")
2025/03/03 06:53:52 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469578535")
2025/03/03 06:53:53 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469579240")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/03 06:53:54 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469580263")
2025/03/03 06:53:55 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469581224")
2025/03/03 06:53:55 DEBUG : box root 'rclone-test-fufatek1rofo': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/03 06:53:55 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469582249")
2025/03/03 06:53:56 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469582514")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/03 06:53:57 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469583242")
2025/03/03 06:53:58 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469584238")
2025/03/03 06:53:59 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469585238")
2025/03/03 06:53:59 DEBUG : box root 'rclone-test-fufatek1rofo': ["file4"(1)|file|ITEM_TRASH|1792092426411|11c02bba0791b1b723782836377dabd6629be87b] ignored due to duplicate EventID
2025/03/03 06:53:59 DEBUG : box root 'rclone-test-fufatek1rofo': ["a"(1)|folder|ITEM_TRASH|309878757079|87bd257e18501fb8aa25e6cb3887be1355373c2e] ignored due to duplicate EventID
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/03 06:54:00 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469586241")
2025/03/03 06:54:00 DEBUG : box root 'rclone-test-fufatek1rofo': ["c"(0)|folder|ITEM_CREATE|309874170381|672965ac0d3affedc4bbee94604bfd9974488890] new parent not found
2025/03/03 06:54:00 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] new parent not found
2025/03/03 06:54:00 DEBUG : box root 'rclone-test-fufatek1rofo': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:00 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469587437")
2025/03/03 06:54:01 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(6)|file|ITEM_TRASH|1792080167383|cbad62b269c7b926608090d0cde22e2c81408bff] ignored due to duplicate EventID
2025/03/03 06:54:01 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] ignored due to duplicate EventID
2025/03/03 06:54:01 DEBUG : box root 'rclone-test-fufatek1rofo': ["somefile"(1)|file|ITEM_TRASH|1792082279391|bc3c8673bff651e5b023d3bde7b50b3b798f2d98] ignored due to duplicate EventID
2025/03/03 06:54:01 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469587792")
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469588271")
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(6)|file|ITEM_TRASH|1792080167383|cbad62b269c7b926608090d0cde22e2c81408bff] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["somefile"(1)|file|ITEM_TRASH|1792082279391|bc3c8673bff651e5b023d3bde7b50b3b798f2d98] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469589236")
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(6)|file|ITEM_TRASH|1792080167383|cbad62b269c7b926608090d0cde22e2c81408bff] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["somefile"(1)|file|ITEM_TRASH|1792082279391|bc3c8673bff651e5b023d3bde7b50b3b798f2d98] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': ["e.txt"(1)|file|ITEM_TRASH|1792089779811|479dd0f2b0cab89f63db4c37ba36f9075526b75d] ignored due to duplicate EventID
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:02 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469589511")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["somefile"(1)|file|ITEM_TRASH|1792082279391|bc3c8673bff651e5b023d3bde7b50b3b798f2d98] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["e.txt"(1)|file|ITEM_TRASH|1792089779811|479dd0f2b0cab89f63db4c37ba36f9075526b75d] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["b"(1)|folder|ITEM_TRASH|309875058561|b857cf480c62d80b0d6fa0006c5c496ab81ee895] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469589792")
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["somefile"(1)|file|ITEM_TRASH|1792082279391|bc3c8673bff651e5b023d3bde7b50b3b798f2d98] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["e.txt"(1)|file|ITEM_TRASH|1792089779811|479dd0f2b0cab89f63db4c37ba36f9075526b75d] ignored due to duplicate EventID
2025/03/03 06:54:03 DEBUG : box root 'rclone-test-fufatek1rofo': ["b"(1)|folder|ITEM_TRASH|309875058561|b857cf480c62d80b0d6fa0006c5c496ab81ee895] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469590288")
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["file name.txt"(4)|file|ITEM_UPLOAD|1792080167383|4d86d470e54a7f27300d3e3125819872e5448ce9] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["somefile"(1)|file|ITEM_TRASH|1792082279391|bc3c8673bff651e5b023d3bde7b50b3b798f2d98] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["e.txt"(1)|file|ITEM_TRASH|1792089779811|479dd0f2b0cab89f63db4c37ba36f9075526b75d] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["b"(1)|folder|ITEM_TRASH|309875058561|b857cf480c62d80b0d6fa0006c5c496ab81ee895] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["dirToPurgeFromRoot"(0)|folder|ITEM_CREATE|309877796802|151aa2310a0a13c295b7985a91b0330760c8ae80] new parent not found
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469591269")
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["e.txt"(1)|file|ITEM_TRASH|1792089779811|479dd0f2b0cab89f63db4c37ba36f9075526b75d] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["b"(1)|folder|ITEM_TRASH|309875058561|b857cf480c62d80b0d6fa0006c5c496ab81ee895] ignored due to duplicate EventID
2025/03/03 06:54:04 DEBUG : box root 'rclone-test-fufatek1rofo': ["dirToPurgeFromRoot"(0)|folder|ITEM_CREATE|309877796802|151aa2310a0a13c295b7985a91b0330760c8ae80] ignored due to duplicate EventID
2025/03/03 06:54:05 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469591527")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/03 06:54:06 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469592224")
2025/03/03 06:54:06 DEBUG : box root 'rclone-test-fufatek1rofo': ["e.txt"(1)|file|ITEM_TRASH|1792089779811|479dd0f2b0cab89f63db4c37ba36f9075526b75d] ignored due to duplicate EventID
2025/03/03 06:54:07 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469593245")
2025/03/03 06:54:07 DEBUG : box root 'rclone-test-fufatek1rofo': ["b"(1)|folder|ITEM_TRASH|309875058561|b857cf480c62d80b0d6fa0006c5c496ab81ee895] ignored due to duplicate EventID
2025/03/03 06:54:08 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469594221")
2025/03/03 06:54:08 DEBUG : box root 'rclone-test-fufatek1rofo': ["dirToPurgeFromRoot"(0)|folder|ITEM_CREATE|309877796802|151aa2310a0a13c295b7985a91b0330760c8ae80] ignored due to duplicate EventID
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/03 06:54:09 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469595256")
2025/03/03 06:54:10 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469596224")
2025/03/03 06:54:11 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469597229")
    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:54:12 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469598241")
2025/03/03 06:54:12 DEBUG : box root 'rclone-test-fufatek1rofo': ["other.txt"(1)|file|ITEM_MOVE|1792098008778|376fc7f0556ca4195e40d5ec34627629f807d286] new parent not found
2025/03/03 06:54:12 DEBUG : box root 'rclone-test-fufatek1rofo': ["fileToPurge.txt"(0)|file|ITEM_UPLOAD|1792097103835|b6c58690142ec23771bb30c22ebbfe1cfe0ecc85] new parent not found
2025/03/03 06:54:12 DEBUG : box root 'rclone-test-fufatek1rofo': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:12 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469599230")
2025/03/03 06:54:13 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469599476")
2025/03/03 06:54:14 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469600233")
2025/03/03 06:54:14 DEBUG : box root 'rclone-test-fufatek1rofo': ["other.txt"(1)|file|ITEM_MOVE|1792098008778|376fc7f0556ca4195e40d5ec34627629f807d286] ignored due to duplicate EventID
2025/03/03 06:54:15 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469601227")
2025/03/03 06:54:15 DEBUG : box root 'rclone-test-fufatek1rofo': ["fileToPurge.txt"(0)|file|ITEM_UPLOAD|1792097103835|b6c58690142ec23771bb30c22ebbfe1cfe0ecc85] ignored due to duplicate EventID
2025/03/03 06:54:15 DEBUG : box root 'rclone-test-fufatek1rofo': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/03 06:54:15 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469602237")
2025/03/03 06:54:15 DEBUG : box root 'rclone-test-fufatek1rofo': ["piped data.txt"(1)|file|ITEM_TRASH|1792099941026|9f85184904d282b869227e6be17fd07017abdecd] ignored due to duplicate EventID
2025/03/03 06:54:16 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469602491")
2025/03/03 06:54:16 DEBUG : box root 'rclone-test-fufatek1rofo': ["piped data.txt"(1)|file|ITEM_TRASH|1792099941026|9f85184904d282b869227e6be17fd07017abdecd] ignored due to duplicate EventID
2025/03/03 06:54:17 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469603240")
2025/03/03 06:54:17 DEBUG : box root 'rclone-test-fufatek1rofo': ["piped data.txt"(1)|file|ITEM_TRASH|1792099941026|9f85184904d282b869227e6be17fd07017abdecd] ignored due to duplicate EventID
2025/03/03 06:54:18 DEBUG : box root 'rclone-test-fufatek1rofo': Checking for changes on remote (next_stream_position: "30401038469604224")
2025/03/03 06:54:19 DEBUG : box root 'rclone-test-fufatek1rofo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/03 06:54:19 ERROR : : error listing: directory not found
2025/03/03 06:54:20 DEBUG : box root 'rclone-test-fufatek1rofo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (49.10s)
    --- FAIL: TestIntegration/FsMkdir (46.77s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (44.43s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	49.106s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 49.633386399s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]