unhelpful syslog entries

[Jamie Strandboge]

$ system-image-cli -i
current build number: 354
device name: generic_amd64
channel: ubuntu-core/devel-proposed
last update: 2015-03-31 05:48:15
version version: 354
version ubuntu: 20150331
version raw-device: 20150331

If I install a snap (eg, sudo snappy install docker), syslog has some pretty unhelpful log entries:

Mar 31 20:00:33 localhost snappy[13281]: Starting download of com.ubuntu.snappy.docker
Mar 31 20:00:45 localhost snappy[13281]: 7.13 KB / 4.04 MB 0.17 % 9.69 MB/s #01521.27 KB / 4.04 MB 0.51 % 105.69 KB/s 38s#01541.07 KB / 4.04 MB 0.99 % 102.19 KB/s 40s#01580.13 KB / 4.04 MB 1.94 % 133.02 KB/s 30s#015101.87 KB / 4.04 MB 2.46 % 126.89 KB/s 31s#015156.56 KB / 4.04 MB 3.78 % 156.06 KB/s 25s#015219.06 KB / 4.04 MB 5.30 % 182.01 KB/s 21s#015226.87 KB / 4.04 MB 5.48 % 161.55 KB/s 24s#015312.81 KB / 4.04 MB 7.56 % 194.93 KB/s 19s#015328.43 KB / 4.04 MB 7.94 % 181.95 KB/s 20s#015344.06 KB / 4.04 MB 8.32 % 171.57 KB/s 22s#015351.87 KB / 4.04 MB 8.51 % 146.24 KB/s 25s#015367.50 KB / 4.04 MB 8.88 % 141.00 KB/s 26s#015375.31 KB / 4.04 MB 9.07 % 124.81 KB/s 30s#015383.12 KB / 4.04 MB 9.26 % 119.45 KB/s 31s#015390.93 KB / 4.04 MB 9.45 % 114.71 KB/s 32s#015398.75 KB / 4.04 MB 9.64 % 104.70 KB/s 35s#015406.56 KB / 4.04 MB 9.83 % 101.41 KB/s 36s#015414.37 KB / 4.04 MB 10.02 % 98.44 KB/s 37s#015422.18 KB / 4.04 MB 10.21 % 91.58 KB/s 40s#015430.00 KB / 4.04 MB 10.39 % 89.39 KB/s 41s#015437.81 KB / 4.04 MB 10.58 % 87.37 KB/s 42s#015445.62 KB / 4.04 MB 10.77 % 85.51 KB/s 43s#015453.43 KB / 4.04 MB 10.96 % 83.79 KB/s 43s#015461.25 KB / 4.04 MB 11.15 % 82.19 KB/s 44s#015469.06 KB / 4.04 MB 11.34 % 78.01 KB/s 47s#015476.87 KB / 4.04 MB 11.53 % 76.75 KB/s 47s#015484.68 KB / 4.04 MB 11.72 % 75.57 KB/s 48s#015492.50 KB / 4.04 MB 11.91 % 74.46 KB/s 48s#015500.31 KB / 4.04 MB 12.09 % 73.42 KB/s 49s#015515.93 KB / 4.04 MB 12.47 % 71.51 KB/s 50s#015531.56 KB / 4.04 MB 12.85 % 69.79 KB/s 51s#015547.18 KB / 4.04 MB 13.23 % 68.25 KB/s 52s#015555.00 KB / 4.04 MB 13.42 % 67.54 KB/s 53s#015570.62 KB / 4.04 MB 13.79 % 67.79 KB/s 52s#015578.43 KB / 4.04 MB 13.98 % 67.12 KB/s 53s#015586.25 KB / 4.04 MB 14.17 % 66.48 KB/s 53s#015601.87 KB / 4.04 MB 14.55 % 66.73 KB/s 52s#015609.68 KB / 4.04 MB 14.74 % 63.38 KB/s 55s#015617.50 KB / 4.04 MB 14.93 % 62.88 KB/s 55s#015625.31 KB / 4.04 MB 15.12 % 62.40 KB/s 56s#015633.12 KB / 4.04 MB 15.30 % 60.75 KB/s 57s#015640.93 KB / 4.04 MB 15.49 % 60.34 KB/s 57s#015648.75 KB / 4.04 MB 15.68 % 57.80 KB/s 1m0s#015656.56 KB / 4.04 MB 15.87 % 57.47 KB/s 1m0s#015664.37 KB / 4.04 MB 16.06 % 56.19 KB/s 1m1s#015672.18 KB / 4.04 MB 16.25 % 5
Mar 31 20:00:57 localhost snappy[13281]: 5.90 KB/s 1m1s#015687.81 KB / 4.04 MB 16.63 % 53.62 KB/s 1m4s#015695.62 KB / 4.04 MB 16.82 % 51.04 KB/s 1m7s#015703.43 KB / 4.04 MB 17.00 % 50.14 KB/s 1m8s#015711.25 KB / 4.04 MB 17.19 % 49.29 KB/s 1m9s#015719.06 KB / 4.04 MB 17.38 % 49.14 KB/s 1m9s#015726.87 KB / 4.04 MB 17.57 % 49.01 KB/s 1m9s#015742.50 KB / 4.04 MB 17.95 % 48.11 KB/s 1m10s#015750.31 KB / 4.04 MB 18.14 % 47.38 KB/s 1m11s#015758.12 KB / 4.04 MB 18.33 % 47.28 KB/s 1m11s#015765.93 KB / 4.04 MB 18.52 % 47.18 KB/s 1m11s#015773.75 KB / 4.04 MB 18.70 % 45.96 KB/s 1m13s#015781.56 KB / 4.04 MB 18.89 % 45.88 KB/s 1m13s#015797.18 KB / 4.04 MB 19.27 % 45.72 KB/s 1m13s#015805.00 KB / 4.04 MB 19.46 % 45.13 KB/s 1m13s#015812.81 KB / 4.04 MB 19.65 % 45.06 KB/s 1m13s#015820.62 KB / 4.04 MB 19.84 % 44.99 KB/s 1m13s#015828.43 KB / 4.04 MB 20.03 % 44.93 KB/s 1m13s#015836.25 KB / 4.04 MB 20.22 % 44.86 KB/s 1m13s#015844.06 KB / 4.04 MB 20.40 % 44.80 KB/s 1m13s#015859.68 KB / 4.04 MB 20.78 % 45.15 KB/s 1m12s#015867.50 KB / 4.04 MB 20.97 % 45.09 KB/s 1m12s#015890.93 KB / 4.04 MB 21.54 % 45.83 KB/s 1m10s#015906.56 KB / 4.04 MB 21.92 % 46.16 KB/s 1m9s#015930.00 KB / 4.04 MB 22.48 % 46.87 KB/s 1m8s#015945.62 KB / 4.04 MB 22.86 % 47.18 KB/s 1m7s#015969.06 KB / 4.04 MB 23.43 % 47.87 KB/s 1m6s#015984.68 KB / 4.04 MB 23.80 % 48.17 KB/s 1m5s#0151023.75 KB / 4.04 MB 24.75 % 49.59 KB/s 1m2s#0151.02 MB / 4.04 MB 25.13 % 49.87 KB/s 1m2s#0151.05 MB / 4.04 MB 25.88 % 50.88 KB/s 1m0s#0151.06 MB / 4.04 MB 26.26 % 50.65 KB/s 1m0s#0151.07 MB / 4.04 MB 26.45 % 50.54 KB/s 1m0s#0151.08 MB / 4.04 MB 26.64 % 50.44 KB/s 1m0s#0151.10 MB / 4.04 MB 27.20 % 51.04 KB/s 58s#0151.11 MB / 4.04 MB 27.58 % 51.29 KB/s 58s#0151.12 MB / 4.04 MB 27.77 % 51.18 KB/s 58s#0151.14 MB / 4.04 MB 28.34 % 51.76 KB/s 57s#0151.16 MB / 4.04 MB 28.71 % 51.99 KB/s 56s#0151.18 MB / 4.04 MB 29.09 % 52.21 KB/s 56s#0151.19 MB / 4.04 MB 29.47 % 52.43 KB/s 55s#0151.21 MB / 4.04 MB 30.04 % 52.98 KB/s 54s#0151.23 MB / 4.04 MB 30.41 % 53.17 KB/s 54s#0151.24 MB / 4.04 MB 30.79 % 53.38 KB/s 53s#0151.26 MB / 4.04 MB 31.17 % 53.59 KB/s 53s#0151.28 MB / 4.04 MB 31.74 % 54.11 KB/s 52s#0151.29 MB / 4.04 MB 31.92 % 53.99 KB/s
Mar 31 20:01:08 localhost snappy[13281]: 52s#0151.31 MB / 4.04 MB 32.49 % 54.05 KB/s 51s#0151.33 MB / 4.04 MB 32.87 % 54.24 KB/s 51s#0151.34 MB / 4.04 MB 33.06 % 54.12 KB/s 51s#0151.34 MB / 4.04 MB 33.25 % 54.00 KB/s 51s#0151.35 MB / 4.04 MB 33.44 % 53.88 KB/s 51s#0151.37 MB / 4.04 MB 33.81 % 54.07 KB/s 50s#0151.37 MB / 4.04 MB 34.00 % 53.96 KB/s 50s#0151.39 MB / 4.04 MB 34.38 % 54.14 KB/s 50s#0151.40 MB / 4.04 MB 34.76 % 54.32 KB/s 49s#0151.41 MB / 4.04 MB 34.95 % 54.20 KB/s 49s#0151.42 MB / 4.04 MB 35.14 % 53.69 KB/s 49s#0151.43 MB / 4.04 MB 35.32 % 53.58 KB/s 49s#0151.43 MB / 4.04 MB 35.51 % 53.48 KB/s 49s#0151.44 MB / 4.04 MB 35.70 % 53.37 KB/s 49s#0151.45 MB / 4.04 MB 35.89 % 53.27 KB/s 49s#0151.46 MB / 4.04 MB 36.08 % 53.16 KB/s 49s#0151.47 MB / 4.04 MB 36.27 % 53.06 KB/s 49s#0151.47 MB / 4.04 MB 36.46 % 52.96 KB/s 49s#0151.48 MB / 4.04 MB 36.65 % 52.50 KB/s 49s#0151.49 MB / 4.04 MB 36.83 % 52.40 KB/s 49s#0151.50 MB / 4.04 MB 37.02 % 52.31 KB/s 49s#0151.50 MB / 4.04 MB 37.21 % 52.22 KB/s 49s#0151.51 MB / 4.04 MB 37.40 % 52.13 KB/s 49s#0151.52 MB / 4.04 MB 37.59 % 52.04 KB/s 49s#0151.53 MB / 4.04 MB 37.78 % 51.95 KB/s 49s#0151.53 MB / 4.04 MB 37.97 % 51.87 KB/s 49s#0151.54 MB / 4.04 MB 38.16 % 51.78 KB/s 49s#0151.55 MB / 4.04 MB 38.35 % 51.70 KB/s 49s#0151.56 MB / 4.04 MB 38.53 % 51.62 KB/s 49s#0151.56 MB / 4.04 MB 38.72 % 51.53 KB/s 49s#0151.58 MB / 4.04 MB 39.10 % 51.70 KB/s 48s#0151.59 MB / 4.04 MB 39.29 % 51.62 KB/s 48s#0151.60 MB / 4.04 MB 39.67 % 51.79 KB/s 48s#0151.61 MB / 4.04 MB 39.86 % 51.71 KB/s 48s#0151.63 MB / 4.04 MB 40.23 % 51.87 KB/s 47s#0151.63 MB / 4.04 MB 40.42 % 51.79 KB/s 47s#0151.65 MB / 4.04 MB 40.80 % 51.95 KB/s 47s#0151.66 MB / 4.04 MB 41.18 % 52.11 KB/s 46s#0151.68 MB / 4.04 MB 41.56 % 52.27 KB/s 46s#0151.69 MB / 4.04 MB 41.75 % 52.19 KB/s 46s#0151.70 MB / 4.04 MB 42.12 % 52.34 KB/s 45s#0151.72 MB / 4.04 MB 42.50 % 52.50 KB/s 45s#0151.72 MB / 4.04 MB 42.69 % 52.42 KB/s 45s#0151.74 MB / 4.04 MB 43.07 % 52.57 KB/s 44s#0151.76 MB / 4.04 MB 43.63 % 52.95 KB/s 44s#0151.78 MB / 4.04 MB 44.01 % 53.09 KB/s 43s#0151.79 MB / 4.04 MB 44.20 % 53.01 KB/s 43s#0151.82 MB / 4.04 MB 44.96 % 53.61 KB/s 42s#0151.83 MB / 4.04 MB 45.33 %
Mar 31 20:01:19 localhost snappy[13281]: 53.74 KB/s 42s#0151.86 MB / 4.04 MB 46.09 % 54.32 KB/s 41s#0151.88 MB / 4.04 MB 46.66 % 54.67 KB/s 40s#0151.89 MB / 4.04 MB 46.84 % 54.58 KB/s 40s#0151.92 MB / 4.04 MB 47.41 % 54.93 KB/s 39s#0151.93 MB / 4.04 MB 47.79 % 55.05 KB/s 39s#0151.95 MB / 4.04 MB 48.36 % 55.40 KB/s 38s#0151.96 MB / 4.04 MB 48.54 % 55.31 KB/s 38s#0151.98 MB / 4.04 MB 49.11 % 55.64 KB/s 37s#0151.99 MB / 4.04 MB 49.30 % 55.55 KB/s 37s#0152.00 MB / 4.04 MB 49.49 % 55.46 KB/s 37s#0152.01 MB / 4.04 MB 49.68 % 55.37 KB/s 37s#0152.02 MB / 4.04 MB 50.05 % 55.50 KB/s 37s#0152.04 MB / 4.04 MB 50.43 % 55.62 KB/s 36s#0152.06 MB / 4.04 MB 51.00 % 55.94 KB/s 36s#0152.07 MB / 4.04 MB 51.19 % 55.85 KB/s 36s#0152.09 MB / 4.04 MB 51.75 % 56.17 KB/s 35s#0152.10 MB / 4.04 MB 51.94 % 56.08 KB/s 35s#0152.11 MB / 4.04 MB 52.13 % 55.99 KB/s 35s#0152.11 MB / 4.04 MB 52.32 % 55.91 KB/s 35s#0152.13 MB / 4.04 MB 52.70 % 56.02 KB/s 34s#0152.14 MB / 4.04 MB 53.08 % 56.13 KB/s 34s#0152.16 MB / 4.04 MB 53.45 % 56.24 KB/s 34s#0152.17 MB / 4.04 MB 53.83 % 56.35 KB/s 33s#0152.19 MB / 4.04 MB 54.21 % 56.46 KB/s 33s#0152.21 MB / 4.04 MB 54.78 % 56.77 KB/s 32s#0152.22 MB / 4.04 MB 54.97 % 56.68 KB/s 32s#0152.24 MB / 4.04 MB 55.53 % 56.98 KB/s 32s#0152.26 MB / 4.04 MB 55.91 % 57.08 KB/s 31s#0152.28 MB / 4.04 MB 56.48 % 57.38 KB/s 31s#0152.30 MB / 4.04 MB 56.85 % 57.48 KB/s 31s#0152.31 MB / 4.04 MB 57.23 % 57.58 KB/s 30s#0152.33 MB / 4.04 MB 57.80 % 57.86 KB/s 30s#0152.34 MB / 4.04 MB 57.99 % 57.77 KB/s 30s#0152.35 MB / 4.04 MB 58.18 % 57.68 KB/s 29s#0152.36 MB / 4.04 MB 58.36 % 57.59 KB/s 29s#0152.37 MB / 4.04 MB 58.55 % 57.23 KB/s 29s#0152.37 MB / 4.04 MB 58.74 % 57.15 KB/s 29s#0152.38 MB / 4.04 MB 58.93 % 57.06 KB/s 29s#0152.39 MB / 4.04 MB 59.12 % 56.71 KB/s 29s#0152.40 MB / 4.04 MB 59.31 % 56.63 KB/s 29s#0152.40 MB / 4.04 MB 59.50 % 56.03 KB/s 29s#0152.41 MB / 4.04 MB 59.69 % 55.96 KB/s 29s#0152.42 MB / 4.04 MB 59.88 % 55.88 KB/s 29s#0152.43 MB / 4.04 MB 60.06 % 55.80 KB/s 29s#0152.43 MB / 4.04 MB 60.25 % 55.48 KB/s 29s#0152.45 MB / 4.04 MB 60.63 % 55.58 KB/s 29s#0152.46 MB / 4.04 MB 60.82 % 55.50 KB/s 29s#0152.47 MB / 4.04 MB 61.20 % 55.36 KB/s 28s#0152.48 MB / 4.04 MB
Mar 31 20:01:29 localhost snappy[13281]: 61.39 % 55.29 KB/s 28s#0152.49 MB / 4.04 MB 61.58 % 55.21 KB/s 28s#0152.50 MB / 4.04 MB 61.76 % 55.14 KB/s 28s#0152.51 MB / 4.04 MB 62.14 % 55.24 KB/s 28s#0152.52 MB / 4.04 MB 62.33 % 55.17 KB/s 28s#0152.53 MB / 4.04 MB 62.71 % 55.27 KB/s 27s#0152.54 MB / 4.04 MB 62.90 % 55.20 KB/s 27s#0152.56 MB / 4.04 MB 63.27 % 55.29 KB/s 27s#0152.56 MB / 4.04 MB 63.46 % 55.23 KB/s 27s#0152.59 MB / 4.04 MB 64.03 % 55.49 KB/s 26s#0152.59 MB / 4.04 MB 64.22 % 55.42 KB/s 26s#0152.62 MB / 4.04 MB 64.97 % 55.83 KB/s 25s#0152.63 MB / 4.04 MB 65.16 % 55.76 KB/s 25s#0152.66 MB / 4.04 MB 65.92 % 56.18 KB/s 25s#0152.68 MB / 4.04 MB 66.30 % 56.27 KB/s 24s#0152.71 MB / 4.04 MB 67.05 % 56.68 KB/s 24s#0152.73 MB / 4.04 MB 67.62 % 56.92 KB/s 23s#0152.75 MB / 4.04 MB 68.00 % 57.01 KB/s 23s#0152.79 MB / 4.04 MB 69.13 % 57.72 KB/s 22s#0152.82 MB / 4.04 MB 69.70 % 57.96 KB/s 21s#0152.86 MB / 4.04 MB 70.83 % 58.67 KB/s 20s#0152.88 MB / 4.04 MB 71.40 % 58.90 KB/s 20s#0152.91 MB / 4.04 MB 72.15 % 59.29 KB/s 19s#0152.95 MB / 4.04 MB 73.10 % 59.82 KB/s 18s#0152.97 MB / 4.04 MB 73.47 % 59.90 KB/s 18s#0152.98 MB / 4.04 MB 73.66 % 59.81 KB/s 18s#0153.00 MB / 4.04 MB 74.23 % 60.04 KB/s 17s#0153.01 MB / 4.04 MB 74.42 % 59.96 KB/s 17s#0153.02 MB / 4.04 MB 74.80 % 60.02 KB/s 17s#0153.03 MB / 4.04 MB 74.98 % 59.94 KB/s 17s#0153.05 MB / 4.04 MB 75.55 % 60.16 KB/s 16s#0153.06 MB / 4.04 MB 75.74 % 60.08 KB/s 16s#0153.08 MB / 4.04 MB 76.31 % 60.30 KB/s 16s#0153.09 MB / 4.04 MB 76.49 % 60.22 KB/s 16s#0153.11 MB / 4.04 MB 77.06 % 60.43 KB/s 15s#0153.12 MB / 4.04 MB 77.25 % 60.35 KB/s 15s#0153.13 MB / 4.04 MB 77.44 % 60.27 KB/s 15s#0153.14 MB / 4.04 MB 77.63 % 60.19 KB/s 15s#0153.14 MB / 4.04 MB 77.82 % 60.11 KB/s 15s#0153.15 MB / 4.04 MB 78.01 % 60.03 KB/s 15s#0153.17 MB / 4.04 MB 78.38 % 59.88 KB/s 14s#0153.17 MB / 4.04 MB 78.57 % 59.80 KB/s 14s#0153.19 MB / 4.04 MB 78.95 % 59.65 KB/s 14s#0153.20 MB / 4.04 MB 79.14 % 59.36 KB/s 14s#0153.20 MB / 4.04 MB 79.33 % 59.28 KB/s 14s#0153.21 MB / 4.04 MB 79.52 % 59.21 KB/s 14s#0153.22 MB / 4.04 MB 79.71 % 58.92 KB/s 14s#0153.23 MB / 4.04 MB 79.89 % 58.85 KB/s 14s#0153.24 MB / 4.04 MB 80.08 % 58.78 KB/s 14s#0153.24 MB
Mar 31 20:01:40 localhost snappy[13281]: / 4.04 MB 80.27 % 58.71 KB/s 13s#0153.25 MB / 4.04 MB 80.46 % 58.64 KB/s 13s#0153.26 MB / 4.04 MB 80.65 % 58.57 KB/s 13s#0153.27 MB / 4.04 MB 80.84 % 58.50 KB/s 13s#0153.28 MB / 4.04 MB 81.22 % 58.57 KB/s 13s#0153.29 MB / 4.04 MB 81.41 % 58.50 KB/s 13s#0153.30 MB / 4.04 MB 81.78 % 58.57 KB/s 12s#0153.31 MB / 4.04 MB 81.97 % 58.50 KB/s 12s#0153.32 MB / 4.04 MB 82.16 % 58.43 KB/s 12s#0153.33 MB / 4.04 MB 82.54 % 58.50 KB/s 12s#0153.35 MB / 4.04 MB 82.92 % 58.57 KB/s 12s#0153.36 MB / 4.04 MB 83.11 % 58.50 KB/s 11s#0153.38 MB / 4.04 MB 83.67 % 58.70 KB/s 11s#0153.39 MB / 4.04 MB 83.86 % 58.63 KB/s 11s#0153.41 MB / 4.04 MB 84.43 % 58.83 KB/s 10s#0153.43 MB / 4.04 MB 84.80 % 58.89 KB/s 10s#0153.46 MB / 4.04 MB 85.56 % 59.22 KB/s 10s#0153.47 MB / 4.04 MB 85.94 % 59.28 KB/s 9s#0153.51 MB / 4.04 MB 86.88 % 59.73 KB/s 9s#0153.53 MB / 4.04 MB 87.26 % 59.79 KB/s 8s#0153.56 MB / 4.04 MB 88.20 % 60.24 KB/s 8s#0153.58 MB / 4.04 MB 88.58 % 60.30 KB/s 7s#0153.60 MB / 4.04 MB 89.15 % 60.49 KB/s 7s#0153.62 MB / 4.04 MB 89.53 % 60.54 KB/s 7s#0153.63 MB / 4.04 MB 89.90 % 60.60 KB/s 6s#0153.66 MB / 4.04 MB 90.66 % 60.91 KB/s 6s#0153.69 MB / 4.04 MB 91.23 % 61.09 KB/s 5s#0153.70 MB / 4.04 MB 91.60 % 61.15 KB/s 5s#0153.71 MB / 4.04 MB 91.79 % 61.08 KB/s 5s#0153.74 MB / 4.04 MB 92.55 % 61.38 KB/s 5s#0153.75 MB / 4.04 MB 92.93 % 61.43 KB/s 4s#0153.78 MB / 4.04 MB 93.68 % 61.73 KB/s 4s#0153.79 MB / 4.04 MB 93.87 % 61.66 KB/s 4s#0153.81 MB / 4.04 MB 94.44 % 61.84 KB/s 3s#0153.83 MB / 4.04 MB 94.81 % 61.69 KB/s 3s#0153.84 MB / 4.04 MB 95.00 % 61.62 KB/s 3s#0153.86 MB / 4.04 MB 95.57 % 61.79 KB/s 2s#0153.87 MB / 4.04 MB 95.76 % 61.72 KB/s 2s#0153.89 MB / 4.04 MB 96.33 % 61.90 KB/s 2s#0153.91 MB / 4.04 MB 96.70 % 61.95 KB/s 2s#0153.92 MB / 4.04 MB 97.08 % 62.00 KB/s 1s#0153.95 MB / 4.04 MB 97.84 % 62.28 KB/s 1s#0153.96 MB / 4.04 MB 98.02 % 62.21 KB/s 1s#0153.98 MB / 4.04 MB 98.59 % 62.38 KB/s #0154.00 MB / 4.04 MB 98.97 % 62.24 KB/s #0154.01 MB / 4.04 MB 99.16 % 61.98 KB/s #0154.01 MB / 4.04 MB 99.35 % 61.91 KB/s #0154.02 MB / 4.04 MB 99.54 % 61.47 KB/s #0154.03 MB / 4.04 MB 99.72 % 61.22 KB/s #0154.04 MB / 4.04 MB 99.91 % 61.16 KB/s #0154.
Mar 31 20:01:40 localhost snappy[13281]: 04 MB / 4.04 MB 100.00 % 61.04 KB/s
Mar 31 20:01:40 localhost snappy[13281]: Done

