"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/04/22 04:02:22 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-naficug1foxu"
2025/04/22 04:02:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/22 04:02:22 DEBUG : Creating backend with remote "TestBox:rclone-test-naficug1foxu"
2025/04/22 04:02:23 DEBUG : box root 'rclone-test-naficug1foxu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/04/22 04:02:25 DEBUG : box root 'rclone-test-naficug1foxu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/04/22 04:02:27 INFO  : dir: Making directory
2025/04/22 04:02:29 INFO  : dir/subdir1: Making directory
2025/04/22 04:02:30 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779296363")
2025/04/22 04:02:30 DEBUG : box root 'rclone-test-naficug1foxu': ["file1.rclone_chunk.001_3vhyia"(0)|file|ITEM_UPLOAD|1841296463863|303c0984209bb5f8255d9c3065a92b09746b5ef6] new parent not found
2025/04/22 04:02:30 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:30 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779297602")
2025/04/22 04:02:31 INFO  : dir/subdir3: Making directory
2025/04/22 04:02:31 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779297843")
2025/04/22 04:02:31 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(3)|file|ITEM_MOVE|1841303749743|94949874ab2fbfacea0a42f8970c4be9066f4f56] new parent not found
2025/04/22 04:02:31 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(2)|file|ITEM_MOVE|1841303749743|8895f444d6ad5f5e2c753a0bc8f6de15a27e7811] new parent not found
2025/04/22 04:02:31 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:31 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779298598")
2025/04/22 04:02:32 INFO  : dir/subdir2: Making directory
2025/04/22 04:02:32 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779298843")
2025/04/22 04:02:32 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(1)|file|ITEM_RENAME|1841296463863|36094fe93c2c079c8224071a26602f127faa58cd] new parent not found
2025/04/22 04:02:32 DEBUG : box root 'rclone-test-naficug1foxu': ["rclone-test-naficug1foxu"(0)|folder|ITEM_CREATE|317854519172|3f27337d717216557ce75c5f7f32ac29d142e18b] new parent not found
2025/04/22 04:02:32 DEBUG : box root 'rclone-test-naficug1foxu': Received 3 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:32 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779299605")
2025/04/22 04:02:33 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779299848")
2025/04/22 04:02:34 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779300600")
2025/04/22 04:02:34 DEBUG : box root 'rclone-test-naficug1foxu': ["dir"(0)|folder|ITEM_CREATE|317854329348|d5297a899288722d4d356d70791e7d31a7459a5b] added new path ("dir") for notify
2025/04/22 04:02:34 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(2)|file|ITEM_MOVE|1841296463863|1448e32b98554170668c047954d9a4b67b634052] new parent not found
2025/04/22 04:02:34 DEBUG : doChangeNotify("dir", 0)
2025/04/22 04:02:34 DEBUG : box root 'rclone-test-naficug1foxu': Received 4 events, resulting in 1 paths and 1 notifications
2025/04/22 04:02:34 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779301600")
2025/04/22 04:02:35 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779301861")
2025/04/22 04:02:36 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779302593")
2025/04/22 04:02:37 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779303601")
2025/04/22 04:02:37 DEBUG : box root 'rclone-test-naficug1foxu': ["subdir1"(0)|folder|ITEM_CREATE|317855875070|3e2859ef27b1596d89e0a43bc5af23a4e28e4fd6] added new path ("dir/subdir1") for notify
2025/04/22 04:02:37 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/04/22 04:02:37 DEBUG : box root 'rclone-test-naficug1foxu': Received 3 events, resulting in 1 paths and 1 notifications
2025/04/22 04:02:37 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779304610")
2025/04/22 04:02:38 INFO  : dir/file2.rclone_chunk.001_3via2z: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/04/22 04:02:38 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779304864")
2025/04/22 04:02:38 DEBUG : box root 'rclone-test-naficug1foxu': ["subdir3"(0)|folder|ITEM_CREATE|317854230804|a34ac1bf7f30016531dfb9bf84ed34ee7154d8a3] added new path ("dir/subdir3") for notify
2025/04/22 04:02:38 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/04/22 04:02:38 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/22 04:02:38 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779305611")
2025/04/22 04:02:39 DEBUG : box root 'rclone-test-naficug1foxu': ["subdir2"(0)|folder|ITEM_CREATE|317853526957|e23c1938fca5a01fe298d682845770321163598f] added new path ("dir/subdir2") for notify
2025/04/22 04:02:39 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/04/22 04:02:39 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/22 04:02:39 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779305852")
2025/04/22 04:02:39 INFO  : dir/file2.rclone_chunk.002_3via2z: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/04/22 04:02:39 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779306097")
2025/04/22 04:02:40 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779306608")
2025/04/22 04:02:41 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779307598")
2025/04/22 04:02:41 DEBUG : box root 'rclone-test-naficug1foxu': ["file2.rclone_chunk.001_3via2z"(0)|file|ITEM_UPLOAD|1841304937867|6bfc310b7006b54714fd437537f36644ad33d234] added new path ("dir/file2.rclone_chunk.001_3via2z") for notify
2025/04/22 04:02:41 DEBUG : doChangeNotify("dir/file2.rclone_chunk.001_3via2z", 1)
2025/04/22 04:02:41 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/22 04:02:41 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779308600")
2025/04/22 04:02:42 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779308844")
2025/04/22 04:02:42 DEBUG : box root 'rclone-test-naficug1foxu': ["file1.rclone_chunk.001_3viav3"(0)|file|ITEM_UPLOAD|1841308273777|ee096b34d49ebf8de7853e871266b99f95e836ec] new parent not found
2025/04/22 04:02:42 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:42 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779309604")
2025/04/22 04:02:43 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779309841")
2025/04/22 04:02:43 DEBUG : box root 'rclone-test-naficug1foxu': ["dir"(0)|folder|ITEM_CREATE|317855310393|00a1af9388aea5edb375011c1852d52bd6773c02] new parent not found
2025/04/22 04:02:43 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:43 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779310596")
2025/04/22 04:02:44 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779310850")
2025/04/22 04:02:44 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(1)|file|ITEM_RENAME|1841308273777|1c205cda996ca80d0fea39408fb4ef736e972134] new parent not found
2025/04/22 04:02:44 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:44 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779311595")
2025/04/22 04:02:45 DEBUG : box root 'rclone-test-naficug1foxu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1841304937867|7fd8e7e541cdb4bc45a4d0a969b2ee73b0900cc4] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:45 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:45 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779311838")
2025/04/22 04:02:45 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779312081")
2025/04/22 04:02:46 INFO  : dir/file4.rclone_chunk.001_3vijyd: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/04/22 04:02:46 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779312604")
2025/04/22 04:02:47 INFO  : dir/file4.rclone_chunk.002_3vijyd: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/04/22 04:02:47 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779313598")
2025/04/22 04:02:47 DEBUG : box root 'rclone-test-naficug1foxu': ["file2.rclone_chunk.002_3via2z"(0)|file|ITEM_UPLOAD|1841308342959|c42953bb523d84c385c5d3bac145b3062241de69] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:47 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:47 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779314599")
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1841308342959|9f7e670a904839607f275c5d78243171dc90ebad] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779314845")
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779315083")
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': ["file2"(0)|file|ITEM_UPLOAD|1841299496700|a73340566d9f03beb916821e115610a072d8dc3c] ignored due to old SequenceID ('\x00')
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:48 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779315604")
2025/04/22 04:02:49 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779315869")
2025/04/22 04:02:50 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779316623")
2025/04/22 04:02:51 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779317599")
2025/04/22 04:02:51 DEBUG : box root 'rclone-test-naficug1foxu': ["file4.rclone_chunk.001_3vijyd"(0)|file|ITEM_UPLOAD|1841305930881|2217a2e96bcb64d4fd2ec99e851292217bc53d61] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:51 DEBUG : box root 'rclone-test-naficug1foxu': ["file4.rclone_chunk.002_3vijyd"(0)|file|ITEM_UPLOAD|1841303752389|3b881c97f5ee0c81aa60cd835d3320eff4f6c162] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:51 DEBUG : box root 'rclone-test-naficug1foxu': Received 3 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:51 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779318595")
2025/04/22 04:02:52 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779318832")
2025/04/22 04:02:52 DEBUG : box root 'rclone-test-naficug1foxu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1841305930881|7ff6307a8ff387433f8c70a691d743afc571f066] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:52 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(1)|file|ITEM_RENAME|1841309118605|4ef1a5f80324e67a4091b68e64ed2282e4d30296] new parent not found
2025/04/22 04:02:52 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:52 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779319601")
2025/04/22 04:02:53 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779319875")
2025/04/22 04:02:54 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779320604")
2025/04/22 04:02:54 DEBUG : box root 'rclone-test-naficug1foxu': ["file1.rclone_chunk.001_3vifnp"(0)|file|ITEM_UPLOAD|1841309118605|19312df9ef8b0b60d7592125dd0083745fdafe53] new parent not found
2025/04/22 04:02:54 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:54 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779321597")
2025/04/22 04:02:55 INFO  : dir/file3.rclone_chunk.001_3virg0: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/04/22 04:02:55 INFO  : dir/file3.rclone_chunk.002_3virg0: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/04/22 04:02:55 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779321847")
2025/04/22 04:02:55 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(3)|file|ITEM_MOVE|1841309118605|af56b74adf9afccaadba55eacdf9d82d282d51ec] new parent not found
2025/04/22 04:02:55 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(2)|file|ITEM_MOVE|1841309118605|55ffd9df6cba370dfa8faa1b73eb1dfdf091d7fb] new parent not found
2025/04/22 04:02:55 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:55 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779322607")
2025/04/22 04:02:56 DEBUG : box root 'rclone-test-naficug1foxu': ["file4"(0)|file|ITEM_UPLOAD|1841296497048|572746b68a84e7c38fed847684c23a0eed79e124] ignored due to old SequenceID ('\x00')
2025/04/22 04:02:56 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:56 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779322849")
2025/04/22 04:02:56 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779323088")
2025/04/22 04:02:57 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779323619")
2025/04/22 04:02:57 DEBUG : box root 'rclone-test-naficug1foxu': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1841303752389|bb64d125b12f4fb2e7ed9e275b70310b80368e3b] ignored due to old SequenceID ('\x01')
2025/04/22 04:02:57 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:02:57 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779324607")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/04/22 04:02:58 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779324926")
2025/04/22 04:02:59 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779325598")
2025/04/22 04:03:00 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779326597")
2025/04/22 04:03:00 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf.rclone_chunk.001_3viszo"(0)|file|ITEM_UPLOAD|1841306699412|4f87adcba000b2ada5c0a99b0dd2267eaf7fbe33] new parent not found
2025/04/22 04:03:00 DEBUG : box root 'rclone-test-naficug1foxu': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1841303015424|642551241fb2b6579a0ed89dabab69f820eabfed] ignored due to old SequenceID ('\x01')
2025/04/22 04:03:00 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:00 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779327601")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/04/22 04:03:01 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779327848")
2025/04/22 04:03:01 DEBUG : box root 'rclone-test-naficug1foxu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1841308842222|f064c1252d2722a74e9d98a07948847cf8771cfa] ignored due to old SequenceID ('\x01')
2025/04/22 04:03:01 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:01 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779328603")
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779328854")
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779329091")
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(1)|file|ITEM_RENAME|1841306699412|545a0ac505c60bde1ca7a8c470f209fa7fe99fc3] new parent not found
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': ["file3"(0)|file|ITEM_UPLOAD|1841300072828|5ab7f7210d8b75189ac1d843e6a360fb78def25a] ignored due to old SequenceID ('\x00')
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:02 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779329600")
2025/04/22 04:03:03 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779329851")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/04/22 04:03:04 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779330596")
2025/04/22 04:03:04 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(4)|file|ITEM_MOVE|1841309118605|5dcfbc34ba5ec9ee63bf64bd0b62a57f8778bc08] new parent not found
2025/04/22 04:03:04 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:04 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779331601")
2025/04/22 04:03:05 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779331853")
2025/04/22 04:03:05 DEBUG : box root 'rclone-test-naficug1foxu': ["file3.rclone_chunk.002_3virg0"(0)|file|ITEM_UPLOAD|1841308842222|565643f4180988e2585995af44da667f097c32bd] ignored due to old SequenceID ('\x01')
2025/04/22 04:03:05 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:05 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779332613")
2025/04/22 04:03:06 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779332861")
2025/04/22 04:03:06 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:06 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779333610")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/04/22 04:03:07 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779333852")
2025/04/22 04:03:08 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779334598")
2025/04/22 04:03:09 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779335591")
2025/04/22 04:03:09 DEBUG : box root 'rclone-test-naficug1foxu': ["dir"(0)|folder|ITEM_CREATE|317854739660|f618bd348a3c1e1f6cfda1577c2f59f78eec652f] new parent not found
2025/04/22 04:03:09 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:09 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779336610")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/04/22 04:03:10 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779336868")
2025/04/22 04:03:11 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779337595")
2025/04/22 04:03:11 DEBUG : box root 'rclone-test-naficug1foxu': ["file1.rclone_chunk.001_3vj2r4"(0)|file|ITEM_UPLOAD|1841298104417|c3e0478751fb7b302dcbc44a2a9e678f4d0b6070] new parent not found
2025/04/22 04:03:11 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:11 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779338603")
2025/04/22 04:03:12 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(1)|file|ITEM_RENAME|1841298104417|6296c0c02fbd7e8791febd5c9bf40d94f32dc3df] new parent not found
2025/04/22 04:03:12 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:12 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779338885")
2025/04/22 04:03:12 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779339116")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/04/22 04:03:13 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779339599")
2025/04/22 04:03:13 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:13 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779340596")
2025/04/22 04:03:14 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779340857")
2025/04/22 04:03:15 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779341602")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/04/22 04:03:16 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779342598")
2025/04/22 04:03:17 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779343601")
2025/04/22 04:03:17 DEBUG : box root 'rclone-test-naficug1foxu': ["file3.rclone_chunk.001_3virg0"(0)|file|ITEM_UPLOAD|1841303015424|1b4200743ed61535e2a5d3bcaad77fb97ea30c44] ignored due to old SequenceID ('\x01')
2025/04/22 04:03:17 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:17 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779344607")
2025/04/22 04:03:18 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(3)|file|ITEM_MOVE|1841298104417|5b58916ffa9f60444070f7c21e9a6fa47ca3c4bd] new parent not found
2025/04/22 04:03:18 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:18 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779344850")
2025/04/22 04:03:18 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779345093")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/04/22 04:03:19 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779345608")
2025/04/22 04:03:20 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779346609")
2025/04/22 04:03:20 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(4)|file|ITEM_MOVE|1841298104417|c27f8ca9610b782392423b030b43c47e72afc8b4] new parent not found
2025/04/22 04:03:20 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:20 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779347604")
2025/04/22 04:03:21 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779347857")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/04/22 04:03:22 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779348598")
2025/04/22 04:03:23 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779349600")
2025/04/22 04:03:23 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf.rclone_chunk.001_3vjfoi"(0)|file|ITEM_UPLOAD|1841303099829|5ea5e50ad19521e3edceb2977d31538bb6f74ec8] new parent not found
2025/04/22 04:03:23 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:23 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779350603")
2025/04/22 04:03:24 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779350837")
    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_3via2z:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/04/22 04:03:25 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779351602")
