"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 2/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/03/31 05:05:20 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-funohek6muhe"
2025/03/31 05:05:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/31 05:05:20 DEBUG : Creating backend with remote "TestBox:rclone-test-funohek6muhe"
2025/03/31 05:05:21 DEBUG : box root 'rclone-test-funohek6muhe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/31 05:05:21 DEBUG : Reset feature "ListR"
2025/03/31 05:05:22 DEBUG : box root 'rclone-test-funohek6muhe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/31 05:05:23 INFO  : dir: Making directory
2025/03/31 05:05:25 INFO  : dir/subdir1: Making directory
2025/03/31 05:05:26 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882271944")
2025/03/31 05:05:26 INFO  : dir/subdir3: Making directory
2025/03/31 05:05:26 DEBUG : box root 'rclone-test-funohek6muhe': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/31 05:05:26 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882273224")
2025/03/31 05:05:27 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882273474")
2025/03/31 05:05:27 INFO  : dir/subdir2: Making directory
2025/03/31 05:05:28 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882274208")
2025/03/31 05:05:29 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882275216")
2025/03/31 05:05:30 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882276192")
2025/03/31 05:05:31 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882277198")
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882278193")
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': ["rclone-test-funohek6muhe"(0)|folder|ITEM_CREATE|314275946173|1ac22096f8d0ef9fc98748368317330c652ce191] new parent not found
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': ["subdir3"(0)|folder|ITEM_CREATE|314275774855|299681ffc22d3ff57b9ded152084b5740f345a4a] added new path ("dir/subdir3") for notify
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': ["subdir2"(0)|folder|ITEM_CREATE|314272590420|0e7b976b9bf223057e9bb0abe22229c3ada0d2e5] added new path ("dir/subdir2") for notify
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': ["subdir1"(0)|folder|ITEM_CREATE|314272566577|ce07687ffdc27b434c27c12fb72776cb361dd480] added new path ("dir/subdir1") for notify
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': ["dir"(0)|folder|ITEM_CREATE|314271532732|5dfc5dfc0147a8270ef1c193bbf00bbc208c1877] added new path ("dir") for notify
2025/03/31 05:05:32 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/31 05:05:32 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/31 05:05:32 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/31 05:05:32 DEBUG : doChangeNotify("dir", 0)
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': Received 5 events, resulting in 4 paths and 4 notifications
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882279187")
2025/03/31 05:05:32 DEBUG : box root 'rclone-test-funohek6muhe': ["subdir3"(0)|folder|ITEM_CREATE|314275774855|299681ffc22d3ff57b9ded152084b5740f345a4a] ignored due to duplicate EventID
2025/03/31 05:05:33 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882279487")
2025/03/31 05:05:33 INFO  : dir/file2.rclone_chunk.001_z7qrau: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/31 05:05:34 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882280191")
2025/03/31 05:05:34 INFO  : dir/file2.rclone_chunk.002_z7qrau: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/31 05:05:35 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882281211")
2025/03/31 05:05:35 DEBUG : box root 'rclone-test-funohek6muhe': ["subdir2"(0)|folder|ITEM_CREATE|314272590420|0e7b976b9bf223057e9bb0abe22229c3ada0d2e5] ignored due to duplicate EventID
2025/03/31 05:05:36 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882282200")
2025/03/31 05:05:36 DEBUG : box root 'rclone-test-funohek6muhe': ["subdir1"(0)|folder|ITEM_CREATE|314272566577|ce07687ffdc27b434c27c12fb72776cb361dd480] ignored due to duplicate EventID
2025/03/31 05:05:36 DEBUG : box root 'rclone-test-funohek6muhe': ["dir"(0)|folder|ITEM_CREATE|314271532732|5dfc5dfc0147a8270ef1c193bbf00bbc208c1877] ignored due to duplicate EventID
2025/03/31 05:05:37 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882283187")
2025/03/31 05:05:38 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882284191")
2025/03/31 05:05:38 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001_z7qrau"(0)|file|ITEM_UPLOAD|1819089452636|adaa1023dcf2e144d1798f38f49c08d5da85ee5a] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:38 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078427326|b55775a5ed29afa7366751afa03c64ef9daba239] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:38 DEBUG : box root 'rclone-test-funohek6muhe': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/31 05:05:38 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882285208")
2025/03/31 05:05:38 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078427326|b55775a5ed29afa7366751afa03c64ef9daba239] ignored due to duplicate EventID
2025/03/31 05:05:39 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882285478")
2025/03/31 05:05:40 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882286199")
2025/03/31 05:05:41 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882287199")
2025/03/31 05:05:41 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078427326|b55775a5ed29afa7366751afa03c64ef9daba239] ignored due to duplicate EventID
2025/03/31 05:05:41 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819089452636|42cd111a64e78cb64b7feec3e8bd562a09eeffad] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:41 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002_z7qrau"(0)|file|ITEM_UPLOAD|1819078427326|1e7d0b812630712f85960651f3989afc34e2efce] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:41 DEBUG : box root 'rclone-test-funohek6muhe': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/31 05:05:41 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882288199")
2025/03/31 05:05:42 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882288449")
2025/03/31 05:05:42 INFO  : dir/file4.rclone_chunk.001_z7r0r4: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/31 05:05:42 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819089452636|42cd111a64e78cb64b7feec3e8bd562a09eeffad] ignored due to duplicate EventID
2025/03/31 05:05:42 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002_z7qrau"(0)|file|ITEM_UPLOAD|1819078427326|1e7d0b812630712f85960651f3989afc34e2efce] ignored due to duplicate EventID
2025/03/31 05:05:42 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001_z7r0r4"(0)|file|ITEM_UPLOAD|1819093646034|f9fecb56040d77ebd95f790e4f8de68898927b28] added new path ("dir/file4.rclone_chunk.001_z7r0r4") for notify
2025/03/31 05:05:42 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_z7r0r4", 1)
2025/03/31 05:05:42 DEBUG : box root 'rclone-test-funohek6muhe': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/31 05:05:42 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882289185")
2025/03/31 05:05:43 INFO  : dir/file4.rclone_chunk.002_z7r0r4: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/31 05:05:43 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882289447")
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882290191")
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819089452636|42cd111a64e78cb64b7feec3e8bd562a09eeffad] ignored due to duplicate EventID
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002_z7qrau"(0)|file|ITEM_UPLOAD|1819078427326|1e7d0b812630712f85960651f3989afc34e2efce] ignored due to duplicate EventID
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001_z7r0r4"(0)|file|ITEM_UPLOAD|1819093646034|f9fecb56040d77ebd95f790e4f8de68898927b28] ignored due to duplicate EventID
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.002_z7r0r4"(0)|file|ITEM_UPLOAD|1819078592816|19a752f94e88e86f8903c8e7fdecbcc31f6aa55f] added new path ("dir/file4.rclone_chunk.002_z7r0r4") for notify
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819093646034|3d0d1c229c5aa47c09bf1a12e47f8f5972810fec] added new path ("dir/file4.rclone_chunk.001") for notify
2025/03/31 05:05:44 DEBUG : doChangeNotify("dir/file4.rclone_chunk.002_z7r0r4", 1)
2025/03/31 05:05:44 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': Received 5 events, resulting in 2 paths and 2 notifications
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882291195")
2025/03/31 05:05:44 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819089452636|42cd111a64e78cb64b7feec3e8bd562a09eeffad] ignored due to duplicate EventID
2025/03/31 05:05:45 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882291466")
2025/03/31 05:05:45 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002_z7qrau"(0)|file|ITEM_UPLOAD|1819078427326|1e7d0b812630712f85960651f3989afc34e2efce] ignored due to duplicate EventID
2025/03/31 05:05:46 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882292194")
2025/03/31 05:05:46 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001_z7r0r4"(0)|file|ITEM_UPLOAD|1819093646034|f9fecb56040d77ebd95f790e4f8de68898927b28] ignored due to duplicate EventID
2025/03/31 05:05:46 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.002_z7r0r4"(0)|file|ITEM_UPLOAD|1819078592816|19a752f94e88e86f8903c8e7fdecbcc31f6aa55f] ignored due to duplicate EventID
2025/03/31 05:05:46 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819093646034|3d0d1c229c5aa47c09bf1a12e47f8f5972810fec] ignored due to duplicate EventID
2025/03/31 05:05:47 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882293206")
2025/03/31 05:05:47 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.002_z7r0r4"(0)|file|ITEM_UPLOAD|1819078592816|19a752f94e88e86f8903c8e7fdecbcc31f6aa55f] ignored due to duplicate EventID
2025/03/31 05:05:48 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882294194")
2025/03/31 05:05:48 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819093646034|3d0d1c229c5aa47c09bf1a12e47f8f5972810fec] ignored due to duplicate EventID
2025/03/31 05:05:48 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078592816|9ad4398f277c74a69426bf14353b9cb54a96f262] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:48 DEBUG : box root 'rclone-test-funohek6muhe': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/31 05:05:48 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882295186")
2025/03/31 05:05:49 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882295493")
2025/03/31 05:05:50 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882296185")
2025/03/31 05:05:50 INFO  : dir/file3.rclone_chunk.001_z7r9zi: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/31 05:05:51 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882297185")
2025/03/31 05:05:51 INFO  : dir/file3.rclone_chunk.002_z7r9zi: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/31 05:05:51 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819078592816|9ad4398f277c74a69426bf14353b9cb54a96f262] ignored due to duplicate EventID
2025/03/31 05:05:52 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882298213")
2025/03/31 05:05:52 DEBUG : box root 'rclone-test-funohek6muhe': ["file2"(0)|file|ITEM_UPLOAD|1819075329141|235544309cd46f0cbfc4b4faea7e34927a58c1a1] ignored due to old SequenceID ('\x00')
2025/03/31 05:05:52 DEBUG : box root 'rclone-test-funohek6muhe': ["file4"(0)|file|ITEM_UPLOAD|1819090605476|a6dac6d09824724756179251c26a9bb7ff8c9945] ignored due to old SequenceID ('\x00')
2025/03/31 05:05:52 DEBUG : box root 'rclone-test-funohek6muhe': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/31 05:05:52 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882299196")
2025/03/31 05:05:52 DEBUG : box root 'rclone-test-funohek6muhe': ["file2"(0)|file|ITEM_UPLOAD|1819075329141|235544309cd46f0cbfc4b4faea7e34927a58c1a1] ignored due to duplicate EventID
2025/03/31 05:05:53 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882299454")
2025/03/31 05:05:53 DEBUG : box root 'rclone-test-funohek6muhe': ["file4"(0)|file|ITEM_UPLOAD|1819090605476|a6dac6d09824724756179251c26a9bb7ff8c9945] ignored due to duplicate EventID
2025/03/31 05:05:53 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819091423122|db8c6df16c412ef8fa6b5b2a01a3a584a21bb551] added new path ("dir/file3.rclone_chunk.002") for notify
2025/03/31 05:05:53 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/31 05:05:53 DEBUG : box root 'rclone-test-funohek6muhe': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/31 05:05:53 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882300214")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/31 05:05:54 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882300510")
2025/03/31 05:05:54 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819091423122|db8c6df16c412ef8fa6b5b2a01a3a584a21bb551] ignored due to duplicate EventID
2025/03/31 05:05:55 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882301187")
2025/03/31 05:05:56 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882302199")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/31 05:05:57 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882303206")
2025/03/31 05:05:57 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819091423122|db8c6df16c412ef8fa6b5b2a01a3a584a21bb551] ignored due to duplicate EventID
2025/03/31 05:05:58 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882304189")
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882305199")
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3"(0)|file|ITEM_UPLOAD|1819086886817|cf3f84f6431d8ba8b04d8fedd4eac8f144798afb] ignored due to old SequenceID ('\x00')
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819091351602|6aae298316ffdb81d2f37a7005a9cfa49ce7d801] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.002_z7r9zi"(0)|file|ITEM_UPLOAD|1819091423122|578e25d7aff6b7ce5ef71b8aa6432e99eda78089] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.001_z7r9zi"(0)|file|ITEM_UPLOAD|1819091351602|09c89167d7d174fe74112a81464036ba5bb3a04c] ignored due to old SequenceID ('\x01')
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882306194")
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819091351602|6aae298316ffdb81d2f37a7005a9cfa49ce7d801] ignored due to duplicate EventID
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.002_z7r9zi"(0)|file|ITEM_UPLOAD|1819091423122|578e25d7aff6b7ce5ef71b8aa6432e99eda78089] ignored due to duplicate EventID
2025/03/31 05:05:59 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.001_z7r9zi"(0)|file|ITEM_UPLOAD|1819091351602|09c89167d7d174fe74112a81464036ba5bb3a04c] ignored due to duplicate EventID
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/31 05:06:00 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882306503")
2025/03/31 05:06:00 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819091351602|6aae298316ffdb81d2f37a7005a9cfa49ce7d801] ignored due to duplicate EventID
2025/03/31 05:06:00 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.002_z7r9zi"(0)|file|ITEM_UPLOAD|1819091423122|578e25d7aff6b7ce5ef71b8aa6432e99eda78089] ignored due to duplicate EventID
2025/03/31 05:06:00 DEBUG : box root 'rclone-test-funohek6muhe': ["file3.rclone_chunk.001_z7r9zi"(0)|file|ITEM_UPLOAD|1819091351602|09c89167d7d174fe74112a81464036ba5bb3a04c] ignored due to duplicate EventID
2025/03/31 05:06:01 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882307284")
2025/03/31 05:06:02 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882308412")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/31 05:06:03 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882309238")
2025/03/31 05:06:04 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882310240")
2025/03/31 05:06:05 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882311218")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/31 05:06:06 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882312184")
2025/03/31 05:06:07 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882313185")
2025/03/31 05:06:08 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882314190")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/31 05:06:09 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882315194")
2025/03/31 05:06:10 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882316183")
2025/03/31 05:06:11 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882317209")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/31 05:06:12 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882318194")
2025/03/31 05:06:13 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882319187")
2025/03/31 05:06:14 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882320192")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/31 05:06:15 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882321203")
2025/03/31 05:06:16 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882322193")
2025/03/31 05:06:17 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882323189")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/31 05:06:18 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882324205")
2025/03/31 05:06:19 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882325194")
2025/03/31 05:06:20 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882326223")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file3:{} dir/file4:{} dir/file4.rclone_chunk.001_z7r0r4:{} dir/file4.rclone_chunk.002_z7r0r4:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/31 05:06:21 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882327204")
2025/03/31 05:06:22 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882328188")
2025/03/31 05:06:23 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882329237")
2025/03/31 05:06:24 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882330217")
2025/03/31 05:06:25 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882331199")
2025/03/31 05:06:25 DEBUG : box root 'rclone-test-funohek6muhe': ["file2"(1)|file|ITEM_TRASH|1819075329141|d045475996ab73d26a1e90389113cfccc33739d0] added old path ("dir/file2") for notify
2025/03/31 05:06:25 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:06:25 DEBUG : box root 'rclone-test-funohek6muhe': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/31 05:06:25 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882332200")
2025/03/31 05:06:25 DEBUG : box root 'rclone-test-funohek6muhe': ["file2"(1)|file|ITEM_TRASH|1819075329141|d045475996ab73d26a1e90389113cfccc33739d0] ignored due to duplicate EventID
2025/03/31 05:06:26 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882332476")
2025/03/31 05:06:27 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882333189")
2025/03/31 05:06:28 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882334205")
2025/03/31 05:06:28 DEBUG : box root 'rclone-test-funohek6muhe': ["file2"(1)|file|ITEM_TRASH|1819075329141|d045475996ab73d26a1e90389113cfccc33739d0] ignored due to duplicate EventID
2025/03/31 05:06:28 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819078427326|3d8ebededd1515b0c6bc5d63567fd04487eb69b5] added old path ("dir/file2.rclone_chunk.002") for notify
2025/03/31 05:06:28 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819089452636|f0fff2215fd69721950e71c61ce06907943fbda8] added old path ("dir/file2.rclone_chunk.001") for notify
2025/03/31 05:06:28 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:06:28 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:06:28 DEBUG : box root 'rclone-test-funohek6muhe': Received 3 events, resulting in 2 paths and 2 notifications
2025/03/31 05:06:28 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882335196")
2025/03/31 05:06:29 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882335472")
2025/03/31 05:06:29 DEBUG : box root 'rclone-test-funohek6muhe': ["file2"(1)|file|ITEM_TRASH|1819075329141|d045475996ab73d26a1e90389113cfccc33739d0] ignored due to duplicate EventID
2025/03/31 05:06:30 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882336224")
2025/03/31 05:06:30 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819078427326|3d8ebededd1515b0c6bc5d63567fd04487eb69b5] ignored due to duplicate EventID
2025/03/31 05:06:30 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819089452636|f0fff2215fd69721950e71c61ce06907943fbda8] ignored due to duplicate EventID
2025/03/31 05:06:30 DEBUG : box root 'rclone-test-funohek6muhe': ["file4"(1)|file|ITEM_TRASH|1819090605476|6ebcab3ab071b4b86a5f7e39647a9687f9b4ab76] added old path ("dir/file4") for notify
2025/03/31 05:06:30 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/31 05:06:30 DEBUG : box root 'rclone-test-funohek6muhe': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/31 05:06:30 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882337191")
2025/03/31 05:06:31 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882337440")
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882338226")
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819078427326|3d8ebededd1515b0c6bc5d63567fd04487eb69b5] ignored due to duplicate EventID
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819089452636|f0fff2215fd69721950e71c61ce06907943fbda8] ignored due to duplicate EventID
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': ["file4"(1)|file|ITEM_TRASH|1819090605476|6ebcab3ab071b4b86a5f7e39647a9687f9b4ab76] ignored due to duplicate EventID
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819093646034|eb903719b1ce6ab439060389c5cd88d5ef8a02e4] added old path ("dir/file4.rclone_chunk.001") for notify
2025/03/31 05:06:32 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/31 05:06:32 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882339201")
2025/03/31 05:06:33 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882339478")
2025/03/31 05:06:33 DEBUG : box root 'rclone-test-funohek6muhe': ["file4"(1)|file|ITEM_TRASH|1819090605476|6ebcab3ab071b4b86a5f7e39647a9687f9b4ab76] ignored due to duplicate EventID
2025/03/31 05:06:33 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819093646034|eb903719b1ce6ab439060389c5cd88d5ef8a02e4] ignored due to duplicate EventID
2025/03/31 05:06:34 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882340194")
2025/03/31 05:06:34 DEBUG : box root 'rclone-test-funohek6muhe': ["file4"(1)|file|ITEM_TRASH|1819090605476|6ebcab3ab071b4b86a5f7e39647a9687f9b4ab76] ignored due to duplicate EventID
2025/03/31 05:06:35 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882341195")
2025/03/31 05:06:35 DEBUG : box root 'rclone-test-funohek6muhe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819093646034|eb903719b1ce6ab439060389c5cd88d5ef8a02e4] ignored due to duplicate EventID
2025/03/31 05:06:36 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882342185")
2025/03/31 05:06:37 DEBUG : box root 'rclone-test-funohek6muhe': Checking for changes on remote (next_stream_position: "30401040882343188")
2025/03/31 05:06:39 DEBUG : box root 'rclone-test-funohek6muhe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/31 05:06:39 ERROR : : error listing: directory not found
2025/03/31 05:06:39 DEBUG : box root 'rclone-test-funohek6muhe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (78.78s)
    --- FAIL: TestIntegration/FsMkdir (76.16s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (73.87s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	78.831s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m21.460827973s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]