aws_iot_mqtt_led_demoがうまくいきません

ROSE本に従ってIDE4GRのExamples_FreeRTOS/aws_iot_mqtt_led_demoを試しているのですが、うまくいきません。

実施した内容としては、「Connect to AWS Greengrass-Renesas」のクイック接続からダウンロードしたaws_clientcredential.hとaws_clientcredential_keys.hをファイル追加し、aws_iot_mqtt_led_demo.inoと同じディレクトリにある状態にしてコンパイルしています。
Etherのケーブルはつないであります。

Mac、Windowsでシリアルモニタに表示されるメッセージが少し異なりますが、どちらもAWS IoTにつながりませんでした。
シリアルモニタには以下のように表示されました。

---- Mac ----
197 [MAIN_TASK] Creating MQTT Echo Task...
1 197 [MQTTEcho] MQTT echo attempting to connect to a2q1o77b4s8skq-ats.iot.us-east-2.amazonaws.com.
2 5261 [IP-task] vDHCPProcess: discover
3 5261 [IP-task] vDHCPProcess: timeout 10000 ticks
4 6265 [IP-task] vDHCPProcess: offer c0a8b315ip
5 6265 [IP-task] vDHCPProcess: reply c0a8b315ip
6 6268 [IP-task] vDHCPProcess: offer c0a8b315ip
7 6268 [IP-task] vDHCPProcess: acked c0a8b315ip
8 6268 [IP-task] IP Address: 192.168.179.21
9 6268 [IP-task] Subnet Mask: 255.255.255.0
10 6268 [IP-task] Gateway Address: 192.168.179.1
11 6268 [IP-task] DNS Server Address: 192.168.179.1
12 9000 [Tmr Svc] data flash(main) hash check...
13 9004 [Tmr Svc] OK
14 9005 [Tmr Svc] data flash(mirror) hash check...
15 9009 [Tmr Svc] OK
16 9011 [Tmr Svc] Write certificate...
17 9011 [Tmr Svc] data flash(main) hash check...
18 9015 [Tmr Svc] OK
19 9015 [Tmr Svc] data flash(mirror) hash check...
20 9020 [Tmr Svc] OK
21 9024 [Tmr Svc] erase codeflash(main)...
22 9025 [Tmr Svc] OK
23 9025 [Tmr Svc] write codeflash(main)...
24 9026 [Tmr Svc] OK
25 9026 [Tmr Svc] OK
26 9026 [Tmr Svc] write codeflash(mirror)...
42 10260 [MQTT] prvProcessDNSCache: add: 'a2q1o77b4s8skq-ats.iot.us-east-2.amazonaws.com' @ 12dd4164ip
43 10260 [MQTT] FreeRTOS_connect: 8924 to 12dd4164ip:8883
44 10260 [MQTT] Socket 8924 -> 12dd4164ip:8883 State eCLOSED->eCONNECT_SYN
45 10260 [IP-task] prvSocketSetMSS: 1400 bytes for 12dd4164ip:8883
46 10260 [IP-task] Connect[12dd4164ip:8883]: next timeout 1: 3000 ms
47 10459 [IP-task] MSS change 1400 -> 1380
48 10459 [IP-task] Change mss 1400 => 1380
49 10459 [IP-task] Socket 8924 -> 12dd4164ip:8883 State eCONNECT_SYN->eESTABLISHED
50 10459 [MQTT] data flash(main) hash check...
51 10464 [MQTT] OK
52 10464 [MQTT] data flash(mirror) hash check...
53 10469 [MQTT] OK
54 10531 [IP-task] Socket 8924 -> 12dd4164ip:8883 State eESTABLISHED->eFIN_WAIT_1
55 10531 [IP-task] FreeRTOS_closesocket[8924 to 12dd4164ip:8883]: buffers 10 socks 0
56 10532 [MQTTEcho] ERROR: MQTT echo failed to connect with error 1.
57 10532 [MQTTEcho] MQTT echo test could not connect to broker.
58 10533 [MQTTEcho] MQTT echo demo finished.
59 10533 [MQTTEcho] ----Demo finished----
60 10739 [IP-task] TCP: No active socket on port 8924 (12dd4164ip:8883)
----

