"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 4/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/03/04 09:30:12 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-hicokel9fiwo"
2025/03/04 09:30:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/04 09:30:12 DEBUG : Creating backend with remote "TestBox:rclone-test-hicokel9fiwo"
2025/03/04 09:30:13 DEBUG : box root 'rclone-test-hicokel9fiwo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/04 09:30:13 DEBUG : Reset feature "ListR"
2025/03/04 09:30:14 DEBUG : box root 'rclone-test-hicokel9fiwo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/04 09:30:15 INFO  : dir: Making directory
2025/03/04 09:30:17 INFO  : dir/subdir1: Making directory
2025/03/04 09:30:18 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565363765")
2025/03/04 09:30:18 INFO  : dir/subdir3: Making directory
2025/03/04 09:30:18 DEBUG : box root 'rclone-test-hicokel9fiwo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:30:18 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565364996")
2025/03/04 09:30:19 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565365232")
2025/03/04 09:30:19 INFO  : dir/subdir2: Making directory
2025/03/04 09:30:20 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565365994")
2025/03/04 09:30:20 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2.rclone_chunk.001"(1)|file|ITEM_RENAME|1793148604537|26d5670d9652b80683ba5f16ad5e226b2e510bac] new parent not found
2025/03/04 09:30:20 DEBUG : box root 'rclone-test-hicokel9fiwo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:30:20 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565367002")
2025/03/04 09:30:20 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2.rclone_chunk.001"(1)|file|ITEM_RENAME|1793148604537|26d5670d9652b80683ba5f16ad5e226b2e510bac] ignored due to duplicate EventID
2025/03/04 09:30:21 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565367236")
2025/03/04 09:30:21 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2.rclone_chunk.001"(1)|file|ITEM_RENAME|1793148604537|26d5670d9652b80683ba5f16ad5e226b2e510bac] ignored due to duplicate EventID
2025/03/04 09:30:22 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565368004")
2025/03/04 09:30:23 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565368996")
2025/03/04 09:30:24 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565370005")
2025/03/04 09:30:25 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565370996")
2025/03/04 09:30:25 DEBUG : box root 'rclone-test-hicokel9fiwo': ["sub dir"(0)|folder|ITEM_CREATE|310045846894|ea0b85bfea2c38cab29776efaed55d2888829bf9] new parent not found
2025/03/04 09:30:25 DEBUG : box root 'rclone-test-hicokel9fiwo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:30:25 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565371994")
2025/03/04 09:30:25 INFO  : dir/file2.rclone_chunk.001_lk00zk: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/04 09:30:25 INFO  : dir/file2.rclone_chunk.002_lk00zk: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/04 09:30:26 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565372229")
2025/03/04 09:30:27 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565372993")
2025/03/04 09:30:27 DEBUG : box root 'rclone-test-hicokel9fiwo': ["sub dir"(0)|folder|ITEM_CREATE|310045846894|ea0b85bfea2c38cab29776efaed55d2888829bf9] ignored due to duplicate EventID
2025/03/04 09:30:28 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565373991")
2025/03/04 09:30:29 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565375004")
2025/03/04 09:30:30 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565376001")
2025/03/04 09:30:31 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565376993")
2025/03/04 09:30:32 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565377987")
2025/03/04 09:30:33 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565378990")
2025/03/04 09:30:33 INFO  : dir/file4.rclone_chunk.001_lk09q0: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/04 09:30:34 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565379988")
2025/03/04 09:30:34 INFO  : dir/file4.rclone_chunk.002_lk09q0: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/04 09:30:35 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565381043")
2025/03/04 09:30:36 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565382003")
2025/03/04 09:30:37 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565383001")
2025/03/04 09:30:38 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565383987")
2025/03/04 09:30:39 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565384995")
2025/03/04 09:30:40 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565386060")
2025/03/04 09:30:40 INFO  : dir/file3.rclone_chunk.001_lk0gc9: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/04 09:30:41 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565386992")
2025/03/04 09:30:41 INFO  : dir/file3.rclone_chunk.002_lk0gc9: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/04 09:30:42 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565387997")
2025/03/04 09:30:43 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565388995")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/04 09:30:44 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565389998")
2025/03/04 09:30:44 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2"(0)|file|ITEM_RENAME|1793138552517|735954fc431284170ccf11aac3ffb2f1c59d565e] new parent not found
2025/03/04 09:30:44 DEBUG : box root 'rclone-test-hicokel9fiwo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:30:44 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565390999")
2025/03/04 09:30:44 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2"(0)|file|ITEM_RENAME|1793138552517|735954fc431284170ccf11aac3ffb2f1c59d565e] ignored due to duplicate EventID
2025/03/04 09:30:45 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565391239")
2025/03/04 09:30:45 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2"(0)|file|ITEM_RENAME|1793138552517|735954fc431284170ccf11aac3ffb2f1c59d565e] ignored due to duplicate EventID
2025/03/04 09:30:46 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565392031")
2025/03/04 09:30:46 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2"(0)|file|ITEM_RENAME|1793138552517|735954fc431284170ccf11aac3ffb2f1c59d565e] ignored due to duplicate EventID
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/04 09:30:47 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565393007")
2025/03/04 09:30:48 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565394000")
2025/03/04 09:30:48 DEBUG : box root 'rclone-test-hicokel9fiwo': ["potato2"(0)|file|ITEM_RENAME|1793138552517|735954fc431284170ccf11aac3ffb2f1c59d565e] ignored due to duplicate EventID
2025/03/04 09:30:49 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565395000")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/04 09:30:50 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565396024")
2025/03/04 09:30:51 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565397005")
2025/03/04 09:30:52 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565398010")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/04 09:30:53 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565399010")
2025/03/04 09:30:54 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565399993")
2025/03/04 09:30:55 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565401027")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/04 09:30:56 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565401993")
2025/03/04 09:30:57 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565403004")
2025/03/04 09:30:58 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565404001")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/04 09:30:59 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565404995")
2025/03/04 09:30:59 DEBUG : box root 'rclone-test-hicokel9fiwo': ["empty space"(1)|file|ITEM_RENAME|1793127976384|3ccd5f32461ce4977d3696c2903d99ec806182eb] new parent not found
2025/03/04 09:30:59 DEBUG : box root 'rclone-test-hicokel9fiwo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:30:59 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565406000")
2025/03/04 09:31:00 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565406244")
2025/03/04 09:31:01 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565407063")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/04 09:31:02 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565408004")
2025/03/04 09:31:03 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565409011")
2025/03/04 09:31:04 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565410006")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/04 09:31:05 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565410997")
2025/03/04 09:31:06 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565412002")
2025/03/04 09:31:07 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565412997")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/04 09:31:08 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565414003")
2025/03/04 09:31:09 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565414998")
2025/03/04 09:31:10 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565415997")
    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/04 09:31:11 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565416992")
2025/03/04 09:31:12 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565418000")
2025/03/04 09:31:13 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565418996")
2025/03/04 09:31:14 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565419995")
2025/03/04 09:31:15 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565420994")
2025/03/04 09:31:16 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565421992")
2025/03/04 09:31:17 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565423005")
2025/03/04 09:31:18 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565424006")
2025/03/04 09:31:19 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565425001")
2025/03/04 09:31:20 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565425996")
2025/03/04 09:31:21 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565426990")
2025/03/04 09:31:22 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565427994")
2025/03/04 09:31:23 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565429006")
2025/03/04 09:31:24 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565429990")
2025/03/04 09:31:25 DEBUG : box root 'rclone-test-hicokel9fiwo': Checking for changes on remote (next_stream_position: "30401038565430996")
2025/03/04 09:31:27 DEBUG : box root 'rclone-test-hicokel9fiwo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/04 09:31:27 ERROR : : error listing: directory not found
2025/03/04 09:31:27 DEBUG : box root 'rclone-test-hicokel9fiwo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (74.80s)
    --- FAIL: TestIntegration/FsMkdir (72.26s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (70.13s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	74.828s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m16.759924021s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]