2025/04/22 04:03:25 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(1)|file|ITEM_RENAME|1841303099829|8fc97314751ee5f812bc498e4887f51ca4da70a0] new parent not found
2025/04/22 04:03:25 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:25 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779352592")
2025/04/22 04:03:26 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779352840")
2025/04/22 04:03:27 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779353595")
2025/04/22 04:03:28 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779354599")
2025/04/22 04:03:29 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779355610")
2025/04/22 04:03:30 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779356608")
2025/04/22 04:03:30 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:30 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779357607")
2025/04/22 04:03:31 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779357842")
2025/04/22 04:03:32 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779358614")
2025/04/22 04:03:32 DEBUG : box root 'rclone-test-naficug1foxu': ["newLeaf"(2)|file|ITEM_MOVE|1841298104417|2b5e344e92973e40557cea1664e7ee149964bae4] new parent not found
2025/04/22 04:03:32 DEBUG : box root 'rclone-test-naficug1foxu': ["dir"(0)|folder|ITEM_CREATE|317854760808|81da35e0e99ce6846996f9a68eaab9acd5e8fb1c] new parent not found
2025/04/22 04:03:32 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:32 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779359604")
2025/04/22 04:03:33 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779359860")
2025/04/22 04:03:33 DEBUG : box root 'rclone-test-naficug1foxu': ["file2"(1)|file|ITEM_TRASH|1841299496700|0d82fc7c3f2631df4f966061f3bcba84315f799d] added old path ("dir/file2") for notify
2025/04/22 04:03:33 DEBUG : doChangeNotify("dir/file2", 1)
2025/04/22 04:03:33 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/22 04:03:33 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779360604")
2025/04/22 04:03:34 DEBUG : box root 'rclone-test-naficug1foxu': ["file1.rclone_chunk.001_3vjp2w"(0)|file|ITEM_UPLOAD|1841308655821|21a6df66503f1e047177f543d78b5c67c5aa7e12] new parent not found
2025/04/22 04:03:34 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:34 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779360854")
2025/04/22 04:03:34 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779361100")
2025/04/22 04:03:35 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779361611")
2025/04/22 04:03:35 DEBUG : box root 'rclone-test-naficug1foxu': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1841304937867|e39e4c31ce6482443d285a7efd8afdc5201c1a68] added old path ("dir/file2.rclone_chunk.001") for notify
2025/04/22 04:03:35 DEBUG : box root 'rclone-test-naficug1foxu': ["file1"(1)|file|ITEM_RENAME|1841308655821|c5ec53e6c587563960bd61de61b2053dcf6455d3] new parent not found
2025/04/22 04:03:35 DEBUG : doChangeNotify("dir/file2", 1)
2025/04/22 04:03:35 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 1 paths and 1 notifications
2025/04/22 04:03:35 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779362602")
2025/04/22 04:03:36 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779362857")
2025/04/22 04:03:36 DEBUG : box root 'rclone-test-naficug1foxu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1841308342959|7373714c463e06cffc9273a08bb030b14ca80379] added old path ("dir/file2.rclone_chunk.002") for notify
2025/04/22 04:03:36 DEBUG : doChangeNotify("dir/file2", 1)
2025/04/22 04:03:36 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/22 04:03:36 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779363603")
2025/04/22 04:03:37 DEBUG : box root 'rclone-test-naficug1foxu': ["file4"(1)|file|ITEM_TRASH|1841296497048|a3f3807640e9d383947888bdda355e34cc9b1eac] added old path ("dir/file4") for notify
2025/04/22 04:03:37 DEBUG : doChangeNotify("dir/file4", 1)
2025/04/22 04:03:37 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/22 04:03:37 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779363838")
2025/04/22 04:03:37 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779364083")
2025/04/22 04:03:38 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779364604")
2025/04/22 04:03:38 DEBUG : box root 'rclone-test-naficug1foxu': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1841305930881|51fb3521aafe1709c6447cd31afd8c1ceb10417f] added old path ("dir/file4.rclone_chunk.001") for notify
2025/04/22 04:03:38 DEBUG : box root 'rclone-test-naficug1foxu': ["file1.rclone_chunk.001_3vjvqa"(0)|file|ITEM_UPLOAD|1841297869337|57ead944afc5ca625f4b734886e2e244ae26a309] new parent not found
2025/04/22 04:03:38 DEBUG : doChangeNotify("dir/file4", 1)
2025/04/22 04:03:38 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 1 paths and 1 notifications
2025/04/22 04:03:38 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779365598")
2025/04/22 04:03:39 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779365850")
2025/04/22 04:03:39 DEBUG : box root 'rclone-test-naficug1foxu': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1841303752389|48a6253c557240d7192da6d109839b9006458706] added old path ("dir/file4.rclone_chunk.002") for notify
2025/04/22 04:03:39 DEBUG : doChangeNotify("dir/file4", 1)
2025/04/22 04:03:39 DEBUG : box root 'rclone-test-naficug1foxu': Received 2 events, resulting in 1 paths and 1 notifications
2025/04/22 04:03:39 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779366594")
2025/04/22 04:03:40 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779366851")
2025/04/22 04:03:40 DEBUG : box root 'rclone-test-naficug1foxu': ["file3"(1)|file|ITEM_TRASH|1841300072828|ddd6023957e002a97203dee3fb37490aafb8e5f4] old parent not cached
2025/04/22 04:03:40 DEBUG : box root 'rclone-test-naficug1foxu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/22 04:03:40 DEBUG : box root 'rclone-test-naficug1foxu': Checking for changes on remote (next_stream_position: "30401042779367605")
2025/04/22 04:03:42 DEBUG : box root 'rclone-test-naficug1foxu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/04/22 04:03:42 ERROR : : error listing: directory not found
2025/04/22 04:03:42 DEBUG : box root 'rclone-test-naficug1foxu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (80.06s)
    --- FAIL: TestIntegration/FsMkdir (76.05s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (73.64s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	80.104s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m22.865127509s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]