---- Windows ----
287 [MAIN_TASK] Creating MQTT Echo Task...
1 287 [MQTTEcho] MQTT echo attempting to connect to a2q1o77b4s8skq-ats.iot.us-east-2.amazonaws.com.
2 5012 [IP-task] vDHCPProcess: discover
3 5012 [IP-task] vDHCPProcess: timeout 10000 ticks
4 6016 [IP-task] vDHCPProcess: offer c0a8b315ip
5 6016 [IP-task] vDHCPProcess: reply c0a8b315ip
6 6018 [IP-task] vDHCPProcess: offer c0a8b315ip
7 6018 [IP-task] vDHCPProcess: acked c0a8b315ip
8 6019 [IP-task] IP Address: 192.168.179.21
9 6019 [IP-task] Subnet Mask: 255.255.255.0
10 6019 [IP-task] Gateway Address: 192.168.179.1
11 6019 [IP-task] DNS Server Address: 192.168.179.1
12 9000 [Tmr Svc] data flash(main) hash check...
13 9004 [Tmr Svc] OK
14 9005 [Tmr Svc] data flash(mirror) hash check...
15 9009 [Tmr Svc] OK
16 9009 [Tmr Svc] ERROR: Failed to provision certificate -1
17 9009 [Tmr Svc] Write certificate...
18 9009 [Tmr Svc] ERROR: Failed to provision certificate 7
19 9009 [Tmr Svc] ERROR: Failed to provision private key 7
20 10347 [MQTT] prvProcessDNSCache: add: 'a2q1o77b4s8skq-ats.iot.us-east-2.amazonaws.com' @ 12dd4164ip
21 10347 [MQTT] FreeRTOS_connect: 26129 to 12dd4164ip:8883
22 10347 [MQTT] Socket 26129 -> 12dd4164ip:8883 State eCLOSED->eCONNECT_SYN
23 10348 [IP-task] prvSocketSetMSS: 1400 bytes for 12dd4164ip:8883
24 10348 [IP-task] Connect[12dd4164ip:8883]: next timeout 1: 3000 ms
25 10550 [IP-task] MSS change 1400 -> 1380
26 10550 [IP-task] Change mss 1400 => 1380
27 10550 [IP-task] Socket 26129 -> 12dd4164ip:8883 State eCONNECT_SYN->eESTABLISHED
28 10550 [MQTT] data flash(main) hash check...
29 10554 [MQTT] OK
30 10554 [MQTT] data flash(mirror) hash check...
31 10559 [MQTT] OK
32 10565 [MQTT] ERROR: Object with label Device Priv TLS Key not found.
33 10565 [IP-task] Socket 26129 -> 12dd4164ip:8883 State eESTABLISHED->eFIN_WAIT_1
34 10565 [IP-task] FreeRTOS_closesocket[26129 to 12dd4164ip:8883]: buffers 10 socks 0
35 10566 [MQTTEcho] ERROR: MQTT echo failed to connect with error 1.
36 10567 [MQTTEcho] MQTT echo test could not connect to broker.
37 10567 [MQTTEcho] MQTT echo demo finished.
38 10567 [MQTTEcho] ----Demo finished----
39 10770 [IP-task] TCP: No active socket on port 26129 (12dd4164ip:8883)
----

aws_clientcredential.hにWifi情報を入れる箇所があったので、SSIDとPWを入れてEtherケーブルを抜いて試してみましたが、こちらもうまくいきませんでした。

うまく行かない原因がわからなかったので解析のポイントなどを教えていただけないでしょうか。

AWS IoTコンソールの方は自分でPublishしたTopic: freertos/demos/echoへの{"command": "led on"}がSubscribeの窓に表示されるのでBrokerの準備はできているように思いますが、通知に以下の内容が出ているのが気になっています。

--
2019/08/11 17:30:56 ユーザーポリシーにアクセスするための十分なアクセス許可がありませんAmazonCloudWatch。cloudwatch:GetMetricStatisticsユーザーポリシー (IAM 管理コンソールにある) にアクセス許可を追加してください。
--

