"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 3/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/05/08 06:46:16 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-cudinij0zazo"
2025/05/08 06:46:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/08 06:46:16 DEBUG : Creating backend with remote "TestBox:rclone-test-cudinij0zazo"
2025/05/08 06:46:17 DEBUG : box root 'rclone-test-cudinij0zazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/05/08 06:46:19 DEBUG : box root 'rclone-test-cudinij0zazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/05/08 06:46:20 INFO  : dir: Making directory
2025/05/08 06:46:23 INFO  : dir/subdir1: Making directory
2025/05/08 06:46:24 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171529903")
2025/05/08 06:46:24 INFO  : dir/subdir3: Making directory
2025/05/08 06:46:24 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:24 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171531138")
2025/05/08 06:46:25 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171531375")
2025/05/08 06:46:25 DEBUG : box root 'rclone-test-cudinij0zazo': Received 3 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:25 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171532134")
2025/05/08 06:46:25 INFO  : dir/subdir2: Making directory
2025/05/08 06:46:25 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:25 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171532380")
2025/05/08 06:46:26 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171532613")
2025/05/08 06:46:26 DEBUG : box root 'rclone-test-cudinij0zazo': ["rclone-test-cudinij0zazo"(0)|folder|ITEM_CREATE|320209506022|e0b30217adce59c5f42b20b38eaaa8700c15de81] new parent not found
2025/05/08 06:46:26 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:26 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171533131")
2025/05/08 06:46:27 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171533371")
2025/05/08 06:46:28 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171534136")
2025/05/08 06:46:28 DEBUG : box root 'rclone-test-cudinij0zazo': ["dir"(0)|folder|ITEM_CREATE|320208296921|8ca1f44c80e7756052288857d04f244a211936dd] added new path ("dir") for notify
2025/05/08 06:46:28 DEBUG : doChangeNotify("dir", 0)
2025/05/08 06:46:28 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/08 06:46:28 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171535131")
2025/05/08 06:46:29 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171535372")
2025/05/08 06:46:30 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171536139")
2025/05/08 06:46:30 DEBUG : box root 'rclone-test-cudinij0zazo': ["subdir1"(0)|folder|ITEM_CREATE|320204355996|6ca786958ce1a84ff65fa3b6d9ad0e33a88e52bb] added new path ("dir/subdir1") for notify
2025/05/08 06:46:30 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/05/08 06:46:30 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/08 06:46:30 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171537143")
2025/05/08 06:46:31 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171537393")
2025/05/08 06:46:32 INFO  : dir/file2.rclone_chunk.001_xprgou: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/05/08 06:46:32 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171538140")
2025/05/08 06:46:32 DEBUG : box root 'rclone-test-cudinij0zazo': ["subdir3"(0)|folder|ITEM_CREATE|320209218716|4e636ea9ca52f2e235e6a5702351adca7d7255da] added new path ("dir/subdir3") for notify
2025/05/08 06:46:32 DEBUG : box root 'rclone-test-cudinij0zazo': ["ignorelistingchecksum"(0)|folder|ITEM_CREATE|320206111139|261d9125ed299f3a776c7b02212c06474f6577a8] new parent not found
2025/05/08 06:46:32 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/05/08 06:46:32 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/08 06:46:32 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171539131")
2025/05/08 06:46:32 INFO  : dir/file2.rclone_chunk.002_xprgou: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/05/08 06:46:33 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171539376")
2025/05/08 06:46:34 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171540129")
2025/05/08 06:46:34 DEBUG : box root 'rclone-test-cudinij0zazo': ["subdir2"(0)|folder|ITEM_CREATE|320207538889|9230ada7968745753d8c17d9f23412af1fafd11f] added new path ("dir/subdir2") for notify
2025/05/08 06:46:34 DEBUG : box root 'rclone-test-cudinij0zazo': ["path1"(0)|folder|ITEM_CREATE|320205505618|0ef858b6333bf868474af520c70054fb8b873e3e] new parent not found
2025/05/08 06:46:34 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/05/08 06:46:34 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/08 06:46:34 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171541128")
2025/05/08 06:46:35 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171541381")
2025/05/08 06:46:36 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171542129")
2025/05/08 06:46:36 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.rclone_chunk.001_xprgou"(0)|file|ITEM_UPLOAD|1856046441467|61782a0ab031a423be14763278efdff81389c110] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:36 DEBUG : box root 'rclone-test-cudinij0zazo': ["subdir"(0)|folder|ITEM_CREATE|320208582132|4636b270afad130845e706c5e3d2f08c713b81ab] new parent not found
2025/05/08 06:46:36 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:36 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171543132")
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171543370")
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.rclone_chunk.002_xprgou"(0)|file|ITEM_UPLOAD|1856041626355|456308091e1f8fe25083287769849a5f1097ed8e] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171544133")
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.copy1.txt"(0)|file|ITEM_UPLOAD|1856041369789|85167d3f1597e95e4ef4d1d5a284daf42f7173a6] new parent not found
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:37 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171544375")
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171544607")
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': ["file20.txt"(0)|file|ITEM_UPLOAD|1856047165934|86b17968ab99ae4a2907c9e5f62d98b527689050] new parent not found
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.txt"(0)|file|ITEM_UPLOAD|1856037104943|f90772063afe8cc8679c4588892c752746efb274] new parent not found
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': ["RCLONE_TEST"(0)|file|ITEM_UPLOAD|1856041244464|a06ce6a86d1c33dde1834e8dc831f09723aef88b] new parent not found
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.copy3.txt"(0)|file|ITEM_UPLOAD|1856042080785|30f12f660a7793051a8d5b846d98da11d03a7b31] new parent not found
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Received 4 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171545135")
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1856041626355|91c1aef0c880e79865cf14dd68711da10869679a] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171545383")
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.copy5.txt"(0)|file|ITEM_UPLOAD|1856047816057|ba579a03a90d35c050193bf3bf2d7031f98a387a] new parent not found
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:38 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171545612")
2025/05/08 06:46:39 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171545853")
2025/05/08 06:46:39 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1856046441467|611e61401e584a59fafc776e1f467fc952a9b9af] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:39 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.copy2.txt"(0)|file|ITEM_UPLOAD|1856042899036|a31f8f7d10e958eeda84ffe99dbfc361dcd5a546] new parent not found
2025/05/08 06:46:39 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:39 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171546135")
2025/05/08 06:46:40 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171546416")
2025/05/08 06:46:40 INFO  : dir/file4.rclone_chunk.001_xproif: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/05/08 06:46:40 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.copy4.txt"(0)|file|ITEM_UPLOAD|1856038611927|8c49f7ab9f80702eae539ffd3bb94e38c688cf96] new parent not found
2025/05/08 06:46:40 DEBUG : box root 'rclone-test-cudinij0zazo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1856045411595|41a4f9d0f33695da659904a304fc5ec918076748] new parent not found
2025/05/08 06:46:40 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:40 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171547137")
2025/05/08 06:46:40 INFO  : dir/file4.rclone_chunk.002_xproif: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/05/08 06:46:41 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171547377")
2025/05/08 06:46:42 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171548132")
2025/05/08 06:46:43 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171549137")
2025/05/08 06:46:43 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2"(0)|file|ITEM_UPLOAD|1856047734981|1a784a56cdd59c55beb5fe3939e9394f7e53877b] ignored due to old SequenceID ('\x00')
2025/05/08 06:46:43 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:43 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171550133")
2025/05/08 06:46:44 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171550365")
2025/05/08 06:46:44 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.rclone_chunk.001_xproif"(0)|file|ITEM_UPLOAD|1856039912809|c9b84cdad79cd26d82aa7eace61bfdfc0671b906] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:44 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:44 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171551154")
2025/05/08 06:46:45 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171551397")
2025/05/08 06:46:45 DEBUG : box root 'rclone-test-cudinij0zazo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1856042186385|fbcebb87d7b84374036dd9a48257d56c43019b4c] new parent not found
2025/05/08 06:46:45 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.rclone_chunk.002_xproif"(0)|file|ITEM_UPLOAD|1856044746017|5a9448c4c561d0e679d88a0786586d6ec4d6fb73] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:45 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:45 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171552140")
2025/05/08 06:46:46 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171552395")
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171553138")
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1856039912809|101e3565f8725d90bbd73f7d71d08c90e47d060f] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171554137")
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1856044746017|cd2d21f7af24edf9c4fd14fdb2f0f81fd8a022f4] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:47 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171554375")
2025/05/08 06:46:48 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171554629")
2025/05/08 06:46:48 INFO  : dir/file3.rclone_chunk.001_xprx8j: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/05/08 06:46:49 INFO  : dir/file3.rclone_chunk.002_xprx8j: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/05/08 06:46:49 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171555137")
2025/05/08 06:46:50 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171556136")
2025/05/08 06:46:51 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171557800")
2025/05/08 06:46:52 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171558169")
2025/05/08 06:46:52 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4"(0)|file|ITEM_UPLOAD|1856036070722|6156e1b8fe23f4d0d3b83821a251a3b964fe0b87] ignored due to old SequenceID ('\x00')
2025/05/08 06:46:52 DEBUG : box root 'rclone-test-cudinij0zazo': ["file3.rclone_chunk.001_xprx8j"(0)|file|ITEM_UPLOAD|1856042339932|6a404d2a647ca034ec1805296405fd6d89acca63] added new path ("dir/file3.rclone_chunk.001_xprx8j") for notify
2025/05/08 06:46:52 DEBUG : doChangeNotify("dir/file3.rclone_chunk.001_xprx8j", 1)
2025/05/08 06:46:52 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/08 06:46:52 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171559134")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/05/08 06:46:53 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171559385")
2025/05/08 06:46:53 DEBUG : box root 'rclone-test-cudinij0zazo': ["file3.rclone_chunk.002_xprx8j"(0)|file|ITEM_UPLOAD|1856041305161|6b61c2c42d54f42912d050c413d7a89bd6b352f0] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:53 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:53 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171560133")
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171560372")
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': ["file20.txt"(1)|file|ITEM_UPLOAD|1856047165934|09227218bc9c3d025063c256ec78f05bc1ecce41] new parent not found
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171561135")
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1856042339932|097936b07c675fcd377f3af0eecf269eb046160e] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171561378")
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1856035182955|84d2669884db287a9cfbe61b94adb88308832ecc] new parent not found
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:54 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171561606")
2025/05/08 06:46:55 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171561854")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/05/08 06:46:56 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171562146")
2025/05/08 06:46:57 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171563135")
2025/05/08 06:46:57 DEBUG : box root 'rclone-test-cudinij0zazo': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1856041305161|db4b2e4d4bbbb515a815f3f7f2eeeb344783a9c3] ignored due to old SequenceID ('\x01')
2025/05/08 06:46:57 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:57 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171564126")
2025/05/08 06:46:58 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171564380")
2025/05/08 06:46:58 DEBUG : box root 'rclone-test-cudinij0zazo': ["file3"(0)|file|ITEM_UPLOAD|1856039419115|f7e31ce7c803fffad8f284deb27a6032184c18d6] ignored due to old SequenceID ('\x00')
2025/05/08 06:46:58 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:58 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171565142")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/05/08 06:46:59 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171565387")
2025/05/08 06:46:59 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.txt"(1)|file|ITEM_UPLOAD|1856037104943|7e3cc1dc9c24e97fef8a285f4b7b428e428acf8d] new parent not found
2025/05/08 06:46:59 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:46:59 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171566130")
2025/05/08 06:47:00 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171566372")
2025/05/08 06:47:01 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171567133")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/05/08 06:47:02 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171568145")
2025/05/08 06:47:03 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171569129")
2025/05/08 06:47:04 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171570135")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171571139")
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Received 6 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171572122")
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171572383")
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:05 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171572623")
2025/05/08 06:47:06 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171572882")
2025/05/08 06:47:06 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:06 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171573133")
2025/05/08 06:47:07 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171573366")
2025/05/08 06:47:07 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:07 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171574133")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/05/08 06:47:08 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171574368")
2025/05/08 06:47:09 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171575135")
2025/05/08 06:47:10 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171576125")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/05/08 06:47:11 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171577168")
2025/05/08 06:47:12 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171578135")
2025/05/08 06:47:13 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171579127")
2025/05/08 06:47:13 DEBUG : box root 'rclone-test-cudinij0zazo': ["max_delete_path1"(0)|folder|ITEM_CREATE|320207247790|90db820199aa5dc8ae99c828f89b7370b028d0b7] new parent not found
2025/05/08 06:47:13 DEBUG : box root 'rclone-test-cudinij0zazo': ["path1"(0)|folder|ITEM_CREATE|320205707720|6ba84a493228eb2dba05659fc5e2c1d0a5c7b260] new parent not found
2025/05/08 06:47:13 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:13 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171580130")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/05/08 06:47:14 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171580393")
2025/05/08 06:47:15 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171581134")
2025/05/08 06:47:15 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.txt"(0)|file|ITEM_UPLOAD|1856037916043|60dc8bbc159e11570c9ad9f6060817f3da34d224] new parent not found
2025/05/08 06:47:15 DEBUG : box root 'rclone-test-cudinij0zazo': ["file3.txt"(0)|file|ITEM_UPLOAD|1856037565774|c5e75748d9adf385cb4286fefe28101bad2020bf] new parent not found
2025/05/08 06:47:15 DEBUG : box root 'rclone-test-cudinij0zazo': ["file1.txt"(0)|file|ITEM_UPLOAD|1856037553608|47f122268aabc237920a471f54abcb673173a9eb] new parent not found
2025/05/08 06:47:15 DEBUG : box root 'rclone-test-cudinij0zazo': Received 3 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:15 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171582125")
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171582375")
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.txt"(0)|file|ITEM_UPLOAD|1856047617134|7056fed718d844e9431efb05060527b0acae0c48] new parent not found
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': ["file6.txt"(0)|file|ITEM_UPLOAD|1856034886808|6ddfc0c8480fc0b10332d5398b102337c5da6071] new parent not found
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171583124")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': ["file8.txt"(0)|file|ITEM_UPLOAD|1856041231012|4c4e4574707bfa68773bf68252ff3f7d2c03bb5d] new parent not found
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:16 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171583357")
2025/05/08 06:47:17 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171583608")
2025/05/08 06:47:17 DEBUG : box root 'rclone-test-cudinij0zazo': ["file5.txt"(0)|file|ITEM_UPLOAD|1856048618345|b44572b2c00811e874b2954c005e65861fca02dd] new parent not found
2025/05/08 06:47:17 DEBUG : box root 'rclone-test-cudinij0zazo': ["file7.txt"(0)|file|ITEM_UPLOAD|1856042111059|14be1c1e79f49e5f139a180811b011057c184c18] new parent not found
2025/05/08 06:47:17 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:17 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171584528")
2025/05/08 06:47:18 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171584768")
2025/05/08 06:47:18 DEBUG : box root 'rclone-test-cudinij0zazo': ["file9.txt"(0)|file|ITEM_UPLOAD|1856048070698|385ad37b827edbbaeeefd3daca8936a2d3619c06] new parent not found
2025/05/08 06:47:18 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:18 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171585132")
2025/05/08 06:47:19 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171585365")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file3.rclone_chunk.001_xprx8j:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/05/08 06:47:20 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171586130")
2025/05/08 06:47:20 DEBUG : box root 'rclone-test-cudinij0zazo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1856039074819|e946c4c959d6ffe9237aeeb51289705a12010bbe] new parent not found
2025/05/08 06:47:20 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:20 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171587140")
2025/05/08 06:47:20 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:20 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171587374")
2025/05/08 06:47:21 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171587603")
2025/05/08 06:47:22 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171588132")
2025/05/08 06:47:23 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171589132")
2025/05/08 06:47:24 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171590124")
2025/05/08 06:47:25 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171591130")
2025/05/08 06:47:25 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:25 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171592149")
2025/05/08 06:47:26 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171592375")
2025/05/08 06:47:27 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171593134")
2025/05/08 06:47:28 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171594133")
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171595130")
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1856046441467|52cba883459df222f5a73c102c624ffbf4e389db] added old path ("dir/file2.rclone_chunk.001") for notify
2025/05/08 06:47:29 DEBUG : doChangeNotify("dir/file2", 1)
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171596130")
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1856041626355|cddb67f5f76730c13a152364fa51c069060161ac] added old path ("dir/file2.rclone_chunk.002") for notify
2025/05/08 06:47:29 DEBUG : doChangeNotify("dir/file2", 1)
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/08 06:47:29 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171596356")
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171596613")
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171597134")
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171597365")
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4"(1)|file|ITEM_TRASH|1856036070722|a7d53bb4071912942da7c755fd45bb01a2b10f0a] added old path ("dir/file4") for notify
2025/05/08 06:47:30 DEBUG : doChangeNotify("dir/file4", 1)
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Received 2 events, resulting in 1 paths and 1 notifications
2025/05/08 06:47:30 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171597612")
2025/05/08 06:47:31 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171597854")
2025/05/08 06:47:32 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171598141")
2025/05/08 06:47:32 DEBUG : box root 'rclone-test-cudinij0zazo': ["file2"(1)|file|ITEM_TRASH|1856047734981|9f03df881582bd1706e786f28df20d894d041a31] added old path ("dir/file2") for notify
2025/05/08 06:47:32 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1856039912809|38ad621250b6e9eccb7c871bd6e3ca58754c7b02] added old path ("dir/file4.rclone_chunk.001") for notify
2025/05/08 06:47:32 DEBUG : doChangeNotify("dir/file2", 1)
2025/05/08 06:47:32 DEBUG : doChangeNotify("dir/file4", 1)
2025/05/08 06:47:32 DEBUG : box root 'rclone-test-cudinij0zazo': Received 3 events, resulting in 2 paths and 2 notifications
2025/05/08 06:47:32 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171599130")
2025/05/08 06:47:33 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171599372")
2025/05/08 06:47:34 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171600135")
2025/05/08 06:47:34 DEBUG : box root 'rclone-test-cudinij0zazo': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1856044746017|673ceaacd72f0f94718e0216d5be3a5592b4ac8b] added old path ("dir/file4.rclone_chunk.002") for notify
2025/05/08 06:47:34 DEBUG : doChangeNotify("dir/file4", 1)
2025/05/08 06:47:34 DEBUG : box root 'rclone-test-cudinij0zazo': Received 1 events, resulting in 1 paths and 1 notifications
2025/05/08 06:47:34 DEBUG : box root 'rclone-test-cudinij0zazo': Checking for changes on remote (next_stream_position: "30401044171601135")
2025/05/08 06:47:36 DEBUG : box root 'rclone-test-cudinij0zazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/05/08 06:47:36 ERROR : : error listing: directory not found
2025/05/08 06:47:36 DEBUG : box root 'rclone-test-cudinij0zazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (80.19s)
    --- FAIL: TestIntegration/FsMkdir (76.09s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (73.86s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	80.221s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m23.339140418s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]