"./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMailru: -verbose -size-limit 10240" - Starting (try 4/5) 2024/05/06 05:31:28 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-wibidar3xovonuc9xipewun6" 2024/05/06 05:31:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/05/06 05:31:28 DEBUG : Creating backend with remote "TestMailru:rclone-test-wibidar3xovonuc9xipewun6" 2024/05/06 05:32:28 DEBUG : pacer: low level retry 1/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:32:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 05:33:28 DEBUG : pacer: low level retry 2/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:33:28 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 05:34:28 DEBUG : pacer: low level retry 3/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:34:28 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/05/06 05:35:28 DEBUG : pacer: low level retry 4/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:35:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/05/06 05:36:28 DEBUG : pacer: low level retry 5/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:36:28 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/05/06 05:37:28 DEBUG : pacer: low level retry 6/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": net/http: TLS handshake timeout) 2024/05/06 05:37:28 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/05/06 05:38:28 DEBUG : pacer: low level retry 7/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:38:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/05/06 05:39:28 DEBUG : pacer: low level retry 8/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:39:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/05/06 05:40:28 DEBUG : pacer: low level retry 9/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:41:28 DEBUG : pacer: low level retry 10/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-wibidar3xovonuc9xipewun6&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:41:28 DEBUG : Reset feature "ListR" 2024/05/06 05:41:28 DEBUG : Creating backend with remote "/tmp/rclone3691266194" 2024/05/06 05:41:28 DEBUG : Config file has changed externally - reloading === RUN TestBisyncRemoteLocal 2024/05/06 05:41:28 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-nuheyok0heqobup4tofuneq0" 2024/05/06 05:41:28 DEBUG : Creating backend with remote "TestMailru:rclone-test-nuheyok0heqobup4tofuneq0" 2024/05/06 05:42:28 DEBUG : pacer: low level retry 1/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:42:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 05:43:28 DEBUG : pacer: low level retry 2/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:43:28 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 05:44:28 DEBUG : pacer: low level retry 3/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:44:28 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/05/06 05:45:28 DEBUG : pacer: low level retry 4/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:45:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/05/06 05:46:28 DEBUG : pacer: low level retry 5/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:46:28 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/05/06 05:47:28 DEBUG : pacer: low level retry 6/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:47:28 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/05/06 05:48:28 DEBUG : pacer: low level retry 7/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:48:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/05/06 05:49:28 DEBUG : pacer: low level retry 8/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:49:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/05/06 05:50:28 DEBUG : pacer: low level retry 9/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:51:28 DEBUG : pacer: low level retry 10/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:51:28 DEBUG : Reset feature "ListR" 2024/05/06 05:51:28 remote: TestChunkerChunk50bMailru:rclone-test-nuheyok0heqobup4tofuneq0 === RUN TestBisyncRemoteLocal/all_changed 2024/05/06 05:51:28 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te" 2024/05/06 05:51:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/05/06 05:51:28 DEBUG : Creating backend with remote "TestMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te" 2024/05/06 05:52:28 DEBUG : pacer: low level retry 1/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:52:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 05:53:28 DEBUG : pacer: low level retry 2/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:53:28 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 05:54:28 DEBUG : pacer: low level retry 3/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:54:28 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/05/06 05:55:28 DEBUG : pacer: low level retry 4/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:55:28 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/05/06 05:56:28 DEBUG : pacer: low level retry 5/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:56:28 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/05/06 05:57:28 DEBUG : pacer: low level retry 6/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:57:28 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/05/06 05:58:28 DEBUG : pacer: low level retry 7/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:58:28 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/05/06 05:59:28 DEBUG : pacer: low level retry 8/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 05:59:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/05/06 06:00:28 DEBUG : pacer: low level retry 9/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:01:28 DEBUG : pacer: low level retry 10/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:01:28 DEBUG : Reset feature "ListR" 2024/05/06 06:01:28 DEBUG : path1: Making directory 2024/05/06 06:01:28 DEBUG : pacer: Reducing sleep to 1.5s 2024/05/06 06:01:28 DEBUG : [rclone-test-nuheyok0heqobup4tofuneq0/055128te]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/05/06 06:01:30 DEBUG : pacer: Reducing sleep to 1.125s 2024/05/06 06:01:30 DEBUG : [rclone-test-nuheyok0heqobup4tofuneq0/055128te]: mkDirs by part "/rclone-test-nuheyok0heqobup4tofuneq0/055128te/path1" 2024/05/06 06:01:31 DEBUG : pacer: Reducing sleep to 843.75ms 2024/05/06 06:01:32 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/05/06 06:01:33 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/05/06 06:01:33 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te/all_changed/path1" 2024/05/06 06:01:33 DEBUG : Config file has changed externally - reloading 2024/05/06 06:01:33 DEBUG : Creating backend with remote "TestMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te/all_changed/path1" 2024/05/06 06:02:33 DEBUG : pacer: low level retry 1/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:02:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 06:03:33 DEBUG : pacer: low level retry 2/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:03:33 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 06:04:33 DEBUG : pacer: low level retry 3/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:04:33 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/05/06 06:05:33 DEBUG : pacer: low level retry 4/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:05:33 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/05/06 06:06:33 DEBUG : pacer: low level retry 5/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:06:33 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/05/06 06:07:33 DEBUG : pacer: low level retry 6/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": net/http: TLS handshake timeout) 2024/05/06 06:07:33 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/05/06 06:08:49 DEBUG : pacer: low level retry 7/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": net/http: TLS handshake timeout) 2024/05/06 06:08:49 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/05/06 06:09:49 DEBUG : pacer: low level retry 8/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:09:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/05/06 06:10:49 DEBUG : pacer: low level retry 9/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:11:49 DEBUG : pacer: low level retry 10/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fall_changed%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:11:49 DEBUG : Reset feature "ListR" 2024/05/06 06:11:49 DEBUG : Creating backend with remote "/tmp/055128te" 2024/05/06 06:11:49 DEBUG : Creating backend with remote "/tmp/055128te/all_changed/path2" 2024/05/06 06:11:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial" 2024/05/06 06:11:49 DEBUG : Creating backend with remote "/tmp/055128te/initdir/test_all_changed-micekil1" 2024/05/06 06:11:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : subdir: Making directory with metadata 2024/05/06 06:11:49 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/05/06 06:11:49 DEBUG : Added delayed dir = "subdir", newDst=subdir 2024/05/06 06:11:49 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : file1.copy2.txt.cisobuh5.partial: renamed to: file1.copy2.txt 2024/05/06 06:11:49 INFO : file1.copy2.txt: Copied (new) 2024/05/06 06:11:49 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/05/06 06:11:49 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : file1.copy1.txt.wakesef2.partial: renamed to: file1.copy1.txt 2024/05/06 06:11:49 INFO : file1.copy1.txt: Copied (new) 2024/05/06 06:11:49 DEBUG : Local file system at /tmp/055128te/initdir/test_all_changed-micekil1: Waiting for checks to finish 2024/05/06 06:11:49 DEBUG : file1.copy3.txt.migajig9.partial: renamed to: file1.copy3.txt 2024/05/06 06:11:49 INFO : file1.copy3.txt: Copied (new) 2024/05/06 06:11:49 DEBUG : Local file system at /tmp/055128te/initdir/test_all_changed-micekil1: Waiting for transfers to finish 2024/05/06 06:11:49 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : file1.copy4.txt.jufopik4.partial: renamed to: file1.copy4.txt 2024/05/06 06:11:49 INFO : file1.copy4.txt: Copied (new) 2024/05/06 06:11:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 06:11:49 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : file1.txt.muneyih3.partial: renamed to: file1.txt 2024/05/06 06:11:49 INFO : file1.txt: Copied (new) 2024/05/06 06:11:49 DEBUG : RCLONE_TEST.vozeyij2.partial: renamed to: RCLONE_TEST 2024/05/06 06:11:49 INFO : RCLONE_TEST: Copied (new) 2024/05/06 06:11:49 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 06:11:49 DEBUG : file1.copy5.txt.qequvaq4.partial: renamed to: file1.copy5.txt 2024/05/06 06:11:49 DEBUG : subdir/file20.txt.jebajuh6.partial: renamed to: subdir/file20.txt 2024/05/06 06:11:49 INFO : file1.copy5.txt: Copied (new) 2024/05/06 06:11:49 INFO : subdir/file20.txt: Copied (new) 2024/05/06 06:11:49 INFO : subdir: Updated directory metadata 2024/05/06 06:22:49 DEBUG : pacer: low level retry 1/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:23:49 DEBUG : pacer: low level retry 2/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:24:49 DEBUG : pacer: low level retry 3/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:25:49 DEBUG : pacer: low level retry 4/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": net/http: TLS handshake timeout) 2024/05/06 06:26:49 DEBUG : pacer: low level retry 5/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:27:49 DEBUG : pacer: low level retry 6/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:28:49 DEBUG : pacer: low level retry 7/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:29:49 DEBUG : pacer: low level retry 8/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:30:49 DEBUG : pacer: low level retry 9/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:31:49 DEBUG : pacer: low level retry 10/10 (error Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:31:49 ERROR : : error listing: Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout fstest.go:277: Error listing: Post "https://cloud.mail.ru/api/m1/folder?access_token=2fdc1c3e30717c2dbeced0a285e9c7358b6c82ba37363830&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout === RUN TestBisyncRemoteLocal/backupdir 2024/05/06 06:31:49 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te" 2024/05/06 06:31:49 DEBUG : Config file has changed externally - reloading 2024/05/06 06:31:49 DEBUG : Reset feature "ListR" 2024/05/06 06:31:49 DEBUG : path1: Making directory 2024/05/06 06:31:49 DEBUG : TestMailru: Loaded fresh token from config file 2024/05/06 06:31:49 DEBUG : TestMailru: Loaded new refresh token from config file 2024/05/06 06:31:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/05/06 06:31:49 DEBUG : [rclone-test-nuheyok0heqobup4tofuneq0/055128te]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/05/06 06:31:49 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/05/06 06:31:49 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te/backupdir/path1" 2024/05/06 06:31:49 DEBUG : Creating backend with remote "TestMailru:rclone-test-nuheyok0heqobup4tofuneq0/055128te/backupdir/path1" 2024/05/06 06:32:49 DEBUG : pacer: low level retry 1/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:32:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 06:33:49 DEBUG : pacer: low level retry 2/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:33:49 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 06:34:49 DEBUG : pacer: low level retry 3/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:34:49 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/05/06 06:35:49 DEBUG : pacer: low level retry 4/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:35:49 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/05/06 06:36:49 DEBUG : pacer: low level retry 5/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:36:49 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/05/06 06:37:49 DEBUG : pacer: low level retry 6/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:37:49 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/05/06 06:38:49 DEBUG : pacer: low level retry 7/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:38:49 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/05/06 06:39:49 DEBUG : pacer: low level retry 8/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) 2024/05/06 06:39:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/05/06 06:40:49 DEBUG : pacer: low level retry 9/10 (error Get "https://cloud.mail.ru/api/m1/file?access_token=08b5d5caa36f773cae636c8765f00bf78b6c82ba37363830&home=rclone-test-nuheyok0heqobup4tofuneq0%2F055128te%2Fbackupdir%2Fpath1&limit=2147483647&offset=0": dial tcp 95.163.57.16:443: i/o timeout) panic: test timed out after 1h0m0s running tests: TestBisyncRemoteLocal (1h0m0s) TestBisyncRemoteLocal/backupdir (9m39s) goroutine 735 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2366 +0x385 created by time.goFunc /usr/local/go/src/time/sleep.go:177 +0x2d goroutine 1 [chan receive, 61 minutes]: testing.(*T).Run(0xc000878820, {0x213ee74?, 0x0?}, 0x226b4d8) /usr/local/go/src/testing/testing.go:1750 +0x3ab testing.runTests.func1(0xc000878820) /usr/local/go/src/testing/testing.go:2161 +0x37 testing.tRunner(0xc000878820, 0xc00064fc00) /usr/local/go/src/testing/testing.go:1689 +0xfb testing.runTests(0xc0007106d8, {0x398d6c0, 0x3, 0x3}, {0x1?, 0xeddca6488?, 0x39c4640?}) /usr/local/go/src/testing/testing.go:2159 +0x445 testing.(*M).Run(0xc00054a8c0) /usr/local/go/src/testing/testing.go:2027 +0x68b github.com/rclone/rclone/fstest.TestMain(0xc00054a8c0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/cmd/bisync_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:224 main.main() _testmain.go:55 +0x196 goroutine 7 [select]: go.opencensus.io/stats/view.(*worker).start(0xc0005c9400) /home/rclone/go/pkg/mod/go.opencensus.io@v0.24.0/stats/view/worker.go:292 +0x9f created by go.opencensus.io/stats/view.init.0 in goroutine 1 /home/rclone/go/pkg/mod/go.opencensus.io@v0.24.0/stats/view/worker.go:34 +0x8d goroutine 12 [syscall, 70 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:151 +0x1f goroutine 13 [chan receive, 70 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab goroutine 159 [chan receive, 51 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 78 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab goroutine 78 [chan receive, 10 minutes]: testing.(*T).Run(0xc000878d00, {0xc0003fa0f5?, 0x282d4f0?}, 0xc0005d4320) /usr/local/go/src/testing/testing.go:1750 +0x3ab github.com/rclone/rclone/cmd/bisync_test.testBisync(0xc000878d00, {0xc000062740, 0x3e}, {0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:346 +0x967 github.com/rclone/rclone/cmd/bisync_test.TestBisyncRemoteLocal(0xc000878d00) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:236 +0x1c5 testing.tRunner(0xc000878d00, 0x226b4d8) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1742 +0x390 goroutine 183 [chan receive, 51 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 78 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 636 [select, 2 minutes]: net/http.(*Transport).getConn(0xc0005d9180, 0xc0007ea100, {{}, 0x0, {0xc00057c5b0, 0x5}, {0xc000058240, 0x11}, 0x0}) /usr/local/go/src/net/http/transport.go:1406 +0x5a5 net/http.(*Transport).roundTrip(0xc0005d9180, 0xc0006627e0) /usr/local/go/src/net/http/transport.go:595 +0x73a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:17 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc00014d4f0, 0xc0006627e0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:278 +0x30a net/http.send(0xc0006627e0, {0x283bf20, 0xc00014d4f0}, {0xc00006c401?, 0x41c385?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e4 net/http.(*Client).send(0xc000ab4f30, 0xc0006627e0, {0x0?, 0x6c4e8?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x98 net/http.(*Client).do(0xc000ab4f30, 0xc0006627e0) /usr/local/go/src/net/http/client.go:724 +0x8dc net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:590 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc00086c5f0, {0x285a3e0, 0x3a26860}, 0xc0006bc280) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:325 +0xcc5 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc00086c5f0, {0x285a3e0, 0x3a26860}, 0xc000aaa0d0?, {0x0?, 0x0?}, {0x1ba9a80, 0xc000892a80}, 0xc00006cd20?, 0x226cb30, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522 +0x3e5 github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:465 github.com/rclone/rclone/backend/mailru.(*Fs).readItemMetaData.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/mailru/mailru.go:616 +0x7e github.com/rclone/rclone/fs.pacerInvoker(0xa, 0xa, 0x283ae80?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:88 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00062ede0, 0xc000ab51a0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc00062ede0, 0xc000ab51a0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x92 github.com/rclone/rclone/backend/mailru.(*Fs).readItemMetaData(0xc0001e0000, {0x285a3e0, 0x3a26860}, {0xc00068c05b, 0x3d}) /home/rclone/go/src/github.com/rclone/rclone/backend/mailru/mailru.go:615 +0x4ad github.com/rclone/rclone/backend/mailru.NewFs({0x285a3e0, 0x3a26860}, {0xc00068c050, 0xa}, {0xc00068c05b, 0x3d}, {0x284b6b8, 0xc000ab4c90}) /home/rclone/go/src/github.com/rclone/rclone/backend/mailru/mailru.go:379 +0x65a github.com/rclone/rclone/fs.NewFs({0x285a3e0, 0x3a26860}, {0xc00068c050, 0x48}) /home/rclone/go/src/github.com/rclone/rclone/fs/newfs.go:67 +0x33b github.com/rclone/rclone/fs/cache.GetFn.func1({0x1cf5fe0?, 0xc00078b2f0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/cache/cache.go:67 +0x38 github.com/rclone/rclone/lib/cache.(*Cache).Get(0xc00078b2c0, {0xc00068c050, 0x48}, 0xc00006d470) /home/rclone/go/src/github.com/rclone/rclone/lib/cache/cache.go:88 +0xdc github.com/rclone/rclone/fs/cache.GetFn({0x285a3e0, 0x3a26860}, {0xc00068c050, 0x48}, 0x226c388) /home/rclone/go/src/github.com/rclone/rclone/fs/cache/cache.go:66 +0x125 github.com/rclone/rclone/fs/cache.Get({0x285a3e0, 0x3a26860}, {0xc00068c050, 0x48}) /home/rclone/go/src/github.com/rclone/rclone/fs/cache/cache.go:140 +0x7b github.com/rclone/rclone/backend/chunker.NewFs({0x285a3e0, 0x3a26860}, {0xc00061cd80, 0x19}, {0xc00061cd9a, 0x3d}, {0x284b6b8, 0xc000ab4a20}) /home/rclone/go/src/github.com/rclone/rclone/backend/chunker/chunker.go:297 +0x26e github.com/rclone/rclone/fs.NewFs({0x285a3e0, 0x3a26860}, {0xc00061cd80, 0x57}) /home/rclone/go/src/github.com/rclone/rclone/fs/newfs.go:67 +0x33b github.com/rclone/rclone/fs/cache.GetFn.func1({0x1cf5fe0?, 0xc00078b2f0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/cache/cache.go:67 +0x38 github.com/rclone/rclone/lib/cache.(*Cache).Get(0xc00078b2c0, {0xc00061cd80, 0x57}, 0xc00006d900) /home/rclone/go/src/github.com/rclone/rclone/lib/cache/cache.go:88 +0xdc github.com/rclone/rclone/fs/cache.GetFn({0x285a3e0, 0x3a26860}, {0xc00061cd80, 0x57}, 0x226c388) /home/rclone/go/src/github.com/rclone/rclone/fs/cache/cache.go:66 +0x125 github.com/rclone/rclone/fs/cache.Get({0x285a3e0, 0x3a26860}, {0xc00061cd80, 0x57}) /home/rclone/go/src/github.com/rclone/rclone/fs/cache/cache.go:140 +0x7b github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).makeTempRemote(0xc00057e000, {0x285a3e0, 0x3a26860}, {0xc000062740, 0x3e}, {0x2116551, 0x5}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:588 +0x5f9 github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestCase(0xc00057e000, {0x285a3e0, 0x3a26860}, 0x603?, {0xc0003fa0f5?, 0x337fde8?}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:380 +0xa5 github.com/rclone/rclone/cmd/bisync_test.testBisync.func4(0xc0006e6680) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:353 +0x225 testing.tRunner(0xc0006e6680, 0xc0005d4320) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 78 /usr/local/go/src/testing/testing.go:1742 +0x390 goroutine 739 [select, 2 minutes]: net.(*netFD).connect.func2() /usr/local/go/src/net/fd_unix.go:118 +0x7a created by net.(*netFD).connect in goroutine 703 /usr/local/go/src/net/fd_unix.go:117 +0x37c goroutine 703 [IO wait, 2 minutes]: internal/poll.runtime_pollWait(0x7f61977c9500, 0x77) /usr/local/go/src/runtime/netpoll.go:345 +0x85 internal/poll.(*pollDesc).wait(0xc000244300?, 0x0?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitWrite(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 internal/poll.(*FD).WaitWrite(...) /usr/local/go/src/internal/poll/fd_unix.go:683 net.(*netFD).connect(0xc000244300, {0x285a4f8, 0xc0006ac0e0}, {0xc000779198?, 0x413fdb?}, {0x283aa60?, 0xc000708120?}) /usr/local/go/src/net/fd_unix.go:141 +0x70b net.(*netFD).dial(0xc000244300, {0x285a4f8, 0xc0006ac0e0}, {0x28607f8?, 0x0?}, {0x28607f8, 0xc0007e26f0}, 0x10?) /usr/local/go/src/net/sock_posix.go:124 +0x3bc net.socket({0x285a4f8, 0xc0006ac0e0}, {0x2114d40, 0x3}, 0x2, 0x1, 0xc0000725e0?, 0x0, {0x28607f8, 0x0}, ...) /usr/local/go/src/net/sock_posix.go:70 +0x29b net.internetSocket({0x285a4f8, 0xc0006ac0e0}, {0x2114d40, 0x3}, {0x28607f8, 0x0}, {0x28607f8, 0xc0007e26f0}, 0x1, 0x0, ...) /usr/local/go/src/net/ipsock_posix.go:154 +0xf8 net.(*sysDialer).doDialTCPProto(0xc000ad03c0, {0x285a4f8, 0xc0006ac0e0}, 0x0, 0xc0007e26f0, 0x0) /usr/local/go/src/net/tcpsock_posix.go:85 +0xec net.(*sysDialer).doDialTCP(...) /usr/local/go/src/net/tcpsock_posix.go:75 net.(*sysDialer).dialTCP(0x0?, {0x285a4f8?, 0xc0006ac0e0?}, 0x413fdb?, 0x413fdb?) /usr/local/go/src/net/tcpsock_posix.go:71 +0x65 net.(*sysDialer).dialSingle(0xc000ad03c0, {0x285a4f8, 0xc0006ac0e0}, {0x284b988, 0xc0007e26f0}) /usr/local/go/src/net/dial.go:651 +0x27d net.(*sysDialer).dialSerial(0xc000ad03c0, {0x285a4f8, 0xc0006ac0e0}, {0xc000aaa210?, 0x1, 0xc000aaa210?}) /usr/local/go/src/net/dial.go:616 +0x24e net.(*sysDialer).dialParallel(0xc000aaa200?, {0x285a4f8?, 0xc0006ac0e0?}, {0xc000aaa210?, 0xc0006ac0e0?, 0x211586b?}, {0x0?, 0x2114d40?, 0xc0003f01c8?}) /usr/local/go/src/net/dial.go:517 +0x3b4 net.(*Dialer).DialContext(0xc000244200, {0x285a3e0, 0x3a26860}, {0x2114d40, 0x3}, {0xc000058240, 0x11}) /usr/local/go/src/net/dial.go:508 +0x69a github.com/rclone/rclone/fs/fshttp.(*Dialer).DialContext(0xc000244200, {0x285a3e0, 0x3a26860}, {0x2114d40?, 0x60?}, {0xc000058240, 0x11}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:62 +0x125 github.com/rclone/rclone/fs/fshttp.NewTransportCustom.func1({0x285a3e0, 0x3a26860}, {0x2114d40, 0x3}, {0xc000058240, 0x11}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:95 +0x65 net/http.(*Transport).dial(0x0?, {0x285a3e0?, 0x3a26860?}, {0x2114d40?, 0x0?}, {0xc000058240?, 0x0?}) /usr/local/go/src/net/http/transport.go:1187 +0xd2 net/http.(*Transport).dialConn(0xc0005d9180, {0x285a3e0, 0x3a26860}, {{}, 0x0, {0xc00057c5b0, 0x5}, {0xc000058240, 0x11}, 0x0}) /usr/local/go/src/net/http/transport.go:1647 +0x7e8 net/http.(*Transport).dialConnFor(0xc0005d9180, 0xc000339080) /usr/local/go/src/net/http/transport.go:1484 +0xca created by net/http.(*Transport).queueForDial in goroutine 636 /usr/local/go/src/net/http/transport.go:1449 +0x3c9 "./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMailru: -verbose -size-limit 10240" - Finished ERROR in 1h10m0.098881343s (try 4/5): exit status 2: Failed []