[Scion] Not receiving paths

Mon Nov 11 13:55:55 CET 2019

Hi

I have built SCION from source according to the tutorial (https://netsec-ethz.github.io/scion-tutorials/install/src/) and setup the VPN client.

The local topology worked fine, but when I try to connect to scionlab and run scmp echo, I get the following error:

./bin/scmp echo -local 17-ffaa:1:ceb,[127.0.0.1] -remote 20-ffaa:0:1404,[0.0.0.0]
CRIT: SCIOND unable to retrieve paths: SCIOND experienced an internal error

The log of sciond is:

cat logs/sd17-ffaa_1_ceb.log
2019-11-11 12:48:16.863748+0000 [INFO] =====================> Service started SD sd17-ffaa_1_ceb
  Scion version: v2019.09-15-gd7eebae
  In docker:     false
  pid:           30036
  euid/egid:     168638 261978
  cmd line:      ["bin/sciond" "-config" "gen/ISD17/ASffaa_1_ceb/endhost/sd.toml"]
2019-11-11 12:48:16.864077+0000 [INFO] Connecting PathDB backend=sqlite connection=gen-cache/sd17-ffaa_1_ceb.path.db
2019-11-11 12:48:16.979552+0000 [INFO] Connecting RevCache backend=mem connection=""
2019-11-11 12:48:16.979577+0000 [INFO] Connecting TrustDB backend=sqlite connection=gen-cache/sd17-ffaa_1_ceb.trust.db
2019-11-11 12:48:17.074049+0000 [DBUG] Registered with dispatcher addr="17-ffaa:1:ceb,[127.0.0.1]:1025 (UDP)"
2019-11-11 12:48:17.074488+0000 [DBUG] Registered with dispatcher addr="17-ffaa:1:ceb,[127.0.0.1]:1026 (UDP)"
2019-11-11 12:48:17.074772+0000 [INFO] DRKey enabled=false
2019-11-11 12:48:17.074811+0000 [INFO] Starting periodic task debug_id=02c025e1 task="Cleaner for segments"
2019-11-11 12:48:17.074838+0000 [INFO] Starting periodic task debug_id=550ed7d9 task="Cleaner for revocations"
2019-11-11 12:48:17.074877+0000 [INFO] Exporting prometheus metrics addr=[127.0.0.1]:30455
2019-11-11 12:48:17.978093+0000 [DBUG] [PathRequestHandler] Received request debug_id=c9b1d73c req="17-ffaa:1:ceb -> 17-ffaa:0:1101, maxPaths=5, flags={false false}"
2019-11-11 12:48:17.978313+0000 [DBUG] Requesting segments debug_id=c9b1d73c ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:48:27.978601+0000 [DBUG] Unclassified RPC error debug_id=c9b1d73c msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:27.978649+0000 [EROR] Unable to retrieve paths from network debug_id=c9b1d73c err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:27.978665+0000 [EROR] Unable to get paths debug_id=c9b1d73c err="No result"
2019-11-11 12:48:27.978742+0000 [DBUG] Replied with paths debug_id=c9b1d73c num_paths=0
2019-11-11 12:48:27.993341+0000 [DBUG] [PathRequestHandler] Received request debug_id=c547de4a req="17-ffaa:1:ceb -> 17-ffaa:0:1101, maxPaths=5, flags={false false}"
2019-11-11 12:48:27.993509+0000 [DBUG] Requesting segments debug_id=c547de4a ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:48:37.993748+0000 [DBUG] Unclassified RPC error debug_id=c547de4a msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:37.993790+0000 [EROR] Unable to retrieve paths from network debug_id=c547de4a err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:37.993804+0000 [EROR] Unable to get paths debug_id=c547de4a err="No result"
2019-11-11 12:48:37.993886+0000 [WARN] Unable to reply to client debug_id=c547de4a client=<nil> err="write unix /run/shm/sciond/default.sock->@: write: broken pipe"
2019-11-11 12:48:38.990405+0000 [DBUG] [PathRequestHandler] Received request debug_id=6dcac452 req="17-ffaa:1:ceb -> 17-ffaa:0:1101, maxPaths=5, flags={false false}"
2019-11-11 12:48:38.990592+0000 [DBUG] Requesting segments debug_id=6dcac452 ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:48:48.990833+0000 [DBUG] Unclassified RPC error debug_id=6dcac452 msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:48.990871+0000 [EROR] Unable to retrieve paths from network debug_id=6dcac452 err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:48.990883+0000 [EROR] Unable to get paths debug_id=6dcac452 err="No result"
2019-11-11 12:48:48.991041+0000 [DBUG] Replied with paths debug_id=6dcac452 num_paths=0
2019-11-11 12:48:48.997702+0000 [DBUG] [PathRequestHandler] Received request debug_id=faede431 req="17-ffaa:1:ceb -> 17-ffaa:0:1101, maxPaths=5, flags={false false}"
2019-11-11 12:48:48.997887+0000 [DBUG] Requesting segments debug_id=faede431 ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:48:58.998161+0000 [DBUG] Unclassified RPC error debug_id=faede431 msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:58.998196+0000 [EROR] Unable to retrieve paths from network debug_id=faede431 err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:48:58.998212+0000 [EROR] Unable to get paths debug_id=faede431 err="No result"
2019-11-11 12:48:58.998294+0000 [WARN] Unable to reply to client debug_id=faede431 client=<nil> err="write unix /run/shm/sciond/default.sock->@: write: broken pipe"
2019-11-11 12:49:00.988075+0000 [DBUG] [PathRequestHandler] Received request debug_id=05b6f631 req="17-ffaa:1:ceb -> 17-ffaa:0:1101, maxPaths=5, flags={false false}"
2019-11-11 12:49:00.988237+0000 [DBUG] Requesting segments debug_id=05b6f631 ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:49:10.988537+0000 [DBUG] Unclassified RPC error debug_id=05b6f631 msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:49:10.988574+0000 [EROR] Unable to retrieve paths from network debug_id=05b6f631 err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:49:10.988587+0000 [EROR] Unable to get paths debug_id=05b6f631 err="No result"
2019-11-11 12:49:10.988666+0000 [DBUG] Replied with paths debug_id=05b6f631 num_paths=0
2019-11-11 12:49:10.989849+0000 [DBUG] [PathRequestHandler] Received request debug_id=f95b4e4f req="17-ffaa:1:ceb -> 17-ffaa:0:1101, maxPaths=5, flags={false false}"
2019-11-11 12:49:10.990062+0000 [DBUG] Requesting segments debug_id=f95b4e4f ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:49:20.990241+0000 [DBUG] Unclassified RPC error debug_id=f95b4e4f msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:49:20.990277+0000 [EROR] Unable to retrieve paths from network debug_id=f95b4e4f err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:49:20.990290+0000 [EROR] Unable to get paths debug_id=f95b4e4f err="No result"
2019-11-11 12:49:20.993752+0000 [WARN] Unable to reply to client debug_id=f95b4e4f client=<nil> err="write unix /run/shm/sciond/default.sock->@: write: broken pipe"
2019-11-11 12:52:33.750095+0000 [DBUG] [PathRequestHandler] Received request debug_id=91c23029 req="17-ffaa:1:ceb -> 20-ffaa:0:1404, maxPaths=0, flags={false false}"
2019-11-11 12:52:33.750315+0000 [DBUG] Requesting segments debug_id=91c23029 ps="17-ffaa:1:ceb,[PS A (0x0001)]:0 (UDP)"
2019-11-11 12:52:43.750614+0000 [DBUG] Unclassified RPC error debug_id=91c23029 msg=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:52:43.750686+0000 [EROR] Unable to retrieve paths from network debug_id=91c23029 err=
>  [Messenger] Request error req_type="SegRequest"
>      Read on stream 0 canceled with error code 1
2019-11-11 12:52:43.750701+0000 [EROR] Unable to get paths debug_id=91c23029 err="No result"
2019-11-11 12:52:43.750784+0000 [DBUG] Replied with paths debug_id=91c23029 num_paths=0

Restarting as described in the troubleshooting section and a fresh install have both not helped. What else could I try?

Thanks for your help.

Cheers,
Joel