]> Cypherpunks.ru repositories - nncp.git/blob - src/sp.go
Unnecessary returns
[nncp.git] / src / sp.go
1 /*
2 NNCP -- Node to Node copy, utilities for store-and-forward data exchange
3 Copyright (C) 2016-2020 Sergey Matveev <stargrave@stargrave.org>
4
5 This program is free software: you can redistribute it and/or modify
6 it under the terms of the GNU General Public License as published by
7 the Free Software Foundation, version 3 of the License.
8
9 This program is distributed in the hope that it will be useful,
10 but WITHOUT ANY WARRANTY; without even the implied warranty of
11 MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
12 GNU General Public License for more details.
13
14 You should have received a copy of the GNU General Public License
15 along with this program.  If not, see <http://www.gnu.org/licenses/>.
16 */
17
18 package nncp
19
20 import (
21         "bytes"
22         "crypto/subtle"
23         "errors"
24         "io"
25         "net"
26         "os"
27         "path/filepath"
28         "sort"
29         "sync"
30         "time"
31
32         xdr "github.com/davecgh/go-xdr/xdr2"
33         "github.com/flynn/noise"
34 )
35
36 const (
37         MaxSPSize       = 1<<16 - 256
38         PartSuffix      = ".part"
39         DefaultDeadline = 10
40
41         SPHeadOverhead  = 4
42 )
43
44 var (
45         MagicNNCPLv1 [8]byte = [8]byte{'N', 'N', 'C', 'P', 'S', 0, 0, 1}
46
47         SPInfoOverhead    int
48         SPFreqOverhead    int
49         SPFileOverhead    int
50         SPHaltMarshalized []byte
51
52         NoiseCipherSuite noise.CipherSuite = noise.NewCipherSuite(
53                 noise.DH25519,
54                 noise.CipherChaChaPoly,
55                 noise.HashBLAKE2b,
56         )
57
58         spWorkersGroup sync.WaitGroup
59 )
60
61 type SPType uint8
62
63 const (
64         SPTypeInfo SPType = iota
65         SPTypeFreq SPType = iota
66         SPTypeFile SPType = iota
67         SPTypeDone SPType = iota
68         SPTypeHalt SPType = iota
69 )
70
71 type SPHead struct {
72         Type SPType
73 }
74
75 type SPInfo struct {
76         Nice uint8
77         Size uint64
78         Hash *[32]byte
79 }
80
81 type SPFreq struct {
82         Hash   *[32]byte
83         Offset uint64
84 }
85
86 type SPFile struct {
87         Hash    *[32]byte
88         Offset  uint64
89         Payload []byte
90 }
91
92 type SPDone struct {
93         Hash *[32]byte
94 }
95
96 type SPRaw struct {
97         Magic   [8]byte
98         Payload []byte
99 }
100
101 type FreqWithNice struct {
102         freq *SPFreq
103         nice uint8
104 }
105
106 type ConnDeadlined interface {
107         io.ReadWriteCloser
108         SetReadDeadline(t time.Time) error
109         SetWriteDeadline(t time.Time) error
110 }
111
112 func init() {
113         var buf bytes.Buffer
114         spHead := SPHead{Type: SPTypeHalt}
115         if _, err := xdr.Marshal(&buf, spHead); err != nil {
116                 panic(err)
117         }
118         SPHaltMarshalized = make([]byte, SPHeadOverhead)
119         copy(SPHaltMarshalized, buf.Bytes())
120         buf.Reset()
121
122         spInfo := SPInfo{Nice: 123, Size: 123, Hash: new([32]byte)}
123         if _, err := xdr.Marshal(&buf, spInfo); err != nil {
124                 panic(err)
125         }
126         SPInfoOverhead = buf.Len()
127         buf.Reset()
128
129         spFreq := SPFreq{Hash: new([32]byte), Offset: 123}
130         if _, err := xdr.Marshal(&buf, spFreq); err != nil {
131                 panic(err)
132         }
133         SPFreqOverhead = buf.Len()
134         buf.Reset()
135
136         spFile := SPFile{Hash: new([32]byte), Offset: 123}
137         if _, err := xdr.Marshal(&buf, spFile); err != nil {
138                 panic(err)
139         }
140         SPFileOverhead = buf.Len()
141 }
142
143 func MarshalSP(typ SPType, sp interface{}) []byte {
144         var buf bytes.Buffer
145         var err error
146         if _, err = xdr.Marshal(&buf, SPHead{typ}); err != nil {
147                 panic(err)
148         }
149         if _, err = xdr.Marshal(&buf, sp); err != nil {
150                 panic(err)
151         }
152         return buf.Bytes()
153 }
154
155 func payloadsSplit(payloads [][]byte) [][]byte {
156         var outbounds [][]byte
157         outbound := make([]byte, 0, MaxSPSize)
158         for i, payload := range payloads {
159                 outbound = append(outbound, payload...)
160                 if i+1 < len(payloads) && len(outbound)+len(payloads[i+1]) > MaxSPSize {
161                         outbounds = append(outbounds, outbound)
162                         outbound = make([]byte, 0, MaxSPSize)
163                 }
164         }
165         if len(outbound) > 0 {
166                 outbounds = append(outbounds, outbound)
167         }
168         return outbounds
169 }
170
171 type SPState struct {
172         Ctx            *Ctx
173         Node           *Node
174         Nice           uint8
175         onlineDeadline uint
176         maxOnlineTime  uint
177         hs             *noise.HandshakeState
178         csOur          *noise.CipherState
179         csTheir        *noise.CipherState
180         payloads       chan []byte
181         infosTheir     map[[32]byte]*SPInfo
182         infosOurSeen   map[[32]byte]uint8
183         queueTheir     []*FreqWithNice
184         wg             sync.WaitGroup
185         RxBytes        int64
186         RxLastSeen     time.Time
187         TxBytes        int64
188         TxLastSeen     time.Time
189         started        time.Time
190         Duration       time.Duration
191         RxSpeed        int64
192         TxSpeed        int64
193         rxLock         *os.File
194         txLock         *os.File
195         xxOnly         TRxTx
196         rxRate         int
197         txRate         int
198         isDead         bool
199         listOnly       bool
200         onlyPkts       map[[32]byte]bool
201         sync.RWMutex
202 }
203
204 func (state *SPState) NotAlive() bool {
205         if state.isDead {
206                 return true
207         }
208         now := time.Now()
209         if state.maxOnlineTime > 0 && state.started.Add(time.Duration(state.maxOnlineTime)*time.Second).Before(now) {
210                 return true
211         }
212         return uint(now.Sub(state.RxLastSeen).Seconds()) >= state.onlineDeadline &&
213                 uint(now.Sub(state.TxLastSeen).Seconds()) >= state.onlineDeadline
214 }
215
216 func (state *SPState) dirUnlock() {
217         state.Ctx.UnlockDir(state.rxLock)
218         state.Ctx.UnlockDir(state.txLock)
219 }
220
221 func (state *SPState) WriteSP(dst io.Writer, payload []byte) error {
222         n, err := xdr.Marshal(dst, SPRaw{Magic: MagicNNCPLv1, Payload: payload})
223         if err == nil {
224                 state.TxLastSeen = time.Now()
225                 state.TxBytes += int64(n)
226         }
227         return err
228 }
229
230 func (state *SPState) ReadSP(src io.Reader) ([]byte, error) {
231         var sp SPRaw
232         n, err := xdr.UnmarshalLimited(src, &sp, 1<<17)
233         if err != nil {
234                 ue := err.(*xdr.UnmarshalError)
235                 if ue.Err == io.EOF {
236                         return nil, ue.Err
237                 }
238                 return nil, err
239         }
240         state.RxLastSeen = time.Now()
241         state.RxBytes += int64(n)
242         if sp.Magic != MagicNNCPLv1 {
243                 return nil, BadMagic
244         }
245         return sp.Payload, nil
246 }
247
248 func (ctx *Ctx) infosOur(nodeId *NodeId, nice uint8, seen *map[[32]byte]uint8) [][]byte {
249         var infos []*SPInfo
250         var totalSize int64
251         for job := range ctx.Jobs(nodeId, TTx) {
252                 job.Fd.Close()
253                 if job.PktEnc.Nice > nice {
254                         continue
255                 }
256                 if _, known := (*seen)[*job.HshValue]; known {
257                         continue
258                 }
259                 totalSize += job.Size
260                 infos = append(infos, &SPInfo{
261                         Nice: job.PktEnc.Nice,
262                         Size: uint64(job.Size),
263                         Hash: job.HshValue,
264                 })
265                 (*seen)[*job.HshValue] = job.PktEnc.Nice
266         }
267         sort.Sort(ByNice(infos))
268         var payloads [][]byte
269         for _, info := range infos {
270                 payloads = append(payloads, MarshalSP(SPTypeInfo, info))
271                 ctx.LogD("sp-info-our", SDS{
272                         "node": nodeId,
273                         "name": ToBase32(info.Hash[:]),
274                         "size": info.Size,
275                 }, "")
276         }
277         if totalSize > 0 {
278                 ctx.LogI("sp-infos", SDS{
279                         "xx":   string(TTx),
280                         "node": nodeId,
281                         "pkts": len(payloads),
282                         "size": totalSize,
283                 }, "")
284         }
285         return payloadsSplit(payloads)
286 }
287
288 func (state *SPState) StartI(conn ConnDeadlined) error {
289         nodeId := state.Node.Id
290         err := state.Ctx.ensureRxDir(nodeId)
291         if err != nil {
292                 return err
293         }
294         var rxLock *os.File
295         if !state.listOnly && (state.xxOnly == "" || state.xxOnly == TRx) {
296                 rxLock, err = state.Ctx.LockDir(nodeId, TRx)
297                 if err != nil {
298                         return err
299                 }
300         }
301         var txLock *os.File
302         if !state.listOnly && (state.xxOnly == "" || state.xxOnly == TTx) {
303                 txLock, err = state.Ctx.LockDir(nodeId, TTx)
304                 if err != nil {
305                         return err
306                 }
307         }
308         started := time.Now()
309         conf := noise.Config{
310                 CipherSuite: NoiseCipherSuite,
311                 Pattern:     noise.HandshakeIK,
312                 Initiator:   true,
313                 StaticKeypair: noise.DHKey{
314                         Private: state.Ctx.Self.NoisePrv[:],
315                         Public:  state.Ctx.Self.NoisePub[:],
316                 },
317                 PeerStatic: state.Node.NoisePub[:],
318         }
319         hs, err := noise.NewHandshakeState(conf)
320         if err != nil {
321                 return err
322         }
323         state.hs = hs
324         state.payloads = make(chan []byte)
325         state.infosTheir = make(map[[32]byte]*SPInfo)
326         state.infosOurSeen = make(map[[32]byte]uint8)
327         state.started = started
328         state.rxLock = rxLock
329         state.txLock = txLock
330
331         var infosPayloads [][]byte
332         if !state.listOnly && (state.xxOnly == "" || state.xxOnly == TTx) {
333                 infosPayloads = state.Ctx.infosOur(nodeId, state.Nice, &state.infosOurSeen)
334         }
335         var firstPayload []byte
336         if len(infosPayloads) > 0 {
337                 firstPayload = infosPayloads[0]
338         }
339         // Pad first payload, to hide actual number of existing files
340         for i := 0; i < (MaxSPSize-len(firstPayload))/SPHeadOverhead; i++ {
341                 firstPayload = append(firstPayload, SPHaltMarshalized...)
342         }
343
344         var buf []byte
345         var payload []byte
346         buf, _, _, err = state.hs.WriteMessage(nil, firstPayload)
347         if err != nil {
348                 state.dirUnlock()
349                 return err
350         }
351         sds := SDS{"node": nodeId, "nice": int(state.Nice)}
352         state.Ctx.LogD("sp-start", sds, "sending first message")
353         conn.SetWriteDeadline(time.Now().Add(DefaultDeadline * time.Second))
354         if err = state.WriteSP(conn, buf); err != nil {
355                 state.Ctx.LogE("sp-start", sds, err, "")
356                 state.dirUnlock()
357                 return err
358         }
359         state.Ctx.LogD("sp-start", sds, "waiting for first message")
360         conn.SetReadDeadline(time.Now().Add(DefaultDeadline * time.Second))
361         if buf, err = state.ReadSP(conn); err != nil {
362                 state.Ctx.LogE("sp-start", sds, err, "")
363                 state.dirUnlock()
364                 return err
365         }
366         payload, state.csOur, state.csTheir, err = state.hs.ReadMessage(nil, buf)
367         if err != nil {
368                 state.Ctx.LogE("sp-start", sds, err, "")
369                 state.dirUnlock()
370                 return err
371         }
372         state.Ctx.LogD("sp-start", sds, "starting workers")
373         err = state.StartWorkers(conn, infosPayloads, payload)
374         if err != nil {
375                 state.Ctx.LogE("sp-start", sds, err, "")
376                 state.dirUnlock()
377         }
378         return err
379 }
380
381 func (state *SPState) StartR(conn ConnDeadlined) error {
382         started := time.Now()
383         conf := noise.Config{
384                 CipherSuite: NoiseCipherSuite,
385                 Pattern:     noise.HandshakeIK,
386                 Initiator:   false,
387                 StaticKeypair: noise.DHKey{
388                         Private: state.Ctx.Self.NoisePrv[:],
389                         Public:  state.Ctx.Self.NoisePub[:],
390                 },
391         }
392         hs, err := noise.NewHandshakeState(conf)
393         if err != nil {
394                 return err
395         }
396         xxOnly := TRxTx("")
397         state.hs = hs
398         state.payloads = make(chan []byte)
399         state.infosOurSeen = make(map[[32]byte]uint8)
400         state.infosTheir = make(map[[32]byte]*SPInfo)
401         state.started = started
402         state.xxOnly = xxOnly
403         var buf []byte
404         var payload []byte
405         state.Ctx.LogD("sp-start", SDS{"nice": int(state.Nice)}, "waiting for first message")
406         conn.SetReadDeadline(time.Now().Add(DefaultDeadline * time.Second))
407         if buf, err = state.ReadSP(conn); err != nil {
408                 state.Ctx.LogE("sp-start", SDS{}, err, "")
409                 return err
410         }
411         if payload, _, _, err = state.hs.ReadMessage(nil, buf); err != nil {
412                 state.Ctx.LogE("sp-start", SDS{}, err, "")
413                 return err
414         }
415
416         var node *Node
417         for _, n := range state.Ctx.Neigh {
418                 if subtle.ConstantTimeCompare(state.hs.PeerStatic(), n.NoisePub[:]) == 1 {
419                         node = n
420                         break
421                 }
422         }
423         if node == nil {
424                 peerId := ToBase32(state.hs.PeerStatic())
425                 state.Ctx.LogE("sp-start", SDS{"peer": peerId}, errors.New("unknown"), "")
426                 return errors.New("Unknown peer: " + peerId)
427         }
428         state.Node = node
429         state.rxRate = node.RxRate
430         state.txRate = node.TxRate
431         state.onlineDeadline = node.OnlineDeadline
432         state.maxOnlineTime = node.MaxOnlineTime
433         sds := SDS{"node": node.Id, "nice": int(state.Nice)}
434
435         if state.Ctx.ensureRxDir(node.Id); err != nil {
436                 return err
437         }
438         var rxLock *os.File
439         if xxOnly == "" || xxOnly == TRx {
440                 rxLock, err = state.Ctx.LockDir(node.Id, TRx)
441                 if err != nil {
442                         return err
443                 }
444         }
445         state.rxLock = rxLock
446         var txLock *os.File
447         if xxOnly == "" || xxOnly == TTx {
448                 txLock, err = state.Ctx.LockDir(node.Id, TTx)
449                 if err != nil {
450                         return err
451                 }
452         }
453         state.txLock = txLock
454
455         var infosPayloads [][]byte
456         if xxOnly == "" || xxOnly == TTx {
457                 infosPayloads = state.Ctx.infosOur(node.Id, state.Nice, &state.infosOurSeen)
458         }
459         var firstPayload []byte
460         if len(infosPayloads) > 0 {
461                 firstPayload = infosPayloads[0]
462         }
463         // Pad first payload, to hide actual number of existing files
464         for i := 0; i < (MaxSPSize-len(firstPayload))/SPHeadOverhead; i++ {
465                 firstPayload = append(firstPayload, SPHaltMarshalized...)
466         }
467
468         state.Ctx.LogD("sp-start", sds, "sending first message")
469         buf, state.csTheir, state.csOur, err = state.hs.WriteMessage(nil, firstPayload)
470         if err != nil {
471                 state.dirUnlock()
472                 return err
473         }
474         conn.SetWriteDeadline(time.Now().Add(DefaultDeadline * time.Second))
475         if err = state.WriteSP(conn, buf); err != nil {
476                 state.Ctx.LogE("sp-start", sds, err, "")
477                 state.dirUnlock()
478                 return err
479         }
480         state.Ctx.LogD("sp-start", sds, "starting workers")
481         err = state.StartWorkers(conn, infosPayloads, payload)
482         if err != nil {
483                 state.dirUnlock()
484         }
485         return err
486 }
487
488 func (state *SPState) StartWorkers(
489         conn ConnDeadlined,
490         infosPayloads [][]byte,
491         payload []byte) error {
492         sds := SDS{"node": state.Node.Id, "nice": int(state.Nice)}
493         if len(infosPayloads) > 1 {
494                 go func() {
495                         for _, payload := range infosPayloads[1:] {
496                                 state.Ctx.LogD(
497                                         "sp-work",
498                                         SdsAdd(sds, SDS{"size": len(payload)}),
499                                         "queuing remaining payload",
500                                 )
501                                 state.payloads <- payload
502                         }
503                 }()
504         }
505         state.Ctx.LogD(
506                 "sp-work",
507                 SdsAdd(sds, SDS{"size": len(payload)}),
508                 "processing first payload",
509         )
510         replies, err := state.ProcessSP(payload)
511         if err != nil {
512                 state.Ctx.LogE("sp-work", sds, err, "")
513                 return err
514         }
515
516         go func() {
517                 for _, reply := range replies {
518                         state.Ctx.LogD(
519                                 "sp-work",
520                                 SdsAdd(sds, SDS{"size": len(reply)}),
521                                 "queuing reply",
522                         )
523                         state.payloads <- reply
524                 }
525         }()
526
527         if !state.listOnly && (state.xxOnly == "" || state.xxOnly == TTx) {
528                 go func() {
529                         for range time.Tick(time.Second) {
530                                 if state.NotAlive() {
531                                         return
532                                 }
533                                 for _, payload := range state.Ctx.infosOur(
534                                         state.Node.Id,
535                                         state.Nice,
536                                         &state.infosOurSeen,
537                                 ) {
538                                         state.Ctx.LogD(
539                                                 "sp-work",
540                                                 SdsAdd(sds, SDS{"size": len(payload)}),
541                                                 "queuing new info",
542                                         )
543                                         state.payloads <- payload
544                                 }
545                         }
546                 }()
547         }
548
549         state.wg.Add(1)
550         go func() {
551                 defer func() {
552                         state.isDead = true
553                         state.wg.Done()
554                 }()
555                 for {
556                         if state.NotAlive() {
557                                 return
558                         }
559                         var payload []byte
560                         select {
561                         case payload = <-state.payloads:
562                                 state.Ctx.LogD(
563                                         "sp-xmit",
564                                         SdsAdd(sds, SDS{"size": len(payload)}),
565                                         "got payload",
566                                 )
567                         default:
568                         }
569                         if payload == nil {
570                                 state.RLock()
571                                 if len(state.queueTheir) == 0 {
572                                         state.Ctx.LogD("sp-xmit", sds, "file queue is empty")
573                                         state.RUnlock()
574                                         time.Sleep(100 * time.Millisecond)
575                                         continue
576                                 }
577                                 freq := state.queueTheir[0].freq
578                                 state.RUnlock()
579
580                                 if state.txRate > 0 {
581                                         time.Sleep(time.Second / time.Duration(state.txRate))
582                                 }
583
584                                 sdsp := SdsAdd(sds, SDS{
585                                         "xx":   string(TTx),
586                                         "pkt":  ToBase32(freq.Hash[:]),
587                                         "size": int64(freq.Offset),
588                                 })
589                                 state.Ctx.LogD("sp-file", sdsp, "queueing")
590                                 fd, err := os.Open(filepath.Join(
591                                         state.Ctx.Spool,
592                                         state.Node.Id.String(),
593                                         string(TTx),
594                                         ToBase32(freq.Hash[:]),
595                                 ))
596                                 if err != nil {
597                                         state.Ctx.LogE("sp-file", sdsp, err, "")
598                                         break
599                                 }
600                                 fi, err := fd.Stat()
601                                 if err != nil {
602                                         state.Ctx.LogE("sp-file", sdsp, err, "")
603                                         break
604                                 }
605                                 fullSize := fi.Size()
606                                 var buf []byte
607                                 if freq.Offset < uint64(fullSize) {
608                                         state.Ctx.LogD("sp-file", sdsp, "seeking")
609                                         if _, err = fd.Seek(int64(freq.Offset), io.SeekStart); err != nil {
610                                                 state.Ctx.LogE("sp-file", sdsp, err, "")
611                                                 break
612                                         }
613                                         buf = make([]byte, MaxSPSize-SPHeadOverhead-SPFileOverhead)
614                                         n, err := fd.Read(buf)
615                                         if err != nil {
616                                                 state.Ctx.LogE("sp-file", sdsp, err, "")
617                                                 break
618                                         }
619                                         buf = buf[:n]
620                                         state.Ctx.LogD(
621                                                 "sp-file",
622                                                 SdsAdd(sdsp, SDS{"size": n}),
623                                                 "read",
624                                         )
625                                 }
626                                 fd.Close()
627                                 payload = MarshalSP(SPTypeFile, SPFile{
628                                         Hash:    freq.Hash,
629                                         Offset:  freq.Offset,
630                                         Payload: buf,
631                                 })
632                                 ourSize := freq.Offset + uint64(len(buf))
633                                 sdsp["size"] = int64(ourSize)
634                                 sdsp["fullsize"] = fullSize
635                                 if state.Ctx.ShowPrgrs {
636                                         Progress("Tx", sdsp)
637                                 }
638                                 state.Lock()
639                                 if len(state.queueTheir) > 0 && *state.queueTheir[0].freq.Hash == *freq.Hash {
640                                         if ourSize == uint64(fullSize) {
641                                                 state.Ctx.LogD("sp-file", sdsp, "finished")
642                                                 if len(state.queueTheir) > 1 {
643                                                         state.queueTheir = state.queueTheir[1:]
644                                                 } else {
645                                                         state.queueTheir = state.queueTheir[:0]
646                                                 }
647                                         } else {
648                                                 state.queueTheir[0].freq.Offset += uint64(len(buf))
649                                         }
650                                 } else {
651                                         state.Ctx.LogD("sp-file", sdsp, "queue disappeared")
652                                 }
653                                 state.Unlock()
654                         }
655                         state.Ctx.LogD(
656                                 "sp-xmit",
657                                 SdsAdd(sds, SDS{"size": len(payload)}),
658                                 "sending",
659                         )
660                         conn.SetWriteDeadline(time.Now().Add(DefaultDeadline * time.Second))
661                         if err := state.WriteSP(conn, state.csOur.Encrypt(nil, nil, payload)); err != nil {
662                                 state.Ctx.LogE("sp-xmit", sds, err, "")
663                                 break
664                         }
665                 }
666         }()
667
668         state.wg.Add(1)
669         go func() {
670                 defer func() {
671                         state.isDead = true
672                         state.wg.Done()
673                 }()
674                 for {
675                         if state.NotAlive() {
676                                 return
677                         }
678                         state.Ctx.LogD("sp-recv", sds, "waiting for payload")
679                         conn.SetReadDeadline(time.Now().Add(DefaultDeadline * time.Second))
680                         payload, err := state.ReadSP(conn)
681                         if err != nil {
682                                 if err == io.EOF {
683                                         break
684                                 }
685                                 unmarshalErr := err.(*xdr.UnmarshalError)
686                                 netErr, ok := unmarshalErr.Err.(net.Error)
687                                 if ok && netErr.Timeout() {
688                                         continue
689                                 }
690                                 if unmarshalErr.ErrorCode == xdr.ErrIO {
691                                         break
692                                 }
693                                 state.Ctx.LogE("sp-recv", sds, err, "")
694                                 break
695                         }
696                         state.Ctx.LogD(
697                                 "sp-recv",
698                                 SdsAdd(sds, SDS{"size": len(payload)}),
699                                 "got payload",
700                         )
701                         payload, err = state.csTheir.Decrypt(nil, nil, payload)
702                         if err != nil {
703                                 state.Ctx.LogE("sp-recv", sds, err, "")
704                                 break
705                         }
706                         state.Ctx.LogD(
707                                 "sp-recv",
708                                 SdsAdd(sds, SDS{"size": len(payload)}),
709                                 "processing",
710                         )
711                         replies, err := state.ProcessSP(payload)
712                         if err != nil {
713                                 state.Ctx.LogE("sp-recv", sds, err, "")
714                                 break
715                         }
716                         go func() {
717                                 for _, reply := range replies {
718                                         state.Ctx.LogD(
719                                                 "sp-recv",
720                                                 SdsAdd(sds, SDS{"size": len(reply)}),
721                                                 "queuing reply",
722                                         )
723                                         state.payloads <- reply
724                                 }
725                         }()
726                         if state.rxRate > 0 {
727                                 time.Sleep(time.Second / time.Duration(state.rxRate))
728                         }
729                 }
730         }()
731
732         return nil
733 }
734
735 func (state *SPState) Wait() {
736         state.wg.Wait()
737         state.dirUnlock()
738         state.Duration = time.Now().Sub(state.started)
739         state.RxSpeed = state.RxBytes
740         state.TxSpeed = state.TxBytes
741         rxDuration := int64(state.RxLastSeen.Sub(state.started).Seconds())
742         txDuration := int64(state.TxLastSeen.Sub(state.started).Seconds())
743         if rxDuration > 0 {
744                 state.RxSpeed = state.RxBytes / rxDuration
745         }
746         if txDuration > 0 {
747                 state.TxSpeed = state.TxBytes / txDuration
748         }
749 }
750
751 func (state *SPState) ProcessSP(payload []byte) ([][]byte, error) {
752         sds := SDS{"node": state.Node.Id, "nice": int(state.Nice)}
753         r := bytes.NewReader(payload)
754         var err error
755         var replies [][]byte
756         var infosGot bool
757         for r.Len() > 0 {
758                 state.Ctx.LogD("sp-process", sds, "unmarshaling header")
759                 var head SPHead
760                 if _, err = xdr.Unmarshal(r, &head); err != nil {
761                         state.Ctx.LogE("sp-process", sds, err, "")
762                         return nil, err
763                 }
764                 switch head.Type {
765                 case SPTypeInfo:
766                         infosGot = true
767                         sdsp := SdsAdd(sds, SDS{"type": "info"})
768                         state.Ctx.LogD("sp-process", sdsp, "unmarshaling packet")
769                         var info SPInfo
770                         if _, err = xdr.Unmarshal(r, &info); err != nil {
771                                 state.Ctx.LogE("sp-process", sdsp, err, "")
772                                 return nil, err
773                         }
774                         sdsp = SdsAdd(sds, SDS{
775                                 "pkt":  ToBase32(info.Hash[:]),
776                                 "size": int64(info.Size),
777                                 "nice": int(info.Nice),
778                         })
779                         if !state.listOnly && info.Nice > state.Nice {
780                                 state.Ctx.LogD("sp-process", sdsp, "too nice")
781                                 continue
782                         }
783                         state.Ctx.LogD("sp-process", sdsp, "received")
784                         if !state.listOnly && state.xxOnly == TTx {
785                                 continue
786                         }
787                         state.Lock()
788                         state.infosTheir[*info.Hash] = &info
789                         state.Unlock()
790                         state.Ctx.LogD("sp-process", sdsp, "stating part")
791                         pktPath := filepath.Join(
792                                 state.Ctx.Spool,
793                                 state.Node.Id.String(),
794                                 string(TRx),
795                                 ToBase32(info.Hash[:]),
796                         )
797                         if _, err = os.Stat(pktPath); err == nil {
798                                 state.Ctx.LogI("sp-info", sdsp, "already done")
799                                 if !state.listOnly {
800                                         replies = append(replies, MarshalSP(SPTypeDone, SPDone{info.Hash}))
801                                 }
802                                 continue
803                         }
804                         if _, err = os.Stat(pktPath + SeenSuffix); err == nil {
805                                 state.Ctx.LogI("sp-info", sdsp, "already seen")
806                                 if !state.listOnly {
807                                         replies = append(replies, MarshalSP(SPTypeDone, SPDone{info.Hash}))
808                                 }
809                                 continue
810                         }
811                         fi, err := os.Stat(pktPath + PartSuffix)
812                         var offset int64
813                         if err == nil {
814                                 offset = fi.Size()
815                         }
816                         if !state.Ctx.IsEnoughSpace(int64(info.Size) - offset) {
817                                 state.Ctx.LogI("sp-info", sdsp, "not enough space")
818                                 continue
819                         }
820                         state.Ctx.LogI(
821                                 "sp-info",
822                                 SdsAdd(sdsp, SDS{"offset": offset}),
823                                 "",
824                         )
825                         if !state.listOnly && (state.onlyPkts == nil || state.onlyPkts[*info.Hash]) {
826                                 replies = append(replies, MarshalSP(
827                                         SPTypeFreq,
828                                         SPFreq{info.Hash, uint64(offset)},
829                                 ))
830                         }
831                 case SPTypeFile:
832                         sdsp := SdsAdd(sds, SDS{"type": "file"})
833                         state.Ctx.LogD("sp-process", sdsp, "unmarshaling packet")
834                         var file SPFile
835                         if _, err = xdr.Unmarshal(r, &file); err != nil {
836                                 state.Ctx.LogE("sp-process", SdsAdd(sds, SDS{"type": "file"}), err, "")
837                                 return nil, err
838                         }
839                         sdsp["xx"] = string(TRx)
840                         sdsp["pkt"] = ToBase32(file.Hash[:])
841                         sdsp["size"] = len(file.Payload)
842                         dirToSync := filepath.Join(
843                                 state.Ctx.Spool,
844                                 state.Node.Id.String(),
845                                 string(TRx),
846                         )
847                         filePath := filepath.Join(dirToSync, ToBase32(file.Hash[:]))
848                         state.Ctx.LogD("sp-file", sdsp, "opening part")
849                         fd, err := os.OpenFile(
850                                 filePath+PartSuffix,
851                                 os.O_RDWR|os.O_CREATE,
852                                 os.FileMode(0666),
853                         )
854                         if err != nil {
855                                 state.Ctx.LogE("sp-file", sdsp, err, "")
856                                 return nil, err
857                         }
858                         state.Ctx.LogD(
859                                 "sp-file",
860                                 SdsAdd(sdsp, SDS{"offset": file.Offset}),
861                                 "seeking",
862                         )
863                         if _, err = fd.Seek(int64(file.Offset), io.SeekStart); err != nil {
864                                 state.Ctx.LogE("sp-file", sdsp, err, "")
865                                 fd.Close()
866                                 return nil, err
867                         }
868                         state.Ctx.LogD("sp-file", sdsp, "writing")
869                         _, err = fd.Write(file.Payload)
870                         if err != nil {
871                                 state.Ctx.LogE("sp-file", sdsp, err, "")
872                                 fd.Close()
873                                 return nil, err
874                         }
875                         ourSize := file.Offset + uint64(len(file.Payload))
876                         state.RLock()
877                         sdsp["size"] = int64(ourSize)
878                         sdsp["fullsize"] = int64(state.infosTheir[*file.Hash].Size)
879                         if state.Ctx.ShowPrgrs {
880                                 Progress("Rx", sdsp)
881                         }
882                         if state.infosTheir[*file.Hash].Size != ourSize {
883                                 state.RUnlock()
884                                 fd.Close()
885                                 continue
886                         }
887                         state.RUnlock()
888                         spWorkersGroup.Wait()
889                         spWorkersGroup.Add(1)
890                         go func() {
891                                 if err := fd.Sync(); err != nil {
892                                         state.Ctx.LogE("sp-file", sdsp, err, "sync")
893                                         fd.Close()
894                                         return
895                                 }
896                                 state.wg.Add(1)
897                                 defer state.wg.Done()
898                                 fd.Seek(0, io.SeekStart)
899                                 state.Ctx.LogD("sp-file", sdsp, "checking")
900                                 gut, err := Check(fd, file.Hash[:], sdsp, state.Ctx.ShowPrgrs)
901                                 fd.Close()
902                                 if err != nil || !gut {
903                                         state.Ctx.LogE("sp-file", sdsp, errors.New("checksum mismatch"), "")
904                                         return
905                                 }
906                                 state.Ctx.LogI("sp-done", SdsAdd(sdsp, SDS{"xx": string(TRx)}), "")
907                                 if err = os.Rename(filePath+PartSuffix, filePath); err != nil {
908                                         state.Ctx.LogE("sp-file", sdsp, err, "rename")
909                                         return
910                                 }
911                                 if err = DirSync(dirToSync); err != nil {
912                                         state.Ctx.LogE("sp-file", sdsp, err, "sync")
913                                         return
914                                 }
915                                 state.Lock()
916                                 delete(state.infosTheir, *file.Hash)
917                                 state.Unlock()
918                                 spWorkersGroup.Done()
919                                 go func() {
920                                         state.payloads <- MarshalSP(SPTypeDone, SPDone{file.Hash})
921                                 }()
922                         }()
923                 case SPTypeDone:
924                         sdsp := SdsAdd(sds, SDS{"type": "done"})
925                         state.Ctx.LogD("sp-process", sdsp, "unmarshaling packet")
926                         var done SPDone
927                         if _, err = xdr.Unmarshal(r, &done); err != nil {
928                                 state.Ctx.LogE("sp-process", SdsAdd(sds, SDS{"type": "done"}), err, "")
929                                 return nil, err
930                         }
931                         sdsp["pkt"] = ToBase32(done.Hash[:])
932                         state.Ctx.LogD("sp-done", sdsp, "removing")
933                         err := os.Remove(filepath.Join(
934                                 state.Ctx.Spool,
935                                 state.Node.Id.String(),
936                                 string(TTx),
937                                 ToBase32(done.Hash[:]),
938                         ))
939                         sdsp["xx"] = string(TTx)
940                         if err == nil {
941                                 state.Ctx.LogI("sp-done", sdsp, "")
942                         } else {
943                                 state.Ctx.LogE("sp-done", sdsp, err, "")
944                         }
945                 case SPTypeFreq:
946                         sdsp := SdsAdd(sds, SDS{"type": "freq"})
947                         state.Ctx.LogD("sp-process", sdsp, "unmarshaling packet")
948                         var freq SPFreq
949                         if _, err = xdr.Unmarshal(r, &freq); err != nil {
950                                 state.Ctx.LogE("sp-process", sdsp, err, "")
951                                 return nil, err
952                         }
953                         sdsp["pkt"] = ToBase32(freq.Hash[:])
954                         sdsp["offset"] = freq.Offset
955                         state.Ctx.LogD("sp-process", sdsp, "queueing")
956                         nice, exists := state.infosOurSeen[*freq.Hash]
957                         if exists {
958                                 if state.onlyPkts == nil || !state.onlyPkts[*freq.Hash] {
959                                         state.Lock()
960                                         insertIdx := 0
961                                         var freqWithNice *FreqWithNice
962                                         for insertIdx, freqWithNice = range state.queueTheir {
963                                                 if freqWithNice.nice > nice {
964                                                         break
965                                                 }
966                                         }
967                                         state.queueTheir = append(state.queueTheir, nil)
968                                         copy(state.queueTheir[insertIdx+1:], state.queueTheir[insertIdx:])
969                                         state.queueTheir[insertIdx] = &FreqWithNice{&freq, nice}
970                                         state.Unlock()
971                                 } else {
972                                         state.Ctx.LogD("sp-process", sdsp, "skipping")
973                                 }
974                         } else {
975                                 state.Ctx.LogD("sp-process", sdsp, "unknown")
976                         }
977                 case SPTypeHalt:
978                         state.Ctx.LogD("sp-process", SdsAdd(sds, SDS{"type": "halt"}), "")
979                         state.Lock()
980                         state.queueTheir = nil
981                         state.Unlock()
982                 default:
983                         state.Ctx.LogE(
984                                 "sp-process",
985                                 SdsAdd(sds, SDS{"type": head.Type}),
986                                 errors.New("unknown type"),
987                                 "",
988                         )
989                         return nil, BadPktType
990                 }
991         }
992         if infosGot {
993                 var pkts int
994                 var size uint64
995                 state.RLock()
996                 for _, info := range state.infosTheir {
997                         pkts++
998                         size += info.Size
999                 }
1000                 state.RUnlock()
1001                 state.Ctx.LogI("sp-infos", SDS{
1002                         "xx":   string(TRx),
1003                         "node": state.Node.Id,
1004                         "pkts": pkts,
1005                         "size": int64(size),
1006                 }, "")
1007         }
1008         return payloadsSplit(replies), nil
1009 }