[e-access] 毎日、同じ時間に、同じ遅延が発生している

先日、イーアクセスが夜22時～翌01時くらいまで遅いという投稿をしました。

tracerouteの結果からイーアクセスの設備内で遅延が起こっている様子がおわかりいただけたかと思います。

で、それがどの程度、操作感に影響しているのか。
自宅のunboundの統計を採ってグラフ化しているので、それを見てもらうと直感的にわかりやすいかな。
まずはDailyとWeeklyの統計です。
該当の時間帯にDNS ServiceTimeがスパイクしている様子が伝わりますかね？

Weekly Graphがわかりやすいかな。
毎日、同じ時間に同じ遅延が発生しているんですよね。
綺麗に+100msくらいの遅延が起こる。逆に100ms以上遅延することはほとんどない。
なんでかな？

Yearly Graphからわかるのは、9月まではこの事象は無く、10月初めから徐々にこの事象が発生しはじめ、
いまや平均のDNS Service Timeが15ms→45msまで高止まりしていることがわかります。

MRTGでは過去の詳細グラフが見られず、あくまで"ある期間の平均"だけですが
感覚的に"3倍くらい遅くなってる"傾向は伝わるかと思います。

なお、8月頃に45msくらいを記録しているのは、当初、unboundをvalidatorで動かしていたからです。
今は（niftyのDNSを信用して）iteratorだけで動かしてます。

[e-access]最近のイーアクセスが夜21時～翌01時まで遅い

意図的なのか偶然なのかわかりませんけど、今年10月以降のイーアクセスは夜21時から翌01時くらいまでのインターネットアクセスがとっても遅いです。この時間帯のtracerouteで調べると、アクセスポイントのすぐ先で+100msくらい遅延します。

ちなみに21時直前はこんな感じ。

pi@raspberrypi4 ~ $ date
2015年 11月 17日 火曜日 20:59:36 JST
pi@raspberrypi4 ~ $ traceroute www.iij.ad.jp
traceroute to www.iij.ad.jp (202.232.2.164), 30 hops max, 60 byte packets
1 aterm.me (192.168.0.1) 0.490 ms 0.335 ms 0.237 ms
2 218.223.128.1.eo.eaccess.ne.jp (218.223.128.1) 12.062 ms 12.088 ms 12.848 ms
3 218.231.129.41 (218.231.129.41) 14.906 ms 15.848 ms 16.809 ms <--ここに注目
4 211.14.193.161 (211.14.193.161) 17.940 ms 18.787 ms 19.663 ms
5 211.15.42.13 (211.15.42.13) 20.872 ms 211.15.42.89 (211.15.42.89) 21.983 ms 211.15.42.1 (211.15.42.1) 23.114 ms
6 * * *
7 * * *
8 * * *
9 * * *
10 202.232.8.177 (202.232.8.177) 19.412 ms 19.657 ms 21.346 ms
11 tky009bf00.IIJ.Net (58.138.83.109) 22.266 ms tky008bf00.IIJ.Net (58.138.83.101) 24.382 ms tky008bf01.IIJ.Net (58.138.83.105) 25.243 ms
12 tky008bb00.IIJ.Net (58.138.80.50) 26.153 ms tky008bb01.IIJ.Net (58.138.80.246) 26.740 ms 27.971 ms
13 tky008sdgw30.IIJ.Net (58.138.105.122) 28.061 ms tky008sdgw30.IIJ.Net (58.138.105.214) 29.480 ms tky008sdgw30.IIJ.Net (58.138.105.186) 15.629 ms
14 tky008sdgw20a.IIJ.Net (210.138.115.66) 16.311 ms 17.392 ms 17.495 ms
15 tky008sdgw21a.IIJ.Net (210.138.146.213) 16.655 ms 15.256 ms 15.753 ms
16 www.iij.ad.jp (202.232.2.164) 18.120 ms 17.044 ms 15.654 ms
pi@raspberrypi4 ~ $

21時を過ぎると急に30msほど遅延するようになります・・・

