"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 4/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestBox:"
2025/03/07 09:19:41 DEBUG : Creating backend with remote "TestBox:rclone-test-lufamul0mupa"
2025/03/07 09:19:41 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/07 09:19:42 DEBUG : box root 'rclone-test-lufamul0mupa': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/07 09:19:43 DEBUG : box root 'rclone-test-lufamul0mupa': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/03/07 09:19:44 INFO  : dir: Making directory
2025/03/07 09:19:46 INFO  : dir/subdir1: Making directory
2025/03/07 09:19:47 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823933026")
2025/03/07 09:19:47 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(0)|folder|ITEM_CREATE|310581188877|a9dc9f9e857df24bf7299cadf9f94312a182e915] new parent not found
2025/03/07 09:19:47 DEBUG : box root 'rclone-test-lufamul0mupa': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/07 09:19:47 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823934281")
2025/03/07 09:19:47 INFO  : dir/subdir3: Making directory
2025/03/07 09:19:48 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823934536")
2025/03/07 09:19:48 INFO  : dir/subdir2: Making directory
2025/03/07 09:19:49 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823935311")
2025/03/07 09:19:49 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir2"(1)|folder|ITEM_TRASH|310583937123|7d242c4c309f6e450b0edb4a012af239bb93dd37] ignored due to duplicate EventID
2025/03/07 09:19:49 DEBUG : box root 'rclone-test-lufamul0mupa': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/07 09:19:49 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823936281")
2025/03/07 09:19:50 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823936572")
2025/03/07 09:19:50 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir2"(1)|folder|ITEM_TRASH|310583937123|7d242c4c309f6e450b0edb4a012af239bb93dd37] ignored due to duplicate EventID
2025/03/07 09:19:51 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823937279")
2025/03/07 09:19:51 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir3"(1)|folder|ITEM_TRASH|310580200295|432acb1a6df31611bdadbeeddf7f70b3b7da4956] ignored due to duplicate EventID
2025/03/07 09:19:51 DEBUG : box root 'rclone-test-lufamul0mupa': ["file2"(1)|file|ITEM_TRASH|1796571198379|e88d89c42048ea53a7765c3a2ef487b50d75c549] ignored due to duplicate EventID
2025/03/07 09:19:51 DEBUG : box root 'rclone-test-lufamul0mupa': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/07 09:19:51 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823938290")
2025/03/07 09:19:51 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir3"(1)|folder|ITEM_TRASH|310580200295|432acb1a6df31611bdadbeeddf7f70b3b7da4956] ignored due to duplicate EventID
2025/03/07 09:19:52 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823938545")
2025/03/07 09:19:53 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823939277")
2025/03/07 09:19:53 DEBUG : box root 'rclone-test-lufamul0mupa': ["file2"(1)|file|ITEM_TRASH|1796571198379|e88d89c42048ea53a7765c3a2ef487b50d75c549] ignored due to duplicate EventID
2025/03/07 09:19:54 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823940277")
2025/03/07 09:19:55 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823941279")
2025/03/07 09:19:55 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(1)|folder|ITEM_TRASH|310581188877|0310c60394f6bf97f25a8b7ccf1c3059597b7225] ignored due to duplicate EventID
2025/03/07 09:19:55 DEBUG : box root 'rclone-test-lufamul0mupa': ["rclone-test-lufamul0mupa"(0)|folder|ITEM_CREATE|310582977376|75e2fc2302e851142ef0d1deff144aaf8b068557] new parent not found
2025/03/07 09:19:55 DEBUG : box root 'rclone-test-lufamul0mupa': Received 3 events, resulting in 0 paths and 0 notifications
2025/03/07 09:19:55 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823942280")
2025/03/07 09:19:56 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823942527")
2025/03/07 09:19:56 DEBUG : box root 'rclone-test-lufamul0mupa': ["file4"(1)|file|ITEM_TRASH|1796555185718|bcfcc92c8a362d55d5904ee09285f59b562ed09c] ignored due to duplicate EventID
2025/03/07 09:19:56 DEBUG : box root 'rclone-test-lufamul0mupa': ["rclone-test-lufamul0mupa"(0)|folder|ITEM_CREATE|310582977376|75e2fc2302e851142ef0d1deff144aaf8b068557] ignored due to duplicate EventID
2025/03/07 09:19:57 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823943273")
    fstests.go:931: Try 1/10 waiting for dirChanges and objChanges
