"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 5/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestBox:"
2025/03/11 08:03:20 DEBUG : Creating backend with remote "TestBox:rclone-test-yaqulax3suku"
2025/03/11 08:03:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/11 08:03:20 DEBUG : box root 'rclone-test-yaqulax3suku': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/11 08:03:21 DEBUG : box root 'rclone-test-yaqulax3suku': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/11 08:03:22 INFO  : dir: Making directory
2025/03/11 08:03:24 INFO  : dir/subdir1: Making directory
2025/03/11 08:03:25 INFO  : dir/subdir3: Making directory
2025/03/11 08:03:25 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164950861")
2025/03/11 08:03:25 DEBUG : box root 'rclone-test-yaqulax3suku': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/11 08:03:25 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164952104")
2025/03/11 08:03:26 INFO  : dir/subdir2: Making directory
2025/03/11 08:03:26 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164952335")
2025/03/11 08:03:27 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164953086")
2025/03/11 08:03:28 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164954085")
2025/03/11 08:03:29 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164955088")
2025/03/11 08:03:30 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164956083")
2025/03/11 08:03:31 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164957084")
2025/03/11 08:03:32 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164958086")
2025/03/11 08:03:33 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164959085")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/11 08:03:34 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164960086")
2025/03/11 08:03:35 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164961088")
2025/03/11 08:03:36 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164962087")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/11 08:03:37 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164963087")
2025/03/11 08:03:38 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164964085")
2025/03/11 08:03:39 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164965080")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/11 08:03:40 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164966091")
2025/03/11 08:03:41 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164967085")
2025/03/11 08:03:42 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164968092")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/11 08:03:43 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164969086")
2025/03/11 08:03:44 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164970110")
2025/03/11 08:03:45 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164971081")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/11 08:03:46 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164972093")
2025/03/11 08:03:47 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164973103")
2025/03/11 08:03:48 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164974138")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/11 08:03:49 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164975107")
2025/03/11 08:03:50 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164976111")
2025/03/11 08:03:51 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164977104")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/11 08:03:51 DEBUG : box root 'rclone-test-yaqulax3suku': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/11 08:03:51 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164978125")
2025/03/11 08:03:52 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164978367")
2025/03/11 08:03:53 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164979112")
2025/03/11 08:03:53 DEBUG : box root 'rclone-test-yaqulax3suku': ["file4"(1)|file|ITEM_TRASH|1799747111340|e988b95e0e89fe3eacb52fda0f1f1e01b61f7286] ignored due to duplicate EventID
2025/03/11 08:03:54 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164980126")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/11 08:03:54 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(1)|file|ITEM_TRASH|1799747144940|f4f1d132ad96f1ee0f133afc01460d0631466707] ignored due to duplicate EventID
2025/03/11 08:03:55 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164981104")
2025/03/11 08:03:55 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir3"(1)|folder|ITEM_TRASH|311110167684|f9bb73dc6751c8240cdeb49e4d43668042b7518f] ignored due to duplicate EventID
2025/03/11 08:03:55 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir1"(0)|folder|ITEM_CREATE|311107508461|2ddc1a45c0955a1d34a525ec790d5d181bd07c2a] added new path ("dir/subdir1") for notify
2025/03/11 08:03:55 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir3"(0)|folder|ITEM_CREATE|311110967659|c8306ce545cb034ab4dc47cc081b64f7f1573582] added new path ("dir/subdir3") for notify
2025/03/11 08:03:55 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/11 08:03:55 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/11 08:03:55 DEBUG : box root 'rclone-test-yaqulax3suku': Received 5 events, resulting in 2 paths and 2 notifications
2025/03/11 08:03:55 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164982115")
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164982393")
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["dir"(1)|folder|ITEM_TRASH|311108087090|164567acdec9f7be4602aa0a238431cfc55e304a] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir1"(0)|folder|ITEM_CREATE|311107508461|2ddc1a45c0955a1d34a525ec790d5d181bd07c2a] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir1"(1)|folder|ITEM_TRASH|311108725694|43b48db13df570e10a6cb17c7ca10e7294e7b962] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir3"(0)|folder|ITEM_CREATE|311110967659|c8306ce545cb034ab4dc47cc081b64f7f1573582] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(0)|folder|ITEM_CREATE|311111502199|8205e7fe7eb6951c1245304afd33a5b5b4106eb9] added new path ("dir/subdir2") for notify
2025/03/11 08:03:56 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': Received 5 events, resulting in 1 paths and 1 notifications
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164983115")
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir1"(0)|folder|ITEM_CREATE|311107508461|2ddc1a45c0955a1d34a525ec790d5d181bd07c2a] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir1"(1)|folder|ITEM_TRASH|311108725694|43b48db13df570e10a6cb17c7ca10e7294e7b962] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir3"(0)|folder|ITEM_CREATE|311110967659|c8306ce545cb034ab4dc47cc081b64f7f1573582] ignored due to duplicate EventID
2025/03/11 08:03:56 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(0)|folder|ITEM_CREATE|311111502199|8205e7fe7eb6951c1245304afd33a5b5b4106eb9] ignored due to duplicate EventID
2025/03/11 08:03:57 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164983382")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/11 08:03:57 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir1"(1)|folder|ITEM_TRASH|311108725694|43b48db13df570e10a6cb17c7ca10e7294e7b962] ignored due to duplicate EventID
2025/03/11 08:03:57 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir3"(0)|folder|ITEM_CREATE|311110967659|c8306ce545cb034ab4dc47cc081b64f7f1573582] ignored due to duplicate EventID
2025/03/11 08:03:57 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(0)|folder|ITEM_CREATE|311111502199|8205e7fe7eb6951c1245304afd33a5b5b4106eb9] ignored due to duplicate EventID
2025/03/11 08:03:57 DEBUG : box root 'rclone-test-yaqulax3suku': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/11 08:03:57 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164984155")
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164984441")
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir3"(0)|folder|ITEM_CREATE|311110967659|c8306ce545cb034ab4dc47cc081b64f7f1573582] ignored due to duplicate EventID
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(0)|folder|ITEM_CREATE|311111502199|8205e7fe7eb6951c1245304afd33a5b5b4106eb9] ignored due to duplicate EventID
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(1)|folder|ITEM_TRASH|311111750342|1d256385d206c73fe3eeb2e80e87b2204b6363de] ignored due to duplicate EventID
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-momisoq6foni"(1)|folder|ITEM_TRASH|311110045382|6fa4e2350eeca680f79e9200807c3ae316336e9b] ignored due to duplicate EventID
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': ["dir"(0)|folder|ITEM_CREATE|311110808667|80f0a2a9e8d8341d6ce861d555635fd527cda854] added new path ("dir") for notify
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': ["file4"(0)|file|ITEM_UPLOAD|1799748875975|8c9987ac6da2451c18641445255f298660c13781] added new path ("dir/file4") for notify
2025/03/11 08:03:58 DEBUG : doChangeNotify("dir", 0)
2025/03/11 08:03:58 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': Received 6 events, resulting in 2 paths and 2 notifications
2025/03/11 08:03:58 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164985113")
2025/03/11 08:03:59 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164985482")
2025/03/11 08:04:00 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164986113")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file4:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/11 08:04:00 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(0)|folder|ITEM_CREATE|311111502199|8205e7fe7eb6951c1245304afd33a5b5b4106eb9] ignored due to duplicate EventID
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164987240")
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': ["subdir2"(1)|folder|ITEM_TRASH|311111750342|1d256385d206c73fe3eeb2e80e87b2204b6363de] ignored due to duplicate EventID
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-momisoq6foni"(1)|folder|ITEM_TRASH|311110045382|6fa4e2350eeca680f79e9200807c3ae316336e9b] ignored due to duplicate EventID
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': ["dir"(0)|folder|ITEM_CREATE|311110808667|80f0a2a9e8d8341d6ce861d555635fd527cda854] ignored due to duplicate EventID
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': ["file4"(0)|file|ITEM_UPLOAD|1799748875975|8c9987ac6da2451c18641445255f298660c13781] ignored due to duplicate EventID
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-yaqulax3suku"(0)|folder|ITEM_CREATE|311111735474|8ff344c1604a25279dfc1b835c0b6558fe309225] new parent not found
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(0)|file|ITEM_UPLOAD|1799746427860|df83ec5cf97ffe73fa45ddfe4273810d2756e569] added new path ("dir/file3") for notify
2025/03/11 08:04:01 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': Received 6 events, resulting in 1 paths and 1 notifications
2025/03/11 08:04:01 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164988436")
2025/03/11 08:04:02 DEBUG : box root 'rclone-test-yaqulax3suku': ["dir"(0)|folder|ITEM_CREATE|311110808667|80f0a2a9e8d8341d6ce861d555635fd527cda854] ignored due to duplicate EventID
2025/03/11 08:04:02 DEBUG : box root 'rclone-test-yaqulax3suku': ["file4"(0)|file|ITEM_UPLOAD|1799748875975|8c9987ac6da2451c18641445255f298660c13781] ignored due to duplicate EventID
2025/03/11 08:04:02 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164988905")
2025/03/11 08:04:02 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-yaqulax3suku"(0)|folder|ITEM_CREATE|311111735474|8ff344c1604a25279dfc1b835c0b6558fe309225] ignored due to duplicate EventID
2025/03/11 08:04:02 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(0)|file|ITEM_UPLOAD|1799746427860|df83ec5cf97ffe73fa45ddfe4273810d2756e569] ignored due to duplicate EventID
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164989216")
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-yaqulax3suku"(0)|folder|ITEM_CREATE|311111735474|8ff344c1604a25279dfc1b835c0b6558fe309225] ignored due to duplicate EventID
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(0)|file|ITEM_UPLOAD|1799746427860|df83ec5cf97ffe73fa45ddfe4273810d2756e569] ignored due to duplicate EventID
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164990171")
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-yaqulax3suku"(0)|folder|ITEM_CREATE|311111735474|8ff344c1604a25279dfc1b835c0b6558fe309225] ignored due to duplicate EventID
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(0)|file|ITEM_UPLOAD|1799746427860|df83ec5cf97ffe73fa45ddfe4273810d2756e569] ignored due to duplicate EventID
2025/03/11 08:04:03 DEBUG : box root 'rclone-test-yaqulax3suku': ["file2"(1)|file|ITEM_TRASH|1799745398387|c9109fc1c85928c4c1a7d2c378cc09c36e72b7a4] ignored due to duplicate EventID
2025/03/11 08:04:04 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164990458")
2025/03/11 08:04:04 DEBUG : box root 'rclone-test-yaqulax3suku': ["rclone-test-yaqulax3suku"(0)|folder|ITEM_CREATE|311111735474|8ff344c1604a25279dfc1b835c0b6558fe309225] ignored due to duplicate EventID
2025/03/11 08:04:04 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(0)|file|ITEM_UPLOAD|1799746427860|df83ec5cf97ffe73fa45ddfe4273810d2756e569] ignored due to duplicate EventID
2025/03/11 08:04:04 DEBUG : box root 'rclone-test-yaqulax3suku': ["file2"(1)|file|ITEM_TRASH|1799745398387|c9109fc1c85928c4c1a7d2c378cc09c36e72b7a4] ignored due to duplicate EventID
2025/03/11 08:04:05 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164991118")
2025/03/11 08:04:05 DEBUG : box root 'rclone-test-yaqulax3suku': ["file3"(0)|file|ITEM_UPLOAD|1799746427860|df83ec5cf97ffe73fa45ddfe4273810d2756e569] ignored due to duplicate EventID
2025/03/11 08:04:06 DEBUG : box root 'rclone-test-yaqulax3suku': Checking for changes on remote (next_stream_position: "30401039164992104")
2025/03/11 08:04:08 DEBUG : box root 'rclone-test-yaqulax3suku': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/11 08:04:08 ERROR : : error listing: directory not found
2025/03/11 08:04:08 DEBUG : box root 'rclone-test-yaqulax3suku': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (48.78s)
    --- FAIL: TestIntegration/FsMkdir (46.44s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (44.09s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	48.789s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 49.286672346s (try 5/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]