"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Starting (try 5/5)
=== RUN   TestIntegration
    fstests.go:442: Using remote "TestBox:"
2025/02/21 03:04:39 DEBUG : Creating backend with remote "TestBox:rclone-test-xucudoy6bodo"
2025/02/21 03:04:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/02/21 03:04:39 DEBUG : box root 'rclone-test-xucudoy6bodo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/02/21 03:04:40 DEBUG : box root 'rclone-test-xucudoy6bodo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsChangeNotify
2025/02/21 03:04:41 INFO  : dir: Making directory
2025/02/21 03:04:43 INFO  : dir/subdir1: Making directory
2025/02/21 03:04:44 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591829810")
2025/02/21 03:04:44 INFO  : dir/subdir3: Making directory
2025/02/21 03:04:45 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591831045")
2025/02/21 03:04:45 INFO  : dir/subdir2: Making directory
2025/02/21 03:04:46 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591832060")
2025/02/21 03:04:46 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-bekecat4"(0)|file|ITEM_COPY|1783709774258|b45a8cf1b3344c87b6943d61a92267f3228e0a62] new parent not found
2025/02/21 03:04:46 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 1 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:46 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591833058")
2025/02/21 03:04:46 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 1 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:46 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591833295")
2025/02/21 03:04:47 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591833531")
2025/02/21 03:04:48 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591834044")
2025/02/21 03:04:48 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783709774258|59678fe8539e9f90f520c8300d1a1bd1ed04263c] new parent not found
2025/02/21 03:04:48 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 1 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:48 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591835044")
2025/02/21 03:04:49 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591835281")
2025/02/21 03:04:49 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file4"(0)|file|ITEM_UPLOAD|1783710159833|9cddc198761f3bd422af2e765b3d65752a2ea415] new parent not found
2025/02/21 03:04:49 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783706915273|fcd49a23ec3a33fded216205318e3961361e7b1a] new parent not found
2025/02/21 03:04:49 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 2 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:49 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591836042")
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591836281")
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783710395822|61978e130a52840c479fd4d6309f56e192326fdf] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_RENAME|1783711499487|73942a16ed7f2530ee06714eec01ce35f326fbad] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file3"(0)|file|ITEM_UPLOAD|1783708564610|d3e0acaf387e27e6c598c06b82dc270ed6d61a60] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783709774258|b40be81f1e70c627533cb38f7d2d6e733db01ea3] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-pujanil5"(0)|file|ITEM_COPY|1783706070493|0e65859218b083a42036b3da46cc4a9857da05e6] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783706099059|483bcf87242fc25ad1c86aafc2c61c5cd492630c] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783704798090|6944e40bde2045afbc9f8f8d3e5abe8fcbf17306] new parent not found
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 9 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:50 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591837049")
2025/02/21 03:04:51 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591837362")
2025/02/21 03:04:51 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783710395822|61978e130a52840c479fd4d6309f56e192326fdf] ignored due to duplicate EventID
2025/02/21 03:04:51 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_RENAME|1783711499487|73942a16ed7f2530ee06714eec01ce35f326fbad] ignored due to duplicate EventID
2025/02/21 03:04:51 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir1"(1)|folder|ITEM_TRASH|308480065416|862c9a1a426523e13cc8ae5b97aa04f3f8b8e771] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591838052")
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file3"(0)|file|ITEM_UPLOAD|1783708564610|d3e0acaf387e27e6c598c06b82dc270ed6d61a60] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783704798090|49a5540716b50eafd664eab99c8035f8c1fddf3e] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783709774258|b40be81f1e70c627533cb38f7d2d6e733db01ea3] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-pujanil5"(0)|file|ITEM_COPY|1783706070493|0e65859218b083a42036b3da46cc4a9857da05e6] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783706099059|483bcf87242fc25ad1c86aafc2c61c5cd492630c] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783704798090|6944e40bde2045afbc9f8f8d3e5abe8fcbf17306] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783691265229|8c0fef77527082d7897cbace16bf4ec1b9242d9f] new parent not found
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 9 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591839071")
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783706099059|483bcf87242fc25ad1c86aafc2c61c5cd492630c] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783704798090|6944e40bde2045afbc9f8f8d3e5abe8fcbf17306] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783691265229|8c0fef77527082d7897cbace16bf4ec1b9242d9f] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783706099059|286dd67f7842c1316cfd77de459f6a72345edae9] ignored due to duplicate EventID
2025/02/21 03:04:52 DEBUG : box root 'rclone-test-xucudoy6bodo': ["rclone-test-detajud0gaka"(1)|folder|ITEM_TRASH|308480714187|929f8b1cddbc63df74c091d2623249ff7151bac7] ignored due to duplicate EventID
2025/02/21 03:04:53 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591839361")
2025/02/21 03:04:53 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783706099059|483bcf87242fc25ad1c86aafc2c61c5cd492630c] ignored due to duplicate EventID
    fstests.go:935: Try 1/10 waiting for dirChanges and objChanges
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591840034")
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783704798090|6944e40bde2045afbc9f8f8d3e5abe8fcbf17306] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783691265229|8c0fef77527082d7897cbace16bf4ec1b9242d9f] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783706099059|286dd67f7842c1316cfd77de459f6a72345edae9] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["rclone-test-detajud0gaka"(1)|folder|ITEM_TRASH|308480714187|929f8b1cddbc63df74c091d2623249ff7151bac7] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 5 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591841051")
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783691265229|8c0fef77527082d7897cbace16bf4ec1b9242d9f] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783706099059|286dd67f7842c1316cfd77de459f6a72345edae9] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["rclone-test-detajud0gaka"(1)|folder|ITEM_TRASH|308480714187|929f8b1cddbc63df74c091d2623249ff7151bac7] ignored due to duplicate EventID
2025/02/21 03:04:54 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(3)|file|ITEM_TRASH|1783709774258|b25071a5f396c46677f909e477555a61da8b8584] ignored due to duplicate EventID
2025/02/21 03:04:55 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591841314")
2025/02/21 03:04:56 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591842044")
2025/02/21 03:04:56 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783706099059|286dd67f7842c1316cfd77de459f6a72345edae9] ignored due to duplicate EventID
2025/02/21 03:04:56 DEBUG : box root 'rclone-test-xucudoy6bodo': ["rclone-test-detajud0gaka"(1)|folder|ITEM_TRASH|308480714187|929f8b1cddbc63df74c091d2623249ff7151bac7] ignored due to duplicate EventID
    fstests.go:935: Try 2/10 waiting for dirChanges and objChanges
