"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 4/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestChunkerChunk50bBox:"
2025/03/09 03:16:53 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-vipewex3zaxe"
2025/03/09 03:16:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/09 03:16:53 DEBUG : Creating backend with remote "TestBox:rclone-test-vipewex3zaxe"
2025/03/09 03:16:53 DEBUG : box root 'rclone-test-vipewex3zaxe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/09 03:16:54 DEBUG : Reset feature "ListR"
2025/03/09 03:16:54 DEBUG : box root 'rclone-test-vipewex3zaxe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/09 03:16:55 INFO  : dir: Making directory
2025/03/09 03:16:57 INFO  : dir/subdir1: Making directory
2025/03/09 03:16:57 INFO  : dir/subdir3: Making directory
2025/03/09 03:16:58 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974963764")
2025/03/09 03:16:58 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/09 03:16:58 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974964981")
2025/03/09 03:16:58 INFO  : dir/subdir2: Making directory
2025/03/09 03:16:59 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974965203")
2025/03/09 03:16:59 DEBUG : box root 'rclone-test-vipewex3zaxe': ["rclone-test-vipewex3zaxe"(0)|folder|ITEM_CREATE|310792813956|6a09cf95c6dbf4375fe3d5578317f40ec1e6d49c] new parent not found
2025/03/09 03:16:59 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/09 03:16:59 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974965983")
2025/03/09 03:17:00 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974966206")
2025/03/09 03:17:01 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974966984")
2025/03/09 03:17:01 DEBUG : box root 'rclone-test-vipewex3zaxe': ["rclone-test-vipewex3zaxe"(0)|folder|ITEM_CREATE|310792813956|6a09cf95c6dbf4375fe3d5578317f40ec1e6d49c] ignored due to duplicate EventID
2025/03/09 03:17:01 DEBUG : box root 'rclone-test-vipewex3zaxe': ["subdir1"(0)|folder|ITEM_CREATE|310794469930|b42515b95fe686939eab72534f7b1b16e72abc43] added new path ("dir/subdir1") for notify
2025/03/09 03:17:01 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/09 03:17:01 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/09 03:17:01 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974967992")
2025/03/09 03:17:02 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974968217")
2025/03/09 03:17:02 DEBUG : box root 'rclone-test-vipewex3zaxe': ["rclone-test-vipewex3zaxe"(0)|folder|ITEM_CREATE|310792813956|6a09cf95c6dbf4375fe3d5578317f40ec1e6d49c] ignored due to duplicate EventID
2025/03/09 03:17:03 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974968982")
2025/03/09 03:17:03 INFO  : dir/file2.rclone_chunk.001_uc1rea: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/03/09 03:17:04 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974969984")
2025/03/09 03:17:04 INFO  : dir/file2.rclone_chunk.002_uc1rea: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/03/09 03:17:05 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974970981")
2025/03/09 03:17:05 DEBUG : box root 'rclone-test-vipewex3zaxe': ["subdir1"(0)|folder|ITEM_CREATE|310794469930|b42515b95fe686939eab72534f7b1b16e72abc43] ignored due to duplicate EventID
2025/03/09 03:17:05 DEBUG : box root 'rclone-test-vipewex3zaxe': ["subdir2"(0)|folder|ITEM_CREATE|310792475389|64ff442c704ca361d018440f88cc51be634d3741] added new path ("dir/subdir2") for notify
2025/03/09 03:17:05 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001_uc1rea"(0)|file|ITEM_UPLOAD|1797948251561|964ab0ffe6760b8aa4e35d9443d0a95a7ceb02b4] added new path ("dir/file2.rclone_chunk.001_uc1rea") for notify
2025/03/09 03:17:05 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/09 03:17:05 DEBUG : doChangeNotify("dir/file2.rclone_chunk.001_uc1rea", 1)
2025/03/09 03:17:05 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 3 events, resulting in 2 paths and 2 notifications
2025/03/09 03:17:05 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974971983")
2025/03/09 03:17:06 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974972207")
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974972980")
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': ["subdir2"(0)|folder|ITEM_CREATE|310792475389|64ff442c704ca361d018440f88cc51be634d3741] ignored due to duplicate EventID
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001_uc1rea"(0)|file|ITEM_UPLOAD|1797948251561|964ab0ffe6760b8aa4e35d9443d0a95a7ceb02b4] ignored due to duplicate EventID
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002_uc1rea"(0)|file|ITEM_UPLOAD|1797946163888|c7d812e7a9ecf4bfafa9f9adfbb7c91fa6497113] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1797948251561|99c68e0722100a5e286c670b9912e764d9cd9d4d] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1797946163888|4117ef3c25b2cfde19898ab075beb646e0fda47e] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 5 events, resulting in 0 paths and 0 notifications
2025/03/09 03:17:07 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974973981")
2025/03/09 03:17:08 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974974231")
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974974987")
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001_uc1rea"(0)|file|ITEM_UPLOAD|1797948251561|964ab0ffe6760b8aa4e35d9443d0a95a7ceb02b4] ignored due to duplicate EventID
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002_uc1rea"(0)|file|ITEM_UPLOAD|1797946163888|c7d812e7a9ecf4bfafa9f9adfbb7c91fa6497113] ignored due to duplicate EventID
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1797948251561|99c68e0722100a5e286c670b9912e764d9cd9d4d] ignored due to duplicate EventID
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1797946163888|4117ef3c25b2cfde19898ab075beb646e0fda47e] ignored due to duplicate EventID
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': ["dir"(0)|folder|ITEM_CREATE|310792684741|5168cd54eb93e94961c652ccebecdfd7692b5b54] added new path ("dir") for notify
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(0)|file|ITEM_UPLOAD|1797953461410|4439a3bf3a78b2dfe14540a4ab7769ab28935139] ignored due to old SequenceID ('\x00')
2025/03/09 03:17:09 DEBUG : doChangeNotify("dir", 0)
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 6 events, resulting in 1 paths and 1 notifications
2025/03/09 03:17:09 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974975981")
2025/03/09 03:17:10 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974976231")
2025/03/09 03:17:10 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002_uc1rea"(0)|file|ITEM_UPLOAD|1797946163888|c7d812e7a9ecf4bfafa9f9adfbb7c91fa6497113] ignored due to duplicate EventID
2025/03/09 03:17:10 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1797948251561|99c68e0722100a5e286c670b9912e764d9cd9d4d] ignored due to duplicate EventID
2025/03/09 03:17:10 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1797946163888|4117ef3c25b2cfde19898ab075beb646e0fda47e] ignored due to duplicate EventID
2025/03/09 03:17:10 DEBUG : box root 'rclone-test-vipewex3zaxe': ["dir"(0)|folder|ITEM_CREATE|310792684741|5168cd54eb93e94961c652ccebecdfd7692b5b54] ignored due to duplicate EventID
2025/03/09 03:17:10 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(0)|file|ITEM_UPLOAD|1797953461410|4439a3bf3a78b2dfe14540a4ab7769ab28935139] ignored due to duplicate EventID
2025/03/09 03:17:10 INFO  : dir/file4.rclone_chunk.001_uc1ym1: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974976988")
2025/03/09 03:17:11 INFO  : dir/file4.rclone_chunk.002_uc1ym1: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1797946163888|4117ef3c25b2cfde19898ab075beb646e0fda47e] ignored due to duplicate EventID
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': ["dir"(0)|folder|ITEM_CREATE|310792684741|5168cd54eb93e94961c652ccebecdfd7692b5b54] ignored due to duplicate EventID
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(0)|file|ITEM_UPLOAD|1797953461410|4439a3bf3a78b2dfe14540a4ab7769ab28935139] ignored due to duplicate EventID
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': ["subdir3"(0)|folder|ITEM_CREATE|310792485249|8e1b14ed17a28cfc3064253c80d623fa1de21a39] ignored due to old SequenceID ('\x00')
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001_uc1ym1"(0)|file|ITEM_UPLOAD|1797932579308|f2277d107ebbfd0246236173a59511b63e35dd23] added new path ("dir/file4.rclone_chunk.001_uc1ym1") for notify
2025/03/09 03:17:11 DEBUG : doChangeNotify("dir/file4.rclone_chunk.001_uc1ym1", 1)
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 5 events, resulting in 1 paths and 1 notifications
2025/03/09 03:17:11 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974977982")
2025/03/09 03:17:12 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974978217")
2025/03/09 03:17:12 DEBUG : box root 'rclone-test-vipewex3zaxe': ["dir"(0)|folder|ITEM_CREATE|310792684741|5168cd54eb93e94961c652ccebecdfd7692b5b54] ignored due to duplicate EventID
2025/03/09 03:17:12 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(0)|file|ITEM_UPLOAD|1797953461410|4439a3bf3a78b2dfe14540a4ab7769ab28935139] ignored due to duplicate EventID
2025/03/09 03:17:13 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974978983")
2025/03/09 03:17:14 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974979980")
2025/03/09 03:17:15 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974980981")
2025/03/09 03:17:15 DEBUG : box root 'rclone-test-vipewex3zaxe': ["subdir3"(0)|folder|ITEM_CREATE|310792485249|8e1b14ed17a28cfc3064253c80d623fa1de21a39] ignored due to duplicate EventID
2025/03/09 03:17:15 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001_uc1ym1"(0)|file|ITEM_UPLOAD|1797932579308|f2277d107ebbfd0246236173a59511b63e35dd23] ignored due to duplicate EventID
2025/03/09 03:17:16 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974981985")
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974982983")
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.002_uc1ym1"(0)|file|ITEM_UPLOAD|1797934989366|8e22012ecb6cf6eef00bb739b3de525a679e7ab7] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974983983")
2025/03/09 03:17:17 INFO  : dir/file3.rclone_chunk.001_uc254c: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1797932579308|d9017fbe28e6820ae56f7bc4c78c1a2b032b2238] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1797934989366|fb8fc0eff70078970720288b7466dc54afd61727] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(0)|file|ITEM_UPLOAD|1797942582893|fbe41d59a0dce526c82eb892f98fc76e2ff70579] ignored due to old SequenceID ('\x00')
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/09 03:17:17 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974984200")
2025/03/09 03:17:17 INFO  : dir/file3.rclone_chunk.002_uc254c: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/03/09 03:17:18 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974984431")
2025/03/09 03:17:18 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1797932579308|d9017fbe28e6820ae56f7bc4c78c1a2b032b2238] ignored due to duplicate EventID
2025/03/09 03:17:19 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974984981")
2025/03/09 03:17:19 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1797934989366|fb8fc0eff70078970720288b7466dc54afd61727] ignored due to duplicate EventID
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974985986")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(0)|file|ITEM_UPLOAD|1797942582893|fbe41d59a0dce526c82eb892f98fc76e2ff70579] ignored due to duplicate EventID
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001_uc254c"(0)|file|ITEM_UPLOAD|1797937902243|e159d9e0cb2a696c12f485082d0bd954f9eb73af] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.002_uc254c"(0)|file|ITEM_UPLOAD|1797947982648|66221f7d6cd65076a4ac4627fff2598f9538b20c] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974986983")
2025/03/09 03:17:20 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(0)|file|ITEM_UPLOAD|1797942582893|fbe41d59a0dce526c82eb892f98fc76e2ff70579] ignored due to duplicate EventID
2025/03/09 03:17:21 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974987210")
2025/03/09 03:17:22 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974987987")
2025/03/09 03:17:23 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974988981")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/09 03:17:23 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001_uc254c"(0)|file|ITEM_UPLOAD|1797937902243|e159d9e0cb2a696c12f485082d0bd954f9eb73af] ignored due to duplicate EventID
2025/03/09 03:17:24 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974989983")
2025/03/09 03:17:24 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.002_uc254c"(0)|file|ITEM_UPLOAD|1797947982648|66221f7d6cd65076a4ac4627fff2598f9538b20c] ignored due to duplicate EventID
2025/03/09 03:17:25 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974990979")
2025/03/09 03:17:25 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3"(0)|file|ITEM_UPLOAD|1797944238111|e63d13776e6d5c16d3cfa98664b57fdefaede02d] ignored due to old SequenceID ('\x00')
2025/03/09 03:17:25 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/09 03:17:25 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974991981")
2025/03/09 03:17:25 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3"(0)|file|ITEM_UPLOAD|1797944238111|e63d13776e6d5c16d3cfa98664b57fdefaede02d] ignored due to duplicate EventID
2025/03/09 03:17:26 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974992202")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/09 03:17:27 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974992980")
2025/03/09 03:17:27 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3"(0)|file|ITEM_UPLOAD|1797944238111|e63d13776e6d5c16d3cfa98664b57fdefaede02d] ignored due to duplicate EventID
2025/03/09 03:17:28 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974993980")
2025/03/09 03:17:28 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1797947982648|0f583972fda5c18acdd8b01a38be0d8f5380caf9] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:28 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1797937902243|b76ef93c8e626f1defdee9f2d79eef6a3d44d035] ignored due to old SequenceID ('\x01')
2025/03/09 03:17:28 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/09 03:17:28 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974994984")
2025/03/09 03:17:29 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974995216")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/09 03:17:30 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974995979")
2025/03/09 03:17:31 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974996980")
2025/03/09 03:17:31 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1797947982648|0f583972fda5c18acdd8b01a38be0d8f5380caf9] ignored due to duplicate EventID
2025/03/09 03:17:31 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1797937902243|b76ef93c8e626f1defdee9f2d79eef6a3d44d035] ignored due to duplicate EventID
2025/03/09 03:17:32 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974997982")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/09 03:17:32 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1797947982648|0f583972fda5c18acdd8b01a38be0d8f5380caf9] ignored due to duplicate EventID
2025/03/09 03:17:32 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1797937902243|b76ef93c8e626f1defdee9f2d79eef6a3d44d035] ignored due to duplicate EventID
2025/03/09 03:17:33 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974998980")
2025/03/09 03:17:34 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038974999983")
2025/03/09 03:17:35 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975000980")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/09 03:17:36 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975001980")
2025/03/09 03:17:37 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975002988")
2025/03/09 03:17:38 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975003980")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/03/09 03:17:39 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975004981")
2025/03/09 03:17:40 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975005979")
2025/03/09 03:17:41 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975006982")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/03/09 03:17:42 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975007984")
2025/03/09 03:17:43 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975008980")
2025/03/09 03:17:44 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975009987")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/03/09 03:17:45 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975010981")
2025/03/09 03:17:46 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975011979")
2025/03/09 03:17:47 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975012979")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file2.rclone_chunk.001_uc1rea:{} dir/file4.rclone_chunk.001_uc1ym1:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/03/09 03:17:48 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975013982")
2025/03/09 03:17:49 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975014984")
2025/03/09 03:17:50 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975015985")
2025/03/09 03:17:51 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975016981")
2025/03/09 03:17:52 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975017988")
2025/03/09 03:17:53 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975018983")
2025/03/09 03:17:54 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975019983")
2025/03/09 03:17:54 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1797948251561|6b8e5496921203fe15705769eb6b7dee2216e4a9] added old path ("dir/file2.rclone_chunk.001") for notify
2025/03/09 03:17:54 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(1)|file|ITEM_TRASH|1797953461410|de1bda05730768682170531b902bedcca8a9fb6c] added old path ("dir/file2") for notify
2025/03/09 03:17:54 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1797946163888|ac37b89e711cc64604ef27d8f33d61589aceff9b] added old path ("dir/file2.rclone_chunk.002") for notify
2025/03/09 03:17:54 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/09 03:17:54 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/09 03:17:54 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/09 03:17:54 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 3 events, resulting in 3 paths and 3 notifications
2025/03/09 03:17:54 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975020978")
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975021205")
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1797948251561|6b8e5496921203fe15705769eb6b7dee2216e4a9] ignored due to duplicate EventID
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(1)|file|ITEM_TRASH|1797953461410|de1bda05730768682170531b902bedcca8a9fb6c] ignored due to duplicate EventID
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1797946163888|ac37b89e711cc64604ef27d8f33d61589aceff9b] ignored due to duplicate EventID
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(1)|file|ITEM_TRASH|1797942582893|cb015f0788501244686979182594f64da92b451f] added old path ("dir/file4") for notify
2025/03/09 03:17:55 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/09 03:17:55 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975021980")
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975022220")
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1797948251561|6b8e5496921203fe15705769eb6b7dee2216e4a9] ignored due to duplicate EventID
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2"(1)|file|ITEM_TRASH|1797953461410|de1bda05730768682170531b902bedcca8a9fb6c] ignored due to duplicate EventID
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1797946163888|ac37b89e711cc64604ef27d8f33d61589aceff9b] ignored due to duplicate EventID
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(1)|file|ITEM_TRASH|1797942582893|cb015f0788501244686979182594f64da92b451f] ignored due to duplicate EventID
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1797932579308|f3b6c4454a1c2c13669b4b2e3a24ba16d2c1556e] added old path ("dir/file4.rclone_chunk.001") for notify
2025/03/09 03:17:56 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 5 events, resulting in 1 paths and 1 notifications
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975022983")
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1797946163888|ac37b89e711cc64604ef27d8f33d61589aceff9b] ignored due to duplicate EventID
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(1)|file|ITEM_TRASH|1797942582893|cb015f0788501244686979182594f64da92b451f] ignored due to duplicate EventID
2025/03/09 03:17:56 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1797932579308|f3b6c4454a1c2c13669b4b2e3a24ba16d2c1556e] ignored due to duplicate EventID
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975023222")
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1797946163888|ac37b89e711cc64604ef27d8f33d61589aceff9b] ignored due to duplicate EventID
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(1)|file|ITEM_TRASH|1797942582893|cb015f0788501244686979182594f64da92b451f] ignored due to duplicate EventID
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1797932579308|f3b6c4454a1c2c13669b4b2e3a24ba16d2c1556e] ignored due to duplicate EventID
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1797934989366|b719547ee409f6da5d912e57fa95f60e01640518] added old path ("dir/file4.rclone_chunk.002") for notify
2025/03/09 03:17:57 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 4 events, resulting in 1 paths and 1 notifications
2025/03/09 03:17:57 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975023980")
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975024225")
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1797946163888|ac37b89e711cc64604ef27d8f33d61589aceff9b] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(1)|file|ITEM_TRASH|1797942582893|cb015f0788501244686979182594f64da92b451f] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1797932579308|f3b6c4454a1c2c13669b4b2e3a24ba16d2c1556e] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1797934989366|b719547ee409f6da5d912e57fa95f60e01640518] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3"(1)|file|ITEM_TRASH|1797944238111|1545ffc0c1c8e655f740ac3f1c37b140a0514b65] added old path ("dir/file3") for notify
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1797937902243|28d85b09aad03f6537414c9867e81264e05e44ea] added old path ("dir/file3.rclone_chunk.001") for notify
2025/03/09 03:17:58 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/09 03:17:58 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': Received 6 events, resulting in 2 paths and 2 notifications
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': Checking for changes on remote (next_stream_position: "30401038975024990")
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4"(1)|file|ITEM_TRASH|1797942582893|cb015f0788501244686979182594f64da92b451f] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1797932579308|f3b6c4454a1c2c13669b4b2e3a24ba16d2c1556e] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1797934989366|b719547ee409f6da5d912e57fa95f60e01640518] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3"(1)|file|ITEM_TRASH|1797944238111|1545ffc0c1c8e655f740ac3f1c37b140a0514b65] ignored due to duplicate EventID
2025/03/09 03:17:58 DEBUG : box root 'rclone-test-vipewex3zaxe': ["file3.rclone_chunk.001"(2)|file|ITEM_TRASH|1797937902243|28d85b09aad03f6537414c9867e81264e05e44ea] ignored due to duplicate EventID
2025/03/09 03:17:59 DEBUG : box root 'rclone-test-vipewex3zaxe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/09 03:17:59 ERROR : : error listing: directory not found
2025/03/09 03:18:00 DEBUG : box root 'rclone-test-vipewex3zaxe': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (66.88s)
    --- FAIL: TestIntegration/FsMkdir (64.74s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (62.89s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	66.904s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m8.835282619s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]