server.go 30.2 KB
Newer Older
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
package llm

import (
	"bufio"
	"bytes"
	"context"
	"encoding/json"
	"errors"
	"fmt"
	"io"
	"log"
	"log/slog"
	"math/rand"
	"net"
	"net/http"
	"os"
	"os/exec"
	"path/filepath"
	"runtime"
20
	"slices"
21
22
	"strconv"
	"strings"
23
	"sync"
24
25
	"time"

Daniel Hiltgen's avatar
Daniel Hiltgen committed
26
27
	"golang.org/x/sync/semaphore"

28
	"github.com/ollama/ollama/api"
29
	"github.com/ollama/ollama/discover"
30
	"github.com/ollama/ollama/envconfig"
31
	"github.com/ollama/ollama/format"
Michael Yang's avatar
Michael Yang committed
32
	"github.com/ollama/ollama/fs/ggml"
33
	"github.com/ollama/ollama/llama"
34
	"github.com/ollama/ollama/logutil"
35
	"github.com/ollama/ollama/model"
36
37
)

38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
type filteredEnv []string

func (e filteredEnv) LogValue() slog.Value {
	var attrs []slog.Attr
	for _, env := range e {
		if key, value, ok := strings.Cut(env, "="); ok {
			switch {
			case strings.HasPrefix(key, "OLLAMA_"),
				strings.HasPrefix(key, "CUDA_"),
				strings.HasPrefix(key, "ROCR_"),
				strings.HasPrefix(key, "ROCM_"),
				strings.HasPrefix(key, "HIP_"),
				strings.HasPrefix(key, "GPU_"),
				strings.HasPrefix(key, "HSA_"),
				strings.HasPrefix(key, "GGML_"),
				slices.Contains([]string{
					"PATH",
					"LD_LIBRARY_PATH",
					"DYLD_LIBRARY_PATH",
				}, key):
				attrs = append(attrs, slog.String(key, value))
			}
		}
	}
	return slog.GroupValue(attrs...)
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
65
66
67
68
type LlamaServer interface {
	Ping(ctx context.Context) error
	WaitUntilRunning(ctx context.Context) error
	Completion(ctx context.Context, req CompletionRequest, fn func(CompletionResponse)) error
69
	Embedding(ctx context.Context, input string) ([]float32, error)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
70
71
72
	Tokenize(ctx context.Context, content string) ([]int, error)
	Detokenize(ctx context.Context, tokens []int) (string, error)
	Close() error
73
	EstimatedVRAM() uint64 // Total VRAM across all GPUs
74
	EstimatedTotal() uint64
Daniel Hiltgen's avatar
Daniel Hiltgen committed
75
	EstimatedVRAMByGPU(gpuID string) uint64
76
	Pid() int
Daniel Hiltgen's avatar
Daniel Hiltgen committed
77
78
79
80
}

// llmServer is an instance of the llama.cpp server
type llmServer struct {
81
82
83
84
85
86
	port        int
	cmd         *exec.Cmd
	done        chan error // Channel to signal when the process exits
	status      *StatusWriter
	options     api.Options
	numParallel int
87
	modelPath   string
88
89
90
91
92
93
94
95
96

	// llamaModel is an instance of the cgo llama.cpp model definition
	// nil if this server is running the new engine
	llamaModel     *llama.Model
	llamaModelLock sync.Mutex

	// textProcessor handles text encoding/decoding for the model in the Ollama engine
	// nil if this server is running the llama.cpp based engine
	textProcessor model.TextProcessor
Daniel Hiltgen's avatar
Daniel Hiltgen committed
97

98
99
100
	estimate    MemoryEstimate
	totalLayers uint64
	// gpuCount     int
101
102
	gpus         discover.GpuInfoList // Recorded just before the model loaded, free space will be incorrect
	loadDuration time.Duration        // Record how long it took the model to load
103
	loadProgress float32
Daniel Hiltgen's avatar
Daniel Hiltgen committed
104
105

	sem *semaphore.Weighted
106
107
}

108
109
110
111
112
// LoadModel will load a model from disk. The model must be in the GGML format.
//
// It collects array values for arrays with a size less than or equal to
// maxArraySize. If maxArraySize is 0, the default value of 1024 is used. If
// the maxArraySize is negative, all arrays are collected.
Michael Yang's avatar
Michael Yang committed
113
func LoadModel(model string, maxArraySize int) (*ggml.GGML, error) {
Daniel Hiltgen's avatar
Daniel Hiltgen committed
114
115
116
117
	if _, err := os.Stat(model); err != nil {
		return nil, err
	}

118
119
120
121
122
123
	f, err := os.Open(model)
	if err != nil {
		return nil, err
	}
	defer f.Close()

Michael Yang's avatar
Michael Yang committed
124
	ggml, _, err := ggml.Decode(f, maxArraySize)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
125
126
	return ggml, err
}
127

Daniel Hiltgen's avatar
Daniel Hiltgen committed
128
129
// NewLlamaServer will run a server for the given GPUs
// The gpu list must be a single family.
130
func NewLlamaServer(gpus discover.GpuInfoList, modelPath string, f *ggml.GGML, adapters, projectors []string, opts api.Options, numParallel int) (LlamaServer, error) {
131
	systemInfo := discover.GetSystemInfo()
Michael Yang's avatar
Michael Yang committed
132
133
134
	systemTotalMemory := systemInfo.System.TotalMemory
	systemFreeMemory := systemInfo.System.FreeMemory
	systemSwapFreeMemory := systemInfo.System.FreeSwap
135
	slog.Info("system memory", "total", format.HumanBytes2(systemTotalMemory), "free", format.HumanBytes2(systemFreeMemory), "free_swap", format.HumanBytes2(systemSwapFreeMemory))
136

137
138
	// If the user wants zero GPU layers, reset the gpu list to be CPU/system ram info
	if opts.NumGPU == 0 {
139
		gpus = discover.GetCPUInfo()
140
	}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
141

142
	estimate := EstimateGPULayers(gpus, f, projectors, opts, numParallel)
Michael Yang's avatar
Michael Yang committed
143
	if len(gpus) > 1 || gpus[0].Library != "cpu" {
Michael Yang's avatar
Michael Yang committed
144
		switch {
145
		case gpus[0].Library == "metal" && estimate.VRAMSize > systemTotalMemory:
Daniel Hiltgen's avatar
Daniel Hiltgen committed
146
147
148
			// disable partial offloading when model is greater than total system memory as this
			// can lead to locking up the system
			opts.NumGPU = 0
149
		case gpus[0].Library != "metal" && estimate.Layers == 0:
150
			// Don't bother loading into the GPU if no layers can fit
151
			gpus = discover.GetCPUInfo()
152
153
		case opts.NumGPU < 0 && estimate.Layers > 0 && gpus[0].Library != "cpu":
			opts.NumGPU = estimate.Layers
154
155
156
		}
	}

157
158
159
	// On linux and windows, over-allocating CPU memory will almost always result in an error
	// Darwin has fully dynamic swap so has no direct concept of free swap space
	if runtime.GOOS != "darwin" {
160
		systemMemoryRequired := estimate.TotalSize - estimate.VRAMSize
161
		available := systemFreeMemory + systemSwapFreeMemory
162
163
164
		if systemMemoryRequired > available {
			slog.Warn("model request too large for system", "requested", format.HumanBytes2(systemMemoryRequired), "available", available, "total", format.HumanBytes2(systemTotalMemory), "free", format.HumanBytes2(systemFreeMemory), "swap", format.HumanBytes2(systemSwapFreeMemory))
			return nil, fmt.Errorf("model requires more system memory (%s) than is available (%s)", format.HumanBytes2(systemMemoryRequired), format.HumanBytes2(available))
165
166
167
		}
	}

Michael Yang's avatar
Michael Yang committed
168
	slog.Info("offload", "", estimate)
169

170
	params := []string{
171
		"--model", modelPath,
Michael Yang's avatar
lint  
Michael Yang committed
172
173
		"--ctx-size", strconv.Itoa(opts.NumCtx),
		"--batch-size", strconv.Itoa(opts.NumBatch),
174
	}
Michael Yang's avatar
Michael Yang committed
175

Michael Yang's avatar
Michael Yang committed
176
	if opts.NumGPU >= 0 {
Michael Yang's avatar
lint  
Michael Yang committed
177
		params = append(params, "--n-gpu-layers", strconv.Itoa(opts.NumGPU))
178
179
180
	}

	if opts.MainGPU > 0 {
Michael Yang's avatar
lint  
Michael Yang committed
181
		params = append(params, "--main-gpu", strconv.Itoa(opts.MainGPU))
182
183
184
	}

	if len(adapters) > 0 {
185
186
187
		for _, adapter := range adapters {
			params = append(params, "--lora", adapter)
		}
188
189
	}

190
	defaultThreads := systemInfo.GetOptimalThreadCount()
191
	if opts.NumThread > 0 {
Michael Yang's avatar
lint  
Michael Yang committed
192
		params = append(params, "--threads", strconv.Itoa(opts.NumThread))
193
194
	} else if defaultThreads > 0 {
		params = append(params, "--threads", strconv.Itoa(defaultThreads))
195
196
	}

197
198
199
200
201
	fa := envconfig.FlashAttention()
	if fa && !gpus.FlashAttentionSupported() {
		slog.Warn("flash attention enabled but not supported by gpu")
		fa = false
	}
Sam's avatar
Sam committed
202

Michael Yang's avatar
Michael Yang committed
203
	if fa && !f.SupportsFlashAttention() {
204
205
206
207
		slog.Warn("flash attention enabled but not supported by model")
		fa = false
	}

208
	kvct := strings.ToLower(envconfig.KvCacheType())
209
210
211
212
213
214
215

	if fa {
		slog.Info("enabling flash attention")
		params = append(params, "--flash-attn")

		// Flash Attention also supports kv cache quantization
		// Enable if the requested and kv cache type is supported by the model
Michael Yang's avatar
Michael Yang committed
216
		if kvct != "" && f.SupportsKVCacheType(kvct) {
217
218
219
			params = append(params, "--kv-cache-type", kvct)
		} else {
			slog.Warn("kv cache type not supported by model", "type", kvct)
Sam's avatar
Sam committed
220
		}
221
222
223
	} else if kvct != "" && kvct != "f16" {
		slog.Warn("quantized kv cache requested but flash attention disabled", "type", kvct)
	}
224

225
226
	// mmap has issues with partial offloading on metal
	for _, g := range gpus {
227
228
		if g.Library == "metal" &&
			uint64(opts.NumGPU) > 0 &&
Michael Yang's avatar
Michael Yang committed
229
			uint64(opts.NumGPU) < f.KV().BlockCount()+1 {
230
231
			opts.UseMMap = new(bool)
			*opts.UseMMap = false
232
		}
Sam's avatar
Sam committed
233
	}
234

235
	// Windows CUDA should not use mmap for best performance
236
	// Linux  with a model larger than free space, mmap leads to thrashing
Daniel Hiltgen's avatar
Daniel Hiltgen committed
237
	// For CPU loads we want the memory to be allocated, not FS cache
238
239
240
241
	if (runtime.GOOS == "windows" && gpus[0].Library == "cuda" && opts.UseMMap == nil) ||
		(runtime.GOOS == "linux" && systemFreeMemory < estimate.TotalSize && opts.UseMMap == nil) ||
		(gpus[0].Library == "cpu" && opts.UseMMap == nil) ||
		(opts.UseMMap != nil && !*opts.UseMMap) {
242
243
244
		params = append(params, "--no-mmap")
	}

245
	// TODO - NUMA support currently doesn't work properly
246

Michael Yang's avatar
lint  
Michael Yang committed
247
	params = append(params, "--parallel", strconv.Itoa(numParallel))
Daniel Hiltgen's avatar
Daniel Hiltgen committed
248

249
250
251
252
	if estimate.TensorSplit != "" {
		params = append(params, "--tensor-split", estimate.TensorSplit)
	}

253
254
255
256
	if envconfig.MultiUserCache() {
		params = append(params, "--multiuser-cache")
	}

Michael Yang's avatar
Michael Yang committed
257
	libs := make(map[string]string)
258
259
	if entries, err := os.ReadDir(discover.LibOllamaPath); err == nil {
		for _, entry := range entries {
Michael Yang's avatar
Michael Yang committed
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
			libs[entry.Name()] = filepath.Join(discover.LibOllamaPath, entry.Name())
		}
	}

	lib := gpus[0].RunnerName()
	requested := envconfig.LLMLibrary()
	if libs[requested] != "" {
		slog.Info("using requested gpu library", "requested", requested)
		lib = requested
	}

	var compatible []string
	for k := range libs {
		// exact match first
		if k == lib {
			compatible = append([]string{k}, compatible...)
276
277
			continue
		}
278

Michael Yang's avatar
Michael Yang committed
279
280
281
		// then match the family (e.g. 'cuda')
		if strings.Split(k, "_")[0] == strings.Split(lib, "_")[0] {
			compatible = append(compatible, k)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
282
		}
Michael Yang's avatar
Michael Yang committed
283
284
	}
	slog.Debug("compatible gpu libraries", "compatible", compatible)
285
286
287
288
289
290
291
292
293
294
295
	exe, err := os.Executable()
	if err != nil {
		return nil, fmt.Errorf("unable to lookup executable path: %w", err)
	}

	if eval, err := filepath.EvalSymlinks(exe); err == nil {
		exe = eval
	}

	var llamaModel *llama.Model
	var textProcessor model.TextProcessor
296
	if envconfig.NewEngine() || f.KV().OllamaEngineRequired() {
297
298
299
300
301
302
303
304
305
306
307
308
309
310
311
312
		textProcessor, err = model.NewTextProcessor(modelPath)
		if err != nil {
			// To prepare for opt-out mode, instead of treating this as an error, we fallback to the old runner
			slog.Debug("model not yet supported by Ollama engine, switching to compatibility mode", "model", modelPath, "error", err)
		}
	}
	if textProcessor == nil {
		llamaModel, err = llama.LoadModelFromFile(modelPath, llama.ModelParams{VocabOnly: true})
		if err != nil {
			return nil, err
		}
	}

	if len(projectors) > 0 && llamaModel != nil {
		params = append(params, "--mmproj", projectors[0])
	}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
313

314
	// iterate through compatible GPU libraries such as 'cuda_v12', 'cuda_v11', 'rocm', etc.
Michael Yang's avatar
Michael Yang committed
315
316
317
	// adding each library's respective path to the LD_LIBRARY_PATH, until finally running
	// without any LD_LIBRARY_PATH flags
	for {
318
319
320
321
322
323
324
325
326
		port := 0
		if a, err := net.ResolveTCPAddr("tcp", "localhost:0"); err == nil {
			var l *net.TCPListener
			if l, err = net.ListenTCP("tcp", a); err == nil {
				port = l.Addr().(*net.TCPAddr).Port
				l.Close()
			}
		}
		if port == 0 {
327
			slog.Debug("ResolveTCPAddr failed, using random port")
328
329
			port = rand.Intn(65535-49152) + 49152 // get a random port in the ephemeral range
		}
330
		finalParams := []string{"runner"}
331
332
333
		if textProcessor != nil {
			// New engine
			// TODO - if we have failure to load scenarios, add logic to retry with the old runner
Jesse Gross's avatar
Jesse Gross committed
334
335
			finalParams = append(finalParams, "--ollama-engine")
		}
336
337
		finalParams = append(finalParams, params...)
		finalParams = append(finalParams, "--port", strconv.Itoa(port))
338

339
340
341
		var pathEnv string
		switch runtime.GOOS {
		case "windows":
342
			pathEnv = "PATH"
343
344
345
346
		case "darwin":
			pathEnv = "DYLD_LIBRARY_PATH"
		default:
			pathEnv = "LD_LIBRARY_PATH"
347
		}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
348

349
350
351
		// Note: we always put our dependency paths first
		// since these are the exact version we compiled/linked against
		libraryPaths := []string{discover.LibOllamaPath}
352
		if libraryPath, ok := os.LookupEnv(pathEnv); ok {
Daniel Hiltgen's avatar
Daniel Hiltgen committed
353
			libraryPaths = append(libraryPaths, filepath.SplitList(libraryPath)...)
354
355
		}

356
		ggmlPaths := []string{discover.LibOllamaPath}
Michael Yang's avatar
Michael Yang committed
357
358
359
360
		if len(compatible) > 0 {
			c := compatible[0]
			if libpath, ok := libs[c]; ok {
				slog.Debug("adding gpu library", "path", libpath)
361
				libraryPaths = append([]string{libpath}, libraryPaths...)
362
				ggmlPaths = append(ggmlPaths, libpath)
Michael Yang's avatar
Michael Yang committed
363
364
365
			}
		}

366
		if gpus[0].DependencyPath != nil {
Michael Yang's avatar
Michael Yang committed
367
			slog.Debug("adding gpu dependency paths", "paths", gpus[0].DependencyPath)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
368
			// assume gpus from the same library have the same dependency path
369
			libraryPaths = append(gpus[0].DependencyPath, libraryPaths...)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
370
371
		}

Michael Yang's avatar
Michael Yang committed
372
373
374
		// finally, add the root library path
		libraryPaths = append(libraryPaths, discover.LibOllamaPath)

Daniel Hiltgen's avatar
Daniel Hiltgen committed
375
		s := &llmServer{
376
377
378
379
380
381
382
383
384
385
386
387
388
			port:          port,
			cmd:           exec.Command(exe, finalParams...),
			status:        NewStatusWriter(os.Stderr),
			options:       opts,
			modelPath:     modelPath,
			llamaModel:    llamaModel,
			textProcessor: textProcessor,
			estimate:      estimate,
			numParallel:   numParallel,
			sem:           semaphore.NewWeighted(int64(numParallel)),
			totalLayers:   f.KV().BlockCount() + 1,
			gpus:          gpus,
			done:          make(chan error, 1),
389
		}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
390

391
		s.cmd.Env = os.Environ()
392
393
		s.cmd.Stdout = os.Stdout
		s.cmd.Stderr = s.status
394
		s.cmd.SysProcAttr = LlamaServerSysProcAttr
395

396
397
		s.cmd.Env = append(s.cmd.Env, "OLLAMA_LIBRARY_PATH="+strings.Join(ggmlPaths, string(filepath.ListSeparator)))

Daniel Hiltgen's avatar
Daniel Hiltgen committed
398
399
400
401
		envWorkarounds := [][2]string{}
		for _, gpu := range gpus {
			envWorkarounds = append(envWorkarounds, gpu.EnvWorkarounds...)
		}
Michael Yang's avatar
lint  
Michael Yang committed
402
		visibleDevicesEnv, visibleDevicesEnvVal := gpus.GetVisibleDevicesEnv()
403
404
405
406
407
408
409
410
411
412
413
414
415
		pathEnvVal := strings.Join(libraryPaths, string(filepath.ListSeparator))

		// Update or add the path and visible devices variable with our adjusted version
		pathNeeded := true
		devicesNeeded := visibleDevicesEnv != ""
		for i := range s.cmd.Env {
			cmp := strings.SplitN(s.cmd.Env[i], "=", 2)
			if strings.EqualFold(cmp[0], pathEnv) {
				s.cmd.Env[i] = pathEnv + "=" + pathEnvVal
				pathNeeded = false
			} else if devicesNeeded && strings.EqualFold(cmp[0], visibleDevicesEnv) {
				s.cmd.Env[i] = visibleDevicesEnv + "=" + visibleDevicesEnvVal
				devicesNeeded = false
Daniel Hiltgen's avatar
Daniel Hiltgen committed
416
417
418
419
420
421
			} else if len(envWorkarounds) != 0 {
				for _, kv := range envWorkarounds {
					if strings.EqualFold(cmp[0], kv[0]) {
						s.cmd.Env[i] = kv[0] + "=" + kv[1]
					}
				}
422
			}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
423
		}
424
425
		if pathNeeded {
			s.cmd.Env = append(s.cmd.Env, pathEnv+"="+pathEnvVal)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
426
		}
427
428
		if devicesNeeded {
			s.cmd.Env = append(s.cmd.Env, visibleDevicesEnv+"="+visibleDevicesEnvVal)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
429
430
		}

431
		slog.Info("starting llama server", "cmd", s.cmd)
432
		slog.Debug("subprocess", "", filteredEnv(s.cmd.Env))
433
434

		if err = s.cmd.Start(); err != nil {
Michael Yang's avatar
Michael Yang committed
435
			var msg string
436
437
438
			if s.status != nil && s.status.LastErrMsg != "" {
				msg = s.status.LastErrMsg
			}
Michael Yang's avatar
Michael Yang committed
439
440
			err := fmt.Errorf("error starting runner: %v %s", err, msg)
			if len(compatible) == 0 {
441
442
443
				if llamaModel != nil {
					llama.FreeModel(llamaModel)
				}
Michael Yang's avatar
Michael Yang committed
444
445
446
447
448
				return nil, err
			}

			slog.Warn("unable to start runner with compatible gpu", "error", err, "compatible", compatible)
			compatible = compatible[1:]
449
450
451
			continue
		}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
452
453
		// reap subprocess when it exits
		go func() {
454
455
456
			err := s.cmd.Wait()
			// Favor a more detailed message over the process exit status
			if err != nil && s.status != nil && s.status.LastErrMsg != "" {
Michael Yang's avatar
Michael Yang committed
457
				slog.Error("llama runner terminated", "error", err)
458
459
460
				if strings.Contains(s.status.LastErrMsg, "unknown model") {
					s.status.LastErrMsg = "this model is not supported by your version of Ollama. You may need to upgrade"
				}
Michael Yang's avatar
lint  
Michael Yang committed
461
				s.done <- errors.New(s.status.LastErrMsg)
462
463
464
			} else {
				s.done <- err
			}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
465
466
		}()

467
468
469
470
471
472
473
474
		return s, nil
	}
}

type ServerStatus int

const ( // iota is reset to 0
	ServerStatusReady ServerStatus = iota
475
	ServerStatusNoSlotsAvailable
476
477
478
479
480
	ServerStatusLoadingModel
	ServerStatusNotResponding
	ServerStatusError
)

481
func (s ServerStatus) String() string {
Daniel Hiltgen's avatar
Daniel Hiltgen committed
482
483
484
	switch s {
	case ServerStatusReady:
		return "llm server ready"
485
	case ServerStatusNoSlotsAvailable:
Daniel Hiltgen's avatar
Daniel Hiltgen committed
486
487
488
489
490
491
492
493
494
495
		return "llm busy - no slots available"
	case ServerStatusLoadingModel:
		return "llm server loading model"
	case ServerStatusNotResponding:
		return "llm server not responding"
	default:
		return "llm server error"
	}
}

496
497
498
type ServerStatusResponse struct {
	Status   ServerStatus `json:"status"`
	Progress float32      `json:"progress"`
499
500
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
501
func (s *llmServer) getServerStatus(ctx context.Context) (ServerStatus, error) {
502
503
504
505
506
507
	// Fail fast if its exited
	if s.cmd.ProcessState != nil {
		msg := ""
		if s.status != nil && s.status.LastErrMsg != "" {
			msg = s.status.LastErrMsg
		}
508
509
		if s.cmd.ProcessState.ExitCode() == -1 {
			// Most likely a signal killed it, log some more details to try to help troubleshoot
510
			slog.Warn("llama runner process no longer running", "sys", s.cmd.ProcessState.Sys(), "string", s.cmd.ProcessState)
511
		}
512
513
514
515
516
517
518
519
520
521
522
523
		return ServerStatusError, fmt.Errorf("llama runner process no longer running: %d %s", s.cmd.ProcessState.ExitCode(), msg)
	}

	req, err := http.NewRequestWithContext(ctx, http.MethodGet, fmt.Sprintf("http://127.0.0.1:%d/health", s.port), nil)
	if err != nil {
		return ServerStatusError, fmt.Errorf("error creating GET request: %v", err)
	}
	req.Header.Set("Content-Type", "application/json")

	resp, err := http.DefaultClient.Do(req)
	if err != nil {
		if errors.Is(err, context.DeadlineExceeded) {
Michael Yang's avatar
Michael Yang committed
524
			return ServerStatusNotResponding, errors.New("server not responding")
525
		}
526
527
528
		if strings.Contains(err.Error(), "connection refused") {
			return ServerStatusNotResponding, errors.New("connection refused")
		}
529
530
531
532
533
534
535
536
537
		return ServerStatusError, fmt.Errorf("health resp: %w", err)
	}
	defer resp.Body.Close()

	body, err := io.ReadAll(resp.Body)
	if err != nil {
		return ServerStatusError, fmt.Errorf("read health request: %w", err)
	}

538
539
	var ssr ServerStatusResponse
	if err := json.Unmarshal(body, &ssr); err != nil {
540
541
542
		return ServerStatusError, fmt.Errorf("health unmarshal encode response: %w", err)
	}

543
544
545
546
547
548
	switch ssr.Status {
	case ServerStatusLoadingModel:
		s.loadProgress = ssr.Progress
		return ssr.Status, nil
	case ServerStatusReady, ServerStatusNoSlotsAvailable:
		return ssr.Status, nil
549
	default:
550
		return ssr.Status, fmt.Errorf("server error: %+v", ssr)
551
552
553
	}
}

554
555
556
557
558
559
560
561
562
563
564
565
566
567
568
569
570
571
572
573
574
575
576
// getServerStatusRetry will retry if ServerStatusNoSlotsAvailable is received
func (s *llmServer) getServerStatusRetry(ctx context.Context) (ServerStatus, error) {
	var retries int
	for {
		status, err := s.getServerStatus(ctx)
		if err != nil {
			return status, err
		}

		if status == ServerStatusNoSlotsAvailable {
			if retries >= 10 {
				return status, fmt.Errorf("no slots available after %d retries", retries)
			}

			time.Sleep(5 * time.Millisecond)
			retries++
			continue
		}

		return status, nil
	}
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
577
func (s *llmServer) Ping(ctx context.Context) error {
578
579
580
581
582
583
584
585
	_, err := s.getServerStatus(ctx)
	if err != nil {
		slog.Debug("server unhealthy", "error", err)
		return err
	}
	return nil
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
586
func (s *llmServer) WaitUntilRunning(ctx context.Context) error {
587
	start := time.Now()
588
	stallDuration := envconfig.LoadTimeout()    // If no progress happens
589
	stallTimer := time.Now().Add(stallDuration) // give up if we stall
590
591
592

	slog.Info("waiting for llama runner to start responding")
	var lastStatus ServerStatus = -1
593
	fullyLoaded := false
ManniX-ITA's avatar
ManniX-ITA committed
594

595
596
	for {
		select {
Daniel Hiltgen's avatar
Daniel Hiltgen committed
597
		case <-ctx.Done():
598
			slog.Warn("client connection closed before server finished loading, aborting load")
599
			return fmt.Errorf("timed out waiting for llama runner to start: %w", ctx.Err())
600
		case err := <-s.done:
601
			return fmt.Errorf("llama runner process has terminated: %w", err)
602
603
		default:
		}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
604
		if time.Now().After(stallTimer) {
ManniX-ITA's avatar
ManniX-ITA committed
605
			// timeout
606
607
608
609
			msg := ""
			if s.status != nil && s.status.LastErrMsg != "" {
				msg = s.status.LastErrMsg
			}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
610
			return fmt.Errorf("timed out waiting for llama runner to start - progress %0.2f - %s", s.loadProgress, msg)
ManniX-ITA's avatar
ManniX-ITA committed
611
612
613
614
615
		}
		if s.cmd.ProcessState != nil {
			msg := ""
			if s.status != nil && s.status.LastErrMsg != "" {
				msg = s.status.LastErrMsg
616
			}
ManniX-ITA's avatar
ManniX-ITA committed
617
618
			return fmt.Errorf("llama runner process no longer running: %d %s", s.cmd.ProcessState.ExitCode(), msg)
		}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
619
620
		ctx, cancel := context.WithTimeout(ctx, 200*time.Millisecond)
		defer cancel()
Daniel Hiltgen's avatar
Daniel Hiltgen committed
621
		priorProgress := s.loadProgress
Daniel Hiltgen's avatar
Daniel Hiltgen committed
622
623
624
		status, _ := s.getServerStatus(ctx)
		if lastStatus != status && status != ServerStatusReady {
			// Only log on status changes
625
			slog.Info("waiting for server to become available", "status", status)
Daniel Hiltgen's avatar
Daniel Hiltgen committed
626
		}
ManniX-ITA's avatar
ManniX-ITA committed
627
628
		switch status {
		case ServerStatusReady:
Daniel Hiltgen's avatar
Daniel Hiltgen committed
629
630
			s.loadDuration = time.Since(start)
			slog.Info(fmt.Sprintf("llama runner started in %0.2f seconds", s.loadDuration.Seconds()))
ManniX-ITA's avatar
ManniX-ITA committed
631
632
			return nil
		default:
Daniel Hiltgen's avatar
Daniel Hiltgen committed
633
			lastStatus = status
Daniel Hiltgen's avatar
Daniel Hiltgen committed
634
635
636
637
			// Reset the timer as long as we're making forward progress on the load
			if priorProgress != s.loadProgress {
				slog.Debug(fmt.Sprintf("model load progress %0.2f", s.loadProgress))
				stallTimer = time.Now().Add(stallDuration)
638
			} else if !fullyLoaded && int(s.loadProgress*100.0) >= 100 {
639
				slog.Debug("model load completed, waiting for server to become available", "status", status)
640
				stallTimer = time.Now().Add(stallDuration)
641
				fullyLoaded = true
Daniel Hiltgen's avatar
Daniel Hiltgen committed
642
			}
ManniX-ITA's avatar
ManniX-ITA committed
643
644
			time.Sleep(time.Millisecond * 250)
			continue
645
646
647
648
		}
	}
}

649
650
651
652
653
654
655
func (s *llmServer) Pid() int {
	if s.cmd != nil && s.cmd.Process != nil {
		return s.cmd.Process.Pid
	}
	return -1
}

656
var grammarJSON = `
657
658
659
660
root   ::= object
value  ::= object | array | string | number | ("true" | "false" | "null") ws
object ::=
  "{" ws (
661
         string ":" ws value
662
    ("," ws string ":" ws value)*
663
  )? ws "}" 
664
665
666
667
array  ::=
  "[" ws (
            value
    ("," ws value)*
668
  )? ws "]" 
669
670
string ::=
  "\"" (
671
    [^"\\\x7F\x00-\x1F] |
672
    "\\" (["\\/bfnrt] | "u" [0-9a-fA-F] [0-9a-fA-F] [0-9a-fA-F] [0-9a-fA-F]) # escapes
673
674
  )* "\"" 
number ::= ("-"? ([0-9] | [1-9] [0-9]*)) ("." [0-9]+)? ([eE] [-+]? [0-9]+)? 
675
676
677
678
679
680
681
# Optional space: by convention, applied in this grammar after literal chars when allowed
ws ::= ([ \t\n] ws)?
`

const maxBufferSize = 512 * format.KiloByte

type ImageData struct {
682
683
684
	Data          []byte `json:"data"`
	ID            int    `json:"id"`
	AspectRatioID int    `json:"aspect_ratio_id"`
685
686
687
688
}

type CompletionRequest struct {
	Prompt  string
689
	Format  json.RawMessage
690
	Images  []ImageData
Michael Yang's avatar
Michael Yang committed
691
	Options *api.Options
692
693

	Grammar string // set before sending the request to the subprocess
694
695
}

696
697
698
699
700
701
702
703
704
705
706
707
708
709
710
711
712
713
714
715
716
717
718
// DoneReason represents the reason why a completion response is done
type DoneReason int

const (
	// DoneReasonStop indicates the completion stopped naturally
	DoneReasonStop DoneReason = iota
	// DoneReasonLength indicates the completion stopped due to length limits
	DoneReasonLength
	// DoneReasonConnectionClosed indicates the completion stopped due to the connection being closed
	DoneReasonConnectionClosed
)

func (d DoneReason) String() string {
	switch d {
	case DoneReasonLength:
		return "length"
	case DoneReasonStop:
		return "stop"
	default:
		return "" // closed
	}
}

719
type CompletionResponse struct {
720
	Content            string        `json:"content"`
721
	DoneReason         DoneReason    `json:"done_reason"`
722
723
724
725
726
	Done               bool          `json:"done"`
	PromptEvalCount    int           `json:"prompt_eval_count"`
	PromptEvalDuration time.Duration `json:"prompt_eval_duration"`
	EvalCount          int           `json:"eval_count"`
	EvalDuration       time.Duration `json:"eval_duration"`
727
728
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
729
func (s *llmServer) Completion(ctx context.Context, req CompletionRequest, fn func(CompletionResponse)) error {
730
731
732
	slog.Debug("completion request", "images", len(req.Images), "prompt", len(req.Prompt), "format", string(req.Format))
	slog.Log(ctx, logutil.LevelTrace, "completion request", "prompt", req.Prompt)

733
	if len(req.Format) > 0 {
734
735
736
737
738
739
		switch string(req.Format) {
		case `null`, `""`:
			// Field was set, but "missing" a value. We accept
			// these as "not set".
			break
		case `"json"`:
740
			req.Grammar = grammarJSON
741
742
743
744
		default:
			if req.Format[0] != '{' {
				return fmt.Errorf("invalid format: %q; expected \"json\" or a valid JSON Schema object", req.Format)
			}
745

746
747
748
749
			// User provided a JSON schema
			g := llama.SchemaToGrammar(req.Format)
			if g == nil {
				return fmt.Errorf("invalid JSON schema in format")
750
			}
751
			req.Grammar = string(g)
752
753
754
		}
	}

755
756
757
758
759
	if req.Options == nil {
		opts := api.DefaultOptions()
		req.Options = &opts
	}

760
761
762
763
764
765
766
767
768
769
770
771
772
773
774
	if err := s.sem.Acquire(ctx, 1); err != nil {
		if errors.Is(err, context.Canceled) {
			slog.Info("aborting completion request due to client closing the connection")
		} else {
			slog.Error("Failed to acquire semaphore", "error", err)
		}
		return err
	}
	defer s.sem.Release(1)

	// put an upper limit on num_predict to avoid the model running on forever
	if req.Options.NumPredict < 0 || req.Options.NumPredict > 10*s.options.NumCtx {
		req.Options.NumPredict = 10 * s.options.NumCtx
	}

775
	// Make sure the server is ready
776
	status, err := s.getServerStatusRetry(ctx)
777
778
779
	if err != nil {
		return err
	} else if status != ServerStatusReady {
780
		return fmt.Errorf("unexpected server status: %s", status)
781
782
	}

783
784
785
786
	// Handling JSON marshaling with special characters unescaped.
	buffer := &bytes.Buffer{}
	enc := json.NewEncoder(buffer)
	enc.SetEscapeHTML(false)
787

788
	if err := enc.Encode(req); err != nil {
789
790
		return fmt.Errorf("failed to marshal data: %v", err)
	}
791

792
793
794
795
796
797
	endpoint := fmt.Sprintf("http://127.0.0.1:%d/completion", s.port)
	serverReq, err := http.NewRequestWithContext(ctx, http.MethodPost, endpoint, buffer)
	if err != nil {
		return fmt.Errorf("error creating POST request: %v", err)
	}
	serverReq.Header.Set("Content-Type", "application/json")
798

799
800
801
802
803
	res, err := http.DefaultClient.Do(serverReq)
	if err != nil {
		return fmt.Errorf("POST predict: %v", err)
	}
	defer res.Body.Close()
804

805
806
	if res.StatusCode >= 400 {
		bodyBytes, err := io.ReadAll(res.Body)
807
		if err != nil {
808
			return fmt.Errorf("failed reading llm error response: %w", err)
809
		}
810
811
812
		log.Printf("llm predict error: %s", bodyBytes)
		return fmt.Errorf("%s", bodyBytes)
	}
813

814
815
816
	scanner := bufio.NewScanner(res.Body)
	buf := make([]byte, 0, maxBufferSize)
	scanner.Buffer(buf, maxBufferSize)
817

818
819
820
	// keep track of the last token generated, this is used to abort if the model starts looping
	var lastToken string
	var tokenRepeat int
821

822
823
824
825
826
827
828
829
830
831
	for scanner.Scan() {
		select {
		case <-ctx.Done():
			// This handles the request cancellation
			return ctx.Err()
		default:
			line := scanner.Bytes()
			if len(line) == 0 {
				continue
			}
832

833
834
			evt, ok := bytes.CutPrefix(line, []byte("data: "))
			if !ok {
835
				evt = line
836
			}
837

838
			var c CompletionResponse
839
			if err := json.Unmarshal(evt, &c); err != nil {
840
				return fmt.Errorf("error unmarshalling llm prediction response: %v", err)
841
842
843
844
845
846
847
848
			}
			switch {
			case strings.TrimSpace(c.Content) == lastToken:
				tokenRepeat++
			default:
				lastToken = strings.TrimSpace(c.Content)
				tokenRepeat = 0
			}
849

850
851
852
853
854
			// 30 picked as an arbitrary max token repeat limit, modify as needed
			if tokenRepeat > 30 {
				slog.Debug("prediction aborted, token repeat limit reached")
				return ctx.Err()
			}
855

856
857
858
859
860
			if c.Content != "" {
				fn(CompletionResponse{
					Content: c.Content,
				})
			}
861

862
863
			if c.Done {
				fn(c)
864
				return nil
865
866
			}
		}
867
	}
868

869
	if err := scanner.Err(); err != nil {
870
		if strings.Contains(err.Error(), "unexpected EOF") || strings.Contains(err.Error(), "forcibly closed") {
871
			s.Close()
872
			var msg string
873
874
			if s.status != nil && s.status.LastErrMsg != "" {
				msg = s.status.LastErrMsg
875
876
			} else {
				msg = err.Error()
877
			}
878
			return fmt.Errorf("an error was encountered while running the model: %s", msg)
879
880
		}

881
		return fmt.Errorf("error reading llm response: %v", err)
882
883
	}

884
	return nil
885
886
}

887
888
type EmbeddingRequest struct {
	Content string `json:"content"`
889
890
}

891
892
type EmbeddingResponse struct {
	Embedding []float32 `json:"embedding"`
893
894
}

895
func (s *llmServer) Embedding(ctx context.Context, input string) ([]float32, error) {
896
897
	slog.Log(ctx, logutil.LevelTrace, "embedding request", "input", input)

898
	if err := s.sem.Acquire(ctx, 1); err != nil {
899
900
901
902
903
		if errors.Is(err, context.Canceled) {
			slog.Info("aborting embedding request due to client closing the connection")
		} else {
			slog.Error("Failed to acquire semaphore", "error", err)
		}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
904
905
		return nil, err
	}
906
	defer s.sem.Release(1)
907

908
	// Make sure the server is ready
909
	status, err := s.getServerStatusRetry(ctx)
910
911
912
	if err != nil {
		return nil, err
	} else if status != ServerStatusReady {
913
		return nil, fmt.Errorf("unexpected server status: %s", status)
914
915
	}

916
	data, err := json.Marshal(EmbeddingRequest{Content: input})
Michael Yang's avatar
Michael Yang committed
917
	if err != nil {
918
919
920
		return nil, fmt.Errorf("error marshaling embed data: %w", err)
	}

921
	r, err := http.NewRequestWithContext(ctx, http.MethodPost, fmt.Sprintf("http://127.0.0.1:%d/embedding", s.port), bytes.NewBuffer(data))
922
923
924
	if err != nil {
		return nil, fmt.Errorf("error creating embed request: %w", err)
	}
925
	r.Header.Set("Content-Type", "application/json")
926

927
	resp, err := http.DefaultClient.Do(r)
928
929
930
931
932
933
934
935
936
937
938
	if err != nil {
		return nil, fmt.Errorf("do embedding request: %w", err)
	}
	defer resp.Body.Close()

	body, err := io.ReadAll(resp.Body)
	if err != nil {
		return nil, fmt.Errorf("error reading embed response: %w", err)
	}

	if resp.StatusCode >= 400 {
939
		log.Printf("llm embedding error: %s", body)
940
941
942
		return nil, fmt.Errorf("%s", body)
	}

943
	var e EmbeddingResponse
944
	if err := json.Unmarshal(body, &e); err != nil {
945
946
947
		return nil, fmt.Errorf("unmarshal tokenize response: %w", err)
	}

948
	return e.Embedding, nil
949
950
}

Michael Yang's avatar
Michael Yang committed
951
952
953
954
955
956
957
958
type TokenizeRequest struct {
	Content string `json:"content"`
}

type TokenizeResponse struct {
	Tokens []int `json:"tokens"`
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
959
func (s *llmServer) Tokenize(ctx context.Context, content string) ([]int, error) {
960
961
	s.llamaModelLock.Lock()
	defer s.llamaModelLock.Unlock()
962

963
964
	if s.llamaModel != nil {
		return s.llamaModel.Tokenize(content, false, true)
Michael Yang's avatar
Michael Yang committed
965
	}
966
	if s.textProcessor != nil {
967
		tokens, err := s.textProcessor.Encode(content, false)
968
969
		if err != nil {
			return nil, err
970
		}
971
972
973
974
975
		toks := make([]int, len(tokens))
		for i, t := range tokens {
			toks[i] = int(t)
		}
		return toks, nil
Michael Yang's avatar
Michael Yang committed
976
	}
977
978
	// not reached
	return nil, fmt.Errorf("no tokenizer configured")
Michael Yang's avatar
Michael Yang committed
979
980
981
982
983
984
985
986
}

type DetokenizeRequest struct {
	Tokens []int `json:"tokens"`
}

type DetokenizeResponse struct {
	Content string `json:"content"`
987
988
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
989
func (s *llmServer) Detokenize(ctx context.Context, tokens []int) (string, error) {
990
991
992
993
	s.llamaModelLock.Lock()
	defer s.llamaModelLock.Unlock()

	if s.llamaModel != nil {
994
995
		var resp string
		for _, token := range tokens {
996
			resp += s.llamaModel.TokenToPiece(token)
997
998
999
		}
		return resp, nil
	}
1000
1001
1002
1003
	if s.textProcessor != nil {
		toks := make([]int32, len(tokens))
		for i, t := range tokens {
			toks[i] = int32(t)
1004
		}
1005
1006
1007
		content, err := s.textProcessor.Decode(toks)
		if err != nil {
			return "", err
1008
		}
1009
		return content, nil
Michael Yang's avatar
Michael Yang committed
1010
	}
1011
1012
	// not reached
	return "", fmt.Errorf("no tokenizer configured")
1013
1014
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
1015
func (s *llmServer) Close() error {
1016
1017
1018
1019
	s.llamaModelLock.Lock()
	if s.llamaModel != nil {
		llama.FreeModel(s.llamaModel)
		s.llamaModel = nil
1020
	}
1021
	s.llamaModelLock.Unlock()
1022

1023
	if s.cmd != nil {
1024
		slog.Debug("stopping llama server", "pid", s.Pid())
1025
1026
1027
		if err := s.cmd.Process.Kill(); err != nil {
			return err
		}
Daniel Hiltgen's avatar
Daniel Hiltgen committed
1028
1029
		// if ProcessState is already populated, Wait already completed, no need to wait again
		if s.cmd.ProcessState == nil {
1030
			slog.Debug("waiting for llama server to exit", "pid", s.Pid())
Daniel Hiltgen's avatar
Daniel Hiltgen committed
1031
1032
			<-s.done
		}
1033

1034
		slog.Debug("llama server stopped", "pid", s.Pid())
1035
1036
1037
1038
1039
	}

	return nil
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
1040
func (s *llmServer) EstimatedVRAM() uint64 {
1041
	return s.estimate.VRAMSize
Daniel Hiltgen's avatar
Daniel Hiltgen committed
1042
1043
}

1044
func (s *llmServer) EstimatedTotal() uint64 {
1045
	return s.estimate.TotalSize
1046
1047
}

Daniel Hiltgen's avatar
Daniel Hiltgen committed
1048
func (s *llmServer) EstimatedVRAMByGPU(gpuID string) uint64 {
1049
1050
	for i, gpu := range s.gpus {
		if gpu.ID == gpuID {
1051
1052
1053
			if i < len(s.estimate.GPUSizes) {
				return s.estimate.GPUSizes[i]
			}
1054
1055
1056
1057
		}
	}
	return 0
}