X-Git-Url: https://git.arvados.org/arvados.git/blobdiff_plain/74dba22bf46c375d6d8daa8e5afff3e55df993ae..afabb6c30c449d6139aec344d0912fc2645e2e89:/services/datamanager/keep/keep.go diff --git a/services/datamanager/keep/keep.go b/services/datamanager/keep/keep.go index 507e5f638b..206a9c43fd 100644 --- a/services/datamanager/keep/keep.go +++ b/services/datamanager/keep/keep.go @@ -3,145 +3,539 @@ package keep import ( + "bufio" + "encoding/json" + "errors" "flag" "fmt" - //"git.curoverse.com/arvados.git/sdk/go/keepclient" "git.curoverse.com/arvados.git/sdk/go/arvadosclient" - "git.curoverse.com/arvados.git/sdk/go/util" - "log" + "git.curoverse.com/arvados.git/sdk/go/blockdigest" + "git.curoverse.com/arvados.git/sdk/go/keepclient" + "git.curoverse.com/arvados.git/sdk/go/logger" + "io" "io/ioutil" + "log" "net/http" + "strconv" "strings" - "sync" + "time" ) +// ServerAddress struct type ServerAddress struct { - Host string `json:"service_host"` - Port int `json:"service_port"` + SSL bool `json:"service_ssl_flag"` + Host string `json:"service_host"` + Port int `json:"service_port"` + UUID string `json:"uuid"` + ServiceType string `json:"service_type"` } +// BlockInfo is info about a particular block returned by the server +type BlockInfo struct { + Digest blockdigest.DigestWithSize + Mtime int64 // TODO(misha): Replace this with a timestamp. +} + +// BlockServerInfo is info about a specified block given by a server +type BlockServerInfo struct { + ServerIndex int + Mtime int64 // TODO(misha): Replace this with a timestamp. +} + +// ServerContents struct type ServerContents struct { - BlockDigestToSize map[string]int + BlockDigestToInfo map[blockdigest.DigestWithSize]BlockInfo +} + +// ServerResponse struct +type ServerResponse struct { + Address ServerAddress + Contents ServerContents + Err error } +// ReadServers struct type ReadServers struct { - ReadAllServers bool - AddressToContents map[ServerAddress]ServerContents + ReadAllServers bool + KeepServerIndexToAddress []ServerAddress + KeepServerAddressToIndex map[ServerAddress]int + ServerToContents map[ServerAddress]ServerContents + BlockToServers map[blockdigest.DigestWithSize][]BlockServerInfo + BlockReplicationCounts map[int]int } +// GetKeepServersParams struct type GetKeepServersParams struct { Client arvadosclient.ArvadosClient - Limit int + Logger *logger.Logger + Limit int } -type KeepServiceList struct { - ItemsAvailable int `json:"items_available"` - KeepServers []ServerAddress `json:"items"` +// ServiceList consists of the addresses of all the available kee servers +type ServiceList struct { + ItemsAvailable int `json:"items_available"` + KeepServers []ServerAddress `json:"items"` } -// Methods to implement util.SdkListResponse Interface -func (k KeepServiceList) NumItemsAvailable() (numAvailable int, err error) { - return k.ItemsAvailable, nil +var serviceType string + +func init() { + flag.StringVar(&serviceType, + "service-type", + "disk", + "Operate only on keep_services with the specified service_type, ignoring all others.") } -func (k KeepServiceList) NumItemsContained() (numContained int, err error) { - return len(k.KeepServers), nil +// String +// TODO(misha): Change this to include the UUID as well. +func (s ServerAddress) String() string { + return s.URL() } -var ( - // Don't access the token directly, use getDataManagerToken() to - // make sure it's been read. - dataManagerToken string - dataManagerTokenFile string - dataManagerTokenFileReadOnce sync.Once -) +// URL of the keep server +func (s ServerAddress) URL() string { + if s.SSL { + return fmt.Sprintf("https://%s:%d", s.Host, s.Port) + } + return fmt.Sprintf("http://%s:%d", s.Host, s.Port) +} -func init() { - flag.StringVar(&dataManagerTokenFile, - "data-manager-token-file", - "", - "File with the API token we should use to contact keep servers.") +// GetKeepServersAndSummarize gets keep servers from api +func GetKeepServersAndSummarize(params GetKeepServersParams) (results ReadServers, err error) { + results, err = GetKeepServers(params) + if err != nil { + return + } + log.Printf("Returned %d keep disks", len(results.ServerToContents)) + + results.Summarize(params.Logger) + log.Printf("Replication level distribution: %v", + results.BlockReplicationCounts) + + return } -func getDataManagerToken() (string) { - readDataManagerToken := func () { - if dataManagerTokenFile == "" { - flag.Usage() - log.Fatalf("Data Manager Token needed, but data manager token file not specified.") +// GetKeepServers from api server +func GetKeepServers(params GetKeepServersParams) (results ReadServers, err error) { + sdkParams := arvadosclient.Dict{ + "filters": [][]string{[]string{"service_type", "!=", "proxy"}}, + } + if params.Limit > 0 { + sdkParams["limit"] = params.Limit + } + + var sdkResponse ServiceList + err = params.Client.List("keep_services", sdkParams, &sdkResponse) + + if err != nil { + return + } + + var keepServers []ServerAddress + for _, server := range sdkResponse.KeepServers { + if server.ServiceType == serviceType { + keepServers = append(keepServers, server) } else { - rawRead, err := ioutil.ReadFile(dataManagerTokenFile) - if err != nil { - log.Fatalf("Unexpected error reading token file %s: %v", - dataManagerTokenFile, - err) - } - dataManagerToken = strings.TrimSpace(string(rawRead)) + log.Printf("Skipping keep_service %q because its service_type %q does not match -service-type=%q", server, server.ServiceType, serviceType) } } - dataManagerTokenFileReadOnce.Do(readDataManagerToken) - return dataManagerToken + if len(keepServers) == 0 { + return results, fmt.Errorf("Found no keepservices with the service type %v", serviceType) + } + + if params.Logger != nil { + params.Logger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + keepInfo["num_keep_servers_available"] = sdkResponse.ItemsAvailable + keepInfo["num_keep_servers_received"] = len(sdkResponse.KeepServers) + keepInfo["keep_servers"] = sdkResponse.KeepServers + keepInfo["indexable_keep_servers"] = keepServers + }) + } + + log.Printf("Received keep services list: %+v", sdkResponse) + + if len(sdkResponse.KeepServers) < sdkResponse.ItemsAvailable { + return results, fmt.Errorf("Did not receive all available keep servers: %+v", sdkResponse) + } + + results.KeepServerIndexToAddress = keepServers + results.KeepServerAddressToIndex = make(map[ServerAddress]int) + for i, address := range results.KeepServerIndexToAddress { + results.KeepServerAddressToIndex[address] = i + } + + log.Printf("Got Server Addresses: %v", results) + + // Send off all the index requests concurrently + responseChan := make(chan ServerResponse) + for _, keepServer := range results.KeepServerIndexToAddress { + // The above keepsServer variable is reused for each iteration, so + // it would be shared across all goroutines. This would result in + // us querying one server n times instead of n different servers + // as we intended. To avoid this we add it as an explicit + // parameter which gets copied. This bug and solution is described + // in https://golang.org/doc/effective_go.html#channels + go func(keepServer ServerAddress) { + responseChan <- GetServerContents(params.Logger, + keepServer, + params.Client) + }(keepServer) + } + + results.ServerToContents = make(map[ServerAddress]ServerContents) + results.BlockToServers = make(map[blockdigest.DigestWithSize][]BlockServerInfo) + + // Read all the responses + for i := range results.KeepServerIndexToAddress { + _ = i // Here to prevent go from complaining. + response := <-responseChan + + // Check if there were any errors during GetServerContents + if response.Err != nil { + return results, response.Err + } + + log.Printf("Received channel response from %v containing %d files", + response.Address, + len(response.Contents.BlockDigestToInfo)) + results.ServerToContents[response.Address] = response.Contents + serverIndex := results.KeepServerAddressToIndex[response.Address] + for _, blockInfo := range response.Contents.BlockDigestToInfo { + results.BlockToServers[blockInfo.Digest] = append( + results.BlockToServers[blockInfo.Digest], + BlockServerInfo{ServerIndex: serverIndex, + Mtime: blockInfo.Mtime}) + } + } + return } -func GetKeepServers(params GetKeepServersParams) (results ReadServers) { - if ¶ms.Client == nil { - log.Fatalf("params.Client passed to GetKeepServers() should " + - "contain a valid ArvadosClient, but instead it is nil.") +// GetServerContents of the keep server +func GetServerContents(arvLogger *logger.Logger, + keepServer ServerAddress, + arv arvadosclient.ArvadosClient) (response ServerResponse) { + + err := GetServerStatus(arvLogger, keepServer, arv) + if err != nil { + response.Err = err + return } - sdkParams := arvadosclient.Dict{} - if params.Limit > 0 { - sdkParams["limit"] = params.Limit + req, err := CreateIndexRequest(arvLogger, keepServer, arv) + if err != nil { + response.Err = err + return } - var sdkResponse KeepServiceList - err := params.Client.List("keep_services", sdkParams, &sdkResponse) + resp, err := arv.Client.Do(req) if err != nil { - log.Fatalf("Error requesting keep disks from API server: %v", err) + response.Err = err + return } - log.Printf("Received keep services list: %v", sdkResponse) + response, err = ReadServerResponse(arvLogger, keepServer, resp) + if err != nil { + response.Err = err + return + } - { - var numReceived, numAvailable int - results.ReadAllServers, numReceived, numAvailable = - util.ContainsAllAvailableItems(sdkResponse) + return +} - if (!results.ReadAllServers) { - log.Printf("ERROR: Did not receive all keep server addresses.") - } - log.Printf("Received %d of %d available keep server addresses.", - numReceived, - numAvailable) +// GetServerStatus get keep server status by invoking /status.json +func GetServerStatus(arvLogger *logger.Logger, + keepServer ServerAddress, + arv arvadosclient.ArvadosClient) error { + url := fmt.Sprintf("http://%s:%d/status.json", + keepServer.Host, + keepServer.Port) + + if arvLogger != nil { + now := time.Now() + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + serverInfo := make(map[string]interface{}) + serverInfo["status_request_sent_at"] = now + serverInfo["host"] = keepServer.Host + serverInfo["port"] = keepServer.Port + + keepInfo[keepServer.UUID] = serverInfo + }) } - // This is safe for concurrent use - client := http.Client{} - - // TODO(misha): Do these in parallel - for _, keepServer := range sdkResponse.KeepServers { - url := fmt.Sprintf("http://%s:%d/index", keepServer.Host, keepServer.Port) - log.Println("About to fetch keep server contents from " + url) + resp, err := arv.Client.Get(url) + if err != nil { + return fmt.Errorf("Error getting keep status from %s: %v", url, err) + } else if resp.StatusCode != 200 { + return fmt.Errorf("Received error code %d in response to request "+ + "for %s status: %s", + resp.StatusCode, url, resp.Status) + } - req, err := http.NewRequest("GET", url, nil) - if err != nil { - log.Fatalf("Error building http request for %s: %v", url, err) - } + var keepStatus map[string]interface{} + decoder := json.NewDecoder(resp.Body) + decoder.UseNumber() + err = decoder.Decode(&keepStatus) + if err != nil { + return fmt.Errorf("Error decoding keep status from %s: %v", url, err) + } - req.Header.Add("Authorization", - fmt.Sprintf("OAuth2 %s", getDataManagerToken())) + if arvLogger != nil { + now := time.Now() + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + serverInfo := keepInfo[keepServer.UUID].(map[string]interface{}) + serverInfo["status_response_processed_at"] = now + serverInfo["status"] = keepStatus + }) + } + + return nil +} + +// CreateIndexRequest to the keep server +func CreateIndexRequest(arvLogger *logger.Logger, + keepServer ServerAddress, + arv arvadosclient.ArvadosClient) (req *http.Request, err error) { + url := fmt.Sprintf("http://%s:%d/index", keepServer.Host, keepServer.Port) + log.Println("About to fetch keep server contents from " + url) + + if arvLogger != nil { + now := time.Now() + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + serverInfo := keepInfo[keepServer.UUID].(map[string]interface{}) + serverInfo["index_request_sent_at"] = now + }) + } + + req, err = http.NewRequest("GET", url, nil) + if err != nil { + return req, fmt.Errorf("Error building http request for %s: %v", url, err) + } - resp, err := client.Do(req) + req.Header.Add("Authorization", "OAuth2 "+arv.ApiToken) + return req, err +} + +// ReadServerResponse reads reasponse from keep server +func ReadServerResponse(arvLogger *logger.Logger, + keepServer ServerAddress, + resp *http.Response) (response ServerResponse, err error) { + + if resp.StatusCode != 200 { + return response, fmt.Errorf("Received error code %d in response to index request for %s: %s", + resp.StatusCode, keepServer.String(), resp.Status) + } + + if arvLogger != nil { + now := time.Now() + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + serverInfo := keepInfo[keepServer.UUID].(map[string]interface{}) + serverInfo["index_response_received_at"] = now + }) + } + + response.Address = keepServer + response.Contents.BlockDigestToInfo = + make(map[blockdigest.DigestWithSize]BlockInfo) + reader := bufio.NewReader(resp.Body) + numLines, numDuplicates, numSizeDisagreements := 0, 0, 0 + for { + numLines++ + line, err := reader.ReadString('\n') + if err == io.EOF { + return response, fmt.Errorf("Index from %s truncated at line %d", + keepServer.String(), numLines) + } else if err != nil { + return response, fmt.Errorf("Error reading index response from %s at line %d: %v", + keepServer.String(), numLines, err) + } + if line == "\n" { + if _, err := reader.Peek(1); err == nil { + extra, _ := reader.ReadString('\n') + return response, fmt.Errorf("Index from %s had trailing data at line %d after EOF marker: %s", + keepServer.String(), numLines+1, extra) + } else if err != io.EOF { + return response, fmt.Errorf("Index from %s had read error after EOF marker at line %d: %v", + keepServer.String(), numLines, err) + } + numLines-- + break + } + blockInfo, err := parseBlockInfoFromIndexLine(line) if err != nil { - log.Fatalf("Error fetching %s: %v", url, err) + return response, fmt.Errorf("Error parsing BlockInfo from index line "+ + "received from %s: %v", + keepServer.String(), + err) } - if resp.StatusCode != 200 { - log.Printf("%v", req) - log.Fatalf("Received error code %d in response to request for %s: %s", - resp.StatusCode, url, resp.Status) + if storedBlock, ok := response.Contents.BlockDigestToInfo[blockInfo.Digest]; ok { + // This server returned multiple lines containing the same block digest. + numDuplicates++ + // Keep the block that's newer. + if storedBlock.Mtime < blockInfo.Mtime { + response.Contents.BlockDigestToInfo[blockInfo.Digest] = blockInfo + } + } else { + response.Contents.BlockDigestToInfo[blockInfo.Digest] = blockInfo } } + log.Printf("%s index contained %d lines with %d duplicates with "+ + "%d size disagreements", + keepServer.String(), + numLines, + numDuplicates, + numSizeDisagreements) + + if arvLogger != nil { + now := time.Now() + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + serverInfo := keepInfo[keepServer.UUID].(map[string]interface{}) + + serverInfo["processing_finished_at"] = now + serverInfo["lines_received"] = numLines + serverInfo["duplicates_seen"] = numDuplicates + serverInfo["size_disagreements_seen"] = numSizeDisagreements + }) + } + resp.Body.Close() + return +} + +func parseBlockInfoFromIndexLine(indexLine string) (blockInfo BlockInfo, err error) { + tokens := strings.Fields(indexLine) + if len(tokens) != 2 { + err = fmt.Errorf("Expected 2 tokens per line but received a "+ + "line containing %#q instead.", + tokens) + } + + var locator blockdigest.BlockLocator + if locator, err = blockdigest.ParseBlockLocator(tokens[0]); err != nil { + err = fmt.Errorf("%v Received error while parsing line \"%#q\"", + err, indexLine) + return + } + if len(locator.Hints) > 0 { + err = fmt.Errorf("Block locator in index line should not contain hints "+ + "but it does: %#q", + locator) + return + } + + blockInfo.Mtime, err = strconv.ParseInt(tokens[1], 10, 64) + if err != nil { + return + } + blockInfo.Digest = + blockdigest.DigestWithSize{Digest: locator.Digest, + Size: uint32(locator.Size)} return } + +// Summarize results from keep server +func (readServers *ReadServers) Summarize(arvLogger *logger.Logger) { + readServers.BlockReplicationCounts = make(map[int]int) + for _, infos := range readServers.BlockToServers { + replication := len(infos) + readServers.BlockReplicationCounts[replication]++ + } + + if arvLogger != nil { + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + keepInfo := logger.GetOrCreateMap(p, "keep_info") + keepInfo["distinct_blocks_stored"] = len(readServers.BlockToServers) + }) + } +} + +// TrashRequest struct +type TrashRequest struct { + Locator string `json:"locator"` + BlockMtime int64 `json:"block_mtime"` +} + +// TrashList is an array of TrashRequest objects +type TrashList []TrashRequest + +// SendTrashLists to trash queue +func SendTrashLists(arvLogger *logger.Logger, kc *keepclient.KeepClient, spl map[string]TrashList, dryRun bool) (errs []error) { + count := 0 + barrier := make(chan error) + + client := kc.Client + + for url, v := range spl { + if arvLogger != nil { + // We need a local variable because Update doesn't call our mutator func until later, + // when our list variable might have been reused by the next loop iteration. + url := url + trashLen := len(v) + arvLogger.Update(func(p map[string]interface{}, e map[string]interface{}) { + trashListInfo := logger.GetOrCreateMap(p, "trash_list_len") + trashListInfo[url] = trashLen + }) + } + + if dryRun { + log.Printf("dry run, not sending trash list to service %s with %d blocks", url, len(v)) + continue + } + + count++ + log.Printf("Sending trash list to %v", url) + + go (func(url string, v TrashList) { + pipeReader, pipeWriter := io.Pipe() + go (func() { + enc := json.NewEncoder(pipeWriter) + enc.Encode(v) + pipeWriter.Close() + })() + + req, err := http.NewRequest("PUT", fmt.Sprintf("%s/trash", url), pipeReader) + if err != nil { + log.Printf("Error creating trash list request for %v error: %v", url, err.Error()) + barrier <- err + return + } + + req.Header.Add("Authorization", "OAuth2 "+kc.Arvados.ApiToken) + + // Make the request + var resp *http.Response + if resp, err = client.Do(req); err != nil { + log.Printf("Error sending trash list to %v error: %v", url, err.Error()) + barrier <- err + return + } + + log.Printf("Sent trash list to %v: response was HTTP %v", url, resp.Status) + + io.Copy(ioutil.Discard, resp.Body) + resp.Body.Close() + + if resp.StatusCode != 200 { + barrier <- errors.New(fmt.Sprintf("Got HTTP code %v", resp.StatusCode)) + } else { + barrier <- nil + } + })(url, v) + } + + for i := 0; i < count; i++ { + b := <-barrier + if b != nil { + errs = append(errs, b) + } + } + + return errs +}