"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 3/5)
=== RUN   TestIntegration
    fstests.go:442: Using remote "TestBox:"
2025/01/20 02:31:27 DEBUG : Creating backend with remote "TestBox:rclone-test-bivifec3xuge"
2025/01/20 02:31:27 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/01/20 02:31:28 DEBUG : box root 'rclone-test-bivifec3xuge': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/01/20 02:31:29 DEBUG : box root 'rclone-test-bivifec3xuge': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/01/20 02:31:31 INFO  : dir: Making directory
2025/01/20 02:31:33 INFO  : dir/subdir1: Making directory
2025/01/20 02:31:34 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825039856")
2025/01/20 02:31:34 INFO  : dir/subdir3: Making directory
2025/01/20 02:31:35 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825041085")
2025/01/20 02:31:35 INFO  : dir/subdir2: Making directory
2025/01/20 02:31:36 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825042079")
2025/01/20 02:31:37 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825043098")
2025/01/20 02:31:38 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825044085")
2025/01/20 02:31:39 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825045084")
2025/01/20 02:31:40 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825046084")
2025/01/20 02:31:41 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825047084")
    fstests.go:935: Try 1/10 waiting for dirChanges and objChanges
2025/01/20 02:31:42 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825048090")
2025/01/20 02:31:43 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825049087")
2025/01/20 02:31:43 DEBUG : box root 'rclone-test-bivifec3xuge': ["test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kazevub4"(4)|file|ITEM_RENAME|1754026957785|fea58dac32563a79eae9f41990931ed951c34e02] new parent not found
2025/01/20 02:31:43 DEBUG : box root 'rclone-test-bivifec3xuge': Received 1 events, resulting in 0 paths and 0 notifications
2025/01/20 02:31:43 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825050086")
2025/01/20 02:31:43 DEBUG : box root 'rclone-test-bivifec3xuge': ["test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kazevub4"(4)|file|ITEM_RENAME|1754026957785|fea58dac32563a79eae9f41990931ed951c34e02] ignored due to duplicate EventID
2025/01/20 02:31:44 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825050325")
2025/01/20 02:31:44 DEBUG : box root 'rclone-test-bivifec3xuge': ["test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kazevub4"(4)|file|ITEM_RENAME|1754026957785|fea58dac32563a79eae9f41990931ed951c34e02] ignored due to duplicate EventID
    fstests.go:935: Try 2/10 waiting for dirChanges and objChanges
2025/01/20 02:31:45 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825051103")
2025/01/20 02:31:45 DEBUG : box root 'rclone-test-bivifec3xuge': ["test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kazevub4"(4)|file|ITEM_RENAME|1754026957785|fea58dac32563a79eae9f41990931ed951c34e02] ignored due to duplicate EventID
2025/01/20 02:31:46 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825052102")
2025/01/20 02:31:46 DEBUG : box root 'rclone-test-bivifec3xuge': ["test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kazevub4"(4)|file|ITEM_RENAME|1754026957785|fea58dac32563a79eae9f41990931ed951c34e02] ignored due to duplicate EventID
2025/01/20 02:31:47 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825053200")
2025/01/20 02:31:47 DEBUG : box root 'rclone-test-bivifec3xuge': ["test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.kazevub4"(4)|file|ITEM_RENAME|1754026957785|fea58dac32563a79eae9f41990931ed951c34e02] ignored due to duplicate EventID
    fstests.go:935: Try 3/10 waiting for dirChanges and objChanges
2025/01/20 02:31:48 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825054118")
2025/01/20 02:31:49 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825055106")
2025/01/20 02:31:50 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825056132")
    fstests.go:935: Try 4/10 waiting for dirChanges and objChanges
2025/01/20 02:31:51 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825057163")
2025/01/20 02:31:52 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825058132")
2025/01/20 02:31:53 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825059215")
2025/01/20 02:31:53 DEBUG : box root 'rclone-test-bivifec3xuge': Received 1 events, resulting in 0 paths and 0 notifications
2025/01/20 02:31:53 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825060293")
    fstests.go:935: Try 5/10 waiting for dirChanges and objChanges