「AmazonFreeRTOSFullAccess」と「AWSIoTFullAccess」はアタッチ済みです。アカウントの管理者権限が無いためのメッセージで無視して良いのでしょうか。

0 Okamiya Yuuki over 4 years ago

がじぇるね岡宮です。

私の方でも試してみましたが、正常にできました。設定で何か悪いのか分かりませんが、まずは動いたのでご報告です。

環境としては、IDE for GR V1.09で「aws_iot_mqtt_led_demo」をそのまま実行しています。

1 285 [MQTTEcho] MQTT echo attempting to connect to a21b1q1p5czmsn-ats.iot.us-east-2.amazonaws .com.
2 5261 [IP-task] vDHCPProcess: discover
3 5261 [IP-task] vDHCPProcess: timeout 10000 ticks
4 5765 [IP-task] vDHCPProcess: offer c0a8000bip
5 5765 [IP-task] vDHCPProcess: reply c0a8000bip
6 5771 [IP-task] vDHCPProcess: offer c0a8000bip
7 5771 [IP-task] vDHCPProcess: acked c0a8000bip
8 5771 [IP-task] IP Address: 192.168.0.11
9 5771 [IP-task] Subnet Mask: 255.255.255.0
10 5771 [IP-task] Gateway Address: 192.168.0.1
11 5771 [IP-task] DNS Server Address: 192.168.0.1
12 6000 [Tmr Svc] data flash(main) hash check...
13 6004 [Tmr Svc] OK
14 6005 [Tmr Svc] data flash(mirror) hash check...
15 6009 [Tmr Svc] OK
16 6011 [Tmr Svc] Write certificate...
17 6011 [Tmr Svc] data flash(main) hash check...
18 6015 [Tmr Svc] OK
19 6015 [Tmr Svc] data flash(mirror) hash check...
20 6020 [Tmr Svc] OK
21 6024 [Tmr Svc] erase codeflash(main)...
22 6025 [Tmr Svc] OK
23 6025 [Tmr Svc] write codeflash(main)...
24 6026 [Tmr Svc] OK
25 6026 [Tmr Svc] OK
26 6026 [Tmr Svc] write codeflash(mirror)...
42 10291 [MQTT] prvProcessDNSCache: add: 'a21b1q1p5czmsn-ats.iot.us-east-2.amazonaws.com' @ 12 da6e17ip
43 10291 [MQTT] FreeRTOS_connect: 17043 to 12da6e17ip:8883
44 10291 [MQTT] Socket 17043 -> 12da6e17ip:8883 State eCLOSED->eCONNECT_SYN
45 10292 [IP-task] prvSocketSetMSS: 1400 bytes for 12da6e17ip:8883
46 10292 [IP-task] Connect[12da6e17ip:8883]: next timeout 1: 3000 ms
47 10457 [IP-task] MSS change 1400 -> 1460
48 10457 [IP-task] Socket 17043 -> 12da6e17ip:8883 State eCONNECT_SYN->eESTABLISHED
49 10457 [MQTT] data flash(main) hash check...
50 10461 [MQTT] OK
51 10461 [MQTT] data flash(mirror) hash check...
52 10466 [MQTT] OK
53 13311 [MQTTEcho] MQTT echo connected.
54 13311 [MQTTEcho] MQTT echo test echoing task created.
55 13489 [MQTTEcho] MQTT Echo demo subscribed to freertos/demos/echo
56 13667 [MQTTEcho] MQTT Echo demo subscribed to freertos/demos/sensor
57 13840 [MQTTEcho] Echo successfully published 'Hello World 0'
58 13865 [IP-task] win_tx_ack: acked 1721 expc 1721 len 69
59 14015 [MQTTEcho] Echo successfully published 'Hello World 0'
60 14030 [IP-task] win_tx_ack: acked 1790 expc 1790 len 104
61 14039 [IP-task] win_tx_ack: acked 1790 expc 1790 len 104
62 14205 [Echoing] Message returned with ACK: 'Hello World 0 ACK'
63 14230 [IP-task] win_tx_ack: acked 1894 expc 1894 len 33
64 14588 [Echoing] Message returned with ACK: 'Hello World 0 ACK'

