]> Cypherpunks.ru repositories - nncp.git/blob - src/cmd/nncp-bundle/main.go
Logging refactoring, no centralized humanizer
[nncp.git] / src / cmd / nncp-bundle / main.go
1 /*
2 NNCP -- Node to Node copy, utilities for store-and-forward data exchange
3 Copyright (C) 2016-2021 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 // Create/digest stream of NNCP encrypted packets.
19 package main
20
21 import (
22         "archive/tar"
23         "bufio"
24         "bytes"
25         "errors"
26         "flag"
27         "fmt"
28         "io"
29         "io/ioutil"
30         "log"
31         "os"
32         "path/filepath"
33         "strings"
34
35         xdr "github.com/davecgh/go-xdr/xdr2"
36         "github.com/dustin/go-humanize"
37         "go.cypherpunks.ru/nncp/v6"
38         "golang.org/x/crypto/blake2b"
39 )
40
41 const (
42         CopyBufSize = 1 << 17
43 )
44
45 func usage() {
46         fmt.Fprintf(os.Stderr, nncp.UsageHeader())
47         fmt.Fprintf(os.Stderr, "nncp-bundle -- Create/digest stream of NNCP encrypted packets\n\n")
48         fmt.Fprintf(os.Stderr, "Usage: %s [options] -tx [-delete] NODE [NODE ...] > ...\n", os.Args[0])
49         fmt.Fprintf(os.Stderr, "       %s [options] -rx -delete [-dryrun] [NODE ...] < ...\n", os.Args[0])
50         fmt.Fprintf(os.Stderr, "       %s [options] -rx [-check] [-dryrun] [NODE ...] < ...\n", os.Args[0])
51         fmt.Fprintln(os.Stderr, "Options:")
52         flag.PrintDefaults()
53 }
54
55 func main() {
56         var (
57                 cfgPath   = flag.String("cfg", nncp.DefaultCfgPath, "Path to configuration file")
58                 niceRaw   = flag.String("nice", nncp.NicenessFmt(255), "Minimal required niceness")
59                 doRx      = flag.Bool("rx", false, "Receive packets")
60                 doTx      = flag.Bool("tx", false, "Transfer packets")
61                 doDelete  = flag.Bool("delete", false, "Delete transferred packets")
62                 doCheck   = flag.Bool("check", false, "Check integrity while receiving")
63                 dryRun    = flag.Bool("dryrun", false, "Do no writes")
64                 spoolPath = flag.String("spool", "", "Override path to spool")
65                 logPath   = flag.String("log", "", "Override path to logfile")
66                 quiet     = flag.Bool("quiet", false, "Print only errors")
67                 showPrgrs = flag.Bool("progress", false, "Force progress showing")
68                 omitPrgrs = flag.Bool("noprogress", false, "Omit progress showing")
69                 debug     = flag.Bool("debug", false, "Print debug messages")
70                 version   = flag.Bool("version", false, "Print version information")
71                 warranty  = flag.Bool("warranty", false, "Print warranty information")
72         )
73         flag.Usage = usage
74         flag.Parse()
75         if *warranty {
76                 fmt.Println(nncp.Warranty)
77                 return
78         }
79         if *version {
80                 fmt.Println(nncp.VersionGet())
81                 return
82         }
83         nice, err := nncp.NicenessParse(*niceRaw)
84         if err != nil {
85                 log.Fatalln(err)
86         }
87         if *doRx && *doTx {
88                 log.Fatalln("-rx and -tx can not be set simultaneously")
89         }
90         if !*doRx && !*doTx {
91                 log.Fatalln("At least one of -rx and -tx must be specified")
92         }
93
94         ctx, err := nncp.CtxFromCmdline(
95                 *cfgPath,
96                 *spoolPath,
97                 *logPath,
98                 *quiet,
99                 *showPrgrs,
100                 *omitPrgrs,
101                 *debug,
102         )
103         if err != nil {
104                 log.Fatalln("Error during initialization:", err)
105         }
106
107         nodeIds := make(map[nncp.NodeId]struct{}, flag.NArg())
108         for i := 0; i < flag.NArg(); i++ {
109                 node, err := ctx.FindNode(flag.Arg(i))
110                 if err != nil {
111                         log.Fatalln("Invalid specified:", err)
112                 }
113                 nodeIds[*node.Id] = struct{}{}
114         }
115
116         ctx.Umask()
117
118         if *doTx {
119                 var pktName string
120                 bufStdout := bufio.NewWriter(os.Stdout)
121                 tarWr := tar.NewWriter(bufStdout)
122                 for nodeId := range nodeIds {
123                         for job := range ctx.Jobs(&nodeId, nncp.TTx) {
124                                 pktName = filepath.Base(job.Path)
125                                 les := nncp.LEs{
126                                         {K: "XX", V: string(nncp.TTx)},
127                                         {K: "Node", V: nodeId.String()},
128                                         {K: "Pkt", V: pktName},
129                                 }
130                                 if job.PktEnc.Nice > nice {
131                                         ctx.LogD("bundle-tx-too-nice", les, func(les nncp.LEs) string {
132                                                 return fmt.Sprintf(
133                                                         "Bundle transfer %s/tx/%s: too nice %s",
134                                                         ctx.NodeName(&nodeId),
135                                                         pktName,
136                                                         nncp.NicenessFmt(job.PktEnc.Nice),
137                                                 )
138                                         })
139                                         continue
140                                 }
141                                 fd, err := os.Open(job.Path)
142                                 if err != nil {
143                                         log.Fatalln("Error during opening:", err)
144                                 }
145                                 if err = tarWr.WriteHeader(&tar.Header{
146                                         Format:   tar.FormatUSTAR,
147                                         Name:     nncp.NNCPBundlePrefix,
148                                         Mode:     0700,
149                                         Typeflag: tar.TypeDir,
150                                 }); err != nil {
151                                         log.Fatalln("Error writing tar header:", err)
152                                 }
153                                 if err = tarWr.WriteHeader(&tar.Header{
154                                         Format: tar.FormatPAX,
155                                         Name: strings.Join([]string{
156                                                 nncp.NNCPBundlePrefix,
157                                                 nodeId.String(),
158                                                 ctx.SelfId.String(),
159                                                 pktName,
160                                         }, "/"),
161                                         Mode:     0400,
162                                         Size:     job.Size,
163                                         Typeflag: tar.TypeReg,
164                                 }); err != nil {
165                                         log.Fatalln("Error writing tar header:", err)
166                                 }
167                                 if _, err = nncp.CopyProgressed(
168                                         tarWr, bufio.NewReader(fd), "Tx",
169                                         append(les, nncp.LEs{
170                                                 {K: "Pkt", V: nncp.Base32Codec.EncodeToString(job.HshValue[:])},
171                                                 {K: "FullSize", V: job.Size},
172                                         }...),
173                                         ctx.ShowPrgrs,
174                                 ); err != nil {
175                                         log.Fatalln("Error during copying to tar:", err)
176                                 }
177                                 if err = fd.Close(); err != nil {
178                                         log.Fatalln("Error during closing:", err)
179                                 }
180                                 if err = tarWr.Flush(); err != nil {
181                                         log.Fatalln("Error during tar flushing:", err)
182                                 }
183                                 if err = bufStdout.Flush(); err != nil {
184                                         log.Fatalln("Error during stdout flushing:", err)
185                                 }
186                                 if *doDelete {
187                                         if err = os.Remove(job.Path); err != nil {
188                                                 log.Fatalln("Error during deletion:", err)
189                                         } else if ctx.HdrUsage {
190                                                 os.Remove(job.Path + nncp.HdrSuffix)
191                                         }
192                                 }
193                                 ctx.LogI(
194                                         "bundle-tx",
195                                         append(les, nncp.LE{K: "Size", V: job.Size}),
196                                         func(les nncp.LEs) string {
197                                                 return fmt.Sprintf(
198                                                         "Bundle transfer, sent to node %s %s (%s)",
199                                                         ctx.NodeName(&nodeId),
200                                                         pktName,
201                                                         humanize.IBytes(uint64(job.Size)),
202                                                 )
203                                         },
204                                 )
205                         }
206                 }
207                 if err = tarWr.Close(); err != nil {
208                         log.Fatalln("Error during tar closing:", err)
209                 }
210         } else {
211                 bufStdin := bufio.NewReaderSize(os.Stdin, CopyBufSize*2)
212                 pktEncBuf := make([]byte, nncp.PktEncOverhead)
213                 var pktEnc *nncp.PktEnc
214                 for {
215                         peeked, err := bufStdin.Peek(CopyBufSize)
216                         if err != nil && err != io.EOF {
217                                 log.Fatalln("Error during reading:", err)
218                         }
219                         prefixIdx := bytes.Index(peeked, []byte(nncp.NNCPBundlePrefix))
220                         if prefixIdx == -1 {
221                                 if err == io.EOF {
222                                         break
223                                 }
224                                 bufStdin.Discard(bufStdin.Buffered() - (len(nncp.NNCPBundlePrefix) - 1)) // #nosec G104
225                                 continue
226                         }
227                         if _, err = bufStdin.Discard(prefixIdx); err != nil {
228                                 panic(err)
229                         }
230                         tarR := tar.NewReader(bufStdin)
231                         entry, err := tarR.Next()
232                         if err != nil {
233                                 if err != io.EOF {
234                                         ctx.LogD(
235                                                 "bundle-rx-read-tar",
236                                                 nncp.LEs{{K: "XX", V: string(nncp.TRx)}, {K: "Err", V: err}},
237                                                 func(les nncp.LEs) string {
238                                                         return "Bundle transfer rx: reading tar"
239                                                 },
240                                         )
241                                 }
242                                 continue
243                         }
244                         if entry.Typeflag != tar.TypeDir {
245                                 ctx.LogD(
246                                         "bundle-rx-read-tar",
247                                         nncp.LEs{
248                                                 {K: "XX", V: string(nncp.TRx)},
249                                                 {K: "Err", V: errors.New("expected NNCP/")},
250                                         },
251                                         func(les nncp.LEs) string {
252                                                 return "Bundle transfer rx: reading tar"
253                                         },
254                                 )
255                                 continue
256                         }
257                         entry, err = tarR.Next()
258                         if err != nil {
259                                 if err != io.EOF {
260                                         ctx.LogD(
261                                                 "bundle-rx-read-tar",
262                                                 nncp.LEs{{K: "XX", V: string(nncp.TRx)}, {K: "Err", V: err}},
263                                                 func(les nncp.LEs) string {
264                                                         return "Bundle transfer rx: reading tar"
265                                                 },
266                                         )
267                                 }
268                                 continue
269                         }
270                         les := nncp.LEs{{K: "XX", V: string(nncp.TRx)}, {K: "Pkt", V: entry.Name}}
271                         logMsg := func(les nncp.LEs) string {
272                                 return "Bundle transfer rx/" + entry.Name
273                         }
274                         if entry.Size < nncp.PktEncOverhead {
275                                 ctx.LogD("bundle-rx-too-small", les, func(les nncp.LEs) string {
276                                         return logMsg(les) + ": too small packet"
277                                 })
278                                 continue
279                         }
280                         if !ctx.IsEnoughSpace(entry.Size) {
281                                 ctx.LogE("bundle-rx", les, errors.New("not enough spool space"), logMsg)
282                                 continue
283                         }
284                         pktName := filepath.Base(entry.Name)
285                         if _, err = nncp.Base32Codec.DecodeString(pktName); err != nil {
286                                 ctx.LogD(
287                                         "bundle-rx",
288                                         append(les, nncp.LE{K: "Err", V: "bad packet name"}),
289                                         logMsg,
290                                 )
291                                 continue
292                         }
293                         if _, err = io.ReadFull(tarR, pktEncBuf); err != nil {
294                                 ctx.LogD(
295                                         "bundle-rx",
296                                         append(les, nncp.LE{K: "Err", V: err}),
297                                         logMsg,
298                                 )
299                                 continue
300                         }
301                         if _, err = xdr.Unmarshal(bytes.NewReader(pktEncBuf), &pktEnc); err != nil {
302                                 ctx.LogD(
303                                         "bundle-rx",
304                                         append(les, nncp.LE{K: "Err", V: "Bad packet structure"}),
305                                         logMsg,
306                                 )
307                                 continue
308                         }
309                         if pktEnc.Magic != nncp.MagicNNCPEv4 {
310                                 ctx.LogD(
311                                         "bundle-rx",
312                                         append(les, nncp.LE{K: "Err", V: "Bad packet magic number"}),
313                                         logMsg,
314                                 )
315                                 continue
316                         }
317                         if pktEnc.Nice > nice {
318                                 ctx.LogD("bundle-rx-too-nice", les, func(les nncp.LEs) string {
319                                         return logMsg(les) + ": too nice"
320                                 })
321                                 continue
322                         }
323                         if *pktEnc.Sender == *ctx.SelfId && *doDelete {
324                                 if len(nodeIds) > 0 {
325                                         if _, exists := nodeIds[*pktEnc.Recipient]; !exists {
326                                                 ctx.LogD("bundle-tx-skip", les, func(les nncp.LEs) string {
327                                                         return logMsg(les) + ": recipient is not requested"
328                                                 })
329                                                 continue
330                                         }
331                                 }
332                                 nodeId32 := nncp.Base32Codec.EncodeToString(pktEnc.Recipient[:])
333                                 les := nncp.LEs{
334                                         {K: "XX", V: string(nncp.TTx)},
335                                         {K: "Node", V: nodeId32},
336                                         {K: "Pkt", V: pktName},
337                                 }
338                                 logMsg = func(les nncp.LEs) string {
339                                         return fmt.Sprintf("Bundle transfer %s/tx/%s", nodeId32, pktName)
340                                 }
341                                 dstPath := filepath.Join(ctx.Spool, nodeId32, string(nncp.TTx), pktName)
342                                 if _, err = os.Stat(dstPath); err != nil {
343                                         ctx.LogD("bundle-tx-missing", les, func(les nncp.LEs) string {
344                                                 return logMsg(les) + ": packet is already missing"
345                                         })
346                                         continue
347                                 }
348                                 hsh, err := blake2b.New256(nil)
349                                 if err != nil {
350                                         log.Fatalln("Error during hasher creation:", err)
351                                 }
352                                 if _, err = hsh.Write(pktEncBuf); err != nil {
353                                         log.Fatalln("Error during writing:", err)
354                                 }
355                                 if _, err = nncp.CopyProgressed(
356                                         hsh, tarR, "Rx",
357                                         append(les, nncp.LE{K: "FullSize", V: entry.Size}),
358                                         ctx.ShowPrgrs,
359                                 ); err != nil {
360                                         log.Fatalln("Error during copying:", err)
361                                 }
362                                 if nncp.Base32Codec.EncodeToString(hsh.Sum(nil)) == pktName {
363                                         ctx.LogI("bundle-tx-removed", les, func(les nncp.LEs) string {
364                                                 return logMsg(les) + ": removed"
365                                         })
366                                         if !*dryRun {
367                                                 os.Remove(dstPath)
368                                                 if ctx.HdrUsage {
369                                                         os.Remove(dstPath + nncp.HdrSuffix)
370                                                 }
371                                         }
372                                 } else {
373                                         ctx.LogE("bundle-tx", les, errors.New("bad checksum"), logMsg)
374                                 }
375                                 continue
376                         }
377                         if *pktEnc.Recipient != *ctx.SelfId {
378                                 ctx.LogD("nncp-bundle", les, func(les nncp.LEs) string {
379                                         return logMsg(les) + ": unknown recipient"
380                                 })
381                                 continue
382                         }
383                         if len(nodeIds) > 0 {
384                                 if _, exists := nodeIds[*pktEnc.Sender]; !exists {
385                                         ctx.LogD("bundle-rx-skip", les, func(les nncp.LEs) string {
386                                                 return logMsg(les) + ": sender is not requested"
387                                         })
388                                         continue
389                                 }
390                         }
391                         sender := nncp.Base32Codec.EncodeToString(pktEnc.Sender[:])
392                         les = nncp.LEs{
393                                 {K: "XX", V: string(nncp.TRx)},
394                                 {K: "Node", V: sender},
395                                 {K: "Pkt", V: pktName},
396                                 {K: "FullSize", V: entry.Size},
397                         }
398                         logMsg = func(les nncp.LEs) string {
399                                 return fmt.Sprintf("Bundle transfer %s/rx/%s", sender, pktName)
400                         }
401                         dstDirPath := filepath.Join(ctx.Spool, sender, string(nncp.TRx))
402                         dstPath := filepath.Join(dstDirPath, pktName)
403                         if _, err = os.Stat(dstPath); err == nil || !os.IsNotExist(err) {
404                                 ctx.LogD("bundle-rx-exists", les, func(les nncp.LEs) string {
405                                         return logMsg(les) + ": packet already exists"
406                                 })
407                                 continue
408                         }
409                         if _, err = os.Stat(dstPath + nncp.SeenSuffix); err == nil || !os.IsNotExist(err) {
410                                 ctx.LogD("bundle-rx-seen", les, func(les nncp.LEs) string {
411                                         return logMsg(les) + ": packet already seen"
412                                 })
413                                 continue
414                         }
415                         if *doCheck {
416                                 if *dryRun {
417                                         hsh, err := blake2b.New256(nil)
418                                         if err != nil {
419                                                 log.Fatalln("Error during hasher creation:", err)
420                                         }
421                                         if _, err = hsh.Write(pktEncBuf); err != nil {
422                                                 log.Fatalln("Error during writing:", err)
423                                         }
424                                         if _, err = nncp.CopyProgressed(hsh, tarR, "check", les, ctx.ShowPrgrs); err != nil {
425                                                 log.Fatalln("Error during copying:", err)
426                                         }
427                                         if nncp.Base32Codec.EncodeToString(hsh.Sum(nil)) != pktName {
428                                                 ctx.LogE("bundle-rx", les, errors.New("bad checksum"), logMsg)
429                                                 continue
430                                         }
431                                 } else {
432                                         tmp, err := ctx.NewTmpFileWHash()
433                                         if err != nil {
434                                                 log.Fatalln("Error during temporary file creation:", err)
435                                         }
436                                         if _, err = tmp.W.Write(pktEncBuf); err != nil {
437                                                 log.Fatalln("Error during writing:", err)
438                                         }
439                                         if _, err = nncp.CopyProgressed(tmp.W, tarR, "check", les, ctx.ShowPrgrs); err != nil {
440                                                 log.Fatalln("Error during copying:", err)
441                                         }
442                                         if err = tmp.W.Flush(); err != nil {
443                                                 log.Fatalln("Error during flusing:", err)
444                                         }
445                                         if nncp.Base32Codec.EncodeToString(tmp.Hsh.Sum(nil)) == pktName {
446                                                 if err = tmp.Commit(dstDirPath); err != nil {
447                                                         log.Fatalln("Error during commiting:", err)
448                                                 }
449                                         } else {
450                                                 ctx.LogE("bundle-rx", les, errors.New("bad checksum"), logMsg)
451                                                 tmp.Cancel()
452                                                 continue
453                                         }
454                                 }
455                         } else {
456                                 if *dryRun {
457                                         if _, err = nncp.CopyProgressed(ioutil.Discard, tarR, "Rx", les, ctx.ShowPrgrs); err != nil {
458                                                 log.Fatalln("Error during copying:", err)
459                                         }
460                                 } else {
461                                         tmp, err := ctx.NewTmpFile()
462                                         if err != nil {
463                                                 log.Fatalln("Error during temporary file creation:", err)
464                                         }
465                                         bufTmp := bufio.NewWriterSize(tmp, CopyBufSize)
466                                         if _, err = bufTmp.Write(pktEncBuf); err != nil {
467                                                 log.Fatalln("Error during writing:", err)
468                                         }
469                                         if _, err = nncp.CopyProgressed(bufTmp, tarR, "Rx", les, ctx.ShowPrgrs); err != nil {
470                                                 log.Fatalln("Error during copying:", err)
471                                         }
472                                         if err = bufTmp.Flush(); err != nil {
473                                                 log.Fatalln("Error during flushing:", err)
474                                         }
475                                         if err = tmp.Sync(); err != nil {
476                                                 log.Fatalln("Error during syncing:", err)
477                                         }
478                                         if err = tmp.Close(); err != nil {
479                                                 log.Fatalln("Error during closing:", err)
480                                         }
481                                         if err = os.MkdirAll(dstDirPath, os.FileMode(0777)); err != nil {
482                                                 log.Fatalln("Error during mkdir:", err)
483                                         }
484                                         if err = os.Rename(tmp.Name(), dstPath); err != nil {
485                                                 log.Fatalln("Error during renaming:", err)
486                                         }
487                                         if err = nncp.DirSync(dstDirPath); err != nil {
488                                                 log.Fatalln("Error during syncing:", err)
489                                         }
490                                         if ctx.HdrUsage {
491                                                 ctx.HdrWrite(pktEncBuf, dstPath)
492                                         }
493                                 }
494                         }
495                         for _, le := range les {
496                                 if le.K == "FullSize" {
497                                         les = append(les, nncp.LE{K: "Size", V: le.V})
498                                         break
499                                 }
500                         }
501                         ctx.LogI("bundle-rx", les, func(les nncp.LEs) string {
502                                 return fmt.Sprintf(
503                                         "Bundle transfer, received from %s %s (%s)",
504                                         sender, pktName, humanize.IBytes(uint64(entry.Size)),
505                                 )
506                         })
507                 }
508         }
509 }