2025/03/07 09:19:58 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823944274")
2025/03/07 09:19:58 DEBUG : box root 'rclone-test-lufamul0mupa': ["rclone-test-lufamul0mupa"(0)|folder|ITEM_CREATE|310582977376|75e2fc2302e851142ef0d1deff144aaf8b068557] ignored due to duplicate EventID
2025/03/07 09:19:58 DEBUG : box root 'rclone-test-lufamul0mupa': Received 2 events, resulting in 0 paths and 0 notifications
2025/03/07 09:19:58 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823945268")
2025/03/07 09:19:59 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823945521")
2025/03/07 09:20:00 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823946277")
2025/03/07 09:20:00 DEBUG : box root 'rclone-test-lufamul0mupa': ["rclone-test-ginudok5ladu"(1)|folder|ITEM_TRASH|310583661394|9536163d44d9dd3987f20e6c568e9c71e327f7bc] ignored due to duplicate EventID
2025/03/07 09:20:00 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir2"(0)|folder|ITEM_CREATE|310583639718|90f512a5ee55fc067bd7bc8d8d36dae7f464f8af] added new path ("dir/subdir2") for notify
2025/03/07 09:20:00 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/03/07 09:20:00 DEBUG : box root 'rclone-test-lufamul0mupa': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/07 09:20:00 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823947277")
    fstests.go:931: Try 2/10 waiting for dirChanges and objChanges
2025/03/07 09:20:01 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823947545")
2025/03/07 09:20:02 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823948273")
2025/03/07 09:20:02 DEBUG : box root 'rclone-test-lufamul0mupa': ["rclone-test-ginudok5ladu"(1)|folder|ITEM_TRASH|310583661394|9536163d44d9dd3987f20e6c568e9c71e327f7bc] ignored due to duplicate EventID
2025/03/07 09:20:03 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823949273")
2025/03/07 09:20:03 DEBUG : box root 'rclone-test-lufamul0mupa': ["file3"(1)|file|ITEM_TRASH|1796570444875|9ff8dbf77ce7794ea0737bb75e9310da323614d4] ignored due to duplicate EventID
2025/03/07 09:20:03 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir2"(0)|folder|ITEM_CREATE|310583639718|90f512a5ee55fc067bd7bc8d8d36dae7f464f8af] ignored due to duplicate EventID
    fstests.go:931: Try 3/10 waiting for dirChanges and objChanges
2025/03/07 09:20:04 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823950270")
2025/03/07 09:20:04 DEBUG : box root 'rclone-test-lufamul0mupa': ["file3"(1)|file|ITEM_TRASH|1796570444875|9ff8dbf77ce7794ea0737bb75e9310da323614d4] ignored due to duplicate EventID
2025/03/07 09:20:04 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir2"(0)|folder|ITEM_CREATE|310583639718|90f512a5ee55fc067bd7bc8d8d36dae7f464f8af] ignored due to duplicate EventID
2025/03/07 09:20:05 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823951281")
2025/03/07 09:20:05 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] added new path ("dir") for notify
2025/03/07 09:20:05 DEBUG : doChangeNotify("dir", 0)
2025/03/07 09:20:05 DEBUG : box root 'rclone-test-lufamul0mupa': Received 1 events, resulting in 1 paths and 1 notifications
2025/03/07 09:20:05 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823952271")
2025/03/07 09:20:05 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] ignored due to duplicate EventID
2025/03/07 09:20:06 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823952514")
2025/03/07 09:20:06 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] ignored due to duplicate EventID
2025/03/07 09:20:06 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(0)|folder|ITEM_CREATE|310582635679|c279e7b209e3c6f7acf648f855da9141539121b9] added new path ("dir/subdir1") for notify
2025/03/07 09:20:06 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/03/07 09:20:06 DEBUG : box root 'rclone-test-lufamul0mupa': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/07 09:20:06 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823953274")
    fstests.go:931: Try 4/10 waiting for dirChanges and objChanges
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823953510")
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] ignored due to duplicate EventID
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(0)|folder|ITEM_CREATE|310582635679|c279e7b209e3c6f7acf648f855da9141539121b9] ignored due to duplicate EventID
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir3"(0)|folder|ITEM_CREATE|310583764771|18d6787e006ed9b29c650957876b68c289021ea7] added new path ("dir/subdir3") for notify
2025/03/07 09:20:07 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': Received 3 events, resulting in 1 paths and 1 notifications
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823954274")
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] ignored due to duplicate EventID
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(0)|folder|ITEM_CREATE|310582635679|c279e7b209e3c6f7acf648f855da9141539121b9] ignored due to duplicate EventID
2025/03/07 09:20:07 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir3"(0)|folder|ITEM_CREATE|310583764771|18d6787e006ed9b29c650957876b68c289021ea7] ignored due to duplicate EventID
2025/03/07 09:20:08 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823954517")
2025/03/07 09:20:08 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] ignored due to duplicate EventID
2025/03/07 09:20:08 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(0)|folder|ITEM_CREATE|310582635679|c279e7b209e3c6f7acf648f855da9141539121b9] ignored due to duplicate EventID
2025/03/07 09:20:08 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir3"(0)|folder|ITEM_CREATE|310583764771|18d6787e006ed9b29c650957876b68c289021ea7] ignored due to duplicate EventID
2025/03/07 09:20:09 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823955266")
2025/03/07 09:20:09 DEBUG : box root 'rclone-test-lufamul0mupa': ["dir"(0)|folder|ITEM_CREATE|310583611151|b553ddd34a939cbacddc0f70134c1a980e43f0cf] ignored due to duplicate EventID
    fstests.go:931: Try 5/10 waiting for dirChanges and objChanges