2025/02/21 03:04:57 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591843044")
2025/02/21 03:04:57 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(3)|file|ITEM_TRASH|1783709774258|b25071a5f396c46677f909e477555a61da8b8584] ignored due to duplicate EventID
2025/02/21 03:04:58 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591844037")
2025/02/21 03:04:59 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591845035")
2025/02/21 03:04:59 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783706070493|653ef46a5d203619e1068ecfab74f67551e68f14] new parent not found
2025/02/21 03:04:59 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783711936769|75180c92be36b6337be0164b5b22e8bce1246cf2] new parent not found
2025/02/21 03:04:59 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 6 events, resulting in 0 paths and 0 notifications
2025/02/21 03:04:59 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591846043")
    fstests.go:935: Try 3/10 waiting for dirChanges and objChanges
2025/02/21 03:05:00 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591846312")
2025/02/21 03:05:00 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783706070493|653ef46a5d203619e1068ecfab74f67551e68f14] ignored due to duplicate EventID
2025/02/21 03:05:01 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591847125")
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591848052")
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783691265229|9ef4c6c9b3589589e177bf38cf0ca76aec3f46d5] ignored due to duplicate EventID
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["dir"(1)|folder|ITEM_TRASH|308483303288|bfad59bbcbd9119969a65afefa0b6fd4fa57f530] ignored due to duplicate EventID
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783711936769|75180c92be36b6337be0164b5b22e8bce1246cf2] ignored due to duplicate EventID
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783701851547|2c3cd3391a8fd4477cba2cb2fb8b5c8c13f6c447] new parent not found
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 5 events, resulting in 0 paths and 0 notifications
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591849047")
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["dir"(1)|folder|ITEM_TRASH|308483303288|bfad59bbcbd9119969a65afefa0b6fd4fa57f530] ignored due to duplicate EventID
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783711936769|75180c92be36b6337be0164b5b22e8bce1246cf2] ignored due to duplicate EventID
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783701851547|f02f08bd84ad5aa9c47b87c9736693a45d44c728] ignored due to duplicate EventID
2025/02/21 03:05:02 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783701851547|2c3cd3391a8fd4477cba2cb2fb8b5c8c13f6c447] ignored due to duplicate EventID
    fstests.go:935: Try 4/10 waiting for dirChanges and objChanges
