"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/27 02:33:17 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-hezijoy8yaco"
2025/03/27 02:33:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/27 02:33:17 DEBUG : Creating backend with remote "TestBox:rclone-test-hezijoy8yaco"
2025/03/27 02:33:18 DEBUG : box root 'rclone-test-hezijoy8yaco': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/27 02:33:18 DEBUG : Reset feature "ListR"
2025/03/27 02:33:18 DEBUG : box root 'rclone-test-hezijoy8yaco': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/27 02:33:20 INFO  : dir: Making directory
2025/03/27 02:33:22 INFO  : dir/subdir1: Making directory
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527549026")
2025/03/27 02:33:23 INFO  : dir/subdir3: Making directory
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["rclone-test-hezijoy8yaco"(0)|folder|ITEM_CREATE|313660096603|8c87c0e4d2993f41f23e30c01a1a243eab59a145] new parent not found
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815160112811|be461a551ac2a28a9a875b257edcb2836e0e9812] new parent not found
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815157746881|9c7d57bcc0a61b095bec8ec188cb68104dcc5089] new parent not found
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527550286")
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["rclone-test-hezijoy8yaco"(0)|folder|ITEM_CREATE|313660096603|8c87c0e4d2993f41f23e30c01a1a243eab59a145] ignored due to duplicate EventID
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815160112811|be461a551ac2a28a9a875b257edcb2836e0e9812] ignored due to duplicate EventID
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815160112811|385085944f57381c42fa79a34b34e6abcf49f708] ignored due to duplicate EventID
2025/03/27 02:33:23 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815157746881|9c7d57bcc0a61b095bec8ec188cb68104dcc5089] ignored due to duplicate EventID
2025/03/27 02:33:24 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527550525")
2025/03/27 02:33:24 INFO  : dir/subdir2: Making directory
2025/03/27 02:33:25 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527551276")
2025/03/27 02:33:25 DEBUG : box root 'rclone-test-hezijoy8yaco': ["rclone-test-hezijoy8yaco"(0)|folder|ITEM_CREATE|313660096603|8c87c0e4d2993f41f23e30c01a1a243eab59a145] ignored due to duplicate EventID
2025/03/27 02:33:25 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815160112811|be461a551ac2a28a9a875b257edcb2836e0e9812] ignored due to duplicate EventID
2025/03/27 02:33:26 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527552264")
2025/03/27 02:33:26 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815160112811|385085944f57381c42fa79a34b34e6abcf49f708] ignored due to duplicate EventID
2025/03/27 02:33:26 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815157746881|9c7d57bcc0a61b095bec8ec188cb68104dcc5089] ignored due to duplicate EventID
2025/03/27 02:33:27 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527553291")
2025/03/27 02:33:28 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527554286")
2025/03/27 02:33:29 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527555269")
2025/03/27 02:33:29 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir1"(0)|folder|ITEM_CREATE|313661672607|6d9adf55dcad975d2106bc8d71dd86b46bb24be2] added new path ("dir/subdir1") for notify
2025/03/27 02:33:29 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/27 02:33:29 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/27 02:33:29 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527556273")
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527556506")
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["dir"(0)|folder|ITEM_CREATE|313660297592|d80a75dace6ddf20c408c4fbb053bb157d47b63b] added new path ("dir") for notify
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir3"(0)|folder|ITEM_CREATE|313666514069|073626722b0127a2f35d3ca015fc6c38829978cb] added new path ("dir/subdir3") for notify
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir2"(0)|folder|ITEM_CREATE|313666732775|abc60384169e3e9f3d31b450e240324dfdb7ae56] added new path ("dir/subdir2") for notify
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158267629|193d697697fcec179db53b6eb2d7a4c94f877654] new parent not found
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001_rm1cus"(0)|file|ITEM_UPLOAD|1815164831158|e4a50fe32cbbee7b9b92c5e43b7392ccefb082f2] added new path ("dir/file2.rclone_chunk.001_rm1cus") for notify
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158900498|9237c9a13ef04b2a9b505b9051ed5a352999a083] new parent not found
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_RENAME|1815158267629|d7920a025ef0fe789716673851ab6b43f7d2200d] new parent not found
2025/03/27 02:33:30 DEBUG : doChangeNotify("dir", 0)
2025/03/27 02:33:30 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/27 02:33:30 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/27 02:33:30 DEBUG : doChangeNotify("dir/file2.rclone_chunk.001_rm1cus", 1)
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 8 events, resulting in 4 paths and 4 notifications
2025/03/27 02:33:30 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527557288")
2025/03/27 02:33:30 INFO  : dir/file2.rclone_chunk.001_rm1cus: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527557579")
2025/03/27 02:33:31 INFO  : dir/file2.rclone_chunk.002_rm1cus: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815158267629|5399fb902e3e37c9416b5b7493fcbc35286f1128] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["dir"(0)|folder|ITEM_CREATE|313660297592|d80a75dace6ddf20c408c4fbb053bb157d47b63b] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir3"(0)|folder|ITEM_CREATE|313666514069|073626722b0127a2f35d3ca015fc6c38829978cb] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir2"(0)|folder|ITEM_CREATE|313666732775|abc60384169e3e9f3d31b450e240324dfdb7ae56] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158267629|193d697697fcec179db53b6eb2d7a4c94f877654] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001_rm1cus"(0)|file|ITEM_UPLOAD|1815164831158|e4a50fe32cbbee7b9b92c5e43b7392ccefb082f2] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158900498|9237c9a13ef04b2a9b505b9051ed5a352999a083] ignored due to duplicate EventID
2025/03/27 02:33:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_RENAME|1815158267629|d7920a025ef0fe789716673851ab6b43f7d2200d] ignored due to duplicate EventID
2025/03/27 02:33:32 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527558282")
2025/03/27 02:33:32 DEBUG : box root 'rclone-test-hezijoy8yaco': ["dir"(0)|folder|ITEM_CREATE|313660297592|d80a75dace6ddf20c408c4fbb053bb157d47b63b] ignored due to duplicate EventID
2025/03/27 02:33:32 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir3"(0)|folder|ITEM_CREATE|313666514069|073626722b0127a2f35d3ca015fc6c38829978cb] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527559276")
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["subdir2"(0)|folder|ITEM_CREATE|313666732775|abc60384169e3e9f3d31b450e240324dfdb7ae56] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158267629|193d697697fcec179db53b6eb2d7a4c94f877654] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001_rm1cus"(0)|file|ITEM_UPLOAD|1815164831158|e4a50fe32cbbee7b9b92c5e43b7392ccefb082f2] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158900498|9237c9a13ef04b2a9b505b9051ed5a352999a083] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_RENAME|1815158267629|d7920a025ef0fe789716673851ab6b43f7d2200d] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002_rm1cus"(0)|file|ITEM_UPLOAD|1815161641552|1702dcec4fa3f8867f9781270d4022f0f03c4beb] added new path ("dir/file2.rclone_chunk.002_rm1cus") for notify
2025/03/27 02:33:33 DEBUG : doChangeNotify("dir/file2.rclone_chunk.002_rm1cus", 1)
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 7 events, resulting in 1 paths and 1 notifications
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527560269")
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001_rm1cus"(0)|file|ITEM_UPLOAD|1815164831158|e4a50fe32cbbee7b9b92c5e43b7392ccefb082f2] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815158900498|9237c9a13ef04b2a9b505b9051ed5a352999a083] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_RENAME|1815158267629|d7920a025ef0fe789716673851ab6b43f7d2200d] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815158900498|1ab836c4ea7d38b2c4e45061285322a6df7c69a6] ignored due to duplicate EventID
2025/03/27 02:33:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002_rm1cus"(0)|file|ITEM_UPLOAD|1815161641552|1702dcec4fa3f8867f9781270d4022f0f03c4beb] ignored due to duplicate EventID
2025/03/27 02:33:34 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527560544")
2025/03/27 02:33:34 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_RENAME|1815158267629|d7920a025ef0fe789716673851ab6b43f7d2200d] ignored due to duplicate EventID
2025/03/27 02:33:34 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815158900498|1ab836c4ea7d38b2c4e45061285322a6df7c69a6] ignored due to duplicate EventID
2025/03/27 02:33:34 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002_rm1cus"(0)|file|ITEM_UPLOAD|1815161641552|1702dcec4fa3f8867f9781270d4022f0f03c4beb] ignored due to duplicate EventID
2025/03/27 02:33:35 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527561278")
2025/03/27 02:33:36 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527562271")
2025/03/27 02:33:37 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527563309")
2025/03/27 02:33:37 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815158900498|1ab836c4ea7d38b2c4e45061285322a6df7c69a6] ignored due to duplicate EventID
2025/03/27 02:33:37 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002_rm1cus"(0)|file|ITEM_UPLOAD|1815161641552|1702dcec4fa3f8867f9781270d4022f0f03c4beb] ignored due to duplicate EventID
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527564281")
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815157622023|96bf06a588e510f6da34a7f57d4dcd574e46a966] new parent not found
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1815161641552|cfe4c25a3ca173b67f3af58bb9bb07feff590165] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527565275")
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815157622023|96bf06a588e510f6da34a7f57d4dcd574e46a966] ignored due to duplicate EventID
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815157622023|3171c9095a0553dbc6413a84d34d760b0fad27a9] ignored due to duplicate EventID
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1815161641552|cfe4c25a3ca173b67f3af58bb9bb07feff590165] ignored due to duplicate EventID
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:38 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527565541")
2025/03/27 02:33:38 INFO  : dir/file4.rclone_chunk.001_rm1lu1: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/27 02:33:39 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527565800")
2025/03/27 02:33:39 INFO  : dir/file4.rclone_chunk.002_rm1lu1: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/27 02:33:39 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(0)|file|ITEM_UPLOAD|1815157622023|96bf06a588e510f6da34a7f57d4dcd574e46a966] ignored due to duplicate EventID
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527566286")
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815157622023|3171c9095a0553dbc6413a84d34d760b0fad27a9] ignored due to duplicate EventID
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1815161641552|cfe4c25a3ca173b67f3af58bb9bb07feff590165] ignored due to duplicate EventID
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': ["rclone-test-wipakog7xiha"(1)|folder|ITEM_TRASH|313665265358|9a5f8a6d4f79a4d53afb1327796f3efec5c48c13] ignored due to duplicate EventID
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4.rclone_chunk.001_rm1lu1"(0)|file|ITEM_UPLOAD|1815154050149|2f2e48e34ee770f09d37c87dac31badf19db0669] added new path ("dir/file4.rclone_chunk.001_rm1lu1") for notify
2025/03/27 02:33:40 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_rm1lu1", 1)
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527567278")
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file1"(1)|file|ITEM_TRASH|1815157622023|3171c9095a0553dbc6413a84d34d760b0fad27a9] ignored due to duplicate EventID
2025/03/27 02:33:40 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1815161641552|cfe4c25a3ca173b67f3af58bb9bb07feff590165] ignored due to duplicate EventID
2025/03/27 02:33:41 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527567535")
2025/03/27 02:33:42 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527568308")
2025/03/27 02:33:43 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527569273")
2025/03/27 02:33:43 DEBUG : box root 'rclone-test-hezijoy8yaco': ["rclone-test-wipakog7xiha"(1)|folder|ITEM_TRASH|313665265358|9a5f8a6d4f79a4d53afb1327796f3efec5c48c13] ignored due to duplicate EventID
2025/03/27 02:33:43 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4.rclone_chunk.001_rm1lu1"(0)|file|ITEM_UPLOAD|1815154050149|2f2e48e34ee770f09d37c87dac31badf19db0669] ignored due to duplicate EventID
2025/03/27 02:33:44 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527570274")
2025/03/27 02:33:45 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527571279")
2025/03/27 02:33:46 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527572288")
2025/03/27 02:33:47 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527573275")
2025/03/27 02:33:47 INFO  : dir/file3.rclone_chunk.001_rm1txz: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/27 02:33:47 INFO  : dir/file3.rclone_chunk.002_rm1txz: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527574284")
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4.rclone_chunk.002_rm1lu1"(0)|file|ITEM_UPLOAD|1815159367184|1a0648c88add79d451bc68c5a44d2a5c9bdc54d3] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527575275")
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1815159367184|9e9f1be74ff0c0ee52da0fb1dd887a129d52d3c3] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:48 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527575519")
2025/03/27 02:33:49 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1815154050149|3366197e8f2687c48f2be8bff13325f3922ec22e] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:49 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:49 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527575756")
2025/03/27 02:33:49 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527575991")
2025/03/27 02:33:50 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527576285")
2025/03/27 02:33:50 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2"(0)|file|ITEM_UPLOAD|1815164449992|ae3e3a9cfe98f80d7acde7990039ba13d36e16dc] ignored due to old SequenceID ('\x00')
2025/03/27 02:33:50 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.001_rm1txz"(0)|file|ITEM_UPLOAD|1815164738323|9fec1843f5f382aa9045885eb43557449cd141d5] added new path ("dir/file3.rclone_chunk.001_rm1txz") for notify
2025/03/27 02:33:50 DEBUG : doChangeNotify("dir/file3.rclone_chunk.001_rm1txz", 1)
2025/03/27 02:33:50 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/27 02:33:50 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527577276")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/27 02:33:51 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527577536")
2025/03/27 02:33:52 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527578273")
2025/03/27 02:33:53 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527579278")
2025/03/27 02:33:53 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.001_rm1txz"(0)|file|ITEM_UPLOAD|1815164738323|9fec1843f5f382aa9045885eb43557449cd141d5] ignored due to duplicate EventID
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/27 02:33:54 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527580280")
2025/03/27 02:33:55 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527581269")
2025/03/27 02:33:56 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527582290")
2025/03/27 02:33:56 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1815167219789|f87b014b46e735500b0ab2e9553c0f8c749d84a4] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:56 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:56 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527583286")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527583524")
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4"(0)|file|ITEM_UPLOAD|1815171201269|61e3dbcb0ddc1d1e4c549df616227443071b03ab] ignored due to old SequenceID ('\x00')
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.002_rm1txz"(0)|file|ITEM_UPLOAD|1815167219789|f768668a81f07cc7bf74f93b7c38a60b0188f8e8] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1815164738323|154cc8ef8d428c4260c1b464191d3a24ad9dcc08] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1815164831158|90a1112bf45a30f412587e92ee29057138cc2bf7] ignored due to old SequenceID ('\x01')
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/27 02:33:57 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527584282")
2025/03/27 02:33:58 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527584549")
2025/03/27 02:33:59 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527585272")
2025/03/27 02:33:59 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1815164738323|154cc8ef8d428c4260c1b464191d3a24ad9dcc08] ignored due to duplicate EventID
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/27 02:34:00 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527586273")
2025/03/27 02:34:01 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527587393")
2025/03/27 02:34:01 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1815164831158|90a1112bf45a30f412587e92ee29057138cc2bf7] ignored due to duplicate EventID
2025/03/27 02:34:01 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3"(0)|file|ITEM_UPLOAD|1815162469291|2b241a44021fe696e25bb17b8695f402f2f5a613] ignored due to old SequenceID ('\x00')
2025/03/27 02:34:01 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/27 02:34:01 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527588342")
2025/03/27 02:34:02 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527588628")
2025/03/27 02:34:02 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3"(0)|file|ITEM_UPLOAD|1815162469291|2b241a44021fe696e25bb17b8695f402f2f5a613] ignored due to duplicate EventID
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/27 02:34:03 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527589304")
2025/03/27 02:34:04 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527590284")
2025/03/27 02:34:05 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527591274")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/27 02:34:06 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527592283")
2025/03/27 02:34:07 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527593276")
2025/03/27 02:34:08 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527594288")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/27 02:34:09 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527595281")
2025/03/27 02:34:10 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527596275")
2025/03/27 02:34:11 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527597272")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/27 02:34:12 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527598296")
2025/03/27 02:34:13 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527599309")
2025/03/27 02:34:14 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527600268")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/27 02:34:15 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527601275")
2025/03/27 02:34:16 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527602298")
2025/03/27 02:34:17 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527603272")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file2.rclone_chunk.001_rm1cus:{} dir/file2.rclone_chunk.002_rm1cus:{} dir/file3.rclone_chunk.001_rm1txz:{} dir/file4.rclone_chunk.001_rm1lu1:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/27 02:34:18 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527604279")
2025/03/27 02:34:19 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527605280")
2025/03/27 02:34:20 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527606287")
2025/03/27 02:34:21 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527607283")
2025/03/27 02:34:22 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527608277")
2025/03/27 02:34:22 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2"(1)|file|ITEM_TRASH|1815164449992|1e33ea42c3a449c01421e708442e2b5de82030a6] added old path ("dir/file2") for notify
2025/03/27 02:34:22 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/27 02:34:22 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/27 02:34:22 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527609283")
2025/03/27 02:34:23 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527609532")
2025/03/27 02:34:24 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527610275")
2025/03/27 02:34:25 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527611268")
2025/03/27 02:34:25 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2"(1)|file|ITEM_TRASH|1815164449992|1e33ea42c3a449c01421e708442e2b5de82030a6] ignored due to duplicate EventID
2025/03/27 02:34:26 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527612287")
2025/03/27 02:34:27 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527613304")
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527614287")
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1815161641552|e2f00618ff3a6e6b85999360a41080e3d6d18255] added old path ("dir/file2.rclone_chunk.002") for notify
2025/03/27 02:34:28 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527615289")
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4"(1)|file|ITEM_TRASH|1815171201269|8bfc497d00e5ba94f0e811965d6a8f092585bab4] added old path ("dir/file4") for notify
2025/03/27 02:34:28 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/27 02:34:28 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527615535")
2025/03/27 02:34:29 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527615779")
2025/03/27 02:34:29 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4"(1)|file|ITEM_TRASH|1815171201269|8bfc497d00e5ba94f0e811965d6a8f092585bab4] ignored due to duplicate EventID
2025/03/27 02:34:29 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1815164831158|809c2651f02cce46d1aa7090401cb0bebd5db8e7] added old path ("dir/file2.rclone_chunk.001") for notify
2025/03/27 02:34:29 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/27 02:34:29 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/27 02:34:29 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527616280")
2025/03/27 02:34:30 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527616542")
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527617291")
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4"(1)|file|ITEM_TRASH|1815171201269|8bfc497d00e5ba94f0e811965d6a8f092585bab4] ignored due to duplicate EventID
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1815164831158|809c2651f02cce46d1aa7090401cb0bebd5db8e7] ignored due to duplicate EventID
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1815159367184|dbe509f211368e5d05aa87ba07b214468558d3bd] added old path ("dir/file4.rclone_chunk.002") for notify
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1815164738323|014355dc7a9adbf52daa3ab60423a05b8acb8427] added old path ("dir/file3.rclone_chunk.001") for notify
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file3"(1)|file|ITEM_TRASH|1815162469291|0c5fe0948eb873fee8eb35d8bdf611895080a5c6] added old path ("dir/file3") for notify
2025/03/27 02:34:31 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/27 02:34:31 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/27 02:34:31 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': Received 5 events, resulting in 3 paths and 3 notifications
2025/03/27 02:34:31 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527618266")
2025/03/27 02:34:32 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527618550")
2025/03/27 02:34:33 DEBUG : box root 'rclone-test-hezijoy8yaco': Checking for changes on remote (next_stream_position: "30401040527619277")
2025/03/27 02:34:33 DEBUG : box root 'rclone-test-hezijoy8yaco': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1815164831158|809c2651f02cce46d1aa7090401cb0bebd5db8e7] ignored due to duplicate EventID
2025/03/27 02:34:35 DEBUG : box root 'rclone-test-hezijoy8yaco': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/27 02:34:35 ERROR : : error listing: directory not found
2025/03/27 02:34:35 DEBUG : box root 'rclone-test-hezijoy8yaco': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (78.42s)
    --- FAIL: TestIntegration/FsMkdir (75.82s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (73.44s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	78.455s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m21.131018444s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]