Error al iniciar el nodo en Raspberry pi 4

angelo · November 16, 2020, 2:53am

Buenas tardes.
Al revisar “docker ps -a” obtengo que el nodo dura conectado menos de 1 minuto.

CONTAINER ID        IMAGE                          COMMAND                  CREATED             STATUS              PORTS                                                NAMES
ebf31f77dc2d        storjlabs/storagenode:latest   "/entrypoint"            3 minutes ago       Up 33 seconds       0.0.0.0:14002->14002/tcp, 0.0.0.0:28967->28967/tcp   storagenode

CONTAINER ID        IMAGE                          COMMAND                  CREATED             STATUS              PORTS                                                NAMES
ebf31f77dc2d        storjlabs/storagenode:latest   "/entrypoint"            3 minutes ago       Up 9 seconds        0.0.0.0:14002->14002/tcp, 0.0.0.0:28967->28967/tcp   storagenode

CONTAINER ID        IMAGE                          COMMAND                  CREATED             STATUS              PORTS                                                NAMES
ebf31f77dc2d        storjlabs/storagenode:latest   "/entrypoint"            7 minutes ago       Up 14 seconds       0.0.0.0:14002->14002/tcp, 0.0.0.0:28967->28967/tcp   storagenode

Al iniciar el nodo, obtengo un error lo que hace que el nodo no se logre mantener en linea.