2025/02/21 03:05:03 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591849313")
2025/02/21 03:05:03 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783711936769|75180c92be36b6337be0164b5b22e8bce1246cf2] ignored due to duplicate EventID
2025/02/21 03:05:04 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591850034")
2025/02/21 03:05:04 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783701851547|f02f08bd84ad5aa9c47b87c9736693a45d44c728] ignored due to duplicate EventID
2025/02/21 03:05:05 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591851041")
2025/02/21 03:05:05 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783701851547|2c3cd3391a8fd4477cba2cb2fb8b5c8c13f6c447] ignored due to duplicate EventID
    fstests.go:935: Try 5/10 waiting for dirChanges and objChanges
2025/02/21 03:05:06 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591852043")
2025/02/21 03:05:07 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591853045")
2025/02/21 03:05:07 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_RENAME|1783711499487|692ee506372299199c6effc4760f305ec958c370] new parent not found
2025/02/21 03:05:07 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file4"(0)|file|ITEM_UPLOAD|1783692642393|2ae861a6902284bdd57082dad4ef2b25f4476ec2] added new path ("dir/file4") for notify
2025/02/21 03:05:07 DEBUG : doChangeNotify("dir/file4", 1)
2025/02/21 03:05:07 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 3 events, resulting in 1 paths and 1 notifications
2025/02/21 03:05:07 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591854040")
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591854284")
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_RENAME|1783711499487|4de05572072ccc1a11f733ca9ee3a0846137d66a] new parent not found
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': ["rclone-test-xucudoy6bodo"(0)|folder|ITEM_CREATE|308482114874|7dccac4d12b9004d7b82ede91848f10add4c3f46] new parent not found
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir3"(0)|folder|ITEM_CREATE|308481222368|68fba84806a1d0eacbe6310c03209ab7a037f158] added new path ("dir/subdir3") for notify
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-bifuvoq1"(0)|file|ITEM_COPY|1783697862425|3f9eb1950b12a3393f2a93f5e9ebf5fee9867bf9] new parent not found
2025/02/21 03:05:08 DEBUG : doChangeNotify("dir/subdir3", 0)
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 8 events, resulting in 1 paths and 1 notifications
2025/02/21 03:05:08 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591855059")
    fstests.go:935: Try 6/10 waiting for dirChanges and objChanges
2025/02/21 03:05:09 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591855351")
2025/02/21 03:05:09 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(3)|file|ITEM_TRASH|1783706070493|7513310f595bc21fdd7bc0cce53ae57015ea8eda] ignored due to duplicate EventID
2025/02/21 03:05:09 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783711936769|d8576650d5a6be4af8cecb1066340b85c9822119] ignored due to duplicate EventID
2025/02/21 03:05:10 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591856036")
2025/02/21 03:05:10 DEBUG : box root 'rclone-test-xucudoy6bodo': ["rclone-test-xucudoy6bodo"(0)|folder|ITEM_CREATE|308482114874|7dccac4d12b9004d7b82ede91848f10add4c3f46] ignored due to duplicate EventID
2025/02/21 03:05:10 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(1)|file|ITEM_TRASH|1783689589291|fe73f0577ceb9fcf52ac5f491d9bf4a8664cee76] ignored due to duplicate EventID
2025/02/21 03:05:11 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591857035")
2025/02/21 03:05:11 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir3"(0)|folder|ITEM_CREATE|308481222368|68fba84806a1d0eacbe6310c03209ab7a037f158] ignored due to duplicate EventID
2025/02/21 03:05:11 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file3"(1)|file|ITEM_TRASH|1783708564610|6acb5a718eb3cbf92ae6f314609b1f1f6ea49eff] ignored due to duplicate EventID
    fstests.go:935: Try 7/10 waiting for dirChanges and objChanges
