"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/05/14 02:25:23 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-zohegav1xaqi"
2025/05/14 02:25:23 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/14 02:25:23 DEBUG : Creating backend with remote "TestBox:rclone-test-zohegav1xaqi"
2025/05/14 02:25:24 DEBUG : box root 'rclone-test-zohegav1xaqi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/05/14 02:25:24 DEBUG : box root 'rclone-test-zohegav1xaqi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/05/14 02:25:26 INFO  : dir: Making directory
2025/05/14 02:25:27 INFO  : dir/subdir1: Making directory
2025/05/14 02:25:28 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674274574")
2025/05/14 02:25:28 INFO  : dir/subdir3: Making directory
2025/05/14 02:25:29 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674275815")
2025/05/14 02:25:29 INFO  : dir/subdir2: Making directory
2025/05/14 02:25:30 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674276810")
2025/05/14 02:25:31 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:31 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674277801")
2025/05/14 02:25:31 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674278045")
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': ["one.bak"(2)|file|ITEM_RENAME|1861646519987|94c44247a821e85a5613d74958b2e4fbe2e6b970] new parent not found
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674278810")
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': ["rclone-test-zohegav1xaqi"(0)|folder|ITEM_CREATE|321102448685|e52b6a07a9b1607306525f3a6b96d021076bb4e6] new parent not found
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674279053")
2025/05/14 02:25:32 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674279281")
2025/05/14 02:25:33 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674279799")
2025/05/14 02:25:34 DEBUG : box root 'rclone-test-zohegav1xaqi': ["dir"(0)|folder|ITEM_CREATE|321101433175|c33f4a5a25c4c587586b0a87b80e7597e6f34f1d] added new path ("dir") for notify
2025/05/14 02:25:34 DEBUG : doChangeNotify("dir", 0)
2025/05/14 02:25:34 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/14 02:25:34 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674280805")
2025/05/14 02:25:34 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674281038")
2025/05/14 02:25:35 DEBUG : box root 'rclone-test-zohegav1xaqi': ["one.rclone_chunk.001_8holmj"(0)|file|ITEM_UPLOAD|1861649276627|46474c099eb32d1db128f01424f617cd421bca1c] new parent not found
2025/05/14 02:25:35 DEBUG : box root 'rclone-test-zohegav1xaqi': ["one"(1)|file|ITEM_RENAME|1861649276627|a3fac8b94aca68056bc4e0284ea6c7cf90aecc39] new parent not found
2025/05/14 02:25:35 DEBUG : box root 'rclone-test-zohegav1xaqi': ["subdir1"(0)|folder|ITEM_CREATE|321103031733|da5a1b6e3e0848fada55b1df62bc5452316b6366] added new path ("dir/subdir1") for notify
2025/05/14 02:25:35 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/05/14 02:25:35 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 3 events, resulting in 1 paths and 1 notifications
2025/05/14 02:25:35 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674281807")
2025/05/14 02:25:35 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674282062")
2025/05/14 02:25:36 INFO  : dir/file2.rclone_chunk.001_8horlg: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/05/14 02:25:36 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674282802")
2025/05/14 02:25:37 DEBUG : box root 'rclone-test-zohegav1xaqi': ["subdir3"(0)|folder|ITEM_CREATE|321102846296|fdd59495d63f2ed7b1af9ce1aca58c1d194a6c61] added new path ("dir/subdir3") for notify
2025/05/14 02:25:37 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/05/14 02:25:37 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/14 02:25:37 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674283813")
2025/05/14 02:25:37 INFO  : dir/file2.rclone_chunk.002_8horlg: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/05/14 02:25:37 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674284054")
2025/05/14 02:25:38 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674284809")
2025/05/14 02:25:39 DEBUG : box root 'rclone-test-zohegav1xaqi': ["subdir2"(0)|folder|ITEM_CREATE|321103036533|8f30f41fb53ead8ed17941ba15752ea2658398a2] added new path ("dir/subdir2") for notify
2025/05/14 02:25:39 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/05/14 02:25:39 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/14 02:25:39 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674285810")
2025/05/14 02:25:39 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674286062")
2025/05/14 02:25:40 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file2.rclone_chunk.001_8horlg"(0)|file|ITEM_UPLOAD|1861661036851|75047cbabb9a28d499b71161ffc5bcfb838120dc] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:40 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:40 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674286797")
2025/05/14 02:25:40 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674287031")
2025/05/14 02:25:41 DEBUG : box root 'rclone-test-zohegav1xaqi': ["three.txt.bak"(2)|file|ITEM_RENAME|1861652358029|620f394a611448d19795ece895a1c8e7fcfddbb0] new parent not found
2025/05/14 02:25:41 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:41 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674287804")
2025/05/14 02:25:41 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674288044")
2025/05/14 02:25:42 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file2.rclone_chunk.002_8horlg"(0)|file|ITEM_UPLOAD|1861651557494|6fae71bcf384a5c8b7fec3aa9a3828d90bad8200] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:42 DEBUG : box root 'rclone-test-zohegav1xaqi': ["three.txt.rclone_chunk.001_8hotej"(0)|file|ITEM_UPLOAD|1861656359744|d74efd74a2872126ce6cea1fb3c2dd5bc6aa0820] new parent not found
2025/05/14 02:25:42 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:42 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674288803")
2025/05/14 02:25:42 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674289058")
2025/05/14 02:25:43 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674289807")
2025/05/14 02:25:44 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1861661036851|61b8a327b5975a1c8aa1d78f8393617b52412cb8] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:44 DEBUG : box root 'rclone-test-zohegav1xaqi': ["three.txt"(1)|file|ITEM_RENAME|1861656359744|3333ad9f53805c0c017b9d8fe015199e129cb707] new parent not found
2025/05/14 02:25:44 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1861651557494|0ca69b6411328161ee252aa7056f8f052fc32abe] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:44 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 3 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:44 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674290798")
2025/05/14 02:25:44 INFO  : dir/file4.rclone_chunk.001_8hp0mu: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/05/14 02:25:44 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674291041")
2025/05/14 02:25:44 INFO  : dir/file4.rclone_chunk.002_8hp0mu: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/05/14 02:25:45 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file2"(0)|file|ITEM_UPLOAD|1861654974692|f3ea077263504693eb4120f3906ad9d86af629d3] ignored due to old SequenceID ('\x00')
2025/05/14 02:25:45 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:45 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674291800")
2025/05/14 02:25:45 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674292032")
2025/05/14 02:25:46 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:46 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674292803")
2025/05/14 02:25:46 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674293036")
2025/05/14 02:25:47 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:47 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674293816")
2025/05/14 02:25:47 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674294046")
2025/05/14 02:25:48 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file4.rclone_chunk.001_8hp0mu"(0)|file|ITEM_UPLOAD|1861652425513|cb0be66246113c4483d640a1b048dd6228acc14f] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:48 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:48 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674294805")
2025/05/14 02:25:48 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674295048")
2025/05/14 02:25:49 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:49 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674295803")
2025/05/14 02:25:49 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674296043")
2025/05/14 02:25:50 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file4.rclone_chunk.002_8hp0mu"(0)|file|ITEM_UPLOAD|1861649090169|4ceb6a1c0e5c520d0f4451d59d1125b4cb29494c] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:50 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:50 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674296800")
2025/05/14 02:25:50 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674297048")
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1861652425513|51f72a653317f1f84f52e8419a45328e87e40732] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674297802")
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1861649090169|6484bdbe4104394a52dd3c809127d7ac611f3eb9] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674298055")
2025/05/14 02:25:51 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674298290")
2025/05/14 02:25:52 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file4"(0)|file|ITEM_UPLOAD|1861651401107|91eb630e5c1387d2c52b67218a93f3243819da08] ignored due to old SequenceID ('\x00')
2025/05/14 02:25:52 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:52 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674298804")
2025/05/14 02:25:52 INFO  : dir/file3.rclone_chunk.001_8hp7fq: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/05/14 02:25:52 INFO  : dir/file3.rclone_chunk.002_8hp7fq: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/05/14 02:25:52 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674299034")
2025/05/14 02:25:53 DEBUG : box root 'rclone-test-zohegav1xaqi': ["dst"(0)|folder|ITEM_CREATE|321100295075|b60e9b76bb8b4bbf33fd76e222d18ae74840f9e7] new parent not found
2025/05/14 02:25:53 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:53 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674299798")
2025/05/14 02:25:53 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674300037")
2025/05/14 02:25:54 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674300802")
2025/05/14 02:25:55 DEBUG : box root 'rclone-test-zohegav1xaqi': ["one.rclone_chunk.001_8hp5gn"(0)|file|ITEM_UPLOAD|1861653568394|5892f6af09a1f1dc02da7803f1739e4895a0a81a] new parent not found
2025/05/14 02:25:55 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file3.rclone_chunk.001_8hp7fq"(0)|file|ITEM_UPLOAD|1861649358177|9926a98d63bfaeabf9c31368cf8884441bbbc496] added new path ("dir/file3.rclone_chunk.001_8hp7fq") for notify
2025/05/14 02:25:55 DEBUG : doChangeNotify("dir/file3.rclone_chunk.001_8hp7fq", 1)
2025/05/14 02:25:55 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/14 02:25:55 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674301808")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/05/14 02:25:55 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674302053")
2025/05/14 02:25:56 DEBUG : box root 'rclone-test-zohegav1xaqi': ["one"(1)|file|ITEM_RENAME|1861653568394|3d801c3048c00c1f6dafeaba1afea82b9a413897] new parent not found
2025/05/14 02:25:56 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:56 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674302805")
2025/05/14 02:25:56 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674303038")
2025/05/14 02:25:57 DEBUG : box root 'rclone-test-zohegav1xaqi': ["file3.rclone_chunk.002_8hp7fq"(0)|file|ITEM_UPLOAD|1861652186246|b7b1782e33877f37a4e1a38d54eb6b4bdfb16cab] ignored due to old SequenceID ('\x01')
2025/05/14 02:25:57 DEBUG : box root 'rclone-test-zohegav1xaqi': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/14 02:25:57 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674303799")
2025/05/14 02:25:57 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674304045")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/05/14 02:25:58 DEBUG : box root 'rclone-test-zohegav1xaqi': Checking for changes on remote (next_stream_position: "30401044674304809")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file3.rclone_chunk.001_8hp7fq:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/05/14 02:26:38 DEBUG : box root 'rclone-test-zohegav1xaqi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/05/14 02:26:38 ERROR : : error listing: directory not found
2025/05/14 02:26:39 DEBUG : box root 'rclone-test-zohegav1xaqi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (76.01s)
    --- FAIL: TestIntegration/FsMkdir (73.11s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (70.92s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	76.056s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m18.778651183s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]