2020-11-15T05:14:54.378Z        INFO    Configuration loaded    {"Location": "/app/config/config.yaml"}
2020-11-15T05:14:54.382Z        INFO    Operator email  {"Address": "YYYYYYYYYYY@YYYYYYYYYYYYYY"}
2020-11-15T05:14:54.382Z        INFO    Operator wallet {"Address": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXX"}
2020-11-15T05:14:55.426Z        INFO    Telemetry enabled
2020-11-15T05:14:55.585Z        INFO    db.migration    Database Version        {"version": 46}
2020-11-15T05:14:56.712Z        INFO    preflight:localtime     start checking local system clock with trusted satellites' system clock.
2020-11-15T05:14:57.521Z        INFO    preflight:localtime     local system clock is in sync with trusted satellites' system clock.
2020-11-15T05:14:57.521Z        INFO    Node 199U9LdTWGhBvpW1dcExyW9TJYBNPmvXXXXXXXXXXXXXXX started
2020-11-15T05:14:57.521Z        INFO    bandwidth       Performing bandwidth usage rollups
2020-11-15T05:14:57.521Z        INFO    Public server started on [::]:28967
2020-11-15T05:14:57.522Z        INFO    Private server started on 127.0.0.1:7778
2020-11-15T05:14:57.525Z        INFO    trust   Scheduling next refresh {"after": "4h28m56.437774128s"}
2020-11-15T05:15:17.464Z        ERROR   nodestats:cache Get pricing-model/join date failed      {"error": "payout service error: unable to connect to the satellite 12L9ZFwhzVpuEKMUNUqkaTLGzwY9G24tbiigLiXpmZWKwmcNDDs: rpc: dial tcp 146.148.29.23:7777: connect: no route to host; payout service error: unable to connect to the satellite 12rfG3sh9NCWiX3ivPjq2HtdLmbqCrvHVEzJubnzFzosMuawymB: rpc: dial tcp: lookup europe-north-1.tardigrade.io on 192.168.31.1:53: read udp 172.17.0.2:43400->192.168.31.1:53: i/o timeout", "errorVerbose": "group:\n--- payout service error: unable to connect to the satellite 12L9ZFwhzVpuEKMUNUqkaTLGzwY9G24tbiigLiXpmZWKwmcNDDs: rpc: dial tcp 146.148.29.23:7777: connect: no route to host\n\tstorj.io/storj/storagenode/payout.(*Endpoint).dial:229\n\tstorj.io/storj/storagenode/payout.(*Endpoint).GetAllPaystubs:105\n\tstorj.io/storj/storagenode/nodestats.(*Cache).Run.func1:80\n\tstorj.io/storj/storagenode/nodestats.(*Cache).satelliteLoop:259\n\tstorj.io/storj/storagenode/nodestats.(*Cache).Run:79\n\tstorj.io/storj/private/lifecycle.(*Group).Run.func1:57\n\tgolang.org/x/sync/errgroup.(*Group).Go.func1:57\n--- payout service error: unable to connect to the satellite 12rfG3sh9NCWiX3ivPjq2HtdLmbqCrvHVEzJubnzFzosMuawymB: rpc: dial tcp: lookup europe-north-1.tardigrade.io on 192.168.31.1:53: read udp 172.17.0.2:43400->192.168.31.1:53: i/o timeout\n\tstorj.io/storj/storagenode/payout.(*Endpoint).dial:229\n\tstorj.io/storj/storagenode/payout.(*Endpoint).GetAllPaystubs:105\n\tstorj.io/storj/storagenode/nodestats.(*Cache).Run.func1:80\n\tstorj.io/storj/storagenode/nodestats.(*Cache).satelliteLoop:259\n\tstorj.io/storj/storagenode/nodestats.(*Cache).Run:79\n\tstorj.io/storj/private/lifecycle.(*Group).Run.func1:57\n\tgolang.org/x/sync/errgroup.(*Group).Go.func1:57"}
2020-11-15T05:15:28.327Z        WARN    ordersfilestore Corrupted order detected in orders file {"error": "ordersfile corrupt entry: ordersfile: unexpected EOF", "errorVerbose": "ordersfile corrupt entry: ordersfile: unexpected EOF\n\tstorj.io/storj/storagenode/orders/ordersfile.(*fileV0).ReadOne:92\n\tstorj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite.func1:239\n\tpath/filepath.walk:360\n\tpath/filepath.walk:384\n\tpath/filepath.Walk:406\n\tstorj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite:193\n\tstorj.io/storj/storagenode/orders.(*Service).sendOrdersFromFileStore:389\n\tstorj.io/storj/storagenode/orders.(*Service).SendOrders:183\n\tstorj.io/storj/storagenode/orders.(*Service).Run.func1:134\n\tstorj.io/common/sync2.(*Cycle).Run:92\n\tstorj.io/common/sync2.(*Cycle).Start.func1:71\n\tgolang.org/x/sync/errgroup.(*Group).Go.func1:57"}
2020-11-15T05:15:28.490Z        WARN    ordersfilestore Corrupted order detected in orders file {"error": "ordersfile corrupt entry: ordersfile: unexpected EOF", "errorVerbose": "ordersfile corrupt entry: ordersfile: unexpected EOF\n\tstorj.io/storj/storagenode/orders/ordersfile.(*fileV0).ReadOne:109\n\tstorj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite.func1:239\n\tpath/filepath.walk:360\n\tpath/filepath.walk:384\n\tpath/filepath.Walk:406\n\tstorj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite:193\n\tstorj.io/storj/storagenode/orders.(*Service).sendOrdersFromFileStore:389\n\tstorj.io/storj/storagenode/orders.(*Service).SendOrders:183\n\tstorj.io/storj/storagenode/orders.(*Service).Run.func1:134\n\tstorj.io/common/sync2.(*Cycle).Run:92\n\tstorj.io/common/sync2.(*Cycle).Start.func1:71\n\tgolang.org/x/sync/errgroup.(*Group).Go.func1:57"}
2020-11-15T05:15:28.572Z        WARN    ordersfilestore Corrupted order detected in orders file {"error": "ordersfile corrupt entry: proto: pb.OrderLimit: illegal tag 0 (wire type 0)", "errorVerbose": "ordersfile corrupt entry: proto: pb.OrderLimit: illegal tag 0 (wire type 0)\n\tstorj.io/storj/storagenode/orders/ordersfile.(*fileV0).ReadOne:98\n\tstorj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite.func1:239\n\tpath/filepath.walk:360\n\tpath/filepath.walk:384\n\tpath/filepath.Walk:406\n\tstorj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite:193\n\tstorj.io/storj/storagenode/orders.(*Service).sendOrdersFromFileStore:389\n\tstorj.io/storj/storagenode/orders.(*Service).SendOrders:183\n\tstorj.io/storj/storagenode/orders.(*Service).Run.func1:134\n\tstorj.io/common/sync2.(*Cycle).Run:92\n\tstorj.io/common/sync2.(*Cycle).Start.func1:71\n\tgolang.org/x/sync/errgroup.(*Group).Go.func1:57"}
panic: runtime error: makeslice: len out of range [recovered]
        panic: runtime error: makeslice: len out of range [recovered]
        panic: runtime error: makeslice: len out of range [recovered]
        panic: runtime error: makeslice: len out of range

goroutine 969 [running]:
github.com/spacemonkeygo/monkit/v3.newSpan.func1(0x0)
        /go/pkg/mod/github.com/spacemonkeygo/monkit/v3@v3.0.7-0.20200515175308-072401d8c752/ctx.go:147 +0x2e0
panic(0x8b0378, 0xa65400)
        /usr/local/go/src/runtime/panic.go:969 +0x118
github.com/spacemonkeygo/monkit/v3.newSpan.func1(0x0)
        /go/pkg/mod/github.com/spacemonkeygo/monkit/v3@v3.0.7-0.20200515175308-072401d8c752/ctx.go:147 +0x2e0
panic(0x8b0378, 0xa65400)
        /usr/local/go/src/runtime/panic.go:975 +0x3c4
github.com/spacemonkeygo/monkit/v3.newSpan.func1(0x1de00f0)
        /go/pkg/mod/github.com/spacemonkeygo/monkit/v3@v3.0.7-0.20200515175308-072401d8c752/ctx.go:147 +0x2e0
panic(0x8b0378, 0xa65400)
        /usr/local/go/src/runtime/panic.go:975 +0x3c4
storj.io/storj/storagenode/orders/ordersfile.(*fileV0).ReadOne(0x1e9e018, 0x0, 0x0, 0x0)
        /go/src/storj.io/storj/storagenode/orders/ordersfile/v0.go:89 +0xe8
storj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite.func1(0x229a620, 0x6a, 0xa7c2a0, 0x2296870, 0x0, 0x0, 0x0, 0x0)
        /go/src/storj.io/storj/storagenode/orders/store.go:239 +0x40c
path/filepath.walk(0x229a620, 0x6a, 0xa7c2a0, 0x2296870, 0x207dbec, 0x0, 0x0)
        /usr/local/go/src/path/filepath/path.go:360 +0x2fc
path/filepath.walk(0x1cc2ac0, 0x14, 0xa7c2a0, 0x1f68510, 0x207dbec, 0x0, 0x677be0)
        /usr/local/go/src/path/filepath/path.go:384 +0x204
path/filepath.Walk(0x1cc2ac0, 0x14, 0x225bbec, 0x0, 0x1fce280)
        /usr/local/go/src/path/filepath/path.go:406 +0xe8
storj.io/storj/storagenode/orders.(*FileStore).ListUnsentBySatellite(0x1d24780, 0xa7a4d0, 0x1c74720, 0xb29b5944, 0xbfe44c3b, 0x95c6c9e4, 0x7, 0x106ac80, 0x20189c0, 0xa6b888ec, ...)
        /go/src/storj.io/storj/storagenode/orders/store.go:193 +0x198
storj.io/storj/storagenode/orders.(*Service).sendOrdersFromFileStore(0x1c9ee10, 0xa7a4d0, 0x1c74060, 0xb29b5944, 0xbfe44c3b, 0x95c6c9e4, 0x7, 0x106ac80)
        /go/src/storj.io/storj/storagenode/orders/service.go:389 +0x314
storj.io/storj/storagenode/orders.(*Service).SendOrders(0x1c9ee10, 0xa7a650, 0x1c988d0, 0xb29b5944, 0xbfe44c3b, 0x95c6c9e4, 0x7, 0x106ac80)
        /go/src/storj.io/storj/storagenode/orders/service.go:183 +0x13c
storj.io/storj/storagenode/orders.(*Service).Run.func1(0xa7a650, 0x1c988d0, 0xa7a650, 0x1c988d0)
        /go/src/storj.io/storj/storagenode/orders/service.go:134 +0x84
storj.io/common/sync2.(*Cycle).Run(0x1cb4fc0, 0xa7a4d0, 0x2136000, 0x1c98358, 0x0, 0x0)
        /go/pkg/mod/storj.io/common@v0.0.0-20201014090530-c4af8e54d5c4/sync2/cycle.go:92 +0x134
storj.io/common/sync2.(*Cycle).Start.func1(0x9afb8c, 0x1ca2610)
        /go/pkg/mod/storj.io/common@v0.0.0-20201014090530-c4af8e54d5c4/sync2/cycle.go:71 +0x34
golang.org/x/sync/errgroup.(*Group).Go.func1(0x1fc6000, 0x202c4a0)
        /go/pkg/mod/golang.org/x/sync@v0.0.0-20200625203802-6e8e738ad208/errgroup/errgroup.go:57 +0x50
created by golang.org/x/sync/errgroup.(*Group).Go
        /go/pkg/mod/golang.org/x/sync@v0.0.0-20200625203802-6e8e738ad208/errgroup/errgroup.go:54 +0x50

angelo · November 17, 2020, 4:44am

Solucionado!

https://forum.storj.io/t/a-lot-of-unsent-orders/9923/18

ifraixedes · November 17, 2020, 9:37am

Hola Angelo,

Me gustaría confirmar que tu nodo corre en una máquina o sistema operativo de 32 bits, ya que hemos detectado este problema solo en esas arquitecturas. Si el tuyo no lo es entonces tendremos que seguir investigando.

Muchas gracias por tu colaboración.

angelo · November 17, 2020, 11:35pm

Adjunto información de mi sistema.

Linux raspberrypi 5.4.72-v7l+ #1356 SMP Thu Oct 22 13:57:51 BST 2020 armv7l GNU/Linux

Architecture:        armv7l
Byte Order:          Little Endian
CPU(s):              4
On-line CPU(s) list: 0-3
Thread(s) per core:  1
Core(s) per socket:  4
Socket(s):           1
Vendor ID:           ARM
Model:               3
Model name:          Cortex-A72
Stepping:            r0p3
CPU max MHz:         1500,0000
CPU min MHz:         600,0000
BogoMIPS:            126.00
Flags:               half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32

No LSB modules are available.
Distributor ID: Raspbian
Description:    Raspbian GNU/Linux 10 (buster)
Release:        10
Codename:       buster

ifraixedes · November 23, 2020, 11:49am

Si no me equivoco en lo que he encontrado, tu nodo es de una arquitectura de 32 bits.
En un principio hemos encontrado el problema y lo hemos solucionado en la versión 1.17.4 que vamos a empezar a desplegarla entre hoy y manañana.

Gracias por tu colaboración.

angelo · November 25, 2020, 12:52am

Si, mi nodo cuenta con 2gb de ram, por lo tanto debería ser de 32 bits.