65 44191 [MQTTEcho] Echo successfully published 'Hello World 1'
66 44217 [IP-task] win_tx_ack: acked 2131 expc 2131 len 69
67 44366 [MQTTEcho] Echo successfully published 'Hello World 1'
68 44382 [IP-task] win_tx_ack: acked 2200 expc 2200 len 104
69 44392 [IP-task] win_tx_ack: acked 2200 expc 2200 len 104
70 44541 [Echoing] Message returned with ACK: 'Hello World 1 ACK'
71 44567 [IP-task] win_tx_ack: acked 2304 expc 2304 len 33
72 44921 [Echoing] Message returned with ACK: 'Hello World 1 ACK'
73 72636 [Echoing] Message returned with ACK: '{
 "command": "led on"
} ACK'
74 74539 [MQTTEcho] Echo successfully published 'Hello World 2'
75 74565 [IP-task] win_tx_ack: acked 2657 expc 2657 len 69
76 74715 [MQTTEcho] Echo successfully published 'Hello World 2'
77 74729 [IP-task] win_tx_ack: acked 2726 expc 2726 len 104
78 74741 [IP-task] win_tx_ack: acked 2726 expc 2726 len 104
79 74917 [Echoing] Message returned with ACK: 'Hello World 2 ACK'
80 74942 [IP-task] win_tx_ack: acked 2830 expc 2830 len 33
81 75299 [Echoing] Message returned with ACK: 'Hello World 2 ACK'
82 80504 [Echoing] Message returned with ACK: '{
 "command": "led off"
} ACK'

0 竹のしん over 4 years ago in reply to Okamiya Yuuki

岡宮さん、お返事ありがとうございます。
私もIDE for GR V1.09のスケッチ例「aws_iot_mqtt_led_demo」をそのまま実行しています。
GR-ROSEは量産版ではなくプロトタイプ版を使用していますが、影響ありますでしょうか？
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Okamiya Yuuki over 4 years ago in reply to 竹のしん

がじぇるね岡宮です。

Ethernetの回路は変更ありませんので、影響はないと思います。TLS関連でCredential が怪しい気もするのですが、ちょっと今のところ分かりません。
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Okamiya Yuuki over 4 years ago in reply to Okamiya Yuuki

ちょっと問題を切り分けたいのですが、Credentialのヘッダーを追加しない場合にどうなるか試してみていただけますか？
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 竹のしん over 4 years ago in reply to Okamiya Yuuki

aws_clientcredential.hおよびaws_clientcredential_keys.hをファイル追加しない場合、IDE for GRに登録されているヘッダファイルが使われたようで、シリアルモニタの表示はMac/Winとも以下のように変わりました。
----
347 [MAIN_TASK] Creating MQTT Echo Task...
1 347 [MQTTEcho] MQTT echo attempting to connect to Paste AWS IoT Broker endpoint here..
2 5261 [IP-task] vDHCPProcess: discover
3 5261 [IP-task] vDHCPProcess: timeout 10000 ticks
4 6266 [IP-task] vDHCPProcess: offer c0a8b315ip
5 6266 [IP-task] vDHCPProcess: reply c0a8b315ip
6 6268 [IP-task] vDHCPProcess: offer c0a8b315ip
7 6268 [IP-task] vDHCPProcess: acked c0a8b315ip
8 6269 [IP-task] IP Address: 192.168.179.21
9 6269 [IP-task] Subnet Mask: 255.255.255.0
10 6269 [IP-task] Gateway Address: 192.168.179.1
11 6269 [IP-task] DNS Server Address: 192.168.179.1
12 9000 [Tmr Svc] data flash(main) hash check...
13 9005 [Tmr Svc] OK
14 9005 [Tmr Svc] data flash(mirror) hash check...
15 9009 [Tmr Svc] OK
16 9009 [Tmr Svc] ERROR: Failed to provision certificate -1
17 9009 [Tmr Svc] Write certificate...
18 9009 [Tmr Svc] ERROR: Failed to provision certificate 7
19 9010 [Tmr Svc] ERROR: Failed to provision private key 7
20 10466 [MQTT] FreeRTOS_connect: 34664 to 0ip:8883
21 10466 [MQTT] Socket 34664 -> 0ip:8883 State eCLOSED->eCONNECT_SYN
22 10467 [IP-task] prvSocketSetMSS: 1400 bytes for 0ip:8883
23 10467 [IP-task] Connect[0ip:8883]: next timeout 1: 3000 ms
24 13466 [IP-task] Connect[0ip:8883]: next timeout 2: 6000 ms
25 19466 [IP-task] Connect[0ip:8883]: next timeout 3: 11000 ms
26 20467 [IP-task] FreeRTOS_closesocket[34664 to 0ip:8883]: buffers 10 socks 0
27 20467 [MQTTEcho] ERROR: MQTT echo failed to connect with error 1.
28 20467 [MQTTEcho] MQTT echo test could not connect to broker.
29 20467 [MQTTEcho] MQTT echo demo finished.
30 20467 [MQTTEcho] ----Demo finished----
----
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Okamiya Yuuki over 4 years ago in reply to 竹のしん

