"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/04/18 04:54:13 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rasagej5qehu"
2025/04/18 04:54:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/18 04:54:13 DEBUG : Creating backend with remote "TestBox:rclone-test-rasagej5qehu"
2025/04/18 04:54:14 DEBUG : box root 'rclone-test-rasagej5qehu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/04/18 04:54:15 DEBUG : box root 'rclone-test-rasagej5qehu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/04/18 04:54:17 INFO  : dir: Making directory
2025/04/18 04:54:18 INFO  : dir/subdir1: Making directory
2025/04/18 04:54:19 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436805567")
2025/04/18 04:54:19 INFO  : dir/subdir3: Making directory
2025/04/18 04:54:20 DEBUG : box root 'rclone-test-rasagej5qehu': ["one.rclone_chunk.001_wj81xn"(0)|file|ITEM_UPLOAD|1837859582096|90dbf07b3d49320922d71f15745a71bb7ba42db1] new parent not found
2025/04/18 04:54:20 DEBUG : box root 'rclone-test-rasagej5qehu': ["one"(1)|file|ITEM_RENAME|1837859582096|bf2abc91fbed811e67144bc87ec5e37dbfb80ec9] new parent not found
2025/04/18 04:54:20 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:20 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436806807")
2025/04/18 04:54:20 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436807067")
2025/04/18 04:54:20 INFO  : dir/subdir2: Making directory
2025/04/18 04:54:21 DEBUG : box root 'rclone-test-rasagej5qehu': ["two.rclone_chunk.001_wj86pw"(0)|file|ITEM_UPLOAD|1837869592310|47de3e69d633ed07dba0f7835dbc112c5a53c29f] new parent not found
2025/04/18 04:54:21 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:21 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436807790")
2025/04/18 04:54:21 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436808024")
2025/04/18 04:54:22 DEBUG : box root 'rclone-test-rasagej5qehu': ["rclone-test-rasagej5qehu"(0)|folder|ITEM_CREATE|317312231965|ee46aaefd0c70a9e2f3a0cd1795b6cd5a74795aa] new parent not found
2025/04/18 04:54:22 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:22 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436808801")
2025/04/18 04:54:22 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436809044")
2025/04/18 04:54:23 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436809800")
2025/04/18 04:54:24 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436810803")
2025/04/18 04:54:25 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436811802")
2025/04/18 04:54:26 DEBUG : box root 'rclone-test-rasagej5qehu': ["dir"(0)|folder|ITEM_CREATE|317308681490|9b301cd6cf60202f1d4f7b2d8feb7d9db9dd2f54] added new path ("dir") for notify
2025/04/18 04:54:26 DEBUG : box root 'rclone-test-rasagej5qehu': ["three.txt.rclone_chunk.001_wj8a3m"(0)|file|ITEM_UPLOAD|1837860974107|38fa4835b6aef95779ac636b790de4e61e392756] new parent not found
2025/04/18 04:54:26 DEBUG : box root 'rclone-test-rasagej5qehu': ["subdir1"(0)|folder|ITEM_CREATE|317308631814|b1d08c3027875f5119a8fd16e4fad8d3f1262bae] added new path ("dir/subdir1") for notify
2025/04/18 04:54:26 DEBUG : doChangeNotify("dir", 0)
2025/04/18 04:54:26 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/04/18 04:54:26 DEBUG : box root 'rclone-test-rasagej5qehu': Received 3 events, resulting in 2 paths and 2 notifications
2025/04/18 04:54:26 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436812795")
2025/04/18 04:54:26 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436813039")
2025/04/18 04:54:27 INFO  : dir/file2.rclone_chunk.001_wj8gd3: Moved (server-side) to: dir/file2.rclone_chunk.001
2025/04/18 04:54:27 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436813797")
2025/04/18 04:54:28 INFO  : dir/file2.rclone_chunk.002_wj8gd3: Moved (server-side) to: dir/file2.rclone_chunk.002
2025/04/18 04:54:28 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436814803")
2025/04/18 04:54:29 DEBUG : box root 'rclone-test-rasagej5qehu': ["subdir3"(0)|folder|ITEM_CREATE|317311158760|00806f0345c4d6d325289cec891b3c44331b5803] added new path ("dir/subdir3") for notify
2025/04/18 04:54:29 DEBUG : box root 'rclone-test-rasagej5qehu': ["subdir2"(0)|folder|ITEM_CREATE|317312027873|983442abc9e85b916644deafb4beb599310dc3ea] added new path ("dir/subdir2") for notify
2025/04/18 04:54:29 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/04/18 04:54:29 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/04/18 04:54:29 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 2 paths and 2 notifications
2025/04/18 04:54:29 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436815797")
2025/04/18 04:54:29 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436816027")
2025/04/18 04:54:30 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:30 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436816799")
2025/04/18 04:54:30 DEBUG : box root 'rclone-test-rasagej5qehu': ["two"(1)|file|ITEM_RENAME|1837869592310|867cded21a2b189cd8f9c9a167b1830250662964] new parent not found
2025/04/18 04:54:30 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:30 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436817029")
2025/04/18 04:54:30 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436817262")
2025/04/18 04:54:31 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436817806")
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': ["one-2019-01-01"(2)|file|ITEM_RENAME|1837859582096|1932b1e62aa0916b387a84c24c0916baea518223] new parent not found
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': ["three.txt"(1)|file|ITEM_RENAME|1837860974107|9f17af107d54f73082e455269fc16d6b73f79fab] new parent not found
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436818794")
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2.rclone_chunk.001_wj8gd3"(0)|file|ITEM_UPLOAD|1837872759751|a5fbbde0a33975d6cd93385a733effa847d721d6] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436819034")
2025/04/18 04:54:32 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436819280")
2025/04/18 04:54:33 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436819807")
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': ["one.rclone_chunk.001_wj8jhb"(0)|file|ITEM_UPLOAD|1837873472907|5fad51745636e834203c26120000e53b1e256205] new parent not found
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2.rclone_chunk.002_wj8gd3"(0)|file|ITEM_UPLOAD|1837862857461|3b6c042dc492c046347f4b71a84960144049c473] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2.rclone_chunk.002"(1)|file|ITEM_RENAME|1837862857461|f750fc71a498c9921fcaa2d45d4759b66f1091cd] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2.rclone_chunk.001"(1)|file|ITEM_RENAME|1837872759751|843403abdf7d534eb7b72b2b4d18a5708c356384] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': Received 5 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436820801")
2025/04/18 04:54:34 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436821067")
2025/04/18 04:54:35 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:35 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436821809")
2025/04/18 04:54:35 INFO  : dir/file4.rclone_chunk.001_wj8py0: Moved (server-side) to: dir/file4.rclone_chunk.001
2025/04/18 04:54:35 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436822048")
2025/04/18 04:54:36 INFO  : dir/file4.rclone_chunk.002_wj8py0: Moved (server-side) to: dir/file4.rclone_chunk.002
2025/04/18 04:54:36 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436822793")
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': ["one"(1)|file|ITEM_RENAME|1837873472907|ecf06441bde5f3b3e8020aca9d2742d59f0869cd] new parent not found
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2"(0)|file|ITEM_UPLOAD|1837864842818|ee950cf2c6f93ca15a8053d485af770588c7a91b] ignored due to old SequenceID ('\x00')
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436823795")
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436824037")
2025/04/18 04:54:37 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436824314")
2025/04/18 04:54:38 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:38 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436824803")
2025/04/18 04:54:38 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436825035")
2025/04/18 04:54:39 DEBUG : box root 'rclone-test-rasagej5qehu': ["three-2019-01-01.txt"(2)|file|ITEM_RENAME|1837860974107|7b529b33f377a28e93539eb2e2f6c5ba3f1e9e82] new parent not found
2025/04/18 04:54:39 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:39 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436825797")
2025/04/18 04:54:39 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436826032")
2025/04/18 04:54:40 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4.rclone_chunk.001_wj8py0"(0)|file|ITEM_UPLOAD|1837861880777|cbe5af3c4ba3df7bdaea14c3c87cfabd90bd1586] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:40 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:40 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436826791")
2025/04/18 04:54:40 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436827030")
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': ["three.txt.rclone_chunk.001_wj8pfu"(0)|file|ITEM_UPLOAD|1837859982830|5cc09bd08e9419982f52dd9b2eed1072bf942443] new parent not found
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': ["three.txt"(1)|file|ITEM_RENAME|1837859982830|0be5c6490e2d9fd0c234e530b29c11c2328e8b35] new parent not found
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4.rclone_chunk.002_wj8py0"(0)|file|ITEM_UPLOAD|1837874094625|bb403bcb329c6a37b583e3db5d5bb3a00385820e] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': Received 5 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436827796")
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436828081")
2025/04/18 04:54:41 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436828316")
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4.rclone_chunk.001"(1)|file|ITEM_RENAME|1837861880777|722f2c701893727e282f244d8876a711dde1d1f8] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436828798")
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4.rclone_chunk.002"(1)|file|ITEM_RENAME|1837874094625|bdbd2f230057286b751a83a0d1c38af2e8d69703] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436829025")
2025/04/18 04:54:42 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436829254")
2025/04/18 04:54:43 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:43 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436829794")
2025/04/18 04:54:43 INFO  : dir/file3.rclone_chunk.001_wj8xio: Moved (server-side) to: dir/file3.rclone_chunk.001
2025/04/18 04:54:43 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436830033")
2025/04/18 04:54:44 INFO  : dir/file3.rclone_chunk.002_wj8xio: Moved (server-side) to: dir/file3.rclone_chunk.002
2025/04/18 04:54:44 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436830798")
2025/04/18 04:54:45 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436831799")
2025/04/18 04:54:46 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:46 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436832795")
2025/04/18 04:54:46 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436833042")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/04/18 04:54:47 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436833796")
2025/04/18 04:54:48 DEBUG : box root 'rclone-test-rasagej5qehu': ["file3.rclone_chunk.001_wj8xio"(0)|file|ITEM_UPLOAD|1837863244028|ba1f38064865a2f199f208b546ce29f5a484281a] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:48 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4"(0)|file|ITEM_UPLOAD|1837863138674|9bd540f83d9548fc15e0e005a61ec8fa6c00275d] ignored due to old SequenceID ('\x00')
2025/04/18 04:54:48 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:48 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436834796")
2025/04/18 04:54:48 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436835032")
2025/04/18 04:54:49 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436835787")
2025/04/18 04:54:50 DEBUG : box root 'rclone-test-rasagej5qehu': ["file3.rclone_chunk.002_wj8xio"(0)|file|ITEM_UPLOAD|1837874334162|ee669da83884b1519d4b4b8f2a0053827909ca07] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:50 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:50 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436836798")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/04/18 04:54:50 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436837027")
2025/04/18 04:54:51 DEBUG : box root 'rclone-test-rasagej5qehu': ["one.rclone_chunk.001_wj8y6m"(0)|file|ITEM_UPLOAD|1837874332143|4c4d3597f459f29cf0af78d87ae0e9858adbe277] new parent not found
2025/04/18 04:54:51 DEBUG : box root 'rclone-test-rasagej5qehu': ["file3.rclone_chunk.002"(1)|file|ITEM_RENAME|1837874334162|e5624fa540de0dbb0512d7adc490c2f8c071161c] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:51 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:51 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436837795")
2025/04/18 04:54:51 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436838033")
2025/04/18 04:54:52 DEBUG : box root 'rclone-test-rasagej5qehu': ["file3"(0)|file|ITEM_UPLOAD|1837874572519|7cae52e5d33af7bce58600a98b8b711317288fcc] ignored due to old SequenceID ('\x00')
2025/04/18 04:54:52 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:52 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436838808")
2025/04/18 04:54:52 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436839039")
2025/04/18 04:54:53 DEBUG : box root 'rclone-test-rasagej5qehu': ["one"(1)|file|ITEM_RENAME|1837874332143|4c9745ee6381316be346374826a15abd733e1e2f] new parent not found
2025/04/18 04:54:53 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:53 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436839819")
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/04/18 04:54:53 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436840059")
2025/04/18 04:54:54 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:54 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436840794")
2025/04/18 04:54:54 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436841024")
2025/04/18 04:54:55 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436841795")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/04/18 04:54:56 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436842798")
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': ["three-2019-01-01.txt"(2)|file|ITEM_RENAME|1837859982830|33a508fd05ce7d20799711b95a8a7e879c9a3d28] new parent not found
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436843793")
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': ["file3.rclone_chunk.001"(1)|file|ITEM_RENAME|1837863244028|92d08dcf89480b64ac7dc7b0b12684507115735c] ignored due to old SequenceID ('\x01')
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436844029")
2025/04/18 04:54:57 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436844328")
2025/04/18 04:54:58 DEBUG : box root 'rclone-test-rasagej5qehu': ["one-2019-01-01"(2)|file|ITEM_RENAME|1837873472907|ac86d428ea057c8333c02070b178ba29c09b01b4] new parent not found
2025/04/18 04:54:58 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:54:58 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436844790")
2025/04/18 04:54:58 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436845028")
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/04/18 04:54:59 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436845797")
2025/04/18 04:55:00 DEBUG : box root 'rclone-test-rasagej5qehu': ["three.txt"(1)|file|ITEM_RENAME|1837863220593|b57be744880c61495cae4cde6da7ef4569a5afa9] new parent not found
2025/04/18 04:55:00 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:00 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436846792")
2025/04/18 04:55:00 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436847030")
2025/04/18 04:55:01 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436847798")
2025/04/18 04:55:02 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:02 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436848803")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/04/18 04:55:02 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436849042")
2025/04/18 04:55:03 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:03 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436849790")
2025/04/18 04:55:03 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436850026")
2025/04/18 04:55:04 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436850796")
2025/04/18 04:55:05 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:05 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436851796")
    fstests.go:931: Try 7/10 waiting for dirChanges and objChanges