2025/02/21 03:05:12 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591858040")
2025/02/21 03:05:12 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-bifuvoq1"(0)|file|ITEM_COPY|1783697862425|3f9eb1950b12a3393f2a93f5e9ebf5fee9867bf9] ignored due to duplicate EventID
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591859046")
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': ["dir"(0)|folder|ITEM_CREATE|308477778056|343f98430edee194aca28863c02a5f5628e7b5ae] added new path ("dir") for notify
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file2"(0)|file|ITEM_UPLOAD|1783701207662|a1532f6dbb367dab1ab1e026f645f588e906ce79] added new path ("dir/file2") for notify
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir1"(0)|folder|ITEM_CREATE|308481023297|362b4e4642614018fd65ae91dcdae92a25ba702e] added new path ("dir/subdir1") for notify
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783706070493|d93f0f7a0504ce3ecee664abebab4fd8df25ff5f] new parent not found
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783697862425|014f30f8d13f8f8fb65dbfaadab09eef3e034449] new parent not found
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file3"(0)|file|ITEM_UPLOAD|1783712094684|3013fa5855a4d427c56c5a2aeb63ade821349fd3] added new path ("dir/file3") for notify
2025/02/21 03:05:13 DEBUG : doChangeNotify("dir", 0)
2025/02/21 03:05:13 DEBUG : doChangeNotify("dir/file2", 1)
2025/02/21 03:05:13 DEBUG : doChangeNotify("dir/subdir1", 0)
2025/02/21 03:05:13 DEBUG : doChangeNotify("dir/file3", 1)
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 6 events, resulting in 4 paths and 4 notifications
2025/02/21 03:05:13 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591860042")
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591860308")
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': ["dir"(0)|folder|ITEM_CREATE|308477778056|343f98430edee194aca28863c02a5f5628e7b5ae] ignored due to duplicate EventID
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file2"(0)|file|ITEM_UPLOAD|1783701207662|a1532f6dbb367dab1ab1e026f645f588e906ce79] ignored due to duplicate EventID
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir1"(0)|folder|ITEM_CREATE|308481023297|362b4e4642614018fd65ae91dcdae92a25ba702e] ignored due to duplicate EventID
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783706070493|d93f0f7a0504ce3ecee664abebab4fd8df25ff5f] ignored due to duplicate EventID
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783697862425|014f30f8d13f8f8fb65dbfaadab09eef3e034449] ignored due to duplicate EventID
2025/02/21 03:05:14 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file3"(0)|file|ITEM_UPLOAD|1783712094684|3013fa5855a4d427c56c5a2aeb63ade821349fd3] ignored due to duplicate EventID
    fstests.go:935: Try 8/10 waiting for dirChanges and objChanges