竹のしんさん、リプライ遅くすみません。ご確認ありがとうございました。

ちょっと「aws_iot_mqtt_led_demo.inoと同じディレクトリにある状態」というのが引っかかっています。

以下のようにIDEのメニューからファイル追加をすると、

Windowsの場合はコンパイル対象のcppファイルやヘッダファイルが、Local/Temp/xxx.tmpフォルダに集められます。IDEのメニューから追加したCredential用のヘッダも追加されるため、sketchでインクルードした際にはこのフォルダ内に存在するヘッダが使用されるようになります。

ですので、竹のしんさんのファイル追加方法が上記と異なるようでしたら、合わせてみていただけますでしょうか。

なお、私の手元にあったβ版ボードでも正常動作しましたので、ハードウェア依存はないと考えられます。

以下、ログの相違の抜粋です。

●正常に動作しないとき

16 9009 [Tmr Svc] ERROR: Failed to provision certificate -1

17 9009 [Tmr Svc] Write certificate...

18 9009 [Tmr Svc] ERROR: Failed to provision certificate 7

19 9009 [Tmr Svc] ERROR: Failed to provision private key 7

●正常に動作するとき

16 6011 [Tmr Svc] Write certificate...

17 6011 [Tmr Svc] data flash(main) hash check...

18 6015 [Tmr Svc] OK

19 6015 [Tmr Svc] data flash(mirror) hash check...

20 6020 [Tmr Svc] OK

21 6024 [Tmr Svc] erase codeflash(main)...

22 6025 [Tmr Svc] OK

23 6025 [Tmr Svc] write codeflash(main)...

24 6026 [Tmr Svc] OK

25 6026 [Tmr Svc] OK

26 6026 [Tmr Svc] write codeflash(mirror)...
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 竹のしん over 4 years ago in reply to Okamiya Yuuki

岡宮さん、お返事ありがとうございます。

同じ方法でファイル追加しているつもりです。具体的には以下です。
1. IDE for GRを立ち上げ、「ファイル」→「スケッチの例」→「Examples_FreeRTOS」→「aws_iot_mqtt_led_demo」
2. 「ファイル」→「名前をつけて保存」
3. 「スケッチ」→「ファイル追加」からaws_clientcredential.hとaws_clientcredential_keys.hをそれぞれ追加。※これでaws_iot_mqtt_led_demo.inoと同じディレクトリに2つのヘッダファイルがある状態になります。
4. PCのブラウザからAWS IoTに接続し、テストの画面（MQTTクライアント）を出し、"freertos/demos/echo"をサブスクライブしておく。
5. ROSEをリセットし、コンパイル、書き込みを行い、シリアルモニタを立ち上げてログを確認。

