"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 5/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/05/06 06:21:11 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-siqaviq8kugo"
2025/05/06 06:21:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/06 06:21:11 DEBUG : Creating backend with remote "TestBox:rclone-test-siqaviq8kugo"
2025/05/06 06:21:12 DEBUG : box root 'rclone-test-siqaviq8kugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/05/06 06:21:13 DEBUG : box root 'rclone-test-siqaviq8kugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/05/06 06:21:14 INFO  : dir: Making directory
2025/05/06 06:21:15 INFO  : dir/subdir1: Making directory
2025/05/06 06:21:16 INFO  : dir/subdir3: Making directory
2025/05/06 06:21:16 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997222265")
2025/05/06 06:21:16 DEBUG : box root 'rclone-test-siqaviq8kugo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1853938316992|100d767a1ac9b83ff0c654a53bf77a0195052db8] new parent not found
2025/05/06 06:21:16 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:16 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997223496")
2025/05/06 06:21:17 INFO  : dir/subdir2: Making directory
2025/05/06 06:21:17 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997223732")
2025/05/06 06:21:18 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997224491")
2025/05/06 06:21:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997225488")
2025/05/06 06:21:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997226492")
2025/05/06 06:21:19 DEBUG : box root 'rclone-test-siqaviq8kugo': ["rclone-test-siqaviq8kugo"(0)|folder|ITEM_CREATE|319854429433|cef13ae38d94ca2bea11d343647069d95aa4d8ed] new parent not found
2025/05/06 06:21:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997226720")
2025/05/06 06:21:20 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997226965")
2025/05/06 06:21:21 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997227487")
2025/05/06 06:21:21 DEBUG : box root 'rclone-test-siqaviq8kugo': ["dir"(0)|folder|ITEM_CREATE|319853989689|10f9c251467227b2b5cc909cdabad2d80c170810] added new path ("dir") for notify
2025/05/06 06:21:21 DEBUG : doChangeNotify("dir", 0)
2025/05/06 06:21:21 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:21:21 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997228489")
2025/05/06 06:21:22 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997228714")
2025/05/06 06:21:22 INFO  : dir/file2.rclone_chunk.001_tz9jpp: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/05/06 06:21:22 DEBUG : box root 'rclone-test-siqaviq8kugo': ["subdir1"(0)|folder|ITEM_CREATE|319853802593|732a49ed87a3257e18c24856933aa2a4a579baed] added new path ("dir/subdir1") for notify
2025/05/06 06:21:22 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/05/06 06:21:22 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:21:22 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997229493")
2025/05/06 06:21:23 INFO  : dir/file2.rclone_chunk.002_tz9jpp: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/05/06 06:21:23 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997229721")
2025/05/06 06:21:23 DEBUG : box root 'rclone-test-siqaviq8kugo': ["subdir3"(0)|folder|ITEM_CREATE|319855749279|bbae4589b484c6093297ddd1a77d6f19e3fb9ee1] added new path ("dir/subdir3") for notify
2025/05/06 06:21:23 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file1.txt"(1)|file|ITEM_UPLOAD|1853936094275|8576ce98fabb8a3970c6b2c5342077bc2ce1fcca] new parent not found
2025/05/06 06:21:23 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/05/06 06:21:23 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/06 06:21:23 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997230494")
2025/05/06 06:21:24 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997230727")
2025/05/06 06:21:24 DEBUG : box root 'rclone-test-siqaviq8kugo': ["subdir2"(0)|folder|ITEM_CREATE|319853257650|d2ab4df095a4d153c88bea55b19f5f84e0288daa] added new path ("dir/subdir2") for notify
2025/05/06 06:21:24 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/05/06 06:21:24 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:21:24 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997231492")
2025/05/06 06:21:25 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997231719")
2025/05/06 06:21:26 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997232492")
2025/05/06 06:21:26 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.rclone_chunk.001_tz9jpp"(0)|file|ITEM_UPLOAD|1853938935865|258534bd564d21a35d8133ef6d974574cecee36e] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:26 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:26 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997233488")
2025/05/06 06:21:27 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997233718")
2025/05/06 06:21:27 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.rclone_chunk.002_tz9jpp"(0)|file|ITEM_UPLOAD|1853936529017|9c3741888c630d26fc12c3607a6ff887d4d0479d] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:27 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file20.txt"(1)|file|ITEM_UPLOAD|1853932919836|d7c933d9f3c45e56d983da4893e3d4fd7c3fe2bd] new parent not found
2025/05/06 06:21:27 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:27 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997234490")
2025/05/06 06:21:28 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997234723")
2025/05/06 06:21:28 DEBUG : box root 'rclone-test-siqaviq8kugo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1853936322518|5380e2a4ce4cf75120141d7e94093f9b6d639e6a] new parent not found
2025/05/06 06:21:28 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1853936529017|ea3a5ad7ca437cc6fd8b95bd513a9cb6e6ade16d] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:28 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1853938935865|e11578938708ed88f1548b801b073b98d213c487] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:28 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 3 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:28 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997235489")
2025/05/06 06:21:29 INFO  : dir/file4.rclone_chunk.001_tz9qwh: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/05/06 06:21:29 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997235727")
2025/05/06 06:21:29 INFO  : dir/file4.rclone_chunk.002_tz9qwh: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/05/06 06:21:30 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997236494")
2025/05/06 06:21:30 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2"(0)|file|ITEM_UPLOAD|1853933962945|c589e2d28e12908cff70ec4a2f3f4dede42b5d62] ignored due to old SequenceID ('\x00')
2025/05/06 06:21:30 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:30 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997237491")
2025/05/06 06:21:31 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997237723")
2025/05/06 06:21:31 DEBUG : box root 'rclone-test-siqaviq8kugo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1853933610508|9757e65543c474b2f24ae933a70ef59fa5079736] new parent not found
2025/05/06 06:21:31 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:31 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997238487")
2025/05/06 06:21:32 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997238716")
2025/05/06 06:21:33 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997239492")
2025/05/06 06:21:33 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.rclone_chunk.001_tz9qwh"(0)|file|ITEM_UPLOAD|1853935262049|a2ed0226f412edb1f356b098d5e4ced279c69e0e] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:33 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:33 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997240491")
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997240725")
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.rclone_chunk.002_tz9qwh"(0)|file|ITEM_UPLOAD|1853930418146|41b683f4ae4ce69c5b7f6e24d0e7dd7c9d31e6df] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997241489")
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file1.txt-rclone-copy-veqejuk7"(0)|file|ITEM_COPY|1853933478963|d11db9bdbe5004cce6a98865eee687570a507521] new parent not found
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:34 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997241718")
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997241948")
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1853935262049|7bb01c4bbbf222fe29bd076bc3736c5034c19954] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997242496")
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1853930418146|98539fd6ae41e7743e95288a0048f0ef01f311a0] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:35 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997242724")
2025/05/06 06:21:36 INFO  : dir/file3.rclone_chunk.001_tz9xqx: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/05/06 06:21:36 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997242958")
2025/05/06 06:21:36 INFO  : dir/file3.rclone_chunk.002_tz9xqx: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/05/06 06:21:37 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997243492")
2025/05/06 06:21:37 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file1.txt"(1)|file|ITEM_RENAME|1853933478963|683277c8a5c7f939dc5a35cf93a04ed4b8333840] new parent not found
2025/05/06 06:21:37 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4"(0)|file|ITEM_UPLOAD|1853931983250|a8c0de943654705a47998e5987c2c26e37aa478b] ignored due to old SequenceID ('\x00')
2025/05/06 06:21:37 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:37 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997244492")
2025/05/06 06:21:38 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997244726")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997245498")
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file20.txt-rclone-copy-xoxakup1"(0)|file|ITEM_COPY|1853933380691|f833d645d1f39c9382ecef129cd2395b381ad48a] new parent not found
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997246491")
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3.rclone_chunk.001_tz9xqx"(0)|file|ITEM_UPLOAD|1853939142482|dc44f9c970fc206dc62f95bf6f1d2be9088b80d7] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:39 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997246721")
2025/05/06 06:21:40 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file20.txt"(1)|file|ITEM_RENAME|1853933380691|548b90d029bd3a669f12513355cb5e42662351ae] new parent not found
2025/05/06 06:21:40 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:40 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997246955")
2025/05/06 06:21:40 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997247186")
2025/05/06 06:21:41 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997247500")
2025/05/06 06:21:41 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3.rclone_chunk.002_tz9xqx"(0)|file|ITEM_UPLOAD|1853934034945|d27940206faf9e79ef4764c89861036343e6858d] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:41 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:41 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997248491")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/05/06 06:21:42 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997248721")
2025/05/06 06:21:42 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1853939142482|c65e8672918adae934bb77ee694eb2308fb98aad] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:42 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 5 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:42 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997249486")
2025/05/06 06:21:42 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 3 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:42 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997249727")
2025/05/06 06:21:43 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1853934034945|7ba2be512b71787a5299f695af2c0257aae9a2b2] ignored due to old SequenceID ('\x01')
2025/05/06 06:21:43 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 3 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:43 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997249972")
2025/05/06 06:21:43 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997250212")
2025/05/06 06:21:43 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:43 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997250488")
2025/05/06 06:21:44 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997250723")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/05/06 06:21:45 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997251504")
2025/05/06 06:21:45 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3"(0)|file|ITEM_UPLOAD|1853934843997|5d8b5ad98f7b8649322fca405eebcc39bf9e2c6e] ignored due to old SequenceID ('\x00')
2025/05/06 06:21:45 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:45 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997252489")
2025/05/06 06:21:45 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 4 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:45 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997252716")
2025/05/06 06:21:46 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 4 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:46 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997252958")
2025/05/06 06:21:46 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:46 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997253195")
2025/05/06 06:21:46 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997253425")
2025/05/06 06:21:47 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997253651")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/05/06 06:21:48 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997254496")
2025/05/06 06:21:49 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997255489")
2025/05/06 06:21:50 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997256485")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/05/06 06:21:51 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997257492")
2025/05/06 06:21:52 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997258489")
2025/05/06 06:21:53 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997259490")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/05/06 06:21:54 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997260493")
2025/05/06 06:21:55 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997261489")
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997262491")
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': ["max_delete_path1"(0)|folder|ITEM_CREATE|319854141483|5fed47e4d281aa70b3469348e2718e1d3bb44618] new parent not found
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': ["path1"(0)|folder|ITEM_CREATE|319855081608|f5e08bb5a4dab45861231ca81a7bc1b7d8538d73] new parent not found
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file1.txt"(0)|file|ITEM_UPLOAD|1853933185643|e965982e24b00f1bbd4e0f70f55dc5945d20ea9d] new parent not found
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.txt"(0)|file|ITEM_UPLOAD|1853938415630|7217ff6ff6b8721390ea6aa29dc330c543668c54] new parent not found
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 4 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997263489")
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3.txt"(0)|file|ITEM_UPLOAD|1853936847999|3ec865537ac0948c178fbe3e3d572d78f1b38657] new parent not found
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:56 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997263732")
2025/05/06 06:21:57 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.txt"(0)|file|ITEM_UPLOAD|1853932626342|9227096c545712d22adf159a916f525b70d73063] new parent not found
2025/05/06 06:21:57 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:57 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997263960")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/05/06 06:21:57 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997264192")
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997264491")
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file6.txt"(0)|file|ITEM_UPLOAD|1853929251786|e1e54c06f04b5b8f3de537b01f7afd923e7033fa] new parent not found
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file5.txt"(0)|file|ITEM_UPLOAD|1853935036506|8c6afa9fab8adf77d89246decfe6ee2eca7415d2] new parent not found
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997265489")
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file7.txt"(0)|file|ITEM_UPLOAD|1853938602592|cd5e859d5fa1830b189629311450c799ee42de09] new parent not found
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file8.txt"(0)|file|ITEM_UPLOAD|1853935609269|64cbb0d6db017937d81454e43d20fc63fbeab6a1] new parent not found
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:58 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997265721")
2025/05/06 06:21:59 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997265952")
2025/05/06 06:21:59 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file9.txt"(0)|file|ITEM_UPLOAD|1853929261386|e7e478e42f85c2dfc9dabf9171d590975a042126] new parent not found
2025/05/06 06:21:59 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:21:59 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997266489")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/05/06 06:22:00 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997266718")
2025/05/06 06:22:01 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997267491")
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997268488")
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': ["path2"(0)|folder|ITEM_CREATE|319854559121|52eddcba2d8a74f8ab8ec582e5f8039c897ede03] new parent not found
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997269494")
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.txt"(0)|file|ITEM_UPLOAD|1853930116090|6703fa1fb373b057561e71cafad0932b59061afc] new parent not found
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:02 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997269722")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997269951")
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file1.txt"(0)|file|ITEM_UPLOAD|1853934481628|4aab8773352c0a7ac52e7a11542184040c6c71b9] new parent not found
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997270492")
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.txt"(0)|file|ITEM_UPLOAD|1853933078152|31304a0b90682fe2e5facb046565139e14cca0b1] new parent not found
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:03 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997270718")
2025/05/06 06:22:04 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997270960")
2025/05/06 06:22:04 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file3.txt"(0)|file|ITEM_UPLOAD|1853933788742|05366274010d2205fd5b96503aa830c1e6e390d3] new parent not found
2025/05/06 06:22:04 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:04 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997271487")
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997271719")
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file5.txt"(0)|file|ITEM_UPLOAD|1853937513255|6e080f0873c0aa460f2498c9a9e0df65c00cc1b0] new parent not found
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file8.txt"(0)|file|ITEM_UPLOAD|1853935984796|891e7546c185adcfe7ff6ec95d32d512f6dfa91c] new parent not found
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file6.txt"(0)|file|ITEM_UPLOAD|1853930648491|db48bcfd08ac0a80864de16d0da48f4b43a07b16] new parent not found
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file7.txt"(0)|file|ITEM_UPLOAD|1853933831308|a7cb74a9e8620550abd263f7fe49a0f24d7cc094] new parent not found
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 4 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:05 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997272491")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[] does not contain [dir/file2 dir/file4 dir/file3]
2025/05/06 06:22:06 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997272731")
2025/05/06 06:22:07 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997273486")
2025/05/06 06:22:07 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file9.txt"(0)|file|ITEM_UPLOAD|1853936756575|60f96dc4194a97024c80b92c2d66c893078525cb] new parent not found
2025/05/06 06:22:07 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:07 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997274488")
2025/05/06 06:22:08 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997274723")
2025/05/06 06:22:08 DEBUG : box root 'rclone-test-siqaviq8kugo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1853933044378|cda13ebd3c5eb8fa3a97c1f06cade7d62ba41b58] new parent not found
2025/05/06 06:22:08 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:08 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997275487")
2025/05/06 06:22:09 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997275714")
2025/05/06 06:22:10 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997276487")
2025/05/06 06:22:10 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:10 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997277490")
2025/05/06 06:22:11 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997277716")
2025/05/06 06:22:11 DEBUG : box root 'rclone-test-siqaviq8kugo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1853933312553|2e53b81b2b8b6442021485e226c3744605a2ac46] new parent not found
2025/05/06 06:22:11 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:11 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997278488")
2025/05/06 06:22:12 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997278721")
2025/05/06 06:22:12 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:12 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997279490")
2025/05/06 06:22:13 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997279716")
2025/05/06 06:22:13 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2"(1)|file|ITEM_TRASH|1853933962945|e7dc7d082463f205296250a42dde0d8eaea082d9] added old path ("dir/file2") for notify
2025/05/06 06:22:13 DEBUG : doChangeNotify("dir/file2", 1)
2025/05/06 06:22:13 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:22:13 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997280489")
2025/05/06 06:22:14 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997280718")
2025/05/06 06:22:14 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1853938935865|f6ed6b77a3292379ad9d8eba4d0a37032fa0bc99] added old path ("dir/file2.rclone_chunk.001") for notify
2025/05/06 06:22:14 DEBUG : doChangeNotify("dir/file2", 1)
2025/05/06 06:22:14 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:22:14 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997281495")
2025/05/06 06:22:15 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997281723")
2025/05/06 06:22:15 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1853936529017|371c7af267f87b4626999bc1b2405a547644562a] added old path ("dir/file2.rclone_chunk.002") for notify
2025/05/06 06:22:15 DEBUG : doChangeNotify("dir/file2", 1)
2025/05/06 06:22:15 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:22:15 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997282490")
2025/05/06 06:22:16 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997282723")
2025/05/06 06:22:16 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4"(1)|file|ITEM_TRASH|1853931983250|9ab71b01b1d05b463715ee40898a1eb27feb8abb] added old path ("dir/file4") for notify
2025/05/06 06:22:16 DEBUG : doChangeNotify("dir/file4", 1)
2025/05/06 06:22:16 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/06 06:22:16 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997283489")
2025/05/06 06:22:17 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997283718")
2025/05/06 06:22:17 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1853935262049|2424cc0b20ba6fedf63e8072371c67c2d6690650] added old path ("dir/file4.rclone_chunk.001") for notify
2025/05/06 06:22:17 DEBUG : doChangeNotify("dir/file4", 1)
2025/05/06 06:22:17 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/06 06:22:17 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997284494")
2025/05/06 06:22:18 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997284718")
2025/05/06 06:22:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997285487")
2025/05/06 06:22:19 DEBUG : box root 'rclone-test-siqaviq8kugo': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1853930418146|4c1fbc7407a8397832eb9a00fbc6660532f80687] added old path ("dir/file4.rclone_chunk.002") for notify
2025/05/06 06:22:19 DEBUG : doChangeNotify("dir/file4", 1)
2025/05/06 06:22:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/06 06:22:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997286490")
2025/05/06 06:22:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/06 06:22:19 DEBUG : box root 'rclone-test-siqaviq8kugo': Checking for changes on remote (next_stream_position: "30401043997286719")
2025/05/06 06:22:21 DEBUG : box root 'rclone-test-siqaviq8kugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/05/06 06:22:21 ERROR : : error listing: directory not found
2025/05/06 06:22:21 DEBUG : box root 'rclone-test-siqaviq8kugo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (69.84s)
    --- FAIL: TestIntegration/FsMkdir (67.68s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (65.57s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	69.929s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m13.066062537s (try 5/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]