"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/03/31 05:06:42 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-quqitez5fazo"
2025/03/31 05:06:42 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/31 05:06:42 DEBUG : Creating backend with remote "TestBox:rclone-test-quqitez5fazo"
2025/03/31 05:06:43 DEBUG : box root 'rclone-test-quqitez5fazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/31 05:06:43 DEBUG : Reset feature "ListR"
2025/03/31 05:06:43 DEBUG : box root 'rclone-test-quqitez5fazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/31 05:06:45 INFO  : dir: Making directory
2025/03/31 05:06:47 INFO  : dir/subdir1: Making directory
2025/03/31 05:06:48 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882354094")
2025/03/31 05:06:48 DEBUG : box root 'rclone-test-quqitez5fazo': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/31 05:06:48 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882355404")
2025/03/31 05:06:49 INFO  : dir/subdir3: Making directory
2025/03/31 05:06:49 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882356166")
2025/03/31 05:06:50 INFO  : dir/subdir2: Making directory
2025/03/31 05:06:50 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882356428")
2025/03/31 05:06:51 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882357416")
2025/03/31 05:06:51 DEBUG : box root 'rclone-test-quqitez5fazo': ["rclone-test-quqitez5fazo"(0)|folder|ITEM_CREATE|314276020786|62c481c8c436fc62dd54a94f37c5a3adf01ac7ad] new parent not found
2025/03/31 05:06:51 DEBUG : box root 'rclone-test-quqitez5fazo': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/31 05:06:51 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882358399")
2025/03/31 05:06:52 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882358637")
2025/03/31 05:06:52 DEBUG : box root 'rclone-test-quqitez5fazo': ["dir"(0)|folder|ITEM_CREATE|314272609652|19712529cfd31e9b27e070e377c0b802bb225052] added new path ("dir") for notify
2025/03/31 05:06:52 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir1"(0)|folder|ITEM_CREATE|314273024880|37e8ea5ecbfe956d1c58319cdb1e63f11f81b1e9] added new path ("dir/subdir1") for notify
2025/03/31 05:06:52 DEBUG : doChangeNotify("dir", 0)
2025/03/31 05:06:52 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/31 05:06:52 DEBUG : box root 'rclone-test-quqitez5fazo': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/31 05:06:52 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882359422")
2025/03/31 05:06:53 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882359670")
2025/03/31 05:06:53 DEBUG : box root 'rclone-test-quqitez5fazo': ["dir"(0)|folder|ITEM_CREATE|314272609652|19712529cfd31e9b27e070e377c0b802bb225052] ignored due to duplicate EventID
2025/03/31 05:06:54 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882360433")
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882361402")
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir1"(0)|folder|ITEM_CREATE|314273024880|37e8ea5ecbfe956d1c58319cdb1e63f11f81b1e9] ignored due to duplicate EventID
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir2"(0)|folder|ITEM_CREATE|314276053679|b0f6d9895e5dd425b43a47f422cf5972f7877e49] added new path ("dir/subdir2") for notify
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir3"(0)|folder|ITEM_CREATE|314273979944|721beb2865af279520a8abb75173c711b5b6740c] added new path ("dir/subdir3") for notify
2025/03/31 05:06:55 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/31 05:06:55 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': Received 3 events, resulting in 2 paths and 2 notifications
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882362421")
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir2"(0)|folder|ITEM_CREATE|314276053679|b0f6d9895e5dd425b43a47f422cf5972f7877e49] ignored due to duplicate EventID
2025/03/31 05:06:55 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir3"(0)|folder|ITEM_CREATE|314273979944|721beb2865af279520a8abb75173c711b5b6740c] ignored due to duplicate EventID
2025/03/31 05:06:56 INFO  : dir/file2.rclone_chunk.001_z7t22t: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/31 05:06:56 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882362666")
2025/03/31 05:06:56 INFO  : dir/file2.rclone_chunk.002_z7t22t: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/31 05:06:56 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir2"(0)|folder|ITEM_CREATE|314276053679|b0f6d9895e5dd425b43a47f422cf5972f7877e49] ignored due to duplicate EventID
2025/03/31 05:06:56 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir3"(0)|folder|ITEM_CREATE|314273979944|721beb2865af279520a8abb75173c711b5b6740c] ignored due to duplicate EventID
2025/03/31 05:06:57 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882363402")
2025/03/31 05:06:57 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir2"(0)|folder|ITEM_CREATE|314276053679|b0f6d9895e5dd425b43a47f422cf5972f7877e49] ignored due to duplicate EventID
2025/03/31 05:06:57 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir3"(0)|folder|ITEM_CREATE|314273979944|721beb2865af279520a8abb75173c711b5b6740c] ignored due to duplicate EventID
2025/03/31 05:06:58 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882364403")
2025/03/31 05:06:58 DEBUG : box root 'rclone-test-quqitez5fazo': ["subdir3"(0)|folder|ITEM_CREATE|314273979944|721beb2865af279520a8abb75173c711b5b6740c] ignored due to duplicate EventID
2025/03/31 05:06:58 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819092997288|9ff93ee3caa126f12c81b4e3c653281881ee34d8] added new path ("dir/file2.rclone_chunk.002") for notify
2025/03/31 05:06:58 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:06:58 DEBUG : box root 'rclone-test-quqitez5fazo': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/31 05:06:58 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882365411")
2025/03/31 05:06:59 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882365663")
2025/03/31 05:07:00 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882366428")
2025/03/31 05:07:00 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819092997288|9ff93ee3caa126f12c81b4e3c653281881ee34d8] ignored due to duplicate EventID
2025/03/31 05:07:00 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002_z7t22t"(0)|file|ITEM_UPLOAD|1819092997288|bdc785b9c9b5c7a148785f246a4723058ac527a4] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:00 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819080188438|eacaec9f89cb05eb6999d2f96d1d4e35cf9a4a0c] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:00 DEBUG : box root 'rclone-test-quqitez5fazo': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/31 05:07:00 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882367556")
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882367825")
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819092997288|9ff93ee3caa126f12c81b4e3c653281881ee34d8] ignored due to duplicate EventID
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002_z7t22t"(0)|file|ITEM_UPLOAD|1819092997288|bdc785b9c9b5c7a148785f246a4723058ac527a4] ignored due to duplicate EventID
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819080188438|eacaec9f89cb05eb6999d2f96d1d4e35cf9a4a0c] ignored due to duplicate EventID
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001_z7t22t"(0)|file|ITEM_UPLOAD|1819080188438|f8ebbaab4cdbe7a478ad7c75cf4e7efcf493f82e] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/31 05:07:01 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882368530")
2025/03/31 05:07:02 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882368894")
2025/03/31 05:07:02 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1819092997288|9ff93ee3caa126f12c81b4e3c653281881ee34d8] ignored due to duplicate EventID
2025/03/31 05:07:03 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882369611")
2025/03/31 05:07:03 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002_z7t22t"(0)|file|ITEM_UPLOAD|1819092997288|bdc785b9c9b5c7a148785f246a4723058ac527a4] ignored due to duplicate EventID
2025/03/31 05:07:03 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819080188438|eacaec9f89cb05eb6999d2f96d1d4e35cf9a4a0c] ignored due to duplicate EventID
2025/03/31 05:07:03 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001_z7t22t"(0)|file|ITEM_UPLOAD|1819080188438|f8ebbaab4cdbe7a478ad7c75cf4e7efcf493f82e] ignored due to duplicate EventID
2025/03/31 05:07:04 INFO  : dir/file4.rclone_chunk.001_z7tap8: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/31 05:07:04 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882370407")
2025/03/31 05:07:04 INFO  : dir/file4.rclone_chunk.002_z7tap8: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/31 05:07:04 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002_z7t22t"(0)|file|ITEM_UPLOAD|1819092997288|bdc785b9c9b5c7a148785f246a4723058ac527a4] ignored due to duplicate EventID
2025/03/31 05:07:04 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1819080188438|eacaec9f89cb05eb6999d2f96d1d4e35cf9a4a0c] ignored due to duplicate EventID
2025/03/31 05:07:05 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882371426")
2025/03/31 05:07:05 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001_z7t22t"(0)|file|ITEM_UPLOAD|1819080188438|f8ebbaab4cdbe7a478ad7c75cf4e7efcf493f82e] ignored due to duplicate EventID
2025/03/31 05:07:05 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001_z7tap8"(0)|file|ITEM_UPLOAD|1819094769331|70a9bff789002ede9821d2956968a7be95d9d677] added new path ("dir/file4.rclone_chunk.001_z7tap8") for notify
2025/03/31 05:07:05 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2"(0)|file|ITEM_UPLOAD|1819084857274|1715536f1987aa0b2be8ea143e07825ea52e20c1] ignored due to old SequenceID ('\x00')
2025/03/31 05:07:05 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_z7tap8", 1)
2025/03/31 05:07:05 DEBUG : box root 'rclone-test-quqitez5fazo': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/31 05:07:05 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882372417")
2025/03/31 05:07:06 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882372670")
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882373426")
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001_z7tap8"(0)|file|ITEM_UPLOAD|1819094769331|70a9bff789002ede9821d2956968a7be95d9d677] ignored due to duplicate EventID
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2"(0)|file|ITEM_UPLOAD|1819084857274|1715536f1987aa0b2be8ea143e07825ea52e20c1] ignored due to duplicate EventID
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819094769331|74d3439e3fd746a26740a2051f32d27d1336a8f4] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819094006031|23759a662853971f0447b5dc20ea17ad1a45bfdd] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/31 05:07:07 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882374405")
2025/03/31 05:07:08 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882374677")
2025/03/31 05:07:08 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001_z7tap8"(0)|file|ITEM_UPLOAD|1819094769331|70a9bff789002ede9821d2956968a7be95d9d677] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882375392")
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2"(0)|file|ITEM_UPLOAD|1819084857274|1715536f1987aa0b2be8ea143e07825ea52e20c1] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819094769331|74d3439e3fd746a26740a2051f32d27d1336a8f4] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819094006031|23759a662853971f0447b5dc20ea17ad1a45bfdd] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002_z7tap8"(0)|file|ITEM_UPLOAD|1819094006031|73e2e8c275d554d4083a26ddfb2c57dc59c90386] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4"(0)|file|ITEM_UPLOAD|1819097142305|3205d706d93cdbad3d695aab5b5db68440bbb41d] ignored due to old SequenceID ('\x00')
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882376394")
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1819094769331|74d3439e3fd746a26740a2051f32d27d1336a8f4] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819094006031|23759a662853971f0447b5dc20ea17ad1a45bfdd] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002_z7tap8"(0)|file|ITEM_UPLOAD|1819094006031|73e2e8c275d554d4083a26ddfb2c57dc59c90386] ignored due to duplicate EventID
2025/03/31 05:07:09 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4"(0)|file|ITEM_UPLOAD|1819097142305|3205d706d93cdbad3d695aab5b5db68440bbb41d] ignored due to duplicate EventID
2025/03/31 05:07:10 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882376672")
2025/03/31 05:07:10 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1819094006031|23759a662853971f0447b5dc20ea17ad1a45bfdd] ignored due to duplicate EventID
2025/03/31 05:07:11 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882377400")
2025/03/31 05:07:12 INFO  : dir/file3.rclone_chunk.001_z7tiql: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/31 05:07:12 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882378393")
2025/03/31 05:07:12 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002_z7tap8"(0)|file|ITEM_UPLOAD|1819094006031|73e2e8c275d554d4083a26ddfb2c57dc59c90386] ignored due to duplicate EventID
2025/03/31 05:07:12 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4"(0)|file|ITEM_UPLOAD|1819097142305|3205d706d93cdbad3d695aab5b5db68440bbb41d] ignored due to duplicate EventID
2025/03/31 05:07:12 INFO  : dir/file3.rclone_chunk.002_z7tiql: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/31 05:07:13 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882379409")
2025/03/31 05:07:14 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882380400")
2025/03/31 05:07:14 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.001_z7tiql"(0)|file|ITEM_UPLOAD|1819096283810|cf7ccc82cdfdd7613980581a55277a638abd043e] added new path ("dir/file3.rclone_chunk.001_z7tiql") for notify
2025/03/31 05:07:14 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.002_z7tiql"(0)|file|ITEM_UPLOAD|1819086683087|b4f0e72f60b44aa9b6432973b139d2580e416a95] added new path ("dir/file3.rclone_chunk.002_z7tiql") for notify
2025/03/31 05:07:14 DEBUG : doChangeNotify("dir/file3.rclone_chunk.001_z7tiql", 1)
2025/03/31 05:07:14 DEBUG : doChangeNotify("dir/file3.rclone_chunk.002_z7tiql", 1)
2025/03/31 05:07:14 DEBUG : box root 'rclone-test-quqitez5fazo': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/31 05:07:14 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882381403")
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882381692")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.001_z7tiql"(0)|file|ITEM_UPLOAD|1819096283810|cf7ccc82cdfdd7613980581a55277a638abd043e] ignored due to duplicate EventID
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.002_z7tiql"(0)|file|ITEM_UPLOAD|1819086683087|b4f0e72f60b44aa9b6432973b139d2580e416a95] ignored due to duplicate EventID
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819096283810|f9562274e12508baade912ba0de4c37cf40dbaf0] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819086683087|706ccc668c0a6924af9b314d9c1acaca862dcf3d] ignored due to old SequenceID ('\x01')
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/31 05:07:15 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882382401")
2025/03/31 05:07:16 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882382656")
2025/03/31 05:07:16 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.001_z7tiql"(0)|file|ITEM_UPLOAD|1819096283810|cf7ccc82cdfdd7613980581a55277a638abd043e] ignored due to duplicate EventID
2025/03/31 05:07:17 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882383403")
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882384410")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.002_z7tiql"(0)|file|ITEM_UPLOAD|1819086683087|b4f0e72f60b44aa9b6432973b139d2580e416a95] ignored due to duplicate EventID
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819096283810|f9562274e12508baade912ba0de4c37cf40dbaf0] ignored due to duplicate EventID
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819086683087|706ccc668c0a6924af9b314d9c1acaca862dcf3d] ignored due to duplicate EventID
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3"(0)|file|ITEM_UPLOAD|1819095177360|e355f9c1aeb84ba01a6946543070dcb1fb358534] ignored due to old SequenceID ('\x00')
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': Received 4 events, resulting in 0 paths and 0 notifications
2025/03/31 05:07:18 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882385403")
2025/03/31 05:07:19 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882385665")
2025/03/31 05:07:19 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1819096283810|f9562274e12508baade912ba0de4c37cf40dbaf0] ignored due to duplicate EventID
2025/03/31 05:07:19 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1819086683087|706ccc668c0a6924af9b314d9c1acaca862dcf3d] ignored due to duplicate EventID
2025/03/31 05:07:19 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3"(0)|file|ITEM_UPLOAD|1819095177360|e355f9c1aeb84ba01a6946543070dcb1fb358534] ignored due to duplicate EventID
2025/03/31 05:07:20 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882386405")
2025/03/31 05:07:21 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882387403")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/31 05:07:21 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3"(0)|file|ITEM_UPLOAD|1819095177360|e355f9c1aeb84ba01a6946543070dcb1fb358534] ignored due to duplicate EventID
2025/03/31 05:07:22 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882388393")
2025/03/31 05:07:23 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882389408")
2025/03/31 05:07:24 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882390396")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/31 05:07:25 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882391396")
2025/03/31 05:07:26 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882392399")
2025/03/31 05:07:27 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882393398")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/31 05:07:28 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882394416")
2025/03/31 05:07:29 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882395404")
2025/03/31 05:07:30 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882396395")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/31 05:07:31 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882397399")
2025/03/31 05:07:32 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882398413")
2025/03/31 05:07:33 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882399407")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/31 05:07:34 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882400399")
2025/03/31 05:07:35 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882401402")
2025/03/31 05:07:36 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882402397")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/31 05:07:37 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882403396")
2025/03/31 05:07:38 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882404406")
2025/03/31 05:07:39 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882405395")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/31 05:07:40 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882406394")
2025/03/31 05:07:41 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882407397")
2025/03/31 05:07:42 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882408415")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file2:{} dir/file3.rclone_chunk.001_z7tiql:{} dir/file3.rclone_chunk.002_z7tiql:{} dir/file4.rclone_chunk.001_z7tap8:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/31 05:07:43 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882409406")
2025/03/31 05:07:44 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882410404")
2025/03/31 05:07:45 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882411408")
2025/03/31 05:07:46 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882412404")
2025/03/31 05:07:47 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882413397")
2025/03/31 05:07:48 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882414427")
2025/03/31 05:07:49 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882415395")
2025/03/31 05:07:50 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882416399")
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882417420")
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2"(1)|file|ITEM_TRASH|1819084857274|b5b5f7d93b752e9a15366975560ce8d3aeca67b8] added old path ("dir/file2") for notify
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819080188438|b0eb9e8a2c21ebd83aa7655a282cca1e066e0be5] added old path ("dir/file2.rclone_chunk.001") for notify
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819092997288|e275175c04d47006356c372fee98d85edf7fa4c7] added old path ("dir/file2.rclone_chunk.002") for notify
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4"(1)|file|ITEM_TRASH|1819097142305|1f609fa6a72c267b3d4e6b61911a1a0c535a39ac] added old path ("dir/file4") for notify
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819094769331|1f5a0d2b67f632525c9c254e5868c8f01fe22bab] added old path ("dir/file4.rclone_chunk.001") for notify
2025/03/31 05:07:51 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:07:51 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:07:51 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/31 05:07:51 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/31 05:07:51 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': Received 5 events, resulting in 5 paths and 5 notifications
2025/03/31 05:07:51 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882418399")
2025/03/31 05:07:52 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882418664")
2025/03/31 05:07:52 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1819080188438|b0eb9e8a2c21ebd83aa7655a282cca1e066e0be5] ignored due to duplicate EventID
2025/03/31 05:07:52 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819092997288|e275175c04d47006356c372fee98d85edf7fa4c7] ignored due to duplicate EventID
2025/03/31 05:07:52 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4"(1)|file|ITEM_TRASH|1819097142305|1f609fa6a72c267b3d4e6b61911a1a0c535a39ac] ignored due to duplicate EventID
2025/03/31 05:07:52 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819094769331|1f5a0d2b67f632525c9c254e5868c8f01fe22bab] ignored due to duplicate EventID
2025/03/31 05:07:53 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882419408")
2025/03/31 05:07:54 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882420401")
2025/03/31 05:07:54 DEBUG : box root 'rclone-test-quqitez5fazo': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1819092997288|e275175c04d47006356c372fee98d85edf7fa4c7] ignored due to duplicate EventID
2025/03/31 05:07:54 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4"(1)|file|ITEM_TRASH|1819097142305|1f609fa6a72c267b3d4e6b61911a1a0c535a39ac] ignored due to duplicate EventID
2025/03/31 05:07:55 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882421397")
2025/03/31 05:07:55 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1819094769331|1f5a0d2b67f632525c9c254e5868c8f01fe22bab] ignored due to duplicate EventID
2025/03/31 05:07:56 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882422402")
2025/03/31 05:07:56 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1819094006031|56b8ebb82f7c387e44a75e34767e90c40c14b2ab] added old path ("dir/file4.rclone_chunk.002") for notify
2025/03/31 05:07:56 DEBUG : box root 'rclone-test-quqitez5fazo': ["file3"(1)|file|ITEM_TRASH|1819095177360|1e2952a821222cf281d8996d4f4059a4a9d43d4a] added old path ("dir/file3") for notify
2025/03/31 05:07:56 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/31 05:07:56 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/31 05:07:56 DEBUG : box root 'rclone-test-quqitez5fazo': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/31 05:07:56 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882423407")
2025/03/31 05:07:57 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882423660")
2025/03/31 05:07:57 DEBUG : box root 'rclone-test-quqitez5fazo': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1819094006031|56b8ebb82f7c387e44a75e34767e90c40c14b2ab] ignored due to duplicate EventID
2025/03/31 05:07:58 DEBUG : box root 'rclone-test-quqitez5fazo': Checking for changes on remote (next_stream_position: "30401040882424397")
2025/03/31 05:08:00 DEBUG : box root 'rclone-test-quqitez5fazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/31 05:08:00 ERROR : : error listing: directory not found
2025/03/31 05:08:00 DEBUG : box root 'rclone-test-quqitez5fazo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (78.31s)
    --- FAIL: TestIntegration/FsMkdir (75.76s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (73.42s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	78.360s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m21.123439681s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]