pi@raspberrypi4 ~ $ date
2015年 11月 17日 火曜日 21:01:52 JST
pi@raspberrypi4 ~ $ traceroute www.iij.ad.jp
traceroute to www.iij.ad.jp (202.232.2.164), 30 hops max, 60 byte packets
1 aterm.me (192.168.0.1) 0.404 ms 0.298 ms 0.336 ms
2 218.223.128.1.eo.eaccess.ne.jp (218.223.128.1) 11.646 ms 13.150 ms 13.494 ms
3 218.231.129.41 (218.231.129.41) 37.679 ms 38.707 ms 39.577 ms <--ここで+30ms！
4 211.14.193.161 (211.14.193.161) 40.237 ms 41.093 ms 42.268 ms
5 211.15.42.1 (211.15.42.1) 43.425 ms 211.15.42.57 (211.15.42.57) 44.859 ms 211.15.42.89 (211.15.42.89) 45.244 ms
6 * * *
7 * * *
8 * * *
9 * * *
10 202.232.8.177 (202.232.8.177) 34.215 ms 34.456 ms 35.885 ms
11 tky001bf00.IIJ.Net (58.138.83.93) 36.864 ms tky009bf01.IIJ.Net (58.138.83.113) 38.470 ms tky001bf01.IIJ.Net (58.138.83.97) 39.815 ms
12 tky008bb01.IIJ.Net (58.138.85.166) 41.465 ms tky008bb00.IIJ.Net (58.138.80.70) 42.859 ms tky008bb01.IIJ.Net (58.138.80.234) 43.556 ms
13 tky008sdgw30.IIJ.Net (58.138.105.186) 43.593 ms tky008sdgw30.IIJ.Net (58.138.105.210) 43.750 ms 30.990 ms
14 tky008sdgw20a.IIJ.Net (210.138.115.66) 31.957 ms 31.962 ms 31.848 ms
15 tky008sdgw21a.IIJ.Net (210.138.146.213) 30.989 ms 29.487 ms 29.365 ms
16 www.iij.ad.jp (202.232.2.164) 31.260 ms 32.063 ms 29.199 ms
pi@raspberrypi4 ~ $

21:30を過ぎると、うわー。やばい。

pi@raspberrypi4 ~ $ date
2015年 11月 17日 火曜日 21:45:45 JST
pi@raspberrypi4 ~ $ traceroute www.iij.ad.jp
traceroute to www.iij.ad.jp (202.232.2.164), 30 hops max, 60 byte packets
1 aterm.me (192.168.0.1) 0.391 ms 0.302 ms 0.325 ms
2 218.223.128.1.eo.eaccess.ne.jp (218.223.128.1) 12.334 ms 12.374 ms 14.620 ms
3 218.231.129.41 (218.231.129.41) 70.967 ms 71.734 ms 71.850 ms
4 211.14.193.161 (211.14.193.161) 73.793 ms 74.378 ms 75.035 ms
5 211.15.42.13 (211.15.42.13) 77.710 ms 211.15.42.1 (211.15.42.1) 78.557 ms 211.15.42.13 (211.15.42.13) 79.233 ms
6 * * *
7 * * *
8 * * *
9 * * *
10 202.232.8.177 (202.232.8.177) 70.019 ms 71.055 ms 71.966 ms
11 tky009bf00.IIJ.Net (58.138.83.109) 73.380 ms tky008bf00.IIJ.Net (58.138.83.101) 75.256 ms tky009bf01.IIJ.Net (58.138.83.113) 74.940 ms
12 tky008bb00.IIJ.Net (58.138.80.70) 78.053 ms tky008bb01.IIJ.Net (58.138.80.246) 78.043 ms tky008bb00.IIJ.Net (58.138.80.70) 79.350 ms
13 tky008sdgw30.IIJ.Net (58.138.105.186) 79.401 ms tky008sdgw30.IIJ.Net (58.138.105.198) 79.800 ms tky008sdgw30.IIJ.Net (58.138.105.122) 66.418 ms
14 tky008sdgw20a.IIJ.Net (210.138.115.66) 66.663 ms 67.859 ms 67.039 ms
15 tky008sdgw21a.IIJ.Net (210.138.146.213) 67.364 ms 66.394 ms 67.542 ms
16 www.iij.ad.jp (202.232.2.164) 68.862 ms 70.694 ms 69.499 ms
pi@raspberrypi4 ~ $

22時直後。あー。もうだめですね。