ROSE本の指示だとボードでGR-ROSE(DHCP)を選んだ後にファイル追加し、その後、スケッチ例を開く手順になっていました（P.129）が、これだと空のスケッチにヘッダが追加され、新たに開いたスケッチ例にはヘッダが付かなかったので、上記の通りスケッチ例を開いてSaveしてからファイル追加しました。

自宅のPCだとTempの場所がMac:"$HOME/tmp.ide4gr/build〜.tmp"、Windows:"C:\Windows\Temp\build〜.tmp"と少し違いましたが、岡宮さんのExplorerの写真と同じファイルができ、そこにあったaws_clientcredential.hとaws_clientcredential_keys.hは自分がAWSからダウンロードしファイル追加したものと中身が同じであることを確認しました。
タイムスタンプもコンパイルした時刻のものであることを確認済みです。

AWS IoTがよくわかっていないのですが、iotアカウントを作ったら、常にMQTTブローカーが立ち上がっているのでしょうか？
もしくはテストの画面を出すと起動するのでしょうか？
ブローカーの起動をする手順が無くて不安になったので・・・
ブローカーの起動状態を確認する方法はありますか？

AWS IoTにて、ROSEからのアクセスがあったが認証は失敗、などの履歴や失敗の理由などを確認する方法はあるのでしょうか？（AWSの質問になってしまいスミマセン）
少なくともMacのシリアルモニタログでは以下のようになっていて、AWS IoTにつなぎに行っていると思われ、これの履歴を確認できたらと思いました。
42 10260 [MQTT] prvProcessDNSCache: add: 'a2q1o77b4s8skq-ats.iot.us-east-2.amazonaws.com' @ 12dd4164ip
43 10260 [MQTT] FreeRTOS_connect: 8924 to 12dd4164ip:8883
44 10260 [MQTT] Socket 8924 -> 12dd4164ip:8883 State eCLOSED->eCONNECT_SYN
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Okamiya Yuuki over 4 years ago in reply to 竹のしん

竹のしんさん、ご返信ありがとうございます。

すみません、私もAWS IoT側のことはよく分かりませんが、以下私が確認用に使用したCredentialで試してみていただけませんか？（私のブローカーにパブリッシュされてしまうわけですが、どこに問題があるのか確認したいです。）

Credentials.zip
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 竹のしん over 4 years ago in reply to 竹のしん

岡宮さんのヘッダファイルを使うと、うまく行ったので差分を確認し、keys.h内#define keyJITR_DEVICE_CERTIFICATE_AUTHORITY_PEMの値を""からNULLに変更することでMQTT接続ができ、led on/offも動きました！
//#define keyJITR_DEVICE_CERTIFICATE_AUTHORITY_PEM ""
#define keyJITR_DEVICE_CERTIFICATE_AUTHORITY_PEM NULL
ここを触った記憶はないのですが、デフォルトが変わったのですかね。

あと、これまで書き忘れていましたが、aws_clientcredential.hに以下を書き足していました。ダウンロードしたままの状態だとこれが無く、コンパイルエラーになりましたので。この部分を書き足したことで岡宮さんのものと同じになっていました。
#include "aws_clientcredential_keys.h"

手厚くサポートして頂き、ありがとうございました。
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Okamiya Yuuki over 4 years ago in reply to 竹のしん

がじぇるね岡宮です。

ご確認ありがとうございました。書籍の原稿執筆時と変わったのかもしれないですね。

別件でROS2 のバージョン互換問題がありますので、GR-ROSEのSDKをアップデートする際に、FreeRTOSのスタックの更新も検討しようと思います。
Cancel
Up 0 Down

Reply

Verify Answer

Cancel
0 Okamiya Yuuki over 2 years ago in reply to Okamiya Yuuki

がじぇるね岡宮です。
月日が経ちましたが、現時点のAWS IoT(FreeRTOSコンソール)でCredentialをダウンロードし、もう一度書籍の通りに手順を進めましたが、エラーになりました。

「aws_clientcredential.h」に対して、以下を追加するとコンパイルができました。
#include "aws_clientcredential_keys.h"

症状が変わりましたが、一応覚え書きで残しておきます。
Cancel
Up 0 Down

Reply

Verify Answer

Cancel