2025/04/18 04:55:05 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436852031")
2025/04/18 04:55:06 DEBUG : box root 'rclone-test-rasagej5qehu': ["three.txt.rclone_chunk.001_wj97li"(0)|file|ITEM_UPLOAD|1837863220593|7573b589c16c4491f342bdd80e236ff61d9ba9f2] new parent not found
2025/04/18 04:55:06 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:06 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436852799")
2025/04/18 04:55:06 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436853042")
2025/04/18 04:55:07 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:07 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436853793")
2025/04/18 04:55:07 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:07 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436854036")
2025/04/18 04:55:07 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436854282")
    fstests.go:931: Try 8/10 waiting for dirChanges and objChanges
2025/04/18 04:55:08 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436854799")
2025/04/18 04:55:09 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436855797")
2025/04/18 04:55:10 DEBUG : box root 'rclone-test-rasagej5qehu': ["Testêé.rclone_chunk.001_wj9j2c"(0)|file|ITEM_UPLOAD|1837870977565|64c6d41ad6495b058b35ce29ea274b4a6739f098] new parent not found
2025/04/18 04:55:10 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:10 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436856812")
2025/04/18 04:55:10 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436857046")
2025/04/18 04:55:11 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:11 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436857792")
    fstests.go:931: Try 9/10 waiting for dirChanges and objChanges