2025/01/20 02:31:54 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|file|ITEM_TRASH|1754041856995|841555ff827fcab4e7623fe00a983ab4daafc426] ignored due to duplicate EventID
2025/01/20 02:31:54 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825060952")
2025/01/20 02:31:54 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|file|ITEM_TRASH|1754041856995|841555ff827fcab4e7623fe00a983ab4daafc426] ignored due to duplicate EventID
2025/01/20 02:31:55 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825061598")
2025/01/20 02:31:55 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|file|ITEM_TRASH|1754041856995|841555ff827fcab4e7623fe00a983ab4daafc426] ignored due to duplicate EventID
2025/01/20 02:31:56 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825062437")
2025/01/20 02:31:56 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|file|ITEM_TRASH|1754041856995|841555ff827fcab4e7623fe00a983ab4daafc426] ignored due to duplicate EventID
    fstests.go:935: Try 6/10 waiting for dirChanges and objChanges
2025/01/20 02:31:57 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825063181")
2025/01/20 02:31:57 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|file|ITEM_TRASH|1754041856995|841555ff827fcab4e7623fe00a983ab4daafc426] ignored due to duplicate EventID
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825064141")
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': Received 1 events, resulting in 0 paths and 0 notifications
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825065204")
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing space␠"(1)|file|ITEM_TRASH|1754038119769|f53c1d4fb48a10d6c07090cb1ec8534bd3b09955] ignored due to duplicate EventID
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] new parent not found
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': Received 2 events, resulting in 0 paths and 0 notifications
2025/01/20 02:31:58 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825065482")
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing space␠"(1)|file|ITEM_TRASH|1754038119769|f53c1d4fb48a10d6c07090cb1ec8534bd3b09955] ignored due to duplicate EventID
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] ignored due to duplicate EventID
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825066197")
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing space␠"(1)|file|ITEM_TRASH|1754038119769|f53c1d4fb48a10d6c07090cb1ec8534bd3b09955] ignored due to duplicate EventID
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] ignored due to duplicate EventID
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': Received 3 events, resulting in 0 paths and 0 notifications
2025/01/20 02:31:59 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825066572")
    fstests.go:935: Try 7/10 waiting for dirChanges and objChanges
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing space␠"(1)|file|ITEM_TRASH|1754038119769|f53c1d4fb48a10d6c07090cb1ec8534bd3b09955] ignored due to duplicate EventID
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] ignored due to duplicate EventID
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': ["very"(1)|folder|ITEM_TRASH|303567190869|cba2c66772fde15780cc10317ee886846b5f3ed1] ignored due to duplicate EventID
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825066987")
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing space␠"(1)|file|ITEM_TRASH|1754038119769|f53c1d4fb48a10d6c07090cb1ec8534bd3b09955] ignored due to duplicate EventID
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] ignored due to duplicate EventID
2025/01/20 02:32:00 DEBUG : box root 'rclone-test-bivifec3xuge': ["very"(1)|folder|ITEM_TRASH|303567190869|cba2c66772fde15780cc10317ee886846b5f3ed1] ignored due to duplicate EventID
2025/01/20 02:32:01 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825067636")
2025/01/20 02:32:01 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing space␠"(1)|file|ITEM_TRASH|1754038119769|f53c1d4fb48a10d6c07090cb1ec8534bd3b09955] ignored due to duplicate EventID
2025/01/20 02:32:01 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] ignored due to duplicate EventID
2025/01/20 02:32:01 DEBUG : box root 'rclone-test-bivifec3xuge': ["very"(1)|folder|ITEM_TRASH|303567190869|cba2c66772fde15780cc10317ee886846b5f3ed1] ignored due to duplicate EventID
2025/01/20 02:32:02 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825068503")
2025/01/20 02:32:02 DEBUG : box root 'rclone-test-bivifec3xuge': ["only13.rclone_chunk.001_dcxazt"(0)|file|ITEM_UPLOAD|1754049585035|a9c5d380e7953a8f77380526edbd271dda1a8192] ignored due to duplicate EventID
2025/01/20 02:32:02 DEBUG : box root 'rclone-test-bivifec3xuge': ["very"(1)|folder|ITEM_TRASH|303567190869|cba2c66772fde15780cc10317ee886846b5f3ed1] ignored due to duplicate EventID
    fstests.go:935: Try 8/10 waiting for dirChanges and objChanges
