X-Git-Url: https://git.arvados.org/arvados.git/blobdiff_plain/a4b55a80251d666ca39d0f8d201c70e493b4c661..2cf42c27a7e8b37e29462d0b695e24cb6f3ad5ce:/sdk/go/keepclient/support.go diff --git a/sdk/go/keepclient/support.go b/sdk/go/keepclient/support.go index e51dcb3c09..c24849e687 100644 --- a/sdk/go/keepclient/support.go +++ b/sdk/go/keepclient/support.go @@ -2,15 +2,18 @@ package keepclient import ( - "git.curoverse.com/arvados.git/sdk/go/streamer" + "crypto/md5" "errors" "fmt" + "git.curoverse.com/arvados.git/sdk/go/streamer" "io" "io/ioutil" "log" + "net" "net/http" "os" "strings" + "time" ) type keepDisk struct { @@ -21,11 +24,65 @@ type keepDisk struct { SvcType string `json:"service_type"` } +func Md5String(s string) string { + return fmt.Sprintf("%x", md5.Sum([]byte(s))) +} + +// Set timeouts apply when connecting to keepproxy services (assumed to be over +// the Internet). +func (this *KeepClient) setClientSettingsProxy() { + if this.Client.Timeout == 0 { + // Maximum time to wait for a complete response + this.Client.Timeout = 300 * time.Second + + // TCP and TLS connection settings + this.Client.Transport = &http.Transport{ + Dial: (&net.Dialer{ + // The maximum time to wait to set up + // the initial TCP connection. + Timeout: 30 * time.Second, + + // The TCP keep alive heartbeat + // interval. + KeepAlive: 120 * time.Second, + }).Dial, + + TLSHandshakeTimeout: 10 * time.Second, + } + } + +} + +// Set timeouts apply when connecting to keepstore services directly (assumed +// to be on the local network). +func (this *KeepClient) setClientSettingsStore() { + if this.Client.Timeout == 0 { + // Maximum time to wait for a complete response + this.Client.Timeout = 20 * time.Second + + // TCP and TLS connection timeouts + this.Client.Transport = &http.Transport{ + Dial: (&net.Dialer{ + // The maximum time to wait to set up + // the initial TCP connection. + Timeout: 2 * time.Second, + + // The TCP keep alive heartbeat + // interval. + KeepAlive: 180 * time.Second, + }).Dial, + + TLSHandshakeTimeout: 4 * time.Second, + } + } +} + func (this *KeepClient) DiscoverKeepServers() error { if prx := os.Getenv("ARVADOS_KEEP_PROXY"); prx != "" { - sr := map[string]string{"proxy":prx} + sr := map[string]string{"proxy": prx} this.SetServiceRoots(sr) this.Using_proxy = true + this.setClientSettingsProxy() return nil } @@ -65,15 +122,17 @@ func (this *KeepClient) DiscoverKeepServers() error { } } + if this.Using_proxy { + this.setClientSettingsProxy() + } else { + this.setClientSettingsStore() + } + this.SetServiceRoots(service_roots) return nil } -func (this KeepClient) shuffledServiceRoots(hash string) (pseq []string) { - return NewRootSorter(this.ServiceRoots(), hash).GetSortedRoots() -} - type uploadStatus struct { err error url string @@ -83,21 +142,29 @@ type uploadStatus struct { } func (this KeepClient) uploadToKeepServer(host string, hash string, body io.ReadCloser, - upload_status chan<- uploadStatus, expectedLength int64) { - - log.Printf("Uploading %s to %s", hash, host) + upload_status chan<- uploadStatus, expectedLength int64, requestId string) { var req *http.Request var err error var url = fmt.Sprintf("%s/%s", host, hash) if req, err = http.NewRequest("PUT", url, nil); err != nil { + log.Printf("[%v] Error creating request PUT %v error: %v", requestId, url, err.Error()) upload_status <- uploadStatus{err, url, 0, 0, ""} body.Close() return } + req.ContentLength = expectedLength if expectedLength > 0 { - req.ContentLength = expectedLength + // http.Client.Do will close the body ReadCloser when it is + // done with it. + req.Body = body + } else { + // "For client requests, a value of 0 means unknown if Body is + // not nil." In this case we do want the body to be empty, so + // don't set req.Body. However, we still need to close the + // body ReadCloser. + body.Close() } req.Header.Add("Authorization", fmt.Sprintf("OAuth2 %s", this.Arvados.ApiToken)) @@ -107,12 +174,10 @@ func (this KeepClient) uploadToKeepServer(host string, hash string, body io.Read req.Header.Add(X_Keep_Desired_Replicas, fmt.Sprint(this.Want_replicas)) } - req.Body = body - var resp *http.Response if resp, err = this.Client.Do(req); err != nil { + log.Printf("[%v] Upload failed %v error: %v", requestId, url, err.Error()) upload_status <- uploadStatus{err, url, 0, 0, ""} - body.Close() return } @@ -125,17 +190,16 @@ func (this KeepClient) uploadToKeepServer(host string, hash string, body io.Read defer io.Copy(ioutil.Discard, resp.Body) respbody, err2 := ioutil.ReadAll(&io.LimitedReader{resp.Body, 4096}) + response := strings.TrimSpace(string(respbody)) if err2 != nil && err2 != io.EOF { - upload_status <- uploadStatus{err2, url, resp.StatusCode, rep, string(respbody)} - return - } - - locator := strings.TrimSpace(string(respbody)) - - if resp.StatusCode == http.StatusOK { - upload_status <- uploadStatus{nil, url, resp.StatusCode, rep, locator} + log.Printf("[%v] Upload %v error: %v response: %v", requestId, url, err2.Error(), response) + upload_status <- uploadStatus{err2, url, resp.StatusCode, rep, response} + } else if resp.StatusCode == http.StatusOK { + log.Printf("[%v] Upload %v success", requestId, url) + upload_status <- uploadStatus{nil, url, resp.StatusCode, rep, response} } else { - upload_status <- uploadStatus{errors.New(resp.Status), url, resp.StatusCode, rep, locator} + log.Printf("[%v] Upload %v error: %v response: %v", requestId, url, resp.StatusCode, response) + upload_status <- uploadStatus{errors.New(resp.Status), url, resp.StatusCode, rep, response} } } @@ -144,6 +208,10 @@ func (this KeepClient) putReplicas( tr *streamer.AsyncStream, expectedLength int64) (locator string, replicas int, err error) { + // Take the hash of locator and timestamp in order to identify this + // specific transaction in log statements. + requestId := fmt.Sprintf("%x", md5.Sum([]byte(locator+time.Now().String())))[0:8] + // Calculate the ordering for uploading to servers sv := NewRootSorter(this.ServiceRoots(), hash).GetSortedRoots() @@ -158,14 +226,14 @@ func (this KeepClient) putReplicas( defer close(upload_status) // Desired number of replicas - remaining_replicas := this.Want_replicas for remaining_replicas > 0 { for active < remaining_replicas { // Start some upload requests if next_server < len(sv) { - go this.uploadToKeepServer(sv[next_server], hash, tr.MakeStreamReader(), upload_status, expectedLength) + log.Printf("[%v] Begin upload %s to %s", requestId, hash, sv[next_server]) + go this.uploadToKeepServer(sv[next_server], hash, tr.MakeStreamReader(), upload_status, expectedLength, requestId) next_server += 1 active += 1 } else { @@ -176,20 +244,18 @@ func (this KeepClient) putReplicas( } } } + log.Printf("[%v] Replicas remaining to write: %v active uploads: %v", + requestId, remaining_replicas, active) // Now wait for something to happen. status := <-upload_status + active -= 1 + if status.statusCode == 200 { // good news! remaining_replicas -= status.replicas_stored locator = status.response - } else { - // writing to keep server failed for some reason - log.Printf("Keep server put to %v failed with '%v'", - status.url, status.err) } - active -= 1 - log.Printf("Upload to %v status code: %v remaining replicas: %v active: %v", status.url, status.statusCode, remaining_replicas, active) } return locator, this.Want_replicas, nil