It turns out that there was indeed a problem with my firewall which was causing the communication issue. The satellite time sync error wasn’t genuine and was leading me down the wrong path.
However, the Storj V3 service itself still won’t remain started. I can now successfully start Storj at the Windows command prompt, but oddly downloads always fail. I exit the process with a CTRL + C so I don’t kill this node’s reputation (if that hasn’t already happened).
I’ve run a Windows disk check and repair on both my system volume as well as the disk for actual Storj data. On the first run errors were found and repaired, and on a second run no errors were found.
Here are some current log messages:
2022-07-16T21:56:39.680-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "XWTWHLO74ZQ45Y3BDUGEJ7SGXCN2Z5FKH52TY6LQ2TDXHTUEWXKQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564597141504}
2022-07-16T21:56:39.717-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "XWTWHLO74ZQ45Y3BDUGEJ7SGXCN2Z5FKH52TY6LQ2TDXHTUEWXKQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 8960}
2022-07-16T21:56:39.839-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "G22AGULZDUHHKNIE5SCF3WHEONMFCRAEFY2N77Z5VIXBSYCZL4AQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 73728}
2022-07-16T21:56:40.247-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "JEK32SZEYQJSWGR4UMKBCE6LAJHGYSNMESEPUJ4XHRWEMK3P5GGA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601114624}
2022-07-16T21:56:40.287-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "JEK32SZEYQJSWGR4UMKBCE6LAJHGYSNMESEPUJ4XHRWEMK3P5GGA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 9728}
2022-07-16T21:56:42.688-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "STJ6JJR2PGR6K5XXI2UPNDBZGUTIF6TISNNPTCLIWHSBJRX7FNLA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601102336}
2022-07-16T21:56:42.702-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "STJ6JJR2PGR6K5XXI2UPNDBZGUTIF6TISNNPTCLIWHSBJRX7FNLA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 1792}
2022-07-16T21:56:44.433-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "G7VHSFY6DW2A5GDWFH4YOM5VZOX3GOY7RK2E6LJ4RQ4SEQFWEG2Q", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601098240}
2022-07-16T21:56:44.448-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "G7VHSFY6DW2A5GDWFH4YOM5VZOX3GOY7RK2E6LJ4RQ4SEQFWEG2Q", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 5120}
2022-07-16T21:56:44.982-0400 INFO piecestore download started {"Process": "storagenode", "Piece ID": "COFZIEBLKY7SMVALHUQRJUSPPMZDXA6WOX2N3ZOFV6LQX2ZN6W7A", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "GET"}
2022-07-16T21:56:44.983-0400 ERROR piecestore download failed {"Process": "storagenode", "Piece ID": "COFZIEBLKY7SMVALHUQRJUSPPMZDXA6WOX2N3ZOFV6LQX2ZN6W7A", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "GET", "error": "file does not exist", "errorVerbose": "file does not exist\n\tstorj.io/common/rpc/rpcstatus.Wrap:73\n\tstorj.io/storj/storagenode/piecestore.(*Endpoint).Download:546\n\tstorj.io/common/pb.DRPCPiecestoreDescription.Method.func2:228\n\tstorj.io/drpc/drpcmux.(*Mux).HandleRPC:33\n\tstorj.io/common/rpc/rpctracing.(*Handler).HandleRPC:58\n\tstorj.io/drpc/drpcserver.(*Server).handleRPC:122\n\tstorj.io/drpc/drpcserver.(*Server).ServeOne:66\n\tstorj.io/drpc/drpcserver.(*Server).Serve.func2:112\n\tstorj.io/drpc/drpcctx.(*Tracker).track:52"}
2022-07-16T21:56:45.433-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "AM7TRR5JRPOXXVC5KDDNXACSFIKHICH7JXPIHEBOAFQSDDCYQSYQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601090048}
2022-07-16T21:56:45.471-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "AM7TRR5JRPOXXVC5KDDNXACSFIKHICH7JXPIHEBOAFQSDDCYQSYQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 11520}
2022-07-16T21:56:46.769-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "I6XWOZ35TM4JEHAXEQ7JDUS23KIQZPXBZ5DDGUFTJ63KKAN4TXRA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601077760}
2022-07-16T21:56:46.784-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "I6XWOZ35TM4JEHAXEQ7JDUS23KIQZPXBZ5DDGUFTJ63KKAN4TXRA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 4608}
2022-07-16T21:56:47.340-0400 INFO piecestore download started {"Process": "storagenode", "Piece ID": "6PV6XCM4FXKNMF5UHFB4RASC5NOGN6BNZSTWEBFO2STWWV3CHFYQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "GET"}
2022-07-16T21:56:47.340-0400 ERROR piecestore download failed {"Process": "storagenode", "Piece ID": "6PV6XCM4FXKNMF5UHFB4RASC5NOGN6BNZSTWEBFO2STWWV3CHFYQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "GET", "error": "file does not exist", "errorVerbose": "file does not exist\n\tstorj.io/common/rpc/rpcstatus.Wrap:73\n\tstorj.io/storj/storagenode/piecestore.(*Endpoint).Download:546\n\tstorj.io/common/pb.DRPCPiecestoreDescription.Method.func2:228\n\tstorj.io/drpc/drpcmux.(*Mux).HandleRPC:33\n\tstorj.io/common/rpc/rpctracing.(*Handler).HandleRPC:58\n\tstorj.io/drpc/drpcserver.(*Server).handleRPC:122\n\tstorj.io/drpc/drpcserver.(*Server).ServeOne:66\n\tstorj.io/drpc/drpcserver.(*Server).Serve.func2:112\n\tstorj.io/drpc/drpcctx.(*Tracker).track:52"}
2022-07-16T21:56:48.141-0400 INFO piecestore download started {"Process": "storagenode", "Piece ID": "EBJZFHQQH3BS4SK3HOAGXJ33V3GPT3CWXWJY7PDPQBEUW4ZRLLIA", "Satellite ID": "121RTSDpyNZVcEU84Ticf2L1ntiuUimbWgfATz21tuvgk3vzoA6", "Action": "GET"}
2022-07-16T21:56:48.141-0400 ERROR piecestore download failed {"Process": "storagenode", "Piece ID": "EBJZFHQQH3BS4SK3HOAGXJ33V3GPT3CWXWJY7PDPQBEUW4ZRLLIA", "Satellite ID": "121RTSDpyNZVcEU84Ticf2L1ntiuUimbWgfATz21tuvgk3vzoA6", "Action": "GET", "error": "file does not exist", "errorVerbose": "file does not exist\n\tstorj.io/common/rpc/rpcstatus.Wrap:73\n\tstorj.io/storj/storagenode/piecestore.(*Endpoint).Download:546\n\tstorj.io/common/pb.DRPCPiecestoreDescription.Method.func2:228\n\tstorj.io/drpc/drpcmux.(*Mux).HandleRPC:33\n\tstorj.io/common/rpc/rpctracing.(*Handler).HandleRPC:58\n\tstorj.io/drpc/drpcserver.(*Server).handleRPC:122\n\tstorj.io/drpc/drpcserver.(*Server).ServeOne:66\n\tstorj.io/drpc/drpcserver.(*Server).Serve.func2:112\n\tstorj.io/drpc/drpcctx.(*Tracker).track:52"}
2022-07-16T21:56:48.405-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "5B3C3R7Q25PTPZFH3QERT7GKEEZ4GWTNLDRAIFRIW2P5HA2MSOCA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601065472}
2022-07-16T21:56:48.416-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "5B3C3R7Q25PTPZFH3QERT7GKEEZ4GWTNLDRAIFRIW2P5HA2MSOCA", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 3328}
2022-07-16T21:56:48.416-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "WXWAREZTEDT4NGBMCO5I3NFCNZNVTVXELMQHP53TZ65DJ7GLC43Q", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601061376}
2022-07-16T21:56:48.570-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "WXWAREZTEDT4NGBMCO5I3NFCNZNVTVXELMQHP53TZ65DJ7GLC43Q", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 11264}
2022-07-16T21:56:49.402-0400 INFO piecestore upload started {"Process": "storagenode", "Piece ID": "45IDDEYJZKL2RZCFPTRE354AEFTJCQ5VCVMULPPFK7XPQE554AHQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Available Space": 564601049088}
2022-07-16T21:56:49.437-0400 INFO piecestore uploaded {"Process": "storagenode", "Piece ID": "45IDDEYJZKL2RZCFPTRE354AEFTJCQ5VCVMULPPFK7XPQE554AHQ", "Satellite ID": "12EayRS2V1kEsWESU9QMRseFhdxYxKicsiFmxrsLZHeLUtdps3S", "Action": "PUT", "Size": 8960}
2022-07-16T21:56:50.283-0400 INFO Got a signal from the OS: "interrupt" {"Process": "storagenode"}