[Scion] Not receiving paths

Frei Matthias matthias.frei at inf.ethz.ch
Wed Nov 13 08:52:49 CET 2019


Hi Joel,


the logs indicate that the connection to the attachment point AS was never established -- no connection means no beacons means no paths. This is apparent from the logs of beacon server or border router, which indicate that the interface is always inactive.


>From the side of the attachment point, your VPN connection looks fine.
Now, to establish whether a link is active, the beacon servers on both sides of the link send "IFID" UDP packets at one second intervals. On the attachment point side, I can see that these appear to be sent out correctly to your machine over VPN, but there is no reply from your machine. Can you try to check if you see these packets on your side? Run `sudo tcpdump -i tun0 -n udp`.


Cheers,

Matthias


________________________________
From: Scion <scion-bounces at lists.inf.ethz.ch> on behalf of Fischer Joel <fischjoe at student.ethz.ch>
Sent: Monday, 11 November 2019 2:52:12 PM
To: Kowalski Mateusz
Cc: scion at lists.inf.ethz.ch
Subject: Re: [Scion] Not receiving paths

Thanks!

The link to my log files: https://polybox.ethz.ch/index.php/s/cKgUgoJi7Rmw8BS

Cheers,
Joel

On 11 Nov 2019, at 14:46, Kowalski Mateusz <mateusz.kowalski at inf.ethz.ch<mailto:mateusz.kowalski at inf.ethz.ch>> wrote:


Thanks. Probably makes more sense if you tar/zip the whole `logs` directory as it's probably a combination of multiple of them what is going to give the answer. As sending attachment to this mailing list won't work, probably you want to upload the archive to the dropbox or smth like this

Cheers!

On 11/11/2019 14:41, Fischer Joel wrote:
Hi,

I get the following outputs:

~/go/src/github.com/scionproto/scion$<http://github.com/scionproto/scion$> ping 10.0.8.1
PING 10.0.8.1 (10.0.8.1) 56(84) bytes of data.
64 bytes from 10.0.8.1: icmp_seq=1 ttl=64 time=0.658 ms
64 bytes from 10.0.8.1: icmp_seq=2 ttl=64 time=0.799 ms
64 bytes from 10.0.8.1: icmp_seq=3 ttl=64 time=0.655 ms
^C
--- 10.0.8.1 ping statistics ---
3 packets transmitted, 3 received, 0% packet loss, time 1999ms
rtt min/avg/max/mdev = 0.655/0.704/0.799/0.067 ms
fischjoe at netsec-yd3sbg:~/go/src/github.com/scionproto/scion$<http://github.com/scionproto/scion$> ./bin/scmp echo -local 17-ffaa:1:ceb,[127.0.0.1] -remote 17-ffaa:0:1107,[0.0.0.0]
CRIT: SCIOND unable to retrieve paths: SCIOND experienced an internal error

fischjoe at netsec-yd3sbg:~/go/src/github.com/scionproto/scion$<http://github.com/scionproto/scion$> ./bin/scmp 17-ffaa:0:1107
CRIT: Invalid local address


On 11 Nov 2019, at 14:33, Kowalski Mateusz <mateusz.kowalski at inf.ethz.ch<mailto:mateusz.kowalski at inf.ethz.ch>> wrote:


Hi,

Can you try the following?

  *   ping 10.0.8.1
  *   scmp 17-ffaa:0:1107

Cheers!

On 11/11/2019 13:55, Fischer Joel wrote:

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
_______________________________________________
Scion mailing list
Scion at lists.inf.ethz.ch<mailto:Scion at lists.inf.ethz.ch>
https://lists.inf.ethz.ch/mailman/listinfo/scion



_______________________________________________
Scion mailing list
Scion at lists.inf.ethz.ch
https://lists.inf.ethz.ch/mailman/listinfo/scion


More information about the Scion mailing list