"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/26 03:52:21 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-cukapuc5xihi"
2025/03/26 03:52:21 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/26 03:52:21 DEBUG : Creating backend with remote "TestBox:rclone-test-cukapuc5xihi"
2025/03/26 03:52:21 DEBUG : box root 'rclone-test-cukapuc5xihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/26 03:52:22 DEBUG : Reset feature "ListR"
2025/03/26 03:52:22 DEBUG : box root 'rclone-test-cukapuc5xihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/26 03:52:24 INFO  : dir: Making directory
2025/03/26 03:52:25 INFO  : dir/subdir1: Making directory
2025/03/26 03:52:26 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445892009")
2025/03/26 03:52:26 INFO  : dir/subdir3: Making directory
2025/03/26 03:52:27 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445893244")
2025/03/26 03:52:27 INFO  : dir/subdir2: Making directory
2025/03/26 03:52:27 DEBUG : box root 'rclone-test-cukapuc5xihi': ["rclone-test-cukapuc5xihi"(0)|folder|ITEM_CREATE|313483407882|868a12e28bc3adcc138e925704623d1a8f525ddb] new parent not found
2025/03/26 03:52:27 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:27 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445894246")
2025/03/26 03:52:28 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445894478")
2025/03/26 03:52:29 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445895243")
2025/03/26 03:52:29 DEBUG : box root 'rclone-test-cukapuc5xihi': ["rclone-test-cukapuc5xihi"(0)|folder|ITEM_CREATE|313483407882|868a12e28bc3adcc138e925704623d1a8f525ddb] ignored due to duplicate EventID
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445896245")
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir1"(0)|folder|ITEM_CREATE|313479831752|8d8a808a620c4d95990fbfe8b22d5c6b2ebd08d6] added new path ("dir/subdir1") for notify
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir3"(0)|folder|ITEM_CREATE|313480712647|54292492053309f7e127347be03699ff7dd7c764] added new path ("dir/subdir3") for notify
2025/03/26 03:52:30 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/26 03:52:30 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445897241")
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir1"(0)|folder|ITEM_CREATE|313479831752|8d8a808a620c4d95990fbfe8b22d5c6b2ebd08d6] ignored due to duplicate EventID
2025/03/26 03:52:30 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir3"(0)|folder|ITEM_CREATE|313480712647|54292492053309f7e127347be03699ff7dd7c764] ignored due to duplicate EventID
2025/03/26 03:52:31 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445897476")
2025/03/26 03:52:31 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir1"(0)|folder|ITEM_CREATE|313479831752|8d8a808a620c4d95990fbfe8b22d5c6b2ebd08d6] ignored due to duplicate EventID
2025/03/26 03:52:31 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir3"(0)|folder|ITEM_CREATE|313480712647|54292492053309f7e127347be03699ff7dd7c764] ignored due to duplicate EventID
2025/03/26 03:52:32 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445898247")
2025/03/26 03:52:32 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir1"(0)|folder|ITEM_CREATE|313479831752|8d8a808a620c4d95990fbfe8b22d5c6b2ebd08d6] ignored due to duplicate EventID
2025/03/26 03:52:32 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir3"(0)|folder|ITEM_CREATE|313480712647|54292492053309f7e127347be03699ff7dd7c764] ignored due to duplicate EventID
2025/03/26 03:52:33 INFO  : dir/file2.rclone_chunk.001_pv13rw: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/26 03:52:33 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445899240")
2025/03/26 03:52:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir3"(0)|folder|ITEM_CREATE|313480712647|54292492053309f7e127347be03699ff7dd7c764] ignored due to duplicate EventID
2025/03/26 03:52:33 INFO  : dir/file2.rclone_chunk.002_pv13rw: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/26 03:52:34 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445900247")
2025/03/26 03:52:35 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445901247")
2025/03/26 03:52:36 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445902254")
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445903250")
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.001_pv13rw"(0)|file|ITEM_UPLOAD|1814077605554|d362459b4386c44931f7c87b497935ab10f594d6] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445904246")
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.002_pv13rw"(0)|file|ITEM_UPLOAD|1814075867967|a1d21098d6e2a2c7c80e8117fb6a6350e9bb7c8b] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:37 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445904484")
2025/03/26 03:52:38 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445904711")
2025/03/26 03:52:39 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445905244")
2025/03/26 03:52:39 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1814077605554|c3e0006f57342650492039b63ed86206b0954fe8] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:39 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1814075867967|7b182c255ad21eae2ce8abe2bed1278706bc06a1] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:39 DEBUG : box root 'rclone-test-cukapuc5xihi': ["dir"(0)|folder|ITEM_CREATE|313482580476|87880ba48bdfeaec5823a729deb0e9fc14be5f7e] added new path ("dir") for notify
2025/03/26 03:52:39 DEBUG : doChangeNotify("dir", 0)
2025/03/26 03:52:39 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/26 03:52:39 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445906244")
2025/03/26 03:52:40 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445906479")
2025/03/26 03:52:40 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir2"(0)|folder|ITEM_CREATE|313483759183|d23551310ffcda785f96d4f617b3ff9dfe95ba8d] ignored due to old SequenceID ('\x00')
2025/03/26 03:52:40 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001_pv1bar"(0)|file|ITEM_UPLOAD|1814081096435|569613d9f63b7d341fbb0b2c7505d7db19686540] added new path ("dir/file4.rclone_chunk.001_pv1bar") for notify
2025/03/26 03:52:40 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_pv1bar", 1)
2025/03/26 03:52:40 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/26 03:52:40 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445907255")
2025/03/26 03:52:40 INFO  : dir/file4.rclone_chunk.001_pv1bar: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/26 03:52:41 INFO  : dir/file4.rclone_chunk.002_pv1bar: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/26 03:52:41 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445907495")
2025/03/26 03:52:42 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445908250")
2025/03/26 03:52:43 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445909245")
2025/03/26 03:52:43 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir2"(0)|folder|ITEM_CREATE|313483759183|d23551310ffcda785f96d4f617b3ff9dfe95ba8d] ignored due to duplicate EventID
2025/03/26 03:52:43 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001_pv1bar"(0)|file|ITEM_UPLOAD|1814081096435|569613d9f63b7d341fbb0b2c7505d7db19686540] ignored due to duplicate EventID
2025/03/26 03:52:43 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1814081096435|e0d048bfaf91b847d4eab854a278fc0849ddbb83] added new path ("dir/file4.rclone_chunk.001") for notify
2025/03/26 03:52:43 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/26 03:52:43 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/26 03:52:43 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445910241")
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445910479")
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': ["subdir2"(0)|folder|ITEM_CREATE|313483759183|d23551310ffcda785f96d4f617b3ff9dfe95ba8d] ignored due to duplicate EventID
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001_pv1bar"(0)|file|ITEM_UPLOAD|1814081096435|569613d9f63b7d341fbb0b2c7505d7db19686540] ignored due to duplicate EventID
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1814081096435|e0d048bfaf91b847d4eab854a278fc0849ddbb83] ignored due to duplicate EventID
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1814082903735|2a5047ab4d35a6b2ef692d857d316ff0a5060761] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445911235")
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1814081096435|e0d048bfaf91b847d4eab854a278fc0849ddbb83] ignored due to duplicate EventID
2025/03/26 03:52:44 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1814082903735|2a5047ab4d35a6b2ef692d857d316ff0a5060761] ignored due to duplicate EventID
2025/03/26 03:52:45 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445911482")
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445912257")
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1814081096435|e0d048bfaf91b847d4eab854a278fc0849ddbb83] ignored due to duplicate EventID
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1814082903735|2a5047ab4d35a6b2ef692d857d316ff0a5060761] ignored due to duplicate EventID
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2"(0)|file|ITEM_UPLOAD|1814062391577|9fd2a85f94a2a983764a51b0e5448eb30de893bf] ignored due to old SequenceID ('\x00')
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4"(0)|file|ITEM_UPLOAD|1814082832667|64fcf5546a57219d4c3deeac7083c8dd2cc47b6a] ignored due to old SequenceID ('\x00')
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445913266")
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1814082903735|2a5047ab4d35a6b2ef692d857d316ff0a5060761] ignored due to duplicate EventID
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2"(0)|file|ITEM_UPLOAD|1814062391577|9fd2a85f94a2a983764a51b0e5448eb30de893bf] ignored due to duplicate EventID
2025/03/26 03:52:46 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4"(0)|file|ITEM_UPLOAD|1814082832667|64fcf5546a57219d4c3deeac7083c8dd2cc47b6a] ignored due to duplicate EventID
2025/03/26 03:52:47 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445913519")
2025/03/26 03:52:48 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445914246")
2025/03/26 03:52:48 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1814082903735|2a5047ab4d35a6b2ef692d857d316ff0a5060761] ignored due to duplicate EventID
2025/03/26 03:52:48 INFO  : dir/file3.rclone_chunk.001_pv1j7h: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/26 03:52:49 INFO  : dir/file3.rclone_chunk.002_pv1j7h: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/26 03:52:49 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445915257")
2025/03/26 03:52:49 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2"(0)|file|ITEM_UPLOAD|1814062391577|9fd2a85f94a2a983764a51b0e5448eb30de893bf] ignored due to duplicate EventID
2025/03/26 03:52:49 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4"(0)|file|ITEM_UPLOAD|1814082832667|64fcf5546a57219d4c3deeac7083c8dd2cc47b6a] ignored due to duplicate EventID
2025/03/26 03:52:50 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445916251")
2025/03/26 03:52:50 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.001_pv1j7h"(0)|file|ITEM_UPLOAD|1814080209525|b108e9f460808067bbc9d7f94316f0a0bb1a355c] added new path ("dir/file3.rclone_chunk.001_pv1j7h") for notify
2025/03/26 03:52:50 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.002_pv1j7h"(0)|file|ITEM_UPLOAD|1814083062222|87ad9cd24da9c6449fe60d27a40c9c96d5ef4d63] added new path ("dir/file3.rclone_chunk.002_pv1j7h") for notify
2025/03/26 03:52:50 DEBUG : doChangeNotify("dir/file3.rclone_chunk.001_pv1j7h", 1)
2025/03/26 03:52:50 DEBUG : doChangeNotify("dir/file3.rclone_chunk.002_pv1j7h", 1)
2025/03/26 03:52:50 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/26 03:52:50 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445917243")
2025/03/26 03:52:51 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445917503")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445918249")
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.001_pv1j7h"(0)|file|ITEM_UPLOAD|1814080209525|b108e9f460808067bbc9d7f94316f0a0bb1a355c] ignored due to duplicate EventID
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.002_pv1j7h"(0)|file|ITEM_UPLOAD|1814083062222|87ad9cd24da9c6449fe60d27a40c9c96d5ef4d63] ignored due to duplicate EventID
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002_pv1bar"(0)|file|ITEM_UPLOAD|1814082903735|9d5526cd06c3406b0c706ea70ef149daf7b82575] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1814083062222|f5fbac35ded8f0a1dea72e9e0bfd4a7d7c2690ad] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:52 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445919243")
2025/03/26 03:52:53 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445919491")
2025/03/26 03:52:54 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445920256")
2025/03/26 03:52:54 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.002_pv1j7h"(0)|file|ITEM_UPLOAD|1814083062222|87ad9cd24da9c6449fe60d27a40c9c96d5ef4d63] ignored due to duplicate EventID
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/26 03:52:55 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445921243")
2025/03/26 03:52:55 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002_pv1bar"(0)|file|ITEM_UPLOAD|1814082903735|9d5526cd06c3406b0c706ea70ef149daf7b82575] ignored due to duplicate EventID
2025/03/26 03:52:56 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445922249")
2025/03/26 03:52:56 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1814083062222|f5fbac35ded8f0a1dea72e9e0bfd4a7d7c2690ad] ignored due to duplicate EventID
2025/03/26 03:52:57 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445923241")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/26 03:52:58 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445924248")
2025/03/26 03:52:58 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1814080209525|fbb3e9c34dc2a67235521f14fb8ea7be0d47ec5b] ignored due to old SequenceID ('\x01')
2025/03/26 03:52:58 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/26 03:52:58 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445925243")
2025/03/26 03:52:59 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445925475")
2025/03/26 03:53:00 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445926239")
2025/03/26 03:53:00 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1814080209525|fbb3e9c34dc2a67235521f14fb8ea7be0d47ec5b] ignored due to duplicate EventID
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/26 03:53:01 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445927246")
2025/03/26 03:53:02 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445928250")
2025/03/26 03:53:02 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1814080209525|fbb3e9c34dc2a67235521f14fb8ea7be0d47ec5b] ignored due to duplicate EventID
2025/03/26 03:53:02 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(0)|file|ITEM_UPLOAD|1814063456823|e9940db5601f0e3ed24878212bd65a6079019490] ignored due to old SequenceID ('\x00')
2025/03/26 03:53:02 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/26 03:53:02 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445929253")
2025/03/26 03:53:02 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(0)|file|ITEM_UPLOAD|1814063456823|e9940db5601f0e3ed24878212bd65a6079019490] ignored due to duplicate EventID
2025/03/26 03:53:03 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445929497")
2025/03/26 03:53:03 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(0)|file|ITEM_UPLOAD|1814063456823|e9940db5601f0e3ed24878212bd65a6079019490] ignored due to duplicate EventID
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/26 03:53:04 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445930243")
2025/03/26 03:53:04 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(0)|file|ITEM_UPLOAD|1814063456823|e9940db5601f0e3ed24878212bd65a6079019490] ignored due to duplicate EventID
2025/03/26 03:53:05 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445931245")
2025/03/26 03:53:05 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(0)|file|ITEM_UPLOAD|1814063456823|e9940db5601f0e3ed24878212bd65a6079019490] ignored due to duplicate EventID
2025/03/26 03:53:06 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445932246")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/26 03:53:07 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445933241")
2025/03/26 03:53:08 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445934246")
2025/03/26 03:53:09 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445935291")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/26 03:53:10 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445936253")
2025/03/26 03:53:11 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445937249")
2025/03/26 03:53:12 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445938244")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/26 03:53:13 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445939247")
2025/03/26 03:53:14 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445940243")
2025/03/26 03:53:15 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445941241")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/26 03:53:16 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445942247")
2025/03/26 03:53:17 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445943248")
2025/03/26 03:53:18 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445944253")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file3.rclone_chunk.001_pv1j7h:{} dir/file3.rclone_chunk.002_pv1j7h:{} dir/file4:{} dir/file4.rclone_chunk.001_pv1bar:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/26 03:53:19 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445945241")
2025/03/26 03:53:20 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445946244")
2025/03/26 03:53:21 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445947249")
2025/03/26 03:53:22 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445948251")
2025/03/26 03:53:23 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445949253")
2025/03/26 03:53:24 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445950262")
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445951243")
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2"(1)|file|ITEM_TRASH|1814062391577|7fe963c741ba3d786a74f862d1b8a309755a4d27] added old path ("dir/file2") for notify
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1814077605554|8aa157d2fd3f8e1aa8fb9043b31dff78d4e45058] added old path ("dir/file2.rclone_chunk.001") for notify
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1814075867967|fe6a2e1dfc41e28cb2bb354eb93a3eaa8d4b672c] added old path ("dir/file2.rclone_chunk.002") for notify
2025/03/26 03:53:25 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/26 03:53:25 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/26 03:53:25 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 3 events, resulting in 3 paths and 3 notifications
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445952248")
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2"(1)|file|ITEM_TRASH|1814062391577|7fe963c741ba3d786a74f862d1b8a309755a4d27] ignored due to duplicate EventID
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1814077605554|8aa157d2fd3f8e1aa8fb9043b31dff78d4e45058] ignored due to duplicate EventID
2025/03/26 03:53:25 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1814075867967|fe6a2e1dfc41e28cb2bb354eb93a3eaa8d4b672c] ignored due to duplicate EventID
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445952512")
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2"(1)|file|ITEM_TRASH|1814062391577|7fe963c741ba3d786a74f862d1b8a309755a4d27] ignored due to duplicate EventID
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1814077605554|8aa157d2fd3f8e1aa8fb9043b31dff78d4e45058] ignored due to duplicate EventID
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1814075867967|fe6a2e1dfc41e28cb2bb354eb93a3eaa8d4b672c] ignored due to duplicate EventID
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4"(1)|file|ITEM_TRASH|1814082832667|46f058596b8b08dfdd03c25b4ec14e357c317a55] added old path ("dir/file4") for notify
2025/03/26 03:53:26 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/26 03:53:26 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445953242")
2025/03/26 03:53:27 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445953493")
2025/03/26 03:53:27 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1814077605554|8aa157d2fd3f8e1aa8fb9043b31dff78d4e45058] ignored due to duplicate EventID
2025/03/26 03:53:28 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445954245")
2025/03/26 03:53:28 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1814075867967|fe6a2e1dfc41e28cb2bb354eb93a3eaa8d4b672c] ignored due to duplicate EventID
2025/03/26 03:53:29 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445955250")
2025/03/26 03:53:30 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445956245")
2025/03/26 03:53:30 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4"(1)|file|ITEM_TRASH|1814082832667|46f058596b8b08dfdd03c25b4ec14e357c317a55] ignored due to duplicate EventID
2025/03/26 03:53:31 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445957244")
2025/03/26 03:53:32 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445958249")
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445959244")
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1814082903735|1e55468b60246177e7c6ff289b7a3a93d2de30cd] added old path ("dir/file4.rclone_chunk.002") for notify
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1814081096435|7f0ac3355f4368abb8894cee81b78a8c09d356ab] added old path ("dir/file4.rclone_chunk.001") for notify
2025/03/26 03:53:33 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/26 03:53:33 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445960244")
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(1)|file|ITEM_TRASH|1814063456823|d70f69af8772992c0b069c039986663bae4bbfcf] added old path ("dir/file3") for notify
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1814080209525|191989e08cb947ac3a8d53c67eb2b31e211ba22f] added old path ("dir/file3.rclone_chunk.001") for notify
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3.rclone_chunk.002"(2)|file|ITEM_TRASH|1814083062222|830fb491467c2816c20c70eb54ed84ebc19c0f69] added old path ("dir/file3.rclone_chunk.002") for notify
2025/03/26 03:53:33 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/26 03:53:33 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/26 03:53:33 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': Received 3 events, resulting in 3 paths and 3 notifications
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': Checking for changes on remote (next_stream_position: "30401040445960486")
2025/03/26 03:53:33 DEBUG : box root 'rclone-test-cukapuc5xihi': ["file3"(1)|file|ITEM_TRASH|1814063456823|d70f69af8772992c0b069c039986663bae4bbfcf] ignored due to duplicate EventID
2025/03/26 03:53:35 DEBUG : box root 'rclone-test-cukapuc5xihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/26 03:53:35 ERROR : : error listing: directory not found
2025/03/26 03:53:35 DEBUG : box root 'rclone-test-cukapuc5xihi': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (74.58s)
    --- FAIL: TestIntegration/FsMkdir (72.14s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (69.89s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	74.619s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m17.668115089s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]