"./operations.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestApplyTransforms|TestCheckSumDownload|TestRemoveExisting)$'" - Starting (try 2/5) 2026/08/28 01:37:59 DEBUG : Creating backend with remote "TestPcloud:rclone-test-zakusib3sole" 2026/08/28 01:37:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:37:59 DEBUG : Creating backend with remote "/tmp/rclone2995383019" === RUN TestCheckSumDownload run.go:198: Remote "pcloud root 'rclone-test-zakusib3sole'", Local "Local file system at /tmp/rclone2995383019", Modify Window "1s" 2026/08/28 01:37:59 DEBUG : Creating backend with remote "TestPcloud:rclone-test-zakusib3sole/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (11.10s) --- PASS: TestCheckSumDownload/subtest1 (1.04s) --- PASS: TestCheckSumDownload/subtest2 (0.41s) --- PASS: TestCheckSumDownload/subtest3 (0.43s) --- PASS: TestCheckSumDownload/subtest4 (0.43s) --- PASS: TestCheckSumDownload/subtest5 (0.42s) --- PASS: TestCheckSumDownload/subtest6 (0.43s) --- PASS: TestCheckSumDownload/subtest7 (0.42s) === RUN TestApplyTransforms 2026/08/28 01:38:11 DEBUG : Creating backend with remote "TestPcloud:rclone-test-lefuxum3pepu" 2026/08/28 01:38:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:11 DEBUG : Creating backend with remote "/tmp/rclone234649390" run.go:198: Remote "pcloud root 'rclone-test-lefuxum3pepu'", Local "Local file system at /tmp/rclone234649390", Modify Window "1s" upper checkfile vs. lower remote (without normalization) 2026/08/28 01:38:12 ERROR : hello, world!: sum not found 2026/08/28 01:38:12 ERROR : HELLO, WORLD!: file not in pcloud root 'rclone-test-lefuxum3pepu' 2026/08/28 01:38:12 NOTICE: pcloud root 'rclone-test-lefuxum3pepu': 1 files missing 2026/08/28 01:38:12 NOTICE: 1 hashes missing 2026/08/28 01:38:12 NOTICE: pcloud root 'rclone-test-lefuxum3pepu': 1 differences found 2026/08/28 01:38:12 NOTICE: pcloud root 'rclone-test-lefuxum3pepu': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/08/28 01:38:12 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:12 +0000": parsing time "Fri, 28 Aug 2026 01:38:12 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:12 +0000" as " " 2026/08/28 01:38:12 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:12 NOTICE: pcloud root 'rclone-test-lefuxum3pepu': 0 differences found 2026/08/28 01:38:12 NOTICE: pcloud root 'rclone-test-lefuxum3pepu': 1 matching files 2026/08/28 01:38:12 DEBUG : Creating backend with remote "TestPcloud:rclone-test-piqanun1siwa" 2026/08/28 01:38:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:13 DEBUG : Creating backend with remote "/tmp/rclone724467944" run.go:198: Remote "pcloud root 'rclone-test-piqanun1siwa'", Local "Local file system at /tmp/rclone724467944", Modify Window "1s" lower checkfile vs. upper remote (without normalization) 2026/08/28 01:38:15 ERROR : hello, world!: file not in pcloud root 'rclone-test-piqanun1siwa' 2026/08/28 01:38:15 NOTICE: pcloud root 'rclone-test-piqanun1siwa': 1 files missing 2026/08/28 01:38:15 NOTICE: pcloud root 'rclone-test-piqanun1siwa': 0 differences found 2026/08/28 01:38:15 NOTICE: pcloud root 'rclone-test-piqanun1siwa': 1 errors while checking lower checkfile vs. upper remote (with normalization) 2026/08/28 01:38:16 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:16 +0000": parsing time "Fri, 28 Aug 2026 01:38:16 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:16 +0000" as " " 2026/08/28 01:38:16 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:16 NOTICE: pcloud root 'rclone-test-piqanun1siwa': 0 differences found 2026/08/28 01:38:16 NOTICE: pcloud root 'rclone-test-piqanun1siwa': 1 matching files 2026/08/28 01:38:16 DEBUG : Creating backend with remote "TestPcloud:rclone-test-dotehej4xofe" 2026/08/28 01:38:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:16 DEBUG : Creating backend with remote "/tmp/rclone2554405531" run.go:198: Remote "pcloud root 'rclone-test-dotehej4xofe'", Local "Local file system at /tmp/rclone2554405531", Modify Window "1s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/08/28 01:38:17 ERROR : HeLlO, wOrLd!: sum not found 2026/08/28 01:38:17 ERROR : hello, world!: file not in pcloud root 'rclone-test-dotehej4xofe' 2026/08/28 01:38:17 NOTICE: pcloud root 'rclone-test-dotehej4xofe': 1 files missing 2026/08/28 01:38:17 NOTICE: 1 hashes missing 2026/08/28 01:38:17 NOTICE: pcloud root 'rclone-test-dotehej4xofe': 1 differences found 2026/08/28 01:38:17 NOTICE: pcloud root 'rclone-test-dotehej4xofe': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/08/28 01:38:17 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:17 +0000": parsing time "Fri, 28 Aug 2026 01:38:17 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:17 +0000" as " " 2026/08/28 01:38:17 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:17 NOTICE: pcloud root 'rclone-test-dotehej4xofe': 0 differences found 2026/08/28 01:38:17 NOTICE: pcloud root 'rclone-test-dotehej4xofe': 1 matching files 2026/08/28 01:38:17 DEBUG : Creating backend with remote "TestPcloud:rclone-test-wihoset8xike" 2026/08/28 01:38:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:18 DEBUG : Creating backend with remote "/tmp/rclone3537209113" run.go:198: Remote "pcloud root 'rclone-test-wihoset8xike'", Local "Local file system at /tmp/rclone3537209113", Modify Window "1s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/08/28 01:38:19 ERROR : HELLO, WORLD!: sum not found 2026/08/28 01:38:19 ERROR : HeLlO, wOrLd!: file not in pcloud root 'rclone-test-wihoset8xike' 2026/08/28 01:38:19 NOTICE: pcloud root 'rclone-test-wihoset8xike': 1 files missing 2026/08/28 01:38:19 NOTICE: 1 hashes missing 2026/08/28 01:38:19 NOTICE: pcloud root 'rclone-test-wihoset8xike': 1 differences found 2026/08/28 01:38:19 NOTICE: pcloud root 'rclone-test-wihoset8xike': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/08/28 01:38:19 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:19 +0000": parsing time "Fri, 28 Aug 2026 01:38:19 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:19 +0000" as " " 2026/08/28 01:38:19 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:19 NOTICE: pcloud root 'rclone-test-wihoset8xike': 0 differences found 2026/08/28 01:38:19 NOTICE: pcloud root 'rclone-test-wihoset8xike': 1 matching files 2026/08/28 01:38:19 DEBUG : Creating backend with remote "TestPcloud:rclone-test-zetibix5pixo" 2026/08/28 01:38:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:20 DEBUG : Creating backend with remote "/tmp/rclone2420932558" run.go:198: Remote "pcloud root 'rclone-test-zetibix5pixo'", Local "Local file system at /tmp/rclone2420932558", Modify Window "1s" NFD checkfile vs. NFC remote (without normalization) 2026/08/28 01:38:21 ERROR : 測試_Русский___ě_áñ: sum not found 2026/08/28 01:38:21 ERROR : 測試_Русский___ě_áñ: file not in pcloud root 'rclone-test-zetibix5pixo' 2026/08/28 01:38:21 NOTICE: pcloud root 'rclone-test-zetibix5pixo': 1 files missing 2026/08/28 01:38:21 NOTICE: 1 hashes missing 2026/08/28 01:38:21 NOTICE: pcloud root 'rclone-test-zetibix5pixo': 1 differences found 2026/08/28 01:38:21 NOTICE: pcloud root 'rclone-test-zetibix5pixo': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/08/28 01:38:21 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:21 +0000": parsing time "Fri, 28 Aug 2026 01:38:21 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:21 +0000" as " " 2026/08/28 01:38:21 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:21 NOTICE: pcloud root 'rclone-test-zetibix5pixo': 0 differences found 2026/08/28 01:38:21 NOTICE: pcloud root 'rclone-test-zetibix5pixo': 1 matching files 2026/08/28 01:38:21 DEBUG : Creating backend with remote "TestPcloud:rclone-test-goyohub2xepo" 2026/08/28 01:38:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:22 DEBUG : Creating backend with remote "/tmp/rclone4180837721" run.go:198: Remote "pcloud root 'rclone-test-goyohub2xepo'", Local "Local file system at /tmp/rclone4180837721", Modify Window "1s" NFC checkfile vs. NFD remote (without normalization) 2026/08/28 01:38:23 ERROR : 測試_Русский___ě_áñ: sum not found 2026/08/28 01:38:23 ERROR : 測試_Русский___ě_áñ: file not in pcloud root 'rclone-test-goyohub2xepo' 2026/08/28 01:38:23 NOTICE: pcloud root 'rclone-test-goyohub2xepo': 1 files missing 2026/08/28 01:38:23 NOTICE: 1 hashes missing 2026/08/28 01:38:23 NOTICE: pcloud root 'rclone-test-goyohub2xepo': 1 differences found 2026/08/28 01:38:23 NOTICE: pcloud root 'rclone-test-goyohub2xepo': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/08/28 01:38:23 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:23 +0000": parsing time "Fri, 28 Aug 2026 01:38:23 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:23 +0000" as " " 2026/08/28 01:38:23 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:23 NOTICE: pcloud root 'rclone-test-goyohub2xepo': 0 differences found 2026/08/28 01:38:23 NOTICE: pcloud root 'rclone-test-goyohub2xepo': 1 matching files 2026/08/28 01:38:23 DEBUG : Creating backend with remote "TestPcloud:rclone-test-kuquzoj8toge" 2026/08/28 01:38:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:24 DEBUG : Creating backend with remote "/tmp/rclone1412683057" run.go:198: Remote "pcloud root 'rclone-test-kuquzoj8toge'", Local "Local file system at /tmp/rclone1412683057", Modify Window "1s" NFDx2 checkfile vs. both remote (without normalization) 2026/08/28 01:38:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/28 01:38:25 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in pcloud root 'rclone-test-kuquzoj8toge' 2026/08/28 01:38:25 NOTICE: pcloud root 'rclone-test-kuquzoj8toge': 1 files missing 2026/08/28 01:38:25 NOTICE: 1 hashes missing 2026/08/28 01:38:25 NOTICE: pcloud root 'rclone-test-kuquzoj8toge': 1 differences found 2026/08/28 01:38:25 NOTICE: pcloud root 'rclone-test-kuquzoj8toge': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/08/28 01:38:25 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:25 +0000": parsing time "Fri, 28 Aug 2026 01:38:25 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:25 +0000" as " " 2026/08/28 01:38:25 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:25 NOTICE: pcloud root 'rclone-test-kuquzoj8toge': 0 differences found 2026/08/28 01:38:25 NOTICE: pcloud root 'rclone-test-kuquzoj8toge': 1 matching files 2026/08/28 01:38:25 DEBUG : Creating backend with remote "TestPcloud:rclone-test-hewubug6tava" 2026/08/28 01:38:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:26 DEBUG : Creating backend with remote "/tmp/rclone2403075002" run.go:198: Remote "pcloud root 'rclone-test-hewubug6tava'", Local "Local file system at /tmp/rclone2403075002", Modify Window "1s" NFCx2 checkfile vs. both remote (without normalization) 2026/08/28 01:38:27 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/28 01:38:27 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in pcloud root 'rclone-test-hewubug6tava' 2026/08/28 01:38:27 NOTICE: pcloud root 'rclone-test-hewubug6tava': 1 files missing 2026/08/28 01:38:27 NOTICE: 1 hashes missing 2026/08/28 01:38:27 NOTICE: pcloud root 'rclone-test-hewubug6tava': 1 differences found 2026/08/28 01:38:27 NOTICE: pcloud root 'rclone-test-hewubug6tava': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/08/28 01:38:27 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:27 +0000": parsing time "Fri, 28 Aug 2026 01:38:27 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:27 +0000" as " " 2026/08/28 01:38:27 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:27 NOTICE: pcloud root 'rclone-test-hewubug6tava': 0 differences found 2026/08/28 01:38:27 NOTICE: pcloud root 'rclone-test-hewubug6tava': 1 matching files 2026/08/28 01:38:27 DEBUG : Creating backend with remote "TestPcloud:rclone-test-tumawuv2toti" 2026/08/28 01:38:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:28 DEBUG : Creating backend with remote "/tmp/rclone3504459609" run.go:198: Remote "pcloud root 'rclone-test-tumawuv2toti'", Local "Local file system at /tmp/rclone3504459609", Modify Window "1s" both checkfile vs. NFDx2 remote (without normalization) 2026/08/28 01:38:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/28 01:38:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in pcloud root 'rclone-test-tumawuv2toti' 2026/08/28 01:38:29 NOTICE: pcloud root 'rclone-test-tumawuv2toti': 1 files missing 2026/08/28 01:38:29 NOTICE: 1 hashes missing 2026/08/28 01:38:29 NOTICE: pcloud root 'rclone-test-tumawuv2toti': 1 differences found 2026/08/28 01:38:29 NOTICE: pcloud root 'rclone-test-tumawuv2toti': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/08/28 01:38:29 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:29 +0000": parsing time "Fri, 28 Aug 2026 01:38:29 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:29 +0000" as " " 2026/08/28 01:38:29 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:29 NOTICE: pcloud root 'rclone-test-tumawuv2toti': 0 differences found 2026/08/28 01:38:29 NOTICE: pcloud root 'rclone-test-tumawuv2toti': 1 matching files 2026/08/28 01:38:29 DEBUG : Creating backend with remote "TestPcloud:rclone-test-yaroyuw2kele" 2026/08/28 01:38:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 01:38:30 DEBUG : Creating backend with remote "/tmp/rclone3474303006" run.go:198: Remote "pcloud root 'rclone-test-yaroyuw2kele'", Local "Local file system at /tmp/rclone3474303006", Modify Window "1s" both checkfile vs. NFCx2 remote (without normalization) 2026/08/28 01:38:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/08/28 01:38:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in pcloud root 'rclone-test-yaroyuw2kele' 2026/08/28 01:38:31 NOTICE: pcloud root 'rclone-test-yaroyuw2kele': 1 files missing 2026/08/28 01:38:31 NOTICE: 1 hashes missing 2026/08/28 01:38:31 NOTICE: pcloud root 'rclone-test-yaroyuw2kele': 1 differences found 2026/08/28 01:38:31 NOTICE: pcloud root 'rclone-test-yaroyuw2kele': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/08/28 01:38:31 DEBUG : Couldn't parse Date: from server ecph1.pcloud.com: "Fri, 28 Aug 2026 01:38:31 +0000": parsing time "Fri, 28 Aug 2026 01:38:31 +0000" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 01:38:31 +0000" as " " 2026/08/28 01:38:31 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/08/28 01:38:31 NOTICE: pcloud root 'rclone-test-yaroyuw2kele': 0 differences found 2026/08/28 01:38:31 NOTICE: pcloud root 'rclone-test-yaroyuw2kele': 1 matching files 2026/08/28 01:38:31 DEBUG : pcloud root 'rclone-test-yaroyuw2kele': Purge remote 2026/08/28 01:38:31 DEBUG : pcloud root 'rclone-test-tumawuv2toti': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-hewubug6tava': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-kuquzoj8toge': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-goyohub2xepo': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-zetibix5pixo': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-wihoset8xike': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-dotehej4xofe': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-piqanun1siwa': Purge remote 2026/08/28 01:38:32 DEBUG : pcloud root 'rclone-test-lefuxum3pepu': Purge remote --- PASS: TestApplyTransforms (22.02s) === RUN TestRemoveExisting run.go:198: Remote "pcloud root 'rclone-test-zakusib3sole'", Local "Local file system at /tmp/rclone2995383019", Modify Window "1s" fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure 2026/08/28 01:38:43 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.cezezev7" before starting 2026/08/28 01:38:43 DEBUG : sub dir/test remove existing.cezezev7: TEST: removing renamed existing file after operation 2026/08/28 01:38:43 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.neligol2" before starting 2026/08/28 01:38:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.neligol2: TEST: renaming existing back after failed operation 2026/08/28 01:38:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.dujunow7" before starting 2026/08/28 01:38:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.dujunow7: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (12.17s) PASS 2026/08/28 01:38:45 DEBUG : pcloud root 'rclone-test-zakusib3sole': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose -test.run '^(TestApplyTransforms|TestCheckSumDownload|TestRemoveExisting)$'" - Finished OK in 46.299435629s (try 2/5)