[James Hunt (jamesodhunt)]

- Can you recreate on newer images? I cannot reproduce this on r354.
- Are the messages in syslog+journalctl?
- Are you logging in via the console or via ssh?
- Do you recall if the messages from syslog also appeared on stdout?

[Jamie Strandboge (jdstrand)]

"- Can you recreate on newer images? I cannot reproduce this on r354."

Yes. Recreated today with a freshly udf'd
$ system-image-cli -i
current build number: 356
device name: generic_amd64
channel: ubuntu-core/devel-proposed
last update: 2015-04-01 05:44:53
version version: 356
version ubuntu: 20150401
version raw-device: 20150401

"- Are the messages in syslog+journalctl?"
They are in syslog for sure. I just realized I rebooted and not sure if they were in journalctl. I tried to reproduce the issue and could not immediately (perhaps there is a race?)

"- Are you logging in via the console or via ssh?"
ssh

"- Do you recall if the messages from syslog also appeared on stdout?"
'sudo snappy install' gives a progress bar for downloading if that is what you mean.

Attached it the full /var/log/syslog (see: "Apr 1 15:00:46 localhost snappy[965]: 7.12 KB / 4.04 MB 0.17 % 2.20 MB/s 1s...".

[James Hunt (jamesodhunt)]

Thanks for the information! I still cannot recreate this using the same image version and ssh'ing in ("ssh -p $port ubuntu@localhost" for me). Do you only see the problem when logging in via ssh? Are you simply running the snappy command directly or via some sort of wrapper / test harness that might be modifying the behaviour?

[Michael Vogt (mvo)]

I can reproduce this, it takes some seconds (minutes?) before it shows up in /var/log/syslog but I also see the download progress output in r361.

[Michael Vogt (mvo)]

This was from the terminal, no ssh involved.

[James Hunt (jamesodhunt)]

Still not able to reproduce using kvm and r361 (amd64) after waiting for 10+ minutes and then forcibly flushing journalctl's buffers.

Please can those affected provide precise instructions.

[Jamie Strandboge (jdstrand)]

From irc:
12:02 < jdstrand> jodh: oh, haha. I just booted r360 and see the logging issue
12:02 < jdstrand> jodh: I didn't even do anything yet
12:02 < jdstrand> jodh: but, I think I have a clue
12:02 * jdstrand goes to paste
12:03 < jdstrand> jodh: http://paste.ubuntu.com/10764132/
12:04 < jdstrand> jodh: so, steps to reproduce: create an older image (eg, r360 on amd64), boot it, let Autopilot kick in to install r361 in the background, see the progress in the logs

[James Hunt (jamesodhunt)]

Ironically, the reason I couldn't recreate was that I was running snappy commands in a tight loop and thus blocking the culprit: the autopilot service: /lib/systemd/system/snappy-autopilot.service.

The plan is to disable progress output either via a command-line option, or ideally have snappy automatically detect it isn't connected to a terminal and auto-suppress the progress output.

[James Hunt (jamesodhunt)]

Current branch: https://code.launchpad.net/~jamesodhunt/snappy/add-quiet-mode-for-bug-1438889

[James Hunt (jamesodhunt)]

Fixed by https://code.launchpad.net/~jamesodhunt/snappy/suppress-progress-if-no-terminal/+merge/255544.