2025/03/07 09:20:10 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823956274")
2025/03/07 09:20:10 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir1"(0)|folder|ITEM_CREATE|310582635679|c279e7b209e3c6f7acf648f855da9141539121b9] ignored due to duplicate EventID
2025/03/07 09:20:11 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823957266")
2025/03/07 09:20:11 DEBUG : box root 'rclone-test-lufamul0mupa': ["subdir3"(0)|folder|ITEM_CREATE|310583764771|18d6787e006ed9b29c650957876b68c289021ea7] ignored due to duplicate EventID
2025/03/07 09:20:12 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823958280")
    fstests.go:931: Try 6/10 waiting for dirChanges and objChanges
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823959278")
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': Received 1 events, resulting in 0 paths and 0 notifications
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823960278")
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': ["file4"(0)|file|ITEM_UPLOAD|1796563933273|515410b3b2918e751b64b935f48936e38375a476] added new path ("dir/file4") for notify
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': ["file2"(0)|file|ITEM_UPLOAD|1796572243152|8053558180e6bca012b1849afc4cf04bf5e734a5] added new path ("dir/file2") for notify
2025/03/07 09:20:13 DEBUG : doChangeNotify("dir/file4", 1)
2025/03/07 09:20:13 DEBUG : doChangeNotify("dir/file2", 1)
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': Received 2 events, resulting in 2 paths and 2 notifications
2025/03/07 09:20:13 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823960518")
2025/03/07 09:20:14 DEBUG : box root 'rclone-test-lufamul0mupa': ["file4"(0)|file|ITEM_UPLOAD|1796563933273|515410b3b2918e751b64b935f48936e38375a476] ignored due to duplicate EventID
2025/03/07 09:20:14 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823960762")
2025/03/07 09:20:14 DEBUG : box root 'rclone-test-lufamul0mupa': ["file2"(0)|file|ITEM_UPLOAD|1796572243152|8053558180e6bca012b1849afc4cf04bf5e734a5] ignored due to duplicate EventID
2025/03/07 09:20:14 DEBUG : box root 'rclone-test-lufamul0mupa': ["file3"(0)|file|ITEM_UPLOAD|1796570433082|be679537965e0dc08b9782924117613a5a3c6f49] added new path ("dir/file3") for notify
2025/03/07 09:20:14 DEBUG : doChangeNotify("dir/file3", 1)
2025/03/07 09:20:14 DEBUG : box root 'rclone-test-lufamul0mupa': Received 2 events, resulting in 1 paths and 1 notifications
2025/03/07 09:20:14 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823961278")
2025/03/07 09:20:15 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823961514")
2025/03/07 09:20:16 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823962273")
2025/03/07 09:20:16 DEBUG : box root 'rclone-test-lufamul0mupa': ["file3"(0)|file|ITEM_UPLOAD|1796570433082|be679537965e0dc08b9782924117613a5a3c6f49] ignored due to duplicate EventID
2025/03/07 09:20:17 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823963272")
2025/03/07 09:20:18 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823964271")
2025/03/07 09:20:18 DEBUG : box root 'rclone-test-lufamul0mupa': ["file3"(0)|file|ITEM_UPLOAD|1796570433082|be679537965e0dc08b9782924117613a5a3c6f49] ignored due to duplicate EventID
2025/03/07 09:20:19 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823965277")
2025/03/07 09:20:20 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823966274")
2025/03/07 09:20:21 DEBUG : box root 'rclone-test-lufamul0mupa': Checking for changes on remote (next_stream_position: "30401038823967262")
2025/03/07 09:20:23 DEBUG : box root 'rclone-test-lufamul0mupa': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/03/07 09:20:23 ERROR : : error listing: directory not found
2025/03/07 09:20:23 DEBUG : box root 'rclone-test-lufamul0mupa': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- PASS: TestIntegration (41.94s)
    --- PASS: TestIntegration/FsMkdir (39.39s)
        --- PASS: TestIntegration/FsMkdir/FsChangeNotify (37.20s)
PASS
ok  	github.com/rclone/rclone/backend/box	41.954s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished OK in 42.551398822s (try 4/5)