2025/02/21 03:05:15 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591861035")
2025/02/21 03:05:15 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file2"(0)|file|ITEM_UPLOAD|1783701207662|a1532f6dbb367dab1ab1e026f645f588e906ce79] ignored due to duplicate EventID
2025/02/21 03:05:16 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591862037")
2025/02/21 03:05:16 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir1"(0)|folder|ITEM_CREATE|308481023297|362b4e4642614018fd65ae91dcdae92a25ba702e] ignored due to duplicate EventID
2025/02/21 03:05:17 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591863036")
2025/02/21 03:05:17 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783706070493|d93f0f7a0504ce3ecee664abebab4fd8df25ff5f] ignored due to duplicate EventID
2025/02/21 03:05:17 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783697862425|014f30f8d13f8f8fb65dbfaadab09eef3e034449] ignored due to duplicate EventID
2025/02/21 03:05:17 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file3"(0)|file|ITEM_UPLOAD|1783712094684|3013fa5855a4d427c56c5a2aeb63ade821349fd3] ignored due to duplicate EventID
    fstests.go:935: Try 9/10 waiting for dirChanges and objChanges
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591864031")
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783697862425|323dce753a4345b1aa880e372bcbe1099e96067c] new parent not found
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783689589291|9c1aae22d258ac23837ea992ee3aaced995edbae] new parent not found
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir2"(0)|folder|ITEM_CREATE|308485041416|8a07e50d97c0952d67b6b7d5218a305b017b79b2] added new path ("dir/subdir2") for notify
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-paqafuy3"(0)|file|ITEM_COPY|1783710712707|10c2c5db29a5047a3394633191e27a483a166e43] new parent not found
2025/02/21 03:05:18 DEBUG : doChangeNotify("dir/subdir2", 0)
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 5 events, resulting in 1 paths and 1 notifications
2025/02/21 03:05:18 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591865043")
2025/02/21 03:05:19 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591865307")
2025/02/21 03:05:19 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(2)|file|ITEM_UPLOAD|1783697862425|323dce753a4345b1aa880e372bcbe1099e96067c] ignored due to duplicate EventID
2025/02/21 03:05:20 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591866033")
2025/02/21 03:05:20 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file4"(1)|file|ITEM_TRASH|1783710159833|84c22b56fbf3ab62a045d17d63ab84df7b1a1fb4] ignored due to duplicate EventID
2025/02/21 03:05:20 DEBUG : box root 'rclone-test-xucudoy6bodo': ["modtime_write_test"(0)|file|ITEM_UPLOAD|1783689589291|9c1aae22d258ac23837ea992ee3aaced995edbae] ignored due to duplicate EventID
    fstests.go:939: map[dir:{} dir/subdir1:{} dir/subdir2:{} dir/subdir3:{}] does not contain [dir/subdir1 dir/subdir3 dir/subdir2] or 
        map[dir/file2:{} dir/file3:{} dir/file4:{}] does not contain [dir/file2 dir/file4 dir/file3]
2025/02/21 03:05:21 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591867037")
2025/02/21 03:05:21 DEBUG : box root 'rclone-test-xucudoy6bodo': ["subdir2"(0)|folder|ITEM_CREATE|308485041416|8a07e50d97c0952d67b6b7d5218a305b017b79b2] ignored due to duplicate EventID
2025/02/21 03:05:21 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt-rclone-copy-paqafuy3"(0)|file|ITEM_COPY|1783710712707|10c2c5db29a5047a3394633191e27a483a166e43] ignored due to duplicate EventID
2025/02/21 03:05:22 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591868032")
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591869030")
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 1 events, resulting in 0 paths and 0 notifications
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591870035")
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783710712707|8e7dfe0339507cac7202be193e726b51fb9003f0] new parent not found
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': Received 1 events, resulting in 0 paths and 0 notifications
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591870262")
2025/02/21 03:05:23 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783710712707|8e7dfe0339507cac7202be193e726b51fb9003f0] ignored due to duplicate EventID
2025/02/21 03:05:24 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591870491")
2025/02/21 03:05:25 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591871030")
2025/02/21 03:05:25 DEBUG : box root 'rclone-test-xucudoy6bodo': ["file1.txt"(1)|file|ITEM_RENAME|1783710712707|8e7dfe0339507cac7202be193e726b51fb9003f0] ignored due to duplicate EventID
2025/02/21 03:05:26 DEBUG : box root 'rclone-test-xucudoy6bodo': Checking for changes on remote (next_stream_position: "30401037591872032")
2025/02/21 03:05:27 DEBUG : box root 'rclone-test-xucudoy6bodo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/02/21 03:05:27 ERROR : : error listing: directory not found
2025/02/21 03:05:28 DEBUG : box root 'rclone-test-xucudoy6bodo': Ignoring "Shared folder — ForNick" - unknown type "web_link"
--- FAIL: TestIntegration (48.97s)
    --- FAIL: TestIntegration/FsMkdir (46.63s)
        --- FAIL: TestIntegration/FsMkdir/FsChangeNotify (44.55s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/box	48.979s
"go test -v -timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsChangeNotify$'" - Finished ERROR in 49.512584215s (try 5/5): exit status 1: Failed [TestIntegration/FsMkdir/FsChangeNotify]