"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 4/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/03/12 07:34:08 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-lasidom6wixu"
2025/03/12 07:34:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/12 07:34:08 DEBUG : Creating backend with remote "TestBox:rclone-test-lasidom6wixu"
2025/03/12 07:34:08 DEBUG : box root 'rclone-test-lasidom6wixu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/12 07:34:09 DEBUG : Reset feature "ListR"
2025/03/12 07:34:09 DEBUG : box root 'rclone-test-lasidom6wixu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/12 07:34:11 INFO  : dir: Making directory
2025/03/12 07:34:12 INFO  : dir/subdir1: Making directory
2025/03/12 07:34:13 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249599310")
2025/03/12 07:34:13 INFO  : dir/subdir3: Making directory
2025/03/12 07:34:13 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:13 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249600547")
2025/03/12 07:34:14 DEBUG : box root 'rclone-test-lasidom6wixu': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:14 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249600791")
2025/03/12 07:34:14 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249601027")
2025/03/12 07:34:14 INFO  : dir/subdir2: Making directory
2025/03/12 07:34:15 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249601543")
2025/03/12 07:34:15 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2"(1)|file|ITEM_TRASH|1800803020015|06dfee07c1ba7a0f2505d9c082486f831e6ff327] ignored due to duplicate EventID
2025/03/12 07:34:16 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249602537")
2025/03/12 07:34:17 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249603540")
2025/03/12 07:34:17 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1800815454797|b7d87cafa93587c028e156bb94709555f2e96f9d] ignored due to duplicate EventID
2025/03/12 07:34:18 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249604535")
2025/03/12 07:34:18 DEBUG : box root 'rclone-test-lasidom6wixu': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:18 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249605531")
2025/03/12 07:34:19 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1800813714255|b48da57264f8f1c307cbe9e028fc013051003bfe] ignored due to duplicate EventID
2025/03/12 07:34:19 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249605760")
2025/03/12 07:34:19 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1800813714255|b48da57264f8f1c307cbe9e028fc013051003bfe] ignored due to duplicate EventID
2025/03/12 07:34:20 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249606535")
2025/03/12 07:34:20 INFO  : dir/file2.rclone_chunk.001_07yrhb: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/12 07:34:21 INFO  : dir/file2.rclone_chunk.002_07yrhb: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/12 07:34:21 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249607535")
2025/03/12 07:34:22 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249608540")
2025/03/12 07:34:23 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249609534")
2025/03/12 07:34:24 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249610539")
2025/03/12 07:34:25 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249611540")
2025/03/12 07:34:26 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249612534")
2025/03/12 07:34:27 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249613534")
2025/03/12 07:34:27 INFO  : dir/file4.rclone_chunk.001_07yzuk: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/12 07:34:28 INFO  : dir/file4.rclone_chunk.002_07yzuk: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/12 07:34:28 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249614531")
2025/03/12 07:34:29 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249615531")
2025/03/12 07:34:30 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249616531")
2025/03/12 07:34:31 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249617532")
2025/03/12 07:34:32 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249618535")
2025/03/12 07:34:33 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249619538")
2025/03/12 07:34:34 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249620538")
2025/03/12 07:34:35 INFO  : dir/file3.rclone_chunk.001_07z6zf: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/12 07:34:35 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249621536")
2025/03/12 07:34:35 INFO  : dir/file3.rclone_chunk.002_07z6zf: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/12 07:34:36 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249622528")
2025/03/12 07:34:37 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249623538")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/12 07:34:38 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249624534")
2025/03/12 07:34:39 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249625533")
2025/03/12 07:34:40 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249626541")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/12 07:34:41 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249627533")
2025/03/12 07:34:42 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249628538")
2025/03/12 07:34:43 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249629562")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/12 07:34:44 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249630543")
2025/03/12 07:34:45 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249631553")
2025/03/12 07:34:46 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249632776")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/12 07:34:47 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249633630")
2025/03/12 07:34:48 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249634581")
2025/03/12 07:34:48 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:48 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249635560")
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["dir"(0)|folder|ITEM_CREATE|311309931933|616a0cf4e9761bf2fc36f9e00afed33dac45567e] added new path ("dir") for notify
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir3"(0)|folder|ITEM_CREATE|311309272120|1fc03b699d7c59c4deb9eb627c9630f760a741f2] ignored due to old SequenceID ('\x00')
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001_07yrhb"(0)|file|ITEM_UPLOAD|1800800646632|5c84b66ebabe569a01e824ee9851777dd75d9649] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir2"(0)|folder|ITEM_CREATE|311310592317|ec0ed9d87eb1171da23758c582880b85900237d9] ignored due to old SequenceID ('\x00')
2025/03/12 07:34:49 DEBUG : doChangeNotify("dir", 0)
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': Received 5 events, resulting in 1 paths and 1 notifications
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249635806")
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1800815008184|fc8c1fdd119c2a1899c7aa62a42fc17546d7a571] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["dir"(0)|folder|ITEM_CREATE|311309931933|616a0cf4e9761bf2fc36f9e00afed33dac45567e] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir3"(0)|folder|ITEM_CREATE|311309272120|1fc03b699d7c59c4deb9eb627c9630f760a741f2] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001_07yrhb"(0)|file|ITEM_UPLOAD|1800800646632|5c84b66ebabe569a01e824ee9851777dd75d9649] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir2"(0)|folder|ITEM_CREATE|311310592317|ec0ed9d87eb1171da23758c582880b85900237d9] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': Received 6 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249636136")
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249636448")
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1800815008184|fc8c1fdd119c2a1899c7aa62a42fc17546d7a571] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["dir"(0)|folder|ITEM_CREATE|311309931933|616a0cf4e9761bf2fc36f9e00afed33dac45567e] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir3"(0)|folder|ITEM_CREATE|311309272120|1fc03b699d7c59c4deb9eb627c9630f760a741f2] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001_07yrhb"(0)|file|ITEM_UPLOAD|1800800646632|5c84b66ebabe569a01e824ee9851777dd75d9649] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir2"(0)|folder|ITEM_CREATE|311310592317|ec0ed9d87eb1171da23758c582880b85900237d9] ignored due to duplicate EventID
2025/03/12 07:34:49 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3"(1)|file|ITEM_TRASH|1800810599566|859128e14af0ee592ed8d8aed0ae040717a2dba2] ignored due to duplicate EventID
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/12 07:34:50 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249636696")
2025/03/12 07:34:50 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1800815008184|fc8c1fdd119c2a1899c7aa62a42fc17546d7a571] ignored due to duplicate EventID
2025/03/12 07:34:50 DEBUG : box root 'rclone-test-lasidom6wixu': ["dir"(0)|folder|ITEM_CREATE|311309931933|616a0cf4e9761bf2fc36f9e00afed33dac45567e] ignored due to duplicate EventID
2025/03/12 07:34:50 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir3"(0)|folder|ITEM_CREATE|311309272120|1fc03b699d7c59c4deb9eb627c9630f760a741f2] ignored due to duplicate EventID
2025/03/12 07:34:51 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249637556")
2025/03/12 07:34:51 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001_07yrhb"(0)|file|ITEM_UPLOAD|1800800646632|5c84b66ebabe569a01e824ee9851777dd75d9649] ignored due to duplicate EventID
2025/03/12 07:34:52 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249638591")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir2"(0)|folder|ITEM_CREATE|311310592317|ec0ed9d87eb1171da23758c582880b85900237d9] ignored due to duplicate EventID
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3"(1)|file|ITEM_TRASH|1800810599566|859128e14af0ee592ed8d8aed0ae040717a2dba2] ignored due to duplicate EventID
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249641038")
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249641365")
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': ["subdir1"(0)|folder|ITEM_CREATE|311310669288|8290966fd05f7b99754107cd06e93d553327eb0d] ignored due to old SequenceID ('\x00')
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:54 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249641685")
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249641968")
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1800799858803|b500475d28799680964bb27b37fb24da28cf40ba] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249642426")
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': ["rclone-test-lasidom6wixu"(0)|folder|ITEM_CREATE|311307462727|3d791f062b7036d5e58fac3e683c20b46f2c9896] new parent not found
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:55 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249642708")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249642975")
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249643229")
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4"(0)|file|ITEM_UPLOAD|1800816760470|14a7d144c7fae6eb8e4e6eb1c3bdf635188a956e] ignored due to old SequenceID ('\x00')
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249643469")
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4"(0)|file|ITEM_UPLOAD|1800816760470|14a7d144c7fae6eb8e4e6eb1c3bdf635188a956e] ignored due to duplicate EventID
2025/03/12 07:34:56 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249643715")
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4"(0)|file|ITEM_UPLOAD|1800816760470|14a7d144c7fae6eb8e4e6eb1c3bdf635188a956e] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1800816757725|972e78c9a8b836e894c5e1a03a099919c5ba9235] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1800800646632|262f2b71bdb37a1cdb2c1111fc7dd5e18660a866] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249644042")
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4"(0)|file|ITEM_UPLOAD|1800816760470|14a7d144c7fae6eb8e4e6eb1c3bdf635188a956e] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1800816757725|972e78c9a8b836e894c5e1a03a099919c5ba9235] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1800800646632|262f2b71bdb37a1cdb2c1111fc7dd5e18660a866] ignored due to duplicate EventID
2025/03/12 07:34:57 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249644318")
2025/03/12 07:34:58 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249644617")
2025/03/12 07:34:58 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.002_07yrhb"(0)|file|ITEM_UPLOAD|1800799858803|ef129199eb064f82bc73d128c32ff029508b7d5b] ignored due to duplicate EventID
2025/03/12 07:34:58 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002_07z6zf"(0)|file|ITEM_UPLOAD|1800815082223|d4fbf0f0c124187a9ce8e880ff2149b01d22a963] ignored due to duplicate EventID
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249645585")
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4"(0)|file|ITEM_UPLOAD|1800816760470|14a7d144c7fae6eb8e4e6eb1c3bdf635188a956e] ignored due to duplicate EventID
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1800816757725|972e78c9a8b836e894c5e1a03a099919c5ba9235] ignored due to duplicate EventID
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1800800646632|262f2b71bdb37a1cdb2c1111fc7dd5e18660a866] ignored due to duplicate EventID
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.002_07yzuk"(0)|file|ITEM_UPLOAD|1800816325585|9dc18b8adeb98f8fdb702c77e350e2e6f136ad11] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001_07z6zf"(0)|file|ITEM_UPLOAD|1800816608448|34af072eb876fc50e2b734fd1567454dc3c2dab6] ignored due to old SequenceID ('\x01')
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/12 07:34:59 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249646597")
2025/03/12 07:35:00 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249646935")
2025/03/12 07:35:01 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4"(0)|file|ITEM_UPLOAD|1800816760470|14a7d144c7fae6eb8e4e6eb1c3bdf635188a956e] ignored due to duplicate EventID
2025/03/12 07:35:01 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1800816757725|972e78c9a8b836e894c5e1a03a099919c5ba9235] ignored due to duplicate EventID
2025/03/12 07:35:01 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1800800646632|262f2b71bdb37a1cdb2c1111fc7dd5e18660a866] ignored due to duplicate EventID
2025/03/12 07:35:01 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.002_07yzuk"(0)|file|ITEM_UPLOAD|1800816325585|9dc18b8adeb98f8fdb702c77e350e2e6f136ad11] ignored due to duplicate EventID
2025/03/12 07:35:01 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001_07z6zf"(0)|file|ITEM_UPLOAD|1800816608448|34af072eb876fc50e2b734fd1567454dc3c2dab6] ignored due to duplicate EventID
2025/03/12 07:35:01 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249647745")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1800816757725|972e78c9a8b836e894c5e1a03a099919c5ba9235] ignored due to duplicate EventID
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1800800646632|262f2b71bdb37a1cdb2c1111fc7dd5e18660a866] ignored due to duplicate EventID
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.002_07yzuk"(0)|file|ITEM_UPLOAD|1800816325585|9dc18b8adeb98f8fdb702c77e350e2e6f136ad11] ignored due to duplicate EventID
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001_07z6zf"(0)|file|ITEM_UPLOAD|1800816608448|34af072eb876fc50e2b734fd1567454dc3c2dab6] ignored due to duplicate EventID
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001_07yzuk"(0)|file|ITEM_UPLOAD|1800816757725|d737308e954f6c5d54c1e6686ad051a59aff01b6] ignored due to old SequenceID ('\x01')
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249648935")
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.002_07yzuk"(0)|file|ITEM_UPLOAD|1800816325585|9dc18b8adeb98f8fdb702c77e350e2e6f136ad11] ignored due to duplicate EventID
2025/03/12 07:35:02 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249649584")
2025/03/12 07:35:03 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001_07z6zf"(0)|file|ITEM_UPLOAD|1800816608448|34af072eb876fc50e2b734fd1567454dc3c2dab6] ignored due to duplicate EventID
2025/03/12 07:35:03 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249650202")
2025/03/12 07:35:04 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249650885")
    fstests.go:935: map[dir:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/12 07:35:05 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001_07yzuk"(0)|file|ITEM_UPLOAD|1800816757725|d737308e954f6c5d54c1e6686ad051a59aff01b6] ignored due to duplicate EventID
2025/03/12 07:35:05 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2"(0)|file|ITEM_UPLOAD|1800812931865|1d08df8b504a89bbb619f98454d999198ffb0ee4] ignored due to old SequenceID ('\x00')
2025/03/12 07:35:05 DEBUG : box root 'rclone-test-lasidom6wixu': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/12 07:35:05 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249651862")
2025/03/12 07:35:05 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.001_07yzuk"(0)|file|ITEM_UPLOAD|1800816757725|d737308e954f6c5d54c1e6686ad051a59aff01b6] ignored due to duplicate EventID
2025/03/12 07:35:05 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249652523")
2025/03/12 07:35:06 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249653094")
2025/03/12 07:35:06 DEBUG : box root 'rclone-test-lasidom6wixu': ["file2"(0)|file|ITEM_UPLOAD|1800812931865|1d08df8b504a89bbb619f98454d999198ffb0ee4] ignored due to duplicate EventID
2025/03/12 07:35:07 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249653683")
2025/03/12 07:35:08 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249654629")
2025/03/12 07:35:09 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249655572")
2025/03/12 07:35:09 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3"(0)|file|ITEM_UPLOAD|1800814053536|9444de31a803016e58f1e56159e45ca10f5e2cad] ignored due to old SequenceID ('\x00')
2025/03/12 07:35:09 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:35:09 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249656556")
2025/03/12 07:35:10 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249656817")
2025/03/12 07:35:10 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1800815082223|7b868fe12e87644a98345edfa24448dc7d9b7613] ignored due to old SequenceID ('\x01')
2025/03/12 07:35:10 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:35:10 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249657551")
2025/03/12 07:35:11 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249657796")
2025/03/12 07:35:11 DEBUG : box root 'rclone-test-lasidom6wixu': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1800816608448|b84ea7e8c3911f6becd736db839ec337d6911e3a] ignored due to old SequenceID ('\x01')
2025/03/12 07:35:11 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:35:11 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249658540")
2025/03/12 07:35:12 DEBUG : box root 'rclone-test-lasidom6wixu': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1800816325585|c4bf1ad0db88a693cd18b37f1aee6a9f4816a099] ignored due to old SequenceID ('\x01')
2025/03/12 07:35:12 DEBUG : box root 'rclone-test-lasidom6wixu': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/12 07:35:12 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249658878")
2025/03/12 07:35:12 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249659112")
2025/03/12 07:35:13 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249659542")
2025/03/12 07:35:14 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249660534")
2025/03/12 07:35:15 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249661534")
2025/03/12 07:35:16 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249662549")
2025/03/12 07:35:17 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249663537")
2025/03/12 07:35:18 DEBUG : box root 'rclone-test-lasidom6wixu': Checking for changes on remote (next_stream_position: "30401039249664533")
2025/03/12 07:35:20 DEBUG : box root 'rclone-test-lasidom6wixu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/12 07:35:20 ERROR : : error listing: directory not found
2025/03/12 07:35:20 DEBUG : box root 'rclone-test-lasidom6wixu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (72.71s)
    --- FAIL: TestIntegration/FsMkdir (70.11s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (68.03s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	72.739s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m14.824383907s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]