"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 3/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/03/04 09:28:53 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-lasivij8xugu"
2025/03/04 09:28:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/04 09:28:53 DEBUG : Creating backend with remote "TestBox:rclone-test-lasivij8xugu"
2025/03/04 09:28:54 DEBUG : box root 'rclone-test-lasivij8xugu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/04 09:28:54 DEBUG : Reset feature "ListR"
2025/03/04 09:28:55 DEBUG : box root 'rclone-test-lasivij8xugu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/04 09:28:56 INFO  : dir: Making directory
2025/03/04 09:28:58 INFO  : dir/subdir1: Making directory
2025/03/04 09:28:59 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565284824")
2025/03/04 09:28:59 INFO  : dir/subdir3: Making directory
2025/03/04 09:29:00 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565286063")
2025/03/04 09:29:00 INFO  : dir/subdir2: Making directory
2025/03/04 09:29:00 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.015_le4ion"(0)|file|ITEM_UPLOAD|1793126024717|991cc6e7b36b81df32a8c1b106b9f736f2b5cb46] new parent not found
2025/03/04 09:29:00 DEBUG : box root 'rclone-test-lasivij8xugu': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/04 09:29:00 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565287146")
2025/03/04 09:29:00 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.015_le4ion"(0)|file|ITEM_UPLOAD|1793126024717|991cc6e7b36b81df32a8c1b106b9f736f2b5cb46] ignored due to duplicate EventID
2025/03/04 09:29:00 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.012"(2)|file|ITEM_TRASH|1793127580570|17b88fbfdffdaaa9ed6de2c753600a0f1c3e5c09] ignored due to duplicate EventID
2025/03/04 09:29:01 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565287400")
2025/03/04 09:29:01 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.015_le4ion"(0)|file|ITEM_UPLOAD|1793126024717|991cc6e7b36b81df32a8c1b106b9f736f2b5cb46] ignored due to duplicate EventID
2025/03/04 09:29:01 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.012"(2)|file|ITEM_TRASH|1793127580570|17b88fbfdffdaaa9ed6de2c753600a0f1c3e5c09] ignored due to duplicate EventID
2025/03/04 09:29:01 DEBUG : box root 'rclone-test-lasivij8xugu': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/04 09:29:01 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565288083")
2025/03/04 09:29:02 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565288330")
2025/03/04 09:29:03 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565289059")
2025/03/04 09:29:03 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.020"(2)|file|ITEM_TRASH|1793126838889|cf12713e9de872592011b4631c39bd19d43b8d2f] ignored due to duplicate EventID
2025/03/04 09:29:04 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565290065")
2025/03/04 09:29:05 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565291061")
2025/03/04 09:29:05 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.020"(2)|file|ITEM_TRASH|1793126838889|cf12713e9de872592011b4631c39bd19d43b8d2f] ignored due to duplicate EventID
2025/03/04 09:29:06 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565292065")
2025/03/04 09:29:06 INFO  : dir/file2.rclone_chunk.001_ljxt0t: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/04 09:29:06 INFO  : dir/file2.rclone_chunk.002_ljxt0t: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/04 09:29:07 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565293073")
2025/03/04 09:29:08 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565294060")
2025/03/04 09:29:09 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565295061")
2025/03/04 09:29:10 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565296066")
2025/03/04 09:29:11 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565297056")
2025/03/04 09:29:12 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565298073")
2025/03/04 09:29:13 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565299059")
2025/03/04 09:29:14 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565300053")
2025/03/04 09:29:14 INFO  : dir/file4.rclone_chunk.001_ljy1ge: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/04 09:29:15 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565301069")
2025/03/04 09:29:15 INFO  : dir/file4.rclone_chunk.002_ljy1ge: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/04 09:29:16 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565302059")
2025/03/04 09:29:17 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565303059")
2025/03/04 09:29:18 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565304058")
2025/03/04 09:29:19 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565305065")
2025/03/04 09:29:20 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565306054")
2025/03/04 09:29:21 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565307059")
2025/03/04 09:29:22 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565308056")
2025/03/04 09:29:22 INFO  : dir/file3.rclone_chunk.001_ljya0s: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/04 09:29:23 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565309059")
2025/03/04 09:29:23 INFO  : dir/file3.rclone_chunk.002_ljya0s: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/04 09:29:24 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565310060")
2025/03/04 09:29:25 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565311055")
2025/03/04 09:29:26 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565312059")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/04 09:29:27 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565313055")
2025/03/04 09:29:28 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565314049")
2025/03/04 09:29:29 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565315048")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/04 09:29:30 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565316054")
2025/03/04 09:29:31 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565317051")
2025/03/04 09:29:32 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565318054")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/04 09:29:33 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565319048")
2025/03/04 09:29:34 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565320049")
2025/03/04 09:29:35 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565321049")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/04 09:29:36 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565322048")
2025/03/04 09:29:37 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565323052")
2025/03/04 09:29:38 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565324051")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/04 09:29:39 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565325050")
2025/03/04 09:29:40 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565326049")
2025/03/04 09:29:41 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565327054")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/04 09:29:42 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565328049")
2025/03/04 09:29:43 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565329064")
2025/03/04 09:29:44 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565330063")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/04 09:29:45 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565331059")
2025/03/04 09:29:46 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565332072")
2025/03/04 09:29:47 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565333069")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/04 09:29:48 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565334069")
2025/03/04 09:29:49 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565335074")
2025/03/04 09:29:50 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565336069")
2025/03/04 09:29:50 DEBUG : box root 'rclone-test-lasivij8xugu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:29:50 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565337063")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/04 09:29:51 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565337327")
2025/03/04 09:29:51 DEBUG : box root 'rclone-test-lasivij8xugu': ["potato2.rclone_chunk.001"(2)|file|ITEM_TRASH|1793148604537|50e1ee46de173b6522cc37b02a48d5647fbef0b8] ignored due to duplicate EventID
2025/03/04 09:29:52 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565338084")
2025/03/04 09:29:52 DEBUG : box root 'rclone-test-lasivij8xugu': ["potato2.rclone_chunk.001"(2)|file|ITEM_TRASH|1793148604537|50e1ee46de173b6522cc37b02a48d5647fbef0b8] ignored due to duplicate EventID
2025/03/04 09:29:53 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565339056")
2025/03/04 09:29:53 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.016_le4ion"(0)|file|ITEM_UPLOAD|1793126399808|d7e88c8901ee62f4184f2080fbb534f723e18693] new parent not found
2025/03/04 09:29:53 DEBUG : box root 'rclone-test-lasivij8xugu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:29:53 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565340066")
    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:29:54 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565340294")