pi@raspberrypi4 ~ $ date
2015年 11月 17日 火曜日 22:03:05 JST
pi@raspberrypi4 ~ $ traceroute www.iij.ad.jp
traceroute to www.iij.ad.jp (202.232.2.164), 30 hops max, 60 byte packets
1 aterm.me (192.168.0.1) 0.422 ms 0.273 ms 0.227 ms
2 218.223.128.1.eo.eaccess.ne.jp (218.223.128.1) 12.450 ms 12.291 ms 12.935 ms
3 218.231.129.41 (218.231.129.41) 102.899 ms 104.349 ms 104.508 ms
4 211.14.193.161 (211.14.193.161) 106.446 ms 108.487 ms 108.641 ms
5 211.15.42.57 (211.15.42.57) 109.267 ms 211.15.42.1 (211.15.42.1) 109.404 ms 110.064 ms
6 * * *
7 * * *
8 * * *
9 * * *
10 202.232.8.177 (202.232.8.177) 113.472 ms 113.731 ms 115.389 ms
11 tky009bf00.IIJ.Net (58.138.83.109) 116.268 ms tky008bf01.IIJ.Net (58.138.83.105) 118.145 ms tky008bf00.IIJ.Net (58.138.83.101) 119.345 ms
12 tky008bb00.IIJ.Net (58.138.80.50) 120.666 ms tky008bb00.IIJ.Net (58.138.80.70) 121.588 ms tky008bb01.IIJ.Net (58.138.85.170) 121.641 ms
13 tky008sdgw30.IIJ.Net (58.138.105.122) 122.861 ms tky008sdgw30.IIJ.Net (58.138.105.186) 123.788 ms tky008sdgw30.IIJ.Net (58.138.105.210) 107.864 ms
14 tky008sdgw20a.IIJ.Net (210.138.115.66) 110.069 ms 110.742 ms 109.612 ms
15 tky008sdgw21a.IIJ.Net (210.138.146.213) 110.191 ms 108.425 ms 108.406 ms
16 www.iij.ad.jp (202.232.2.164) 110.030 ms 109.927 ms 107.528 ms
pi@raspberrypi4 ~ $

毎日同じ時間帯で同じように遅延が入るので、イーアクセスが何か調整しているのではないかと勘ぐってみたり。なぜなら、逆に100ms以上は遅延しませんし。

この現象、10月くらいからなんですよ。
詳しくはまた次回で。

RaspberryPiでELECOM WDC-150SU2Mを使う

省電力に惹かれて買ったが、RaspberryPiに挿しただけだと次のメッセージがdmesgに出るだけで使えない。


$ dmesg|tail
R8188EU: ERROR indicate disassoc
R8188EU: ERROR indicate disassoc
R8188EU: ERROR indicate disassoc


以下の記事が参考になった。
Setup r8188eu with Raspbian the easy way
http://blog.thibmaekelbergh.be/2015/04/02/setup-r8188eu-with-raspbian-the-easy-way.html

自動でカーネルにあったドライバをインストールしてくれる。
簡単なので手順を紹介する。

mkdir drivers
cd drivers
wget https://dl.dropboxusercontent.com/u/80256631/install-8188eu.tgz
tar -zxvf install-8188eu.tgz
sudo ./install-8188eu.sh


再起動するとWDC-150SU2Mが使えるようになる。

※この方法はカーネル3.18.11+では使えない。上記リンクから個別の対応を参考にしてほしい。

今度はCRDAに関するメッセージが出るようになる。

$ dmesg|tail
[ 19.913080] cfg80211: Calling CRDA to update world regulatory domain
[ 23.073084] cfg80211: Calling CRDA to update world regulatory domain
(中略)
cfg80211: Exceeded CRDA call max attempts. Not calling CRDA


このままでも問題は無いと思われるが、メッセージを出さなくするには
パッケージiwをインストールして、地域に日本を設定する。

sudo apt-get install iw
sudo iw reg set JP
sudo iw reg get
country JP:
(2402 - 2482 @ 40), (N/A, 20)
(2474 - 2494 @ 20), (N/A, 20), NO-OFDM
(4910 - 4990 @ 40), (N/A, 23)
(5030 - 5090 @ 40), (N/A, 23)
(5170 - 5250 @ 80), (N/A, 20)
(5250 - 5330 @ 80), (N/A, 20), DFS
(5490 - 5710 @ 160), (N/A, 23), DFS


ここまで。