// Copyright (C) The Arvados Authors. All rights reserved. // // SPDX-License-Identifier: Apache-2.0 package keepclient import ( "crypto/md5" "errors" "fmt" "io" "io/ioutil" "log" "net/http" "os" "strconv" "strings" "git.arvados.org/arvados.git/sdk/go/arvadosclient" ) // DebugPrintf emits debug messages. The easiest way to enable // keepclient debug messages in your application is to assign // log.Printf to DebugPrintf. var DebugPrintf = func(string, ...interface{}) {} func init() { if arvadosclient.StringBool(os.Getenv("ARVADOS_DEBUG")) { DebugPrintf = log.Printf } } type keepService struct { Uuid string `json:"uuid"` Hostname string `json:"service_host"` Port int `json:"service_port"` SSL bool `json:"service_ssl_flag"` SvcType string `json:"service_type"` ReadOnly bool `json:"read_only"` } // Md5String returns md5 hash for the bytes in the given string func Md5String(s string) string { return fmt.Sprintf("%x", md5.Sum([]byte(s))) } type svcList struct { Items []keepService `json:"items"` } type uploadStatus struct { err error url string statusCode int replicasStored int classesStored map[string]int response string } func (kc *KeepClient) uploadToKeepServer(host string, hash string, classesTodo []string, body io.Reader, uploadStatusChan chan<- uploadStatus, expectedLength int64, reqid 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 { DebugPrintf("DEBUG: [%s] Error creating request PUT %v error: %v", reqid, url, err.Error()) uploadStatusChan <- uploadStatus{err, url, 0, 0, nil, ""} return } req.ContentLength = expectedLength if expectedLength > 0 { req.Body = ioutil.NopCloser(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. } req.Header.Add("X-Request-Id", reqid) req.Header.Add("Authorization", "OAuth2 "+kc.Arvados.ApiToken) req.Header.Add("Content-Type", "application/octet-stream") req.Header.Add(XKeepDesiredReplicas, fmt.Sprint(kc.Want_replicas)) if len(classesTodo) > 0 { req.Header.Add(XKeepStorageClasses, strings.Join(classesTodo, ", ")) } var resp *http.Response if resp, err = kc.httpClient().Do(req); err != nil { DebugPrintf("DEBUG: [%s] Upload failed %v error: %v", reqid, url, err.Error()) uploadStatusChan <- uploadStatus{err, url, 0, 0, nil, err.Error()} return } rep := 1 if xr := resp.Header.Get(XKeepReplicasStored); xr != "" { fmt.Sscanf(xr, "%d", &rep) } scc := resp.Header.Get(XKeepStorageClassesConfirmed) classesStored, err := parseStorageClassesConfirmedHeader(scc) if err != nil { DebugPrintf("DEBUG: [%s] Ignoring invalid %s header %q: %s", reqid, XKeepStorageClassesConfirmed, scc, err) } defer resp.Body.Close() defer io.Copy(ioutil.Discard, resp.Body) respbody, err2 := ioutil.ReadAll(&io.LimitedReader{R: resp.Body, N: 4096}) response := strings.TrimSpace(string(respbody)) if err2 != nil && err2 != io.EOF { DebugPrintf("DEBUG: [%s] Upload %v error: %v response: %v", reqid, url, err2.Error(), response) uploadStatusChan <- uploadStatus{err2, url, resp.StatusCode, rep, classesStored, response} } else if resp.StatusCode == http.StatusOK { DebugPrintf("DEBUG: [%s] Upload %v success", reqid, url) uploadStatusChan <- uploadStatus{nil, url, resp.StatusCode, rep, classesStored, response} } else { if resp.StatusCode >= 300 && response == "" { response = resp.Status } DebugPrintf("DEBUG: [%s] Upload %v error: %v response: %v", reqid, url, resp.StatusCode, response) uploadStatusChan <- uploadStatus{errors.New(resp.Status), url, resp.StatusCode, rep, classesStored, response} } } func (kc *KeepClient) putReplicas( hash string, getReader func() io.Reader, expectedLength int64) (locator string, replicas int, err error) { reqid := kc.getRequestID() // Calculate the ordering for uploading to servers sv := NewRootSorter(kc.WritableLocalRoots(), hash).GetSortedRoots() // The next server to try contacting nextServer := 0 // The number of active writers active := 0 // Used to communicate status from the upload goroutines uploadStatusChan := make(chan uploadStatus) defer func() { // Wait for any abandoned uploads (e.g., we started // two uploads and the first replied with replicas=2) // to finish before closing the status channel. go func() { for active > 0 { <-uploadStatusChan } close(uploadStatusChan) }() }() replicasWanted := kc.Want_replicas replicasTodo := map[string]int{} for _, c := range kc.StorageClasses { replicasTodo[c] = replicasWanted } replicasDone := 0 replicasPerThread := kc.replicasPerService if replicasPerThread < 1 { // unlimited or unknown replicasPerThread = replicasWanted } retriesRemaining := 1 + kc.Retries var retryServers []string lastError := make(map[string]string) trackingClasses := len(replicasTodo) > 0 for retriesRemaining > 0 { retriesRemaining-- nextServer = 0 retryServers = []string{} for { var classesTodo []string var maxConcurrency int for sc, r := range replicasTodo { classesTodo = append(classesTodo, sc) if maxConcurrency == 0 || maxConcurrency > r { // Having more than r // writes in flight // would overreplicate // class sc. maxConcurrency = r } } if !trackingClasses { maxConcurrency = replicasWanted - replicasDone } if maxConcurrency < 1 { // If there are no non-zero entries in // replicasTodo, we're done. break } for active*replicasPerThread < maxConcurrency { // Start some upload requests if nextServer < len(sv) { DebugPrintf("DEBUG: [%s] Begin upload %s to %s", reqid, hash, sv[nextServer]) go kc.uploadToKeepServer(sv[nextServer], hash, classesTodo, getReader(), uploadStatusChan, expectedLength, reqid) nextServer++ active++ } else { if active == 0 && retriesRemaining == 0 { msg := "Could not write sufficient replicas: " for _, resp := range lastError { msg += resp + "; " } msg = msg[:len(msg)-2] return locator, replicasDone, InsufficientReplicasError(errors.New(msg)) } break } } DebugPrintf("DEBUG: [%s] Replicas remaining to write: %v active uploads: %v", reqid, replicasTodo, active) if active < 1 { break } // Wait for something to happen. status := <-uploadStatusChan active-- if status.statusCode == http.StatusOK { delete(lastError, status.url) replicasDone += status.replicasStored if len(status.classesStored) == 0 { // Server doesn't report // storage classes. Give up // trying to track which ones // are satisfied; just rely on // total # replicas. trackingClasses = false } for className, replicas := range status.classesStored { if replicasTodo[className] > replicas { replicasTodo[className] -= replicas } else { delete(replicasTodo, className) } } locator = status.response } else { msg := fmt.Sprintf("[%d] %s", status.statusCode, status.response) if len(msg) > 100 { msg = msg[:100] } lastError[status.url] = msg } if status.statusCode == 0 || status.statusCode == 408 || status.statusCode == 429 || (status.statusCode >= 500 && status.statusCode != 503) { // Timeout, too many requests, or other server side failure // Do not retry when status code is 503, which means the keep server is full retryServers = append(retryServers, status.url[0:strings.LastIndex(status.url, "/")]) } } sv = retryServers } return locator, replicasDone, nil } func parseStorageClassesConfirmedHeader(hdr string) (map[string]int, error) { if hdr == "" { return nil, nil } classesStored := map[string]int{} for _, cr := range strings.Split(hdr, ",") { cr = strings.TrimSpace(cr) if cr == "" { continue } fields := strings.SplitN(cr, "=", 2) if len(fields) != 2 { return nil, fmt.Errorf("expected exactly one '=' char in entry %q", cr) } className := fields[0] if className == "" { return nil, fmt.Errorf("empty class name in entry %q", cr) } replicas, err := strconv.Atoi(fields[1]) if err != nil || replicas < 1 { return nil, fmt.Errorf("invalid replica count %q", fields[1]) } classesStored[className] = replicas } return classesStored, nil }