2025/03/04 09:29:55 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565341063")
2025/03/04 09:29:55 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.016_le4ion"(0)|file|ITEM_UPLOAD|1793126399808|d7e88c8901ee62f4184f2080fbb534f723e18693] ignored due to duplicate EventID
2025/03/04 09:29:56 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565342058")
2025/03/04 09:29:56 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.016_le4ion"(0)|file|ITEM_UPLOAD|1793126399808|d7e88c8901ee62f4184f2080fbb534f723e18693] ignored due to duplicate EventID
2025/03/04 09:29:57 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565343055")
2025/03/04 09:29:58 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565344060")
2025/03/04 09:29:59 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565345070")
2025/03/04 09:30:00 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565346067")
2025/03/04 09:30:01 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565347128")
2025/03/04 09:30:02 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565348190")
2025/03/04 09:30:03 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565349089")
2025/03/04 09:30:04 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565350075")
2025/03/04 09:30:04 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.003"(1)|file|ITEM_RENAME|1793140842706|f7f75464a3c98b274dc9fe352a5de5c8e15f73dd] new parent not found
2025/03/04 09:30:04 DEBUG : box root 'rclone-test-lasivij8xugu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/04 09:30:04 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565351065")
2025/03/04 09:30:05 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565351292")
2025/03/04 09:30:05 DEBUG : box root 'rclone-test-lasivij8xugu': ["ignore_checksum_big_file_from_pipe.rclone_chunk.003"(1)|file|ITEM_RENAME|1793140842706|f7f75464a3c98b274dc9fe352a5de5c8e15f73dd] ignored due to duplicate EventID
2025/03/04 09:30:06 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565352080")
2025/03/04 09:30:07 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565353061")
2025/03/04 09:30:08 DEBUG : box root 'rclone-test-lasivij8xugu': Checking for changes on remote (next_stream_position: "30401038565354070")
2025/03/04 09:30:10 DEBUG : box root 'rclone-test-lasivij8xugu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/04 09:30:10 ERROR : : error listing: directory not found
2025/03/04 09:30:10 DEBUG : box root 'rclone-test-lasivij8xugu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (77.30s)
    --- FAIL: TestIntegration/FsMkdir (74.48s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (72.04s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	77.319s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m19.362675717s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]