2025/01/20 02:32:03 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825069485")
2025/01/20 02:32:03 DEBUG : box root 'rclone-test-bivifec3xuge': ["very"(1)|folder|ITEM_TRASH|303567190869|cba2c66772fde15780cc10317ee886846b5f3ed1] ignored due to duplicate EventID
2025/01/20 02:32:03 DEBUG : box root 'rclone-test-bivifec3xuge': ["both14.rclone_chunk.001_dcxepp"(0)|file|ITEM_UPLOAD|1754036802208|adfcd227171c4373ad85f6df21c92af080352367] new parent not found
2025/01/20 02:32:03 DEBUG : box root 'rclone-test-bivifec3xuge': Received 2 events, resulting in 0 paths and 0 notifications
2025/01/20 02:32:03 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825070479")
2025/01/20 02:32:04 DEBUG : box root 'rclone-test-bivifec3xuge': ["both14.rclone_chunk.001_dcxepp"(0)|file|ITEM_UPLOAD|1754036802208|adfcd227171c4373ad85f6df21c92af080352367] ignored due to duplicate EventID
2025/01/20 02:32:04 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825071094")
2025/01/20 02:32:04 DEBUG : box root 'rclone-test-bivifec3xuge': ["both14.rclone_chunk.001_dcxepp"(0)|file|ITEM_UPLOAD|1754036802208|adfcd227171c4373ad85f6df21c92af080352367] ignored due to duplicate EventID
2025/01/20 02:32:05 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825071530")
2025/01/20 02:32:05 DEBUG : box root 'rclone-test-bivifec3xuge': ["both14.rclone_chunk.001_dcxepp"(0)|file|ITEM_UPLOAD|1754036802208|adfcd227171c4373ad85f6df21c92af080352367] ignored due to duplicate EventID
    fstests.go:935: Try 9/10 waiting for dirChanges and objChanges
2025/01/20 02:32:06 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825072344")
2025/01/20 02:32:06 DEBUG : box root 'rclone-test-bivifec3xuge': ["both14.rclone_chunk.001_dcxepp"(0)|file|ITEM_UPLOAD|1754036802208|adfcd227171c4373ad85f6df21c92af080352367] ignored due to duplicate EventID
2025/01/20 02:32:07 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825073111")
2025/01/20 02:32:07 DEBUG : box root 'rclone-test-bivifec3xuge': ["both14.rclone_chunk.001_dcxepp"(0)|file|ITEM_UPLOAD|1754036802208|adfcd227171c4373ad85f6df21c92af080352367] ignored due to duplicate EventID
2025/01/20 02:32:08 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825074112")
2025/01/20 02:32:08 DEBUG : box root 'rclone-test-bivifec3xuge': Received 1 events, resulting in 0 paths and 0 notifications
2025/01/20 02:32:08 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825075131")
2025/01/20 02:32:08 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|folder|ITEM_TRASH|303569550484|cdab133eb222df70af8334814580b4ea2f082350] ignored due to duplicate EventID
    fstests.go:939: map[] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[] does not contain [dir/file2 dir/file4 dir/file3]
2025/01/20 02:32:09 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825075391")
2025/01/20 02:32:09 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|folder|ITEM_TRASH|303569550484|cdab133eb222df70af8334814580b4ea2f082350] ignored due to duplicate EventID
2025/01/20 02:32:10 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825076171")
2025/01/20 02:32:10 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|folder|ITEM_TRASH|303569550484|cdab133eb222df70af8334814580b4ea2f082350] ignored due to duplicate EventID
2025/01/20 02:32:11 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825077107")
2025/01/20 02:32:11 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|folder|ITEM_TRASH|303569550484|cdab133eb222df70af8334814580b4ea2f082350] ignored due to duplicate EventID
2025/01/20 02:32:12 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825078102")
2025/01/20 02:32:12 DEBUG : box root 'rclone-test-bivifec3xuge': ["trailing HT␉"(1)|folder|ITEM_TRASH|303569550484|cdab133eb222df70af8334814580b4ea2f082350] ignored due to duplicate EventID
2025/01/20 02:32:13 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825079419")
2025/01/20 02:32:14 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825080147")
2025/01/20 02:32:15 DEBUG : box root 'rclone-test-bivifec3xuge': Checking for changes on remote (next_stream_position: "30401034825081159")
2025/01/20 02:32:17 DEBUG : box root 'rclone-test-bivifec3xuge': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/01/20 02:32:17 ERROR : : error listing: directory not found
2025/01/20 02:32:18 DEBUG : box root 'rclone-test-bivifec3xuge': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (50.38s)
    --- FAIL: TestIntegration/FsMkdir (46.97s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (44.02s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	50.402s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 51.343503256s (try 3/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]