2025/04/18 04:55:11 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436858031")
2025/04/18 04:55:12 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436858795")
2025/04/18 04:55:13 DEBUG : box root 'rclone-test-rasagej5qehu': ["Testêé"(1)|file|ITEM_RENAME|1837870977565|9278f63120a659b9c771b9a777e9cd61dd5a59b1] new parent not found
2025/04/18 04:55:13 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:13 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436859799")
2025/04/18 04:55:13 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436860033")
2025/04/18 04:55:14 DEBUG : box root 'rclone-test-rasagej5qehu': ["Testêé.rclone_chunk.001_wj9ndz"(0)|file|ITEM_UPLOAD|1837870237910|e876ccfbef02e12e22279abb85b124408530437e] new parent not found
2025/04/18 04:55:14 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:14 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436860799")
    fstests.go:935: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[] does not contain [dir/file2 dir/file4 dir/file3]
2025/04/18 04:55:14 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436861033")
2025/04/18 04:55:15 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436861798")
2025/04/18 04:55:16 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436862795")
2025/04/18 04:55:17 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436863810")
2025/04/18 04:55:18 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:18 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436864797")
2025/04/18 04:55:18 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436865034")
2025/04/18 04:55:19 DEBUG : box root 'rclone-test-rasagej5qehu': ["Testêé"(1)|file|ITEM_RENAME|1837870237910|d6a4641ac0a62da583c1c3fd957384cbadab603d] new parent not found
2025/04/18 04:55:19 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:19 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436865805")
2025/04/18 04:55:19 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436866043")
2025/04/18 04:55:20 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436866790")
2025/04/18 04:55:21 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436867799")
2025/04/18 04:55:22 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436868798")
2025/04/18 04:55:23 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2"(1)|file|ITEM_TRASH|1837864842818|0ee60a7ea5d53fa7cb428e4aaec298e6be5d3a12] added old path ("dir/file2") for notify
2025/04/18 04:55:23 DEBUG : doChangeNotify("dir/file2", 1)
2025/04/18 04:55:23 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/18 04:55:23 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436869811")
2025/04/18 04:55:23 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436870045")
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': ["existing.rclone_chunk.001_wj9wrz"(0)|file|ITEM_UPLOAD|1837858847765|98d5a92db33e45385616701bb33763dafff0e164] new parent not found
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': ["existing"(1)|file|ITEM_RENAME|1837858847765|34cfe4d5a3d70ddec798f01e37c017dbe125c7e1] new parent not found
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436870797")
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2.rclone_chunk.001"(2)|file|ITEM_TRASH|1837872759751|70a3473cb380b699e72ecc6c65060345508240ce] added old path ("dir/file2.rclone_chunk.001") for notify
2025/04/18 04:55:24 DEBUG : doChangeNotify("dir/file2", 1)
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436871032")
2025/04/18 04:55:24 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436871269")
2025/04/18 04:55:25 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436871799")
2025/04/18 04:55:26 DEBUG : box root 'rclone-test-rasagej5qehu': ["file2.rclone_chunk.002"(2)|file|ITEM_TRASH|1837862857461|6f8c51ec2f66f5ff806cceaf204eb7a1383d1fe5] added old path ("dir/file2.rclone_chunk.002") for notify
2025/04/18 04:55:26 DEBUG : doChangeNotify("dir/file2", 1)
2025/04/18 04:55:26 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/18 04:55:26 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436872796")
2025/04/18 04:55:26 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436873035")
2025/04/18 04:55:27 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4"(1)|file|ITEM_TRASH|1837863138674|9b651fe4feb7181049031d0686caa51c3f478552] added old path ("dir/file4") for notify
2025/04/18 04:55:27 DEBUG : doChangeNotify("dir/file4", 1)
2025/04/18 04:55:27 DEBUG : box root 'rclone-test-rasagej5qehu': Received 2 events, resulting in 1 paths and 1 notifications
2025/04/18 04:55:27 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436873801")
2025/04/18 04:55:27 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436874045")
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4.rclone_chunk.001"(2)|file|ITEM_TRASH|1837861880777|93450da1949c608dae03b564aa32635e6b1f5db0] added old path ("dir/file4.rclone_chunk.001") for notify
2025/04/18 04:55:28 DEBUG : doChangeNotify("dir/file4", 1)
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436874794")
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': ["file4.rclone_chunk.002"(2)|file|ITEM_TRASH|1837874094625|5bee29c3827a04c05b8a580caffdd053be2f6b05] added old path ("dir/file4.rclone_chunk.002") for notify
2025/04/18 04:55:28 DEBUG : doChangeNotify("dir/file4", 1)
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 1 paths and 1 notifications
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436875031")
2025/04/18 04:55:28 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436875259")
2025/04/18 04:55:29 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436875797")
2025/04/18 04:55:30 DEBUG : box root 'rclone-test-rasagej5qehu': ["file3"(1)|file|ITEM_TRASH|1837874572519|fbe31caa48f17aeff60ff56bf270708c0b9ab480] old parent not cached
2025/04/18 04:55:30 DEBUG : box root 'rclone-test-rasagej5qehu': Received 1 events, resulting in 0 paths and 0 notifications
2025/04/18 04:55:30 DEBUG : box root 'rclone-test-rasagej5qehu': Checking for changes on remote (next_stream_position: "30401042436876797")
2025/04/18 04:55:31 DEBUG : box root 'rclone-test-rasagej5qehu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/04/18 04:55:31 ERROR : : error listing: directory not found
2025/04/18 04:55:32 DEBUG : box root 'rclone-test-rasagej5qehu': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (78.57s)
    --- FAIL: TestIntegration/FsMkdir (75.62s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (72.49s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/chunker	78.626s
"go test -v -timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 1m21.27619889s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]