"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 2/5) === RUN TestIntegration fstests.go:438: Using remote "TestBox:" 2025/03/11 08:00:47 DEBUG : Creating backend with remote "TestBox:rclone-test-filucas7zuke" 2025/03/11 08:00:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/11 08:00:47 DEBUG : box root 'rclone-test-filucas7zuke': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/11 08:00:48 DEBUG : box root 'rclone-test-filucas7zuke': Ignoring "Shared folder — ForNick" - unknown type "web_link" === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsChangeNotify 2025/03/11 08:00:50 INFO : dir: Making directory 2025/03/11 08:00:52 INFO : dir/subdir1: Making directory 2025/03/11 08:00:53 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164798855") 2025/03/11 08:00:53 INFO : dir/subdir3: Making directory 2025/03/11 08:00:54 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164800115") 2025/03/11 08:00:54 DEBUG : box root 'rclone-test-filucas7zuke': Received 1 events, resulting in 0 paths and 0 notifications 2025/03/11 08:00:54 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164801095") 2025/03/11 08:00:54 INFO : dir/subdir2: Making directory 2025/03/11 08:00:55 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164801349") 2025/03/11 08:00:56 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164802105") 2025/03/11 08:00:56 DEBUG : box root 'rclone-test-filucas7zuke': Received 8 events, resulting in 0 paths and 0 notifications 2025/03/11 08:00:56 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164803100") 2025/03/11 08:00:56 DEBUG : box root 'rclone-test-filucas7zuke': ["rclone-test-haniqid7hato"(3)|folder|ITEM_TRASH|311110163929|534073c5452bef5bb8b663291823f014de461c3d] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164803391") 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["file name.txt"(6)|file|ITEM_TRASH|1799734218826|cc8f56c0b1f222589269b22bef41ac726bc95754] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(1)|file|ITEM_TRASH|1799737979282|884f65a667444f97c37405c389df11ca962ee6d9] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["Hello, 世界"(1)|folder|ITEM_TRASH|311110377421|6b85bed906a976a1a4869a753afd5ef5bb856658] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["z.txt"(4)|file|ITEM_TRASH|1799734122879|07743b496c2992e970854b89ec904b00136ccb6f] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["êé"(1)|folder|ITEM_TRASH|311110026778|5361419a133e2db1f021d88cc6a08a95e30ce6c1] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["hello? sausage"(1)|folder|ITEM_TRASH|311110240766|095cc78a7f6765d08f64a6b2182e9bf14cb31703] ignored due to duplicate EventID 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799737979282|bb8b740228c078c4d73ccfa91e847514686195fe] new parent not found 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': Received 7 events, resulting in 0 paths and 0 notifications 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164804100") 2025/03/11 08:00:57 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(1)|file|ITEM_TRASH|1799737979282|884f65a667444f97c37405c389df11ca962ee6d9] ignored due to duplicate EventID 2025/03/11 08:00:58 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164804411") 2025/03/11 08:00:58 DEBUG : box root 'rclone-test-filucas7zuke': ["Hello, 世界"(1)|folder|ITEM_TRASH|311110377421|6b85bed906a976a1a4869a753afd5ef5bb856658] ignored due to duplicate EventID 2025/03/11 08:00:58 DEBUG : box root 'rclone-test-filucas7zuke': ["z.txt"(4)|file|ITEM_TRASH|1799734122879|07743b496c2992e970854b89ec904b00136ccb6f] ignored due to duplicate EventID 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164805116") 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': ["êé"(1)|folder|ITEM_TRASH|311110026778|5361419a133e2db1f021d88cc6a08a95e30ce6c1] ignored due to duplicate EventID 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': ["hello? sausage"(1)|folder|ITEM_TRASH|311110240766|095cc78a7f6765d08f64a6b2182e9bf14cb31703] ignored due to duplicate EventID 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799737979282|bb8b740228c078c4d73ccfa91e847514686195fe] ignored due to duplicate EventID 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': ["somefile"(0)|file|ITEM_UPLOAD|1799745416571|c0a1a52bc86fe11564af0972b7e0550a52c59617] new parent not found 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': Received 6 events, resulting in 0 paths and 0 notifications 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164806139") 2025/03/11 08:00:59 DEBUG : box root 'rclone-test-filucas7zuke': ["hello? sausage"(1)|folder|ITEM_TRASH|311110240766|095cc78a7f6765d08f64a6b2182e9bf14cb31703] ignored due to duplicate EventID 2025/03/11 08:01:00 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164806484") 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164807222") 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799737979282|bb8b740228c078c4d73ccfa91e847514686195fe] ignored due to duplicate EventID 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': ["rclone-test-fuhapus9rexe"(1)|folder|ITEM_TRASH|311110124692|0590e65f80d12c8fb6a557be5573ca9028d06301] ignored due to duplicate EventID 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': ["somefile"(1)|file|ITEM_TRASH|1799745416571|0152c567e86fee7dcc7067935dd91fd2f302bc67] ignored due to duplicate EventID 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': ["somefile"(0)|file|ITEM_UPLOAD|1799745416571|c0a1a52bc86fe11564af0972b7e0550a52c59617] ignored due to duplicate EventID 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799745378778|6afa937663b81855fefe3b27e48794182ef425df] new parent not found 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': Received 6 events, resulting in 0 paths and 0 notifications 2025/03/11 08:01:01 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164808467") 2025/03/11 08:01:02 DEBUG : box root 'rclone-test-filucas7zuke': ["rclone-test-fuhapus9rexe"(1)|folder|ITEM_TRASH|311110124692|0590e65f80d12c8fb6a557be5573ca9028d06301] ignored due to duplicate EventID 2025/03/11 08:01:02 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164809101") 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164809595") 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["somefile"(1)|file|ITEM_TRASH|1799745416571|0152c567e86fee7dcc7067935dd91fd2f302bc67] ignored due to duplicate EventID 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["somefile"(0)|file|ITEM_UPLOAD|1799745416571|c0a1a52bc86fe11564af0972b7e0550a52c59617] ignored due to duplicate EventID 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-put.txt"(1)|file|ITEM_TRASH|1799735236272|0cae71d476343580a6b2fc6ee1afcbb4dc08b77b] ignored due to duplicate EventID 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799745378778|6afa937663b81855fefe3b27e48794182ef425df] ignored due to duplicate EventID 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["subdir1"(0)|folder|ITEM_CREATE|311109990837|cc1a2325c05d876fd8d6bb905cbd1d2d5f656ed7] added new path ("dir/subdir1") for notify 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["file2"(0)|file|ITEM_UPLOAD|1799746285282|d7e92becad3d8be2fee8ee76686e4c973c947e19] added new path ("dir/file2") for notify 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1799736964231|68c09022e21031690fca50969e8a994cb9215d1e] new parent not found 2025/03/11 08:01:03 DEBUG : doChangeNotify("dir/subdir1", 0) 2025/03/11 08:01:03 DEBUG : doChangeNotify("dir/file2", 1) 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': Received 8 events, resulting in 2 paths and 2 notifications 2025/03/11 08:01:03 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164810165") fstests.go:931: Try 1/10 waiting for dirChanges and objChanges 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164810503") 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-put.txt"(1)|file|ITEM_TRASH|1799735236272|0cae71d476343580a6b2fc6ee1afcbb4dc08b77b] ignored due to duplicate EventID 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799745378778|6afa937663b81855fefe3b27e48794182ef425df] ignored due to duplicate EventID 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': ["subdir1"(0)|folder|ITEM_CREATE|311109990837|cc1a2325c05d876fd8d6bb905cbd1d2d5f656ed7] ignored due to duplicate EventID 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-update.txt"(2)|file|ITEM_TRASH|1799736964231|a8b30a7e00edc3e49b2afd539664df6a68663d94] ignored due to duplicate EventID 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': ["file2"(0)|file|ITEM_UPLOAD|1799746285282|d7e92becad3d8be2fee8ee76686e4c973c947e19] ignored due to duplicate EventID 2025/03/11 08:01:04 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1799736964231|68c09022e21031690fca50969e8a994cb9215d1e] ignored due to duplicate EventID 2025/03/11 08:01:05 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164811104") 2025/03/11 08:01:05 DEBUG : box root 'rclone-test-filucas7zuke': ["piped data.txt"(0)|file|ITEM_UPLOAD|1799745378778|6afa937663b81855fefe3b27e48794182ef425df] ignored due to duplicate EventID 2025/03/11 08:01:06 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164812102") 2025/03/11 08:01:06 DEBUG : box root 'rclone-test-filucas7zuke': ["subdir1"(0)|folder|ITEM_CREATE|311109990837|cc1a2325c05d876fd8d6bb905cbd1d2d5f656ed7] ignored due to duplicate EventID 2025/03/11 08:01:06 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-update.txt"(2)|file|ITEM_TRASH|1799736964231|a8b30a7e00edc3e49b2afd539664df6a68663d94] ignored due to duplicate EventID 2025/03/11 08:01:06 DEBUG : box root 'rclone-test-filucas7zuke': ["file2"(0)|file|ITEM_UPLOAD|1799746285282|d7e92becad3d8be2fee8ee76686e4c973c947e19] ignored due to duplicate EventID 2025/03/11 08:01:06 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1799736964231|68c09022e21031690fca50969e8a994cb9215d1e] ignored due to duplicate EventID fstests.go:931: Try 2/10 waiting for dirChanges and objChanges 2025/03/11 08:01:07 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164813125") 2025/03/11 08:01:07 DEBUG : box root 'rclone-test-filucas7zuke': ["file2"(0)|file|ITEM_UPLOAD|1799746285282|d7e92becad3d8be2fee8ee76686e4c973c947e19] ignored due to duplicate EventID 2025/03/11 08:01:07 DEBUG : box root 'rclone-test-filucas7zuke': ["unknown-size-update.txt"(0)|file|ITEM_UPLOAD|1799736964231|68c09022e21031690fca50969e8a994cb9215d1e] ignored due to duplicate EventID 2025/03/11 08:01:07 DEBUG : box root 'rclone-test-filucas7zuke': ["subdir3"(0)|folder|ITEM_CREATE|311107616520|568f97bae17c933d74765afd08a91c666267ec56] added new path ("dir/subdir3") for notify 2025/03/11 08:01:07 DEBUG : doChangeNotify("dir/subdir3", 0) 2025/03/11 08:01:07 DEBUG : box root 'rclone-test-filucas7zuke': Received 3 events, resulting in 1 paths and 1 notifications 2025/03/11 08:01:07 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164814135") 2025/03/11 08:01:08 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164814416") 2025/03/11 08:01:09 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164815103") fstests.go:931: Try 3/10 waiting for dirChanges and objChanges 2025/03/11 08:01:10 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164816105") 2025/03/11 08:01:11 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164817117") 2025/03/11 08:01:11 DEBUG : box root 'rclone-test-filucas7zuke': ["subdir3"(0)|folder|ITEM_CREATE|311107616520|568f97bae17c933d74765afd08a91c666267ec56] ignored due to duplicate EventID 2025/03/11 08:01:11 DEBUG : box root 'rclone-test-filucas7zuke': ["file3"(0)|file|ITEM_UPLOAD|1799744283759|61ce259ee8fc2a085984fe3611d15263c0eae41f] added new path ("dir/file3") for notify 2025/03/11 08:01:11 DEBUG : doChangeNotify("dir/file3", 1) 2025/03/11 08:01:11 DEBUG : box root 'rclone-test-filucas7zuke': Received 2 events, resulting in 1 paths and 1 notifications 2025/03/11 08:01:11 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164818092") 2025/03/11 08:01:12 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164818331") fstests.go:931: Try 4/10 waiting for dirChanges and objChanges 2025/03/11 08:01:13 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164819117") 2025/03/11 08:01:13 DEBUG : box root 'rclone-test-filucas7zuke': ["file3"(0)|file|ITEM_UPLOAD|1799744283759|61ce259ee8fc2a085984fe3611d15263c0eae41f] ignored due to duplicate EventID 2025/03/11 08:01:14 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164820092") 2025/03/11 08:01:14 DEBUG : box root 'rclone-test-filucas7zuke': ["file3"(0)|file|ITEM_UPLOAD|1799744283759|61ce259ee8fc2a085984fe3611d15263c0eae41f] ignored due to duplicate EventID 2025/03/11 08:01:15 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164821091") fstests.go:931: Try 5/10 waiting for dirChanges and objChanges 2025/03/11 08:01:16 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164822086") 2025/03/11 08:01:17 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164823088") 2025/03/11 08:01:18 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164824084") fstests.go:931: Try 6/10 waiting for dirChanges and objChanges 2025/03/11 08:01:19 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164825147") 2025/03/11 08:01:20 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164826086") 2025/03/11 08:01:21 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164827084") fstests.go:931: Try 7/10 waiting for dirChanges and objChanges 2025/03/11 08:01:22 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164828091") 2025/03/11 08:01:23 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164829084") 2025/03/11 08:01:24 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164830081") fstests.go:931: Try 8/10 waiting for dirChanges and objChanges 2025/03/11 08:01:25 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164831080") 2025/03/11 08:01:26 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164832083") 2025/03/11 08:01:27 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164833084") fstests.go:931: Try 9/10 waiting for dirChanges and objChanges 2025/03/11 08:01:28 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164834079") 2025/03/11 08:01:29 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164835082") 2025/03/11 08:01:30 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164836086") fstests.go:935: map[dir/subdir1:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or map[dir/file2:{} dir/file3:{}] does not contain [dir/file2 dir/file4 dir/file3] 2025/03/11 08:01:31 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164837083") 2025/03/11 08:01:32 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164838085") 2025/03/11 08:01:33 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164839089") 2025/03/11 08:01:34 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164840082") 2025/03/11 08:01:35 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164841079") 2025/03/11 08:01:36 DEBUG : box root 'rclone-test-filucas7zuke': Checking for changes on remote (next_stream_position: "30401039164842081") 2025/03/11 08:01:37 DEBUG : box root 'rclone-test-filucas7zuke': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/03/11 08:01:37 ERROR : : error listing: directory not found 2025/03/11 08:01:38 DEBUG : box root 'rclone-test-filucas7zuke': Ignoring "Shared folder — ForNick" - unknown type "web_link" --- FAIL: TestIntegration (51.16s) --- FAIL: TestIntegration/FsMkdir (47.98s) --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (45.86s) FAIL exit status 1 FAIL github.com/rclone/rclone/backend/box 51.176s "go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 51.755923524s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]