Skip to content

Instantly share code, notes, and snippets.

@sfowl

sfowl/SNAPDEBUG.ohn5.txt Secret

Created Feb 2, 2020
Embed
What would you like to do?
Sun, 02 Feb 2020 18:16:32 +1000
Distributor ID: Debian
Description: Debian GNU/Linux 10 (buster)
Release: 10
Codename: buster
snap 2.42.5
snapd 2.42.5
series 16
debian 10
kernel 4.19.0-6-amd64
ID Status Spawn Ready Summary
13 Error today at 10:05 AEST today at 10:19 AEST Install "electronplayer" snap
14 Error today at 10:22 AEST today at 10:45 AEST Install "electronplayer" snap
15 Done today at 10:32 AEST today at 10:32 AEST Refresh all snaps: no updates
16 Error today at 10:45 AEST today at 11:50 AEST Install "electronplayer" snap
17 Error today at 12:10 AEST today at 12:25 AEST Install "electronplayer" snap
18 Error today at 14:25 AEST today at 14:34 AEST Install "electronplayer" snap
19 Error today at 15:26 AEST today at 15:42 AEST Install "electronplayer" snap
20 Error today at 15:43 AEST today at 16:00 AEST Install "electronplayer" snap
email: -
Start: 2020-02-02T18:16:37+1000
HOST: t470s Loss% Snt Last Avg Best Wrst StDev
1.|-- 192.168.0.1 0.0% 10 0.8 0.7 0.5 0.8 0.1
2.|-- 100.71.0.1 0.0% 10 2.4 8.8 2.1 67.3 20.6
3.|-- 100.127.250.254 0.0% 10 2.1 2.1 1.8 2.3 0.1
4.|-- 103-70-192-34.connectedoz.com.au 0.0% 10 2.3 5.7 2.0 36.4 10.8
5.|-- 61-68-33-230.static.tpgi.com.au 0.0% 10 3.4 3.3 3.1 3.5 0.1
6.|-- 61-68-33-229.static.tpgi.com.au 10.0% 10 4.1 3.8 3.3 4.6 0.3
7.|-- au-ql-0066-ipg-01-eth2-2003.tpgi.com.au 20.0% 10 4.3 4.2 4.0 4.3 0.1
8.|-- bri-pow-que-crt1-po20.tpgi.com.au 10.0% 10 15.5 16.4 15.0 25.5 3.4
9.|-- bri-pow-que-crt3-be-90.tpg.com.au 0.0% 10 15.9 15.8 15.3 16.1 0.3
10.|-- syd-apt-ros-crt1-be-50.tpg.com.au 10.0% 10 20.8 18.7 15.4 22.2 2.5
11.|-- syd-apt-ros-int2-be100.tpgi.com.au 0.0% 10 16.6 16.9 15.6 18.9 1.2
12.|-- sjo-b21-link.telia.net 20.0% 10 168.9 169.4 168.9 169.8 0.3
13.|-- ??? 100.0 10 0.0 0.0 0.0 0.0 0.0
14.|-- ??? 100.0 10 0.0 0.0 0.0 0.0 0.0
15.|-- SOURCE-MANA.ear2.London1.Level3.net 0.0% 10 298.9 302.0 298.8 318.5 6.7
16.|-- ??? 100.0 10 0.0 0.0 0.0 0.0 0.0
17.|-- zeeman.canonical.com 0.0% 10 299.0 299.2 299.0 299.4 0.1
18.|-- api.snapcraft.io 0.0% 10 300.2 299.4 299.1 300.2 0.3
2020/02/02 18:17:03.630197 cmd_linux.go:212: DEBUG: restarting into "/snap/core/current/usr/bin/snap"
Fetching snap "hello"
2020/02/02 18:17:03.642070 helpers.go:280: DEBUG: Going to download snap "hello" in "/tmp".
2020/02/02 18:17:03.642410 store.go:2337: DEBUG: Deltas enabled. Adding header Snap-Accept-Delta-Format: xdelta3
2020/02/02 18:17:03.642436 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/v2/snaps/refresh, attempt 1, elapsed time=3.805µs
2020/02/02 18:17:03.642454 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:17:03.642682 logger.go:67: DEBUG: > "POST /v2/snaps/refresh HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nContent-Length: 345\r\nAccept: application/json\r\nContent-Type: application/json\r\nSnap-Accept-Delta-Format: xdelta3\r\nSnap-Classic: true\r\nSnap-Device-Architecture: amd64\r\nSnap-Device-Series: 16\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:17:05.088422 retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/v2/snaps/refresh finished after 1 retries, elapsed time=1.445971944s, status: 200
2020/02/02 18:17:05.096480 helpers.go:309: DEBUG: not downloading, using existing file /tmp/hello_38.snap
Fetching assertions for "hello"
2020/02/02 18:17:05.101782 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/snap-revision/9tG3seCSZYJbqqH5GtmenoZwBodTUVE3ZkQA10gHe2dM6JCIjiNfAm11QQoWiRWl?max-format=0, attempt 1, elapsed time=18.381µs
2020/02/02 18:17:05.101858 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:17:05.102374 logger.go:67: DEBUG: > "GET /api/v1/snaps/assertions/snap-revision/9tG3seCSZYJbqqH5GtmenoZwBodTUVE3ZkQA10gHe2dM6JCIjiNfAm11QQoWiRWl?max-format=0 HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: application/x.ubuntu.assertion\r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:17:05.429540 retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/snap-revision/9tG3seCSZYJbqqH5GtmenoZwBodTUVE3ZkQA10gHe2dM6JCIjiNfAm11QQoWiRWl?max-format=0 finished after 1 retries, elapsed time=327.765611ms, status: 200
2020/02/02 18:17:05.429784 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/snap-declaration/16/mVyGrEwiqSi5PugCwyH7WgpoQLemtTd6?max-format=3, attempt 1, elapsed time=14.191µs
2020/02/02 18:17:05.429883 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:17:05.430702 logger.go:67: DEBUG: > "GET /api/v1/snaps/assertions/snap-declaration/16/mVyGrEwiqSi5PugCwyH7WgpoQLemtTd6?max-format=3 HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: application/x.ubuntu.assertion\r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:17:05.734208 retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/snap-declaration/16/mVyGrEwiqSi5PugCwyH7WgpoQLemtTd6?max-format=3 finished after 1 retries, elapsed time=304.413274ms, status: 200
2020/02/02 18:17:05.734409 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0, attempt 1, elapsed time=13.307µs
2020/02/02 18:17:05.734511 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:17:05.735301 logger.go:67: DEBUG: > "GET /api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0 HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: application/x.ubuntu.assertion\r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:17:06.038448 retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0 finished after 1 retries, elapsed time=304.035683ms, status: 200
Install the snap with:
snap ack hello_38.assert
snap install hello_38.snap
2020/02/02 18:17:06.087402 cmd_linux.go:212: DEBUG: restarting into "/snap/core/current/usr/bin/snap"
Fetching snap "core"
2020/02/02 18:17:06.131631 helpers.go:280: DEBUG: Going to download snap "core" in "/tmp".
2020/02/02 18:17:06.133548 store.go:2337: DEBUG: Deltas enabled. Adding header Snap-Accept-Delta-Format: xdelta3
2020/02/02 18:17:06.133721 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/v2/snaps/refresh, attempt 1, elapsed time=40.694µs
2020/02/02 18:17:06.133809 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:17:06.135027 logger.go:67: DEBUG: > "POST /v2/snaps/refresh HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nContent-Length: 344\r\nAccept: application/json\r\nContent-Type: application/json\r\nSnap-Accept-Delta-Format: xdelta3\r\nSnap-Classic: true\r\nSnap-Device-Architecture: amd64\r\nSnap-Device-Series: 16\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:17:07.521502 retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/v2/snaps/refresh finished after 1 retries, elapsed time=1.387828052s, status: 200
2020/02/02 18:17:07.523533 store.go:1357: DEBUG: Available deltas returned by store: []
2020/02/02 18:17:07.523672 store.go:1391: DEBUG: Resuming download of "/tmp/core_8268.snap.partial" at 104453801.
2020/02/02 18:17:08.031382 store.go:1427: DEBUG: Hashsum error on download: sha3-384 mismatch for "core": got 427843f918eabbfd8c8e87fb3a1476b853e7ac3f6af219ee440a7ece7d56cd5766a961e95333879d8b1086ffa80beb03 but expected 7946b4de33a1b621a7c9f22a1463d858c55d09db7294b187527127ef1c71b126dc7d687f294d89f7c2eb63a644af982f
2020/02/02 18:17:08.031399 store.go:1428: DEBUG: Truncating and trying again from scratch.
2020/02/02 18:17:08.041686 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap, attempt 1, elapsed time=19.56µs
2020/02/02 18:17:08.041715 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:17:08.041865 logger.go:67: DEBUG: > "GET /api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: \r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:17:09.945807 logger.go:67: DEBUG: > "GET /download-origin/canonical-lgw01/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap?token=1580644800_82caeb727a70e86e35609db9a023cb4482a63e1a HTTP/1.1\r\nHost: canonical-bos01.cdn.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: \r\nReferer: https://api.snapcraft.io/api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap\r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\nAccept-Encoding: gzip\r\n\r\n"
2020/02/02 18:48:22.169573 retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap, attempt 2, elapsed time=31m14.127903817s
2020/02/02 18:48:22.448225 store.go:916: DEBUG: cannot set device session: no device serial yet
2020/02/02 18:48:22.448376 logger.go:67: DEBUG: > "GET /api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap HTTP/1.1\r\nHost: api.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: \r\nRange: bytes=59264888-\r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\n\r\n"
2020/02/02 18:48:23.738575 logger.go:67: DEBUG: > "GET /download-origin/canonical-lgw01/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap?token=1580644800_82caeb727a70e86e35609db9a023cb4482a63e1a HTTP/1.1\r\nHost: canonical-lcy01.cdn.snapcraft.io\r\nUser-Agent: snapd/2.42.5 (series 16; classic; devmode) snap debian/10 (amd64) linux/4.19.0-6-amd64\r\nAccept: \r\nRange: bytes=59264888-\r\nReferer: https://api.snapcraft.io/api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap\r\nX-Ubuntu-Architecture: amd64\r\nX-Ubuntu-Classic: true\r\nX-Ubuntu-Series: 16\r\nX-Ubuntu-Wire-Protocol: 1\r\n\r\n"
2020/02/02 18:56:22.456575 store.go:1439: DEBUG: download of "https://api.snapcraft.io/api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_8268.snap" failed: &errors.errorString{s:"unexpected EOF"}
[?25herror: unexpected EOF
@hloeung

This comment has been minimized.

Copy link

@hloeung hloeung commented Feb 5, 2020

Hi,

Please see https://forum.snapcraft.io/t/extremely-slow-snap-downloads/4668/28

We also made some changes on our end, so please retest.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment