Connect with experts and redefine what’s possible at work – join us at the Microsoft 365 Community Conference May 6-8. Learn more >

IoT Edge

6 Topics

IoT Edge runtime not able to connect to the Azure IoT Hub
Hi, System: ARM32, Yocto build system I have successfully compiled Azure Edge 1.2.6(https://github.com/Azure/meta-iotedge/pull/76) for my system. I have successfully installed moby-docker as well. Below is the iotedge system status: root@xxxxx-imx6ull:~# iotedge system status System services: aziot-edged Running aziot-identityd Running aziot-keyd Running aziot-certd Running aziot-tpmd Ready Use 'iotedge system logs' to check for non-fatal errors. Use 'iotedge check' to diagnose connectivity and configuration issues. I can't see my device online. After doing further debugging, I found that I am not able to access the internet(not able to resolve the DNS) from the azure IoT edge agent container. Below are the logs for the 'iotedge check' root@xxxxx-imx6ull:~# iotedge check Configuration checks (aziot-identity-service) --------------------------------------------- �√ keyd configuration is well-formed - OK �√ certd configuration is well-formed - OK �√ tpmd configuration is well-formed - OK �√ identityd configuration is well-formed - OK �√ daemon configurations up-to-date with config.toml - OK �√ identityd config toml file specifies a valid hostname - OK �‼ aziot-identity-service package is up-to-date - Warning Installed aziot-identity-service package has version 1.2.4 but 1.2.6 is the latest stable version available. Please see https://aka.ms/aziot-update-runtime for update instructions. �√ host time is close to reference time - OK �√ preloaded certificates are valid - OK �√ keyd is running - OK �√ certd is running - OK �√ identityd is running - OK �√ read all preloaded certificates from the Certificates Service - OK �√ read all preloaded key pairs from the Keys Service - OK �√ ensure all preloaded certificates match preloaded private keys with the same ID - OK Connectivity checks (aziot-identity-service) -------------------------------------------- �√ host can connect to and perform TLS handshake with iothub AMQP port - OK �√ host can connect to and perform TLS handshake with iothub HTTPS / WebSockets port - OK �√ host can connect to and perform TLS handshake with iothub MQTT port - OK Configuration checks -------------------- �√ aziot-edged configuration is well-formed - OK �√ configuration up-to-date with config.toml - OK �√ container engine is installed and functional - OK �√ configuration has correct URIs for daemon mgmt endpoint - OK �‼ aziot-edge package is up-to-date - Warning Installed IoT Edge daemon has version 1.2.6 but 1.2.9 is the latest stable version available. Please see https://aka.ms/iotedge-update-runtime for update instructions. �√ container time is close to host time - OK �√ DNS server - OK �‼ production readiness: container engine - Warning Device is not using a production-supported container engine (moby-engine). Please see https://aka.ms/iotedge-prod-checklist-moby for details. �‼ production readiness: logs policy - Warning Container engine is not configured to rotate module logs which may cause it run out of disk space. Please see https://aka.ms/iotedge-prod-checklist-logs for best practices. You can ignore this warning if you are setting log policy per module in the Edge deployment. �‼ production readiness: Edge Agent's storage directory is persisted on the host filesystem - Warning The edgeAgent module is not configured to persist its /tmp/edgeAgent directory on the host filesystem. Data might be lost if the module is deleted or updated. Please see https://aka.ms/iotedge-storage-host for best practices. �× production readiness: Edge Hub's storage directory is persisted on the host filesystem - Error Could not check current state of edgeHub container �√ Agent image is valid and can be pulled from upstream - OK Connectivity checks ------------------- �× container on the default network can connect to upstream AMQP port - Error Container on the default network could not connect to ourIoTHub.azure-devices.net:5671 �× container on the default network can connect to upstream HTTPS / WebSockets port - Error Container on the default network could not connect to ourIoTHub.azure-devices.net:443 �× container on the default network can connect to upstream MQTT port - Error Container on the default network could not connect to ourIoTHub.azure-devices.net:8883 �× container on the IoT Edge module network can connect to upstream AMQP port - Error Container on the azure-iot-edge network could not connect to ourIoTHub.azure-devices.net:5671 �× container on the IoT Edge module network can connect to upstream HTTPS / WebSockets port - Error Container on the azure-iot-edge network could not connect to ourIoTHub.azure-devices.net:443 �× container on the IoT Edge module network can connect to upstream MQTT port - Error Container on the azure-iot-edge network could not connect to ourIoTHub.azure-devices.net:8883 24 check(s) succeeded. 5 check(s) raised warnings. Re-run with --verbose for more details. 7 check(s) raised errors. Re-run with --verbose for more details. Can you please help me to debug it further? ========================================= Below are the docker daemon configurations root@xxxxx-imx6ull:/# cat /etc/docker/daemon.json { "dns": ["8.8.8.8","8.8.4.4","0.0.0.0"], "dns-opts": [], "dns-search": [] } Socket permissions root@xxxxx-imx6ull:~# ls -all /var/run/iotedge/ total 0 drwxr-xr-x 2 root root 80 May 9 06:36 . drwxr-xr-x 20 root root 740 May 9 06:36 .. srw-rw-rw- 1 root iotedge 0 May 9 06:36 mgmt.sock srw-rw-rw- 1 iotedge iotedge 0 May 9 06:36 workload.sock root@xxxxx-imx6ull:~# ls -all /var/run/aziot/ total 0 drwxr-xr-x 2 root root 120 May 9 06:36 . drwxr-xr-x 20 root root 740 May 9 06:36 .. srw-rw---- 1 aziotcs aziotcs 0 May 9 06:36 certd.sock srw-rw---- 1 aziotid aziotid 0 May 9 06:36 identityd.sock srw-rw---- 1 aziotks aziotks 0 May 9 06:36 keyd.sock srw-rw---- 1 aziottpm aziottpm 0 May 9 06:36 tpmd.sock iotedge system logs -- Journal begins at Mon 2022-05-09 06:34:04 UTC, ends at Mon 2022-05-09 06:49:46 UTC. -- May 09 06:34:20 xxxxx-imx6ull aziot-certd[207]: 2022-05-09T06:34:20Z [INFO] - Starting service... May 09 06:34:20 xxxxx-imx6ull aziot-certd[207]: 2022-05-09T06:34:20Z [INFO] - Version - dev build May 09 06:34:20 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:20Z [INFO] - Version - dev build May 09 06:34:20 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:20Z [INFO] - Loaded libaziot-keys with version 0x02000000 May 09 06:34:20 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:20Z [INFO] - Starting server... May 09 06:34:20 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:20Z [INFO] - Starting service... May 09 06:34:20 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:20Z [INFO] - Version - dev build May 09 06:34:21 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:20Z [INFO] - Provisioning starting. Reason: Startup May 09 06:34:21 xxxxx-imx6ull aziot-certd[207]: 2022-05-09T06:34:20Z [INFO] - Starting server... May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:21Z [ERR!] - service encountered an error May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:21Z [ERR!] - caused by: internal error May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:21Z [ERR!] - caused by: could not initialize TPM May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:21Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:21Z [ERR!] - 0: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 1: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 3: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 4: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 5: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 6: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 7: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 8: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 9: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 10: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 11: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 12: <unknown> May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 13: __libc_start_main May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:20Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: Error: Time:Mon May 9 06:34:20 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: Error: Time:Mon May 9 06:34:20 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: Error: Time:Mon May 9 06:34:20 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:20Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:34:21 xxxxx-imx6ull aziot-tpmd[210]: 2022-05-09T06:34:20Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:21Z [INFO] - Updated device info for testdevid. May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:21Z [INFO] - Provisioning complete. May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:21Z [INFO] - Identity reconciliation started. Reason: Startup May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:21Z [DBUG] - [hyper::proto::h1::io] flushed 71 bytes May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:21Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:21Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:21Z [INFO] - <-- GET /key/device-id?api-version=2020-09-01 {"host": "keyd.sock"} May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (240 bytes) May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock) May 09 06:34:22 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::client::pool] reuse idle connection for ("http", keyd.sock) May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::io] flushed 349 bytes May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (370 bytes) May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [INFO] - <-- POST /sign?api-version=2020-09-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "370"} May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:34:22 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::io] flushed 168 bytes May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::io] flushed 486 bytes May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (60 bytes) May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock) May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:22Z [DBUG] - [hyper::client::connect::dns] resolving host="ourIoTHub.azure-devices.net" May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:23Z [WARN] - Failed to communicate with IoT Hub (attempt 1 of 1): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:23Z [WARN] - Network not available for Identity reconciliation. Using offline backup from last run. May 09 06:34:23 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:34:23Z [INFO] - Starting server... May 09 06:34:21 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:34:21 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [INFO] - Starting service... May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [INFO] - Version - dev build May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] - service encountered an error May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] - caused by: internal error May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] - caused by: could not initialize TPM May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] - 0: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 1: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 3: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 4: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 5: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 6: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 7: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 8: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 9: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 10: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 11: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 12: <unknown> May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 13: __libc_start_main May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: Error: Time:Mon May 9 06:34:31 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: Error: Time:Mon May 9 06:34:31 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: Error: Time:Mon May 9 06:34:31 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:34:31 xxxxx-imx6ull aziot-tpmd[289]: 2022-05-09T06:34:31Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:34:30 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 1. May 09 06:34:30 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:37Z [INFO] - Starting service... May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:37Z [INFO] - Version - dev build May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] - service encountered an error May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] - caused by: internal error May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] - caused by: could not initialize TPM May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] - 0: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 1: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 3: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 4: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 5: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 6: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 7: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 8: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 9: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 10: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 11: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 12: <unknown> May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 13: __libc_start_main May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:37Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: Error: Time:Mon May 9 06:34:38 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: Error: Time:Mon May 9 06:34:38 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: Error: Time:Mon May 9 06:34:38 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:34:39 xxxxx-imx6ull aziot-tpmd[333]: 2022-05-09T06:34:38Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:34:30 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:34:31 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:34:31 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:34:36 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 2. May 09 06:34:36 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:34:37 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:34:38 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:34:38 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [INFO] - Starting service... May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [INFO] - Version - dev build May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] - service encountered an error May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] - caused by: internal error May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] - caused by: could not initialize TPM May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] - 0: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 1: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 3: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 4: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 5: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 6: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 7: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 8: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 9: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 10: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 11: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 12: <unknown> May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 13: __libc_start_main May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: Error: Time:Mon May 9 06:34:52 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: Error: Time:Mon May 9 06:34:52 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: Error: Time:Mon May 9 06:34:52 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:34:56 xxxxx-imx6ull aziot-tpmd[374]: 2022-05-09T06:34:52Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:34:49 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 3. May 09 06:34:50 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [INFO] - Starting service... May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [INFO] - Version - dev build May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] - service encountered an error May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] - caused by: internal error May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] - caused by: could not initialize TPM May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] - 0: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 1: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 3: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 4: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 5: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 6: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 7: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 8: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 9: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 10: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 11: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 12: <unknown> May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 13: __libc_start_main May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: Error: Time:Mon May 9 06:34:58 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: Error: Time:Mon May 9 06:34:58 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: Error: Time:Mon May 9 06:34:58 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:34:58 xxxxx-imx6ull aziot-tpmd[398]: 2022-05-09T06:34:58Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:34:51 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:34:52 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:34:52 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:34:57 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 4. May 09 06:34:57 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:34:57 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:34:58 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:34:58 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:03 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 5. May 09 06:35:03 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:03 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:03 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:03Z [INFO] - Starting service... May 09 06:35:03 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:03Z [INFO] - Version - dev build May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:04Z [ERR!] - service encountered an error May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:04Z [ERR!] - caused by: internal error May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:04Z [ERR!] - caused by: could not initialize TPM May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:04Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:04Z [ERR!] - 0: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 1: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 3: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 4: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 5: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 6: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 7: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 8: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 9: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 10: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 11: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 12: <unknown> May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 13: __libc_start_main May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:03Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: Error: Time:Mon May 9 06:35:03 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: Error: Time:Mon May 9 06:35:03 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: Error: Time:Mon May 9 06:35:03 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:03Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:04 xxxxx-imx6ull aziot-tpmd[429]: 2022-05-09T06:35:03Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:04 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:04 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:09 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 6. May 09 06:35:09 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:09 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [INFO] - Starting service... May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [INFO] - Version - dev build May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] - service encountered an error May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] - caused by: internal error May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] - caused by: could not initialize TPM May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] - 0: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 1: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 3: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 4: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 5: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 6: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 7: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 8: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 9: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 10: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 11: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 12: <unknown> May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 13: __libc_start_main May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: Error: Time:Mon May 9 06:35:09 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: Error: Time:Mon May 9 06:35:09 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: Error: Time:Mon May 9 06:35:09 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:09 xxxxx-imx6ull aziot-tpmd[518]: 2022-05-09T06:35:09Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:09 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:09 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:14 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 7. May 09 06:35:14 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:14 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [INFO] - Starting service... May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [INFO] - Version - dev build May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] - service encountered an error May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] - caused by: internal error May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] - caused by: could not initialize TPM May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] - 0: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 1: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 3: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 4: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 5: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 6: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 7: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 8: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 9: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 10: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 11: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 12: <unknown> May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 13: __libc_start_main May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: Error: Time:Mon May 9 06:35:14 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: Error: Time:Mon May 9 06:35:14 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: Error: Time:Mon May 9 06:35:14 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:14 xxxxx-imx6ull aziot-tpmd[541]: 2022-05-09T06:35:14Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:14 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:15 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:20 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 8. May 09 06:35:20 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:20 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [INFO] - Starting service... May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [INFO] - Version - dev build May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] - service encountered an error May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] - caused by: internal error May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] - caused by: could not initialize TPM May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] - 0: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 1: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 3: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 4: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 5: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 6: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 7: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 8: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 9: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 10: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 11: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 12: <unknown> May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 13: __libc_start_main May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: Error: Time:Mon May 9 06:35:20 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: Error: Time:Mon May 9 06:35:20 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: Error: Time:Mon May 9 06:35:20 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:20 xxxxx-imx6ull aziot-tpmd[572]: 2022-05-09T06:35:20Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:20 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:20 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:25 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 9. May 09 06:35:25 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:25 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:25 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:25Z [INFO] - Starting service... May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [INFO] - Version - dev build May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] - service encountered an error May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] - caused by: internal error May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] - caused by: could not initialize TPM May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] - 0: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 1: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 3: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 4: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 5: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 6: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 7: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 8: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 9: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 10: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 11: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 12: <unknown> May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 13: __libc_start_main May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:25Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: Error: Time:Mon May 9 06:35:26 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: Error: Time:Mon May 9 06:35:26 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: Error: Time:Mon May 9 06:35:26 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:26 xxxxx-imx6ull aziot-tpmd[593]: 2022-05-09T06:35:26Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:26 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:26 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:31 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 10. May 09 06:35:31 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:31 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [INFO] - Starting service... May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [INFO] - Version - dev build May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] - service encountered an error May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] - caused by: internal error May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] - caused by: could not initialize TPM May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] - 0: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 1: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 3: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 4: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 5: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 6: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 7: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 8: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 9: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 10: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 11: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 12: <unknown> May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 13: __libc_start_main May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: Error: Time:Mon May 9 06:35:31 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: Error: Time:Mon May 9 06:35:31 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: Error: Time:Mon May 9 06:35:31 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:31 xxxxx-imx6ull aziot-tpmd[620]: 2022-05-09T06:35:31Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:31 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:31 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:36 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 11. May 09 06:35:36 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:36 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [INFO] - Starting service... May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [INFO] - Version - dev build May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] - service encountered an error May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] - caused by: internal error May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] - caused by: could not initialize TPM May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] - 0: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 1: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 3: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 4: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 5: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 6: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 7: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 8: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 9: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 10: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 11: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 12: <unknown> May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 13: __libc_start_main May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: Error: Time:Mon May 9 06:35:37 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: Error: Time:Mon May 9 06:35:37 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: Error: Time:Mon May 9 06:35:37 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:37 xxxxx-imx6ull aziot-tpmd[643]: 2022-05-09T06:35:37Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:37 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:37 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:42 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 12. May 09 06:35:42 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:42 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [INFO] - Starting service... May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [INFO] - Version - dev build May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] - service encountered an error May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] - caused by: internal error May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] - caused by: could not initialize TPM May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] - 0: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 1: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 3: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 4: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 5: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 6: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 7: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 8: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 9: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 10: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 11: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 12: <unknown> May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 13: __libc_start_main May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: Error: Time:Mon May 9 06:35:42 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: Error: Time:Mon May 9 06:35:42 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: Error: Time:Mon May 9 06:35:42 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:42 xxxxx-imx6ull aziot-tpmd[674]: 2022-05-09T06:35:42Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:42 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:42 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:47 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 13. May 09 06:35:47 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:48 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [INFO] - Starting service... May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [INFO] - Version - dev build May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] - service encountered an error May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] - caused by: internal error May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] - caused by: could not initialize TPM May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] - 0: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 1: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 3: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 4: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 5: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 6: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 7: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 8: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 9: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 10: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 11: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 12: <unknown> May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 13: __libc_start_main May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: Error: Time:Mon May 9 06:35:48 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: Error: Time:Mon May 9 06:35:48 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: Error: Time:Mon May 9 06:35:48 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:48 xxxxx-imx6ull aziot-tpmd[701]: 2022-05-09T06:35:48Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:48 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:48 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:53 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 14. May 09 06:35:53 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:53 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:53 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:53Z [INFO] - Starting service... May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [INFO] - Version - dev build May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] - service encountered an error May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] - caused by: internal error May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] - caused by: could not initialize TPM May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] - 0: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 1: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 3: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 4: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 5: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 6: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 7: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 8: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 9: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 10: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 11: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 12: <unknown> May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 13: __libc_start_main May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:53Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: Error: Time:Mon May 9 06:35:54 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: Error: Time:Mon May 9 06:35:54 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: Error: Time:Mon May 9 06:35:54 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:54 xxxxx-imx6ull aziot-tpmd[730]: 2022-05-09T06:35:54Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:54 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:54 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:35:59 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 15. May 09 06:35:59 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:35:59 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [INFO] - Starting service... May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [INFO] - Version - dev build May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] - service encountered an error May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] - caused by: internal error May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] - caused by: could not initialize TPM May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] - 0: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 1: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 3: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 4: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 5: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 6: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 7: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 8: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 9: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 10: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 11: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 12: <unknown> May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 13: __libc_start_main May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: Error: Time:Mon May 9 06:35:59 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: Error: Time:Mon May 9 06:35:59 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: Error: Time:Mon May 9 06:35:59 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:35:59 xxxxx-imx6ull aziot-tpmd[753]: 2022-05-09T06:35:59Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:35:59 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:35:59 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:36:04 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 16. May 09 06:36:04 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:36:04 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [INFO] - Starting service... May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [INFO] - Version - dev build May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] - service encountered an error May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] - caused by: internal error May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] - caused by: could not initialize TPM May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] - 0: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 1: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 3: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 4: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 5: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 6: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 7: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 8: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 9: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 10: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 11: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 12: <unknown> May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 13: __libc_start_main May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: Error: Time:Mon May 9 06:36:05 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: Error: Time:Mon May 9 06:36:05 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: Error: Time:Mon May 9 06:36:05 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:36:05 xxxxx-imx6ull aziot-tpmd[781]: 2022-05-09T06:36:05Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:36:05 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:36:05 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:36:10 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 17. May 09 06:36:10 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:36:10 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [INFO] - Starting service... May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [INFO] - Version - dev build May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] - service encountered an error May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] - caused by: internal error May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] - caused by: could not initialize TPM May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] - 0: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 1: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 3: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 4: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 5: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 6: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 7: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 8: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 9: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 10: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 11: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 12: <unknown> May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 13: __libc_start_main May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: Error: Time:Mon May 9 06:36:10 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: Error: Time:Mon May 9 06:36:10 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: Error: Time:Mon May 9 06:36:10 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:36:10 xxxxx-imx6ull aziot-tpmd[804]: 2022-05-09T06:36:10Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:36:10 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:36:10 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:36:15 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 18. May 09 06:36:15 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:36:15 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [INFO] - Starting service... May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [INFO] - Version - dev build May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] - service encountered an error May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] - caused by: internal error May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] - caused by: could not initialize TPM May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] - 0: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 1: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 3: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 4: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 5: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 6: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 7: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 8: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 9: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 10: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 11: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 12: <unknown> May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 13: __libc_start_main May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: Error: Time:Mon May 9 06:36:15 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: Error: Time:Mon May 9 06:36:15 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: Error: Time:Mon May 9 06:36:15 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:36:15 xxxxx-imx6ull aziot-tpmd[834]: 2022-05-09T06:36:15Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:36:16 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:36:16 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:36:20 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:36:20Z [DBUG] - [config_common::watcher] Incoming file watcher event: Ok(NoticeWrite("/etc/aziot/keyd/config.d/00-super.toml")) May 09 06:36:20 xxxxx-imx6ull aziot-certd[207]: 2022-05-09T06:36:20Z [DBUG] - [config_common::watcher] Incoming file watcher event: Ok(NoticeWrite("/etc/aziot/certd/config.d/00-super.toml")) May 09 06:36:20 xxxxx-imx6ull aziot-identityd[208]: 2022-05-09T06:36:20Z [DBUG] - [config_common::watcher] Incoming file watcher event: Ok(NoticeWrite("/etc/aziot/identityd/config.d/00-super.toml")) May 09 06:36:21 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Scheduled restart job, restart counter is at 19. May 09 06:36:21 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:36:21 xxxxx-imx6ull systemd[1]: Started Azure IoT TPM Service. May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [INFO] - Starting service... May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [INFO] - Version - dev build May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] - service encountered an error May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] - caused by: internal error May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] - caused by: could not initialize TPM May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] - caused by: TPM API returned an invalid null response May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] - 0: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 1: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 3: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 4: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 5: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 6: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 7: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 8: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 9: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 10: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 11: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 12: <unknown> May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 13: __libc_start_main May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [INFO] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_log.c:log_init:22) Initialized logging May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: Error: Time:Mon May 9 06:36:21 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_usermode_resmgr_connect Line:268 Failure: No user mode TRM found. May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: Error: Time:Mon May 9 06:36:21 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_comm_linux.c Func:tpm_comm_create Line:335 Failure: connecting to the TPM device May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: Error: Time:Mon May 9 06:36:21 2022 File:/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/deps/utpm/src/tpm_codec.c Func:Initialize_TPM_Codec Line:258 creating tpm_comm object May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:initialize_tpm_device:278) Failure initializeing TPM Codec May 09 06:36:21 xxxxx-imx6ull aziot-tpmd[869]: 2022-05-09T06:36:21Z [ERR!] (/usr/src/debug/aziotd/1.2.4-r0/git/tpm/aziot-tpm-sys/azure-iot-hsm-c/src/hsm_client_tpm_device.c:hsm_client_tpm_create:311) Failure initializing tpm device. May 09 06:36:21 xxxxx-imx6ull systemd[1]: Stopping Azure IoT Identity Service... May 09 06:36:21 xxxxx-imx6ull aziot-keyd[209]: 2022-05-09T06:36:21Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:36:21 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Main process exited, code=exited, status=1/FAILURE May 09 06:36:21 xxxxx-imx6ull systemd[1]: aziot-tpmd.service: Failed with result 'exit-code'. May 09 06:36:21 xxxxx-imx6ull systemd[1]: aziot-identityd.service: Succeeded. May 09 06:36:21 xxxxx-imx6ull systemd[1]: Stopped Azure IoT Identity Service. May 09 06:36:22 xxxxx-imx6ull systemd[1]: Stopping Azure IoT Keys Service... May 09 06:36:22 xxxxx-imx6ull systemd[1]: aziot-keyd.service: Succeeded. May 09 06:36:22 xxxxx-imx6ull systemd[1]: Stopped Azure IoT Keys Service. May 09 06:36:22 xxxxx-imx6ull systemd[1]: Stopping Azure IoT Certificates Service... May 09 06:36:22 xxxxx-imx6ull systemd[1]: aziot-certd.service: Succeeded. May 09 06:36:22 xxxxx-imx6ull systemd[1]: Stopped Azure IoT Certificates Service. May 09 06:36:22 xxxxx-imx6ull systemd[1]: Stopped Azure IoT TPM Service. May 09 06:36:54 xxxxx-imx6ull systemd[1]: Started Azure IoT Edge daemon. May 09 06:36:54 xxxxx-imx6ull aziot-edged[1176]: 2022-05-09T06:36:54Z [INFO] - Starting Azure IoT Edge Module Runtime May 09 06:36:54 xxxxx-imx6ull aziot-edged[1176]: 2022-05-09T06:36:54Z [INFO] - Version - 1.2.6 May 09 06:36:54 xxxxx-imx6ull aziot-edged[1176]: 2022-05-09T06:36:54Z [INFO] - Initializing the module runtime... May 09 06:36:54 xxxxx-imx6ull aziot-edged[1176]: 2022-05-09T06:36:54Z [INFO] - Initializing module runtime... May 09 06:36:54 xxxxx-imx6ull aziot-edged[1176]: 2022-05-09T06:36:54Z [INFO] - Using runtime network id azure-iot-edge May 09 06:36:55 xxxxx-imx6ull systemd[1]: Stopping Azure IoT Edge daemon... May 09 06:36:55 xxxxx-imx6ull systemd[1]: aziot-edged.service: Succeeded. May 09 06:36:55 xxxxx-imx6ull systemd[1]: Stopped Azure IoT Edge daemon. May 09 06:36:59 xxxxx-imx6ull systemd[1]: Started Azure IoT Edge daemon. May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Starting Azure IoT Edge Module Runtime May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Version - 1.2.6 May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Initializing the module runtime... May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Initializing module runtime... May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Using runtime network id azure-iot-edge May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Successfully initialized module runtime May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Finished initializing the module runtime. May 09 06:36:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:36:59Z [INFO] - Obtaining edge device provisioning data... May 09 06:36:59 xxxxx-imx6ull systemd[1]: Started Azure IoT Identity Service. May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Starting service... May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Version - dev build May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Provisioning starting. Reason: Startup May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Updated device info for testdevid. May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Provisioning complete. May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Identity reconciliation started. Reason: Startup May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 71 bytes May 09 06:37:00 xxxxx-imx6ull systemd[1]: Started Azure IoT Keys Service. May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - Starting service... May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - Version - dev build May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - Loaded libaziot-keys with version 0x02000000 May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - Starting server... May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - <-- GET /key/device-id?api-version=2020-09-01 {"host": "keyd.sock"} May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (240 bytes) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::pool] reuse idle connection for ("http", keyd.sock) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 486 bytes May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 349 bytes May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (370 bytes) May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - <-- POST /sign?api-version=2020-09-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "370"} May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 168 bytes May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (60 bytes) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::connect::dns] resolving host="ourIoTHub.azure-devices.net" May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::connect::http] connecting to 20.43.121.64:443 May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::connect::http] connected to 20.43.121.64:443 May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 267 bytes May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 6 headers May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (975 bytes) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [aziot_hub_client_async] IoTHub response status 200 May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [aziot_hub_client_async] IoTHub response headers{"content-length": "975", "content-type": "application/json; charset=utf-8", "vary": "Origin", "server": "Microsoft-HTTPAPI/2.0", "x-ms-request-id": "653ab146-9ec6-408c-9304-99a4e57acd8d", "date": "Mon, 09 May 2022 06:36:59 GMT"} May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Identity reconciliation complete. May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - Starting server... May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (16 bytes) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - <-- POST /identities/device?api-version=2020-09-01 {"content-type": "application/json", "host": "2f72756e2f617a696f742f6964656e74697479642e736f636b:0", "content-length": "16"} May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::pool] reuse idle connection for ("http", keyd.sock) May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - <-- GET /key/device-id?api-version=2020-09-01 {"host": "keyd.sock"} May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 71 bytes May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:00 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 349 bytes May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (240 bytes) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock) May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:00 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:00Z [DBUG] - [hyper::proto::h1::io] flushed 502 bytes May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [INFO] - Finished provisioning edge device. May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [INFO] - Stopping all modules... May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [INFO] - Stopping module edgeAgent... May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [WARN] - Could not stop module edgeAgent May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [WARN] - caused by: Target of operation already in this state May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [INFO] - Finished stopping modules. May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [INFO] - Detecting if device information has changed... May 09 06:37:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:00Z [INFO] - Starting management API... May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Starting new listener for module edgeAgent May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Starting watchdog with 60 second frequency... May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Listening on fd://aziot-edged.mgmt.socket with 1 thread for management API. May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Checking edge runtime status May 09 06:37:01 xxxxx-imx6ull systemd[1]: Started Azure IoT Certificates Service. May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - Starting service... May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - Version - dev build May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - Starting server... May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - <-- GET /certificates/aziot-edged-ca?api-version=2020-09-01 {"host": "2f72756e2f617a696f742f63657274642e736f636b:0"} May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Edge runtime status is failed, starting module now... May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Starting module edgeAgent... May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Starting new listener for module edgeAgent May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Listener edgeAgent already started, removing old listener May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::io] flushed 1242 bytes May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - <-- GET /certificates/aziot-edged-ca?api-version=2020-09-01 {"host": "2f72756e2f617a696f742f63657274642e736f636b:0"} May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::io] flushed 1242 bytes May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Listening on fd://aziot-edged.workload.socket with 1 thread for workload API. May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Listening on unix:///var/lib/aziot/edged/mnt/edgeAgent.sock with 1 thread for workload API. May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - <-- GET /certificates/aziot-edged-ca?api-version=2020-09-01 {"host": "2f72756e2f617a696f742f63657274642e736f636b:0"} May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:01Z [DBUG] - [hyper::proto::h1::io] flushed 1242 bytes May 09 06:37:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:01Z [INFO] - Listening on unix:///var/lib/aziot/edged/mnt/edgeAgent.sock with 1 thread for workload API. May 09 06:37:15 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:15Z [INFO] - Successfully started module edgeAgent May 09 06:37:35 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:35Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:35 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:35Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:35 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:35Z [INFO] - <-- GET /certificates/aziot-edged-trust-bundle?api-version=2020-09-01 {"host": "2f72756e2f617a696f742f63657274642e736f636b:0"} May 09 06:37:35 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:35Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:35 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:35Z [DBUG] - [hyper::proto::h1::io] flushed 1242 bytes May 09 06:37:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:35Z [INFO] - [work] - - - [2022-05-09 06:37:35.811169826 UTC] "GET /trust-bundle?api-version=2019-01-30 HTTP/1.1" 200 OK 1140 "-" "-" auth_id(-) May 09 06:37:44 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:44Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:37:44 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:44Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:37:45 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:37:45 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:45 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:45 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:45Z [INFO] - <-- GET /certificates/aziot-edged-trust-bundle?api-version=2020-09-01 {"host": "certd.sock"} May 09 06:37:45 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:45Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:37:45 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::io] flushed 1242 bytes May 09 06:37:45 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [INFO] - <-- GET /keypair/device-id?api-version=2020-09-01 {"host": "keyd.sock"} May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [ERR!] - error:0909006C:PEM routines:get_name:no start line:../openssl-1.1.1k/crypto/pem/pem_lib.c:745:Expecting: ANY PRIVATE KEY May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [ERR!] - !!! internal error May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [ERR!] - !!! caused by: could not load key pair May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [ERR!] - !!! caused by: could not load key pair: AZIOT_KEYS_RC_ERR_EXTERNAL May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [INFO] - --> 500 {"content-type": "application/json"} May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::io] flushed 155 bytes May 09 06:37:45 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:37:45Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:37:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:45Z [INFO] - Querying system info... May 09 06:37:46 xxxxx-imx6ull aziot-edged[1579]: none May 09 06:37:46 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:46Z [INFO] - Successfully queried system info May 09 06:37:46 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:46Z [INFO] - [mgmt] - - - [2022-05-09 06:37:46.092148369 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:37:56 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:37:56Z [INFO] - Querying system info... May 09 06:38:00 xxxxx-imx6ull aziot-edged[1672]: none May 09 06:38:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:00Z [INFO] - Successfully queried system info May 09 06:38:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:00Z [INFO] - [mgmt] - - - [2022-05-09 06:38:00.899822176 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:38:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:01Z [INFO] - Checking edge runtime status May 09 06:38:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:01Z [INFO] - Edge runtime is running. May 09 06:38:30 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:38:30Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:38:31 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:38:31Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:38:32 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:32Z [INFO] - Querying system info... May 09 06:38:33 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:33Z [INFO] - Querying system info... May 09 06:38:35 xxxxx-imx6ull aziot-edged[1928]: none May 09 06:38:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:35Z [INFO] - Successfully queried system info May 09 06:38:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:35Z [INFO] - [mgmt] - - - [2022-05-09 06:38:35.816178895 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:38:36 xxxxx-imx6ull aziot-edged[1936]: none May 09 06:38:36 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:36Z [INFO] - Successfully queried system info May 09 06:38:36 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:36Z [INFO] - [mgmt] - - - [2022-05-09 06:38:36.851207443 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:38:40 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:40Z [INFO] - [mgmt] - - - [2022-05-09 06:38:40.803202462 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:38:44 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:44Z [INFO] - Querying system resources... May 09 06:38:47 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:47Z [INFO] - [mgmt] - - - [2022-05-09 06:38:47.178954890 UTC] "GET /systeminfo/resources?api-version=2020-07-07 HTTP/1.1" 200 OK 3203 "-" "-" auth_id(-) May 09 06:38:52 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:38:52Z [INFO] - [mgmt] - - - [2022-05-09 06:38:52.466956638 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:39:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:00Z [INFO] - Checking edge runtime status May 09 06:39:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:00Z [INFO] - Edge runtime is running. May 09 06:39:02 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:02Z [INFO] - [mgmt] - - - [2022-05-09 06:39:02.412896352 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:39:12 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:12Z [INFO] - [mgmt] - - - [2022-05-09 06:39:12.489403137 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:39:13 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:13Z [INFO] - Querying system info... May 09 06:39:14 xxxxx-imx6ull aziot-edged[2208]: none May 09 06:39:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:14Z [INFO] - Successfully queried system info May 09 06:39:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:14Z [INFO] - [mgmt] - - - [2022-05-09 06:39:14.087476058 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:39:22 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:22Z [INFO] - [mgmt] - - - [2022-05-09 06:39:22.776492869 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:39:32 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:32Z [INFO] - [mgmt] - - - [2022-05-09 06:39:32.911880810 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:39:43 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:43Z [INFO] - [mgmt] - - - [2022-05-09 06:39:43.011086040 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:39:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:45Z [INFO] - Querying system info... May 09 06:39:46 xxxxx-imx6ull aziot-edged[2359]: none May 09 06:39:46 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:46Z [INFO] - Successfully queried system info May 09 06:39:46 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:46Z [INFO] - [mgmt] - - - [2022-05-09 06:39:46.209714783 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:39:50 xxxxx-imx6ull aziot-identityd[1221]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [INFO] - <-- GET /certificates/aziot-edged-trust-bundle?api-version=2020-09-01 {"host": "certd.sock"} May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [INFO] - --> 200 {"content-type": "application/json"} May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::io] flushed 1242 bytes May 09 06:39:50 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [INFO] - <-- GET /keypair/device-id?api-version=2020-09-01 {"host": "keyd.sock"} May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [ERR!] - error:0909006C:PEM routines:get_name:no start line:../openssl-1.1.1k/crypto/pem/pem_lib.c:745:Expecting: ANY PRIVATE KEY May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [ERR!] - !!! internal error May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [ERR!] - !!! caused by: could not load key pair May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [ERR!] - !!! caused by: could not load key pair: AZIOT_KEYS_RC_ERR_EXTERNAL May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [INFO] - --> 500 {"content-type": "application/json"} May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::io] flushed 155 bytes May 09 06:39:50 xxxxx-imx6ull aziot-keyd[1223]: 2022-05-09T06:39:50Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:39:53 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:39:53Z [INFO] - [mgmt] - - - [2022-05-09 06:39:53.130218186 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:00Z [INFO] - Checking edge runtime status May 09 06:40:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:00Z [INFO] - Edge runtime is running. May 09 06:40:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:40:01Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:40:01 xxxxx-imx6ull aziot-certd[1236]: 2022-05-09T06:40:01Z [DBUG] - [hyper::proto::h1::conn] read eof May 09 06:40:03 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:03Z [INFO] - [mgmt] - - - [2022-05-09 06:40:03.281204581 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:13 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:13Z [INFO] - [mgmt] - - - [2022-05-09 06:40:13.350285913 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:14Z [INFO] - [mgmt] - - - [2022-05-09 06:40:14.205853838 UTC] "GET /modules/?api-version=2018-06-28 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:24 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:24Z [INFO] - [mgmt] - - - [2022-05-09 06:40:24.040582518 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:25 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:25Z [INFO] - Querying system info... May 09 06:40:27 xxxxx-imx6ull aziot-edged[2662]: none May 09 06:40:27 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:27Z [INFO] - Successfully queried system info May 09 06:40:27 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:27Z [INFO] - [mgmt] - - - [2022-05-09 06:40:27.349309493 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:40:33 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:33Z [INFO] - [mgmt] - - - [2022-05-09 06:40:33.626815896 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:43 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:43Z [INFO] - [mgmt] - - - [2022-05-09 06:40:43.897566832 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:53 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:53Z [INFO] - [mgmt] - - - [2022-05-09 06:40:53.969927782 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:40:59 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:40:59Z [INFO] - Querying system info... May 09 06:41:00 xxxxx-imx6ull aziot-edged[2968]: none May 09 06:41:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:00Z [INFO] - Successfully queried system info May 09 06:41:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:00Z [INFO] - [mgmt] - - - [2022-05-09 06:41:00.409623086 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:41:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:00Z [INFO] - Checking edge runtime status May 09 06:41:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:00Z [INFO] - Edge runtime is running. May 09 06:41:04 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:04Z [INFO] - [mgmt] - - - [2022-05-09 06:41:04.230640405 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:41:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:14Z [INFO] - [mgmt] - - - [2022-05-09 06:41:14.101820340 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:41:24 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:24Z [INFO] - [mgmt] - - - [2022-05-09 06:41:24.192032768 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:41:34 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:34Z [INFO] - [mgmt] - - - [2022-05-09 06:41:34.359096206 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:41:44 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:44Z [INFO] - [mgmt] - - - [2022-05-09 06:41:44.256416913 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:41:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:45Z [INFO] - Querying system info... May 09 06:41:47 xxxxx-imx6ull aziot-edged[3298]: none May 09 06:41:47 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:47Z [INFO] - Successfully queried system info May 09 06:41:47 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:47Z [INFO] - [mgmt] - - - [2022-05-09 06:41:47.564757217 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:41:54 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:41:54Z [INFO] - [mgmt] - - - [2022-05-09 06:41:54.266310253 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:42:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:00Z [INFO] - Checking edge runtime status May 09 06:42:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:00Z [INFO] - Edge runtime is running. May 09 06:42:04 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:04Z [INFO] - [mgmt] - - - [2022-05-09 06:42:04.410086323 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:42:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:14Z [INFO] - [mgmt] - - - [2022-05-09 06:42:14.522823444 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:42:19 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:19Z [INFO] - Querying system info... May 09 06:42:21 xxxxx-imx6ull aziot-edged[3550]: none May 09 06:42:21 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:21Z [INFO] - Successfully queried system info May 09 06:42:21 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:21Z [INFO] - [mgmt] - - - [2022-05-09 06:42:21.659286308 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:42:24 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:24Z [INFO] - [mgmt] - - - [2022-05-09 06:42:24.540849202 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:42:34 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:34Z [INFO] - [mgmt] - - - [2022-05-09 06:42:34.518500247 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:42:44 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:44Z [INFO] - [mgmt] - - - [2022-05-09 06:42:44.589985055 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:42:54 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:42:54Z [INFO] - [mgmt] - - - [2022-05-09 06:42:54.955023731 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:43:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:00Z [INFO] - Checking edge runtime status May 09 06:43:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:00Z [INFO] - Edge runtime is running. May 09 06:43:04 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:04Z [INFO] - [mgmt] - - - [2022-05-09 06:43:04.863286782 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:43:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:14Z [INFO] - [mgmt] - - - [2022-05-09 06:43:14.669686581 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:43:24 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:24Z [INFO] - [mgmt] - - - [2022-05-09 06:43:24.749466614 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:43:28 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:28Z [INFO] - Querying system info... May 09 06:43:29 xxxxx-imx6ull aziot-edged[4024]: none May 09 06:43:29 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:29Z [INFO] - Successfully queried system info May 09 06:43:29 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:29Z [INFO] - [mgmt] - - - [2022-05-09 06:43:29.291802958 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:43:34 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:34Z [INFO] - [mgmt] - - - [2022-05-09 06:43:34.769288015 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:43:44 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:44Z [INFO] - [mgmt] - - - [2022-05-09 06:43:44.937980387 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:43:52 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:52Z [INFO] - Querying system resources... May 09 06:43:54 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:54Z [INFO] - [mgmt] - - - [2022-05-09 06:43:54.060292426 UTC] "GET /systeminfo/resources?api-version=2020-07-07 HTTP/1.1" 200 OK 3209 "-" "-" auth_id(-) May 09 06:43:55 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:43:55Z [INFO] - [mgmt] - - - [2022-05-09 06:43:55.000017854 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:44:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:00Z [INFO] - Checking edge runtime status May 09 06:44:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:00Z [INFO] - Querying system info... May 09 06:44:01 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:01Z [INFO] - Edge runtime is running. May 09 06:44:02 xxxxx-imx6ull aziot-edged[4257]: none May 09 06:44:02 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:02Z [INFO] - Successfully queried system info May 09 06:44:02 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:02Z [INFO] - [mgmt] - - - [2022-05-09 06:44:02.428216428 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:44:04 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:04Z [INFO] - [mgmt] - - - [2022-05-09 06:44:04.907095038 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:44:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:14Z [INFO] - [mgmt] - - - [2022-05-09 06:44:14.906218641 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:44:24 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:24Z [INFO] - [mgmt] - - - [2022-05-09 06:44:24.899026386 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:44:34 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:34Z [INFO] - [mgmt] - - - [2022-05-09 06:44:34.888225885 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:44:44 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:44Z [INFO] - [mgmt] - - - [2022-05-09 06:44:44.906210684 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:44:54 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:44:54Z [INFO] - [mgmt] - - - [2022-05-09 06:44:54.926598425 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:45:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:00Z [INFO] - Checking edge runtime status May 09 06:45:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:00Z [INFO] - Edge runtime is running. May 09 06:45:04 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:04Z [INFO] - [mgmt] - - - [2022-05-09 06:45:04.948250429 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:45:14 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:14Z [INFO] - [mgmt] - - - [2022-05-09 06:45:14.953776849 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:45:24 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:24Z [INFO] - [mgmt] - - - [2022-05-09 06:45:24.969045203 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:45:34 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:34Z [INFO] - Querying system info... May 09 06:45:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:35Z [INFO] - [mgmt] - - - [2022-05-09 06:45:35.057722823 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:45:35 xxxxx-imx6ull aziot-edged[4763]: none May 09 06:45:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:35Z [INFO] - Successfully queried system info May 09 06:45:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:35Z [INFO] - [mgmt] - - - [2022-05-09 06:45:35.271644763 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:45:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:45Z [INFO] - [mgmt] - - - [2022-05-09 06:45:45.039729497 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:45:55 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:45:55Z [INFO] - [mgmt] - - - [2022-05-09 06:45:55.040574909 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:46:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:00Z [INFO] - Checking edge runtime status May 09 06:46:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:00Z [INFO] - Edge runtime is running. May 09 06:46:05 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:05Z [INFO] - [mgmt] - - - [2022-05-09 06:46:05.059077464 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:46:06 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:06Z [INFO] - Querying system info... May 09 06:46:07 xxxxx-imx6ull aziot-edged[4916]: none May 09 06:46:07 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:07Z [INFO] - Successfully queried system info May 09 06:46:07 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:07Z [INFO] - [mgmt] - - - [2022-05-09 06:46:07.039026494 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:46:15 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:15Z [INFO] - [mgmt] - - - [2022-05-09 06:46:15.053369183 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:46:25 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:25Z [INFO] - [mgmt] - - - [2022-05-09 06:46:25.071450483 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:46:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:35Z [INFO] - [mgmt] - - - [2022-05-09 06:46:35.087961350 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:46:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:45Z [INFO] - [mgmt] - - - [2022-05-09 06:46:45.080818387 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:46:55 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:46:55Z [INFO] - [mgmt] - - - [2022-05-09 06:46:55.109138336 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:47:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:00Z [INFO] - Checking edge runtime status May 09 06:47:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:00Z [INFO] - Edge runtime is running. May 09 06:47:05 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:05Z [INFO] - [mgmt] - - - [2022-05-09 06:47:05.115756683 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:47:15 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:15Z [INFO] - [mgmt] - - - [2022-05-09 06:47:15.132137216 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:47:25 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:25Z [INFO] - [mgmt] - - - [2022-05-09 06:47:25.160556499 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:47:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:35Z [INFO] - [mgmt] - - - [2022-05-09 06:47:35.236845782 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:47:39 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:39Z [INFO] - Querying system info... May 09 06:47:39 xxxxx-imx6ull aziot-edged[5367]: none May 09 06:47:39 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:39Z [INFO] - Successfully queried system info May 09 06:47:39 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:39Z [INFO] - [mgmt] - - - [2022-05-09 06:47:39.492088172 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:47:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:45Z [INFO] - [mgmt] - - - [2022-05-09 06:47:45.227384420 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:47:55 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:47:55Z [INFO] - [mgmt] - - - [2022-05-09 06:47:55.217107299 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:00Z [INFO] - Checking edge runtime status May 09 06:48:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:00Z [INFO] - Edge runtime is running. May 09 06:48:05 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:05Z [INFO] - [mgmt] - - - [2022-05-09 06:48:05.227870690 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:10 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:10Z [INFO] - Querying system info... May 09 06:48:11 xxxxx-imx6ull aziot-edged[5493]: none May 09 06:48:11 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:11Z [INFO] - Successfully queried system info May 09 06:48:11 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:11Z [INFO] - [mgmt] - - - [2022-05-09 06:48:11.036681579 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:48:15 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:15Z [INFO] - [mgmt] - - - [2022-05-09 06:48:15.276381308 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:25 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:25Z [INFO] - [mgmt] - - - [2022-05-09 06:48:25.242973737 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:35Z [INFO] - [mgmt] - - - [2022-05-09 06:48:35.248005902 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:45Z [INFO] - [mgmt] - - - [2022-05-09 06:48:45.275826845 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:54 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:54Z [INFO] - Querying system resources... May 09 06:48:55 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:55Z [INFO] - [mgmt] - - - [2022-05-09 06:48:55.283596371 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:48:55 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:48:55Z [INFO] - [mgmt] - - - [2022-05-09 06:48:55.809253647 UTC] "GET /systeminfo/resources?api-version=2020-07-07 HTTP/1.1" 200 OK 3217 "-" "-" auth_id(-) May 09 06:49:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:00Z [INFO] - Checking edge runtime status May 09 06:49:00 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:00Z [INFO] - Edge runtime is running. May 09 06:49:05 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:05Z [INFO] - [mgmt] - - - [2022-05-09 06:49:05.286818183 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:49:15 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:15Z [INFO] - [mgmt] - - - [2022-05-09 06:49:15.306459156 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:49:25 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:25Z [INFO] - [mgmt] - - - [2022-05-09 06:49:25.316220171 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:49:35 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:35Z [INFO] - [mgmt] - - - [2022-05-09 06:49:35.327580728 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) May 09 06:49:42 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:42Z [INFO] - Querying system info... May 09 06:49:43 xxxxx-imx6ull aziot-edged[5908]: none May 09 06:49:43 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:43Z [INFO] - Successfully queried system info May 09 06:49:43 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:43Z [INFO] - [mgmt] - - - [2022-05-09 06:49:43.135280233 UTC] "GET /systeminfo?api-version=2020-07-07 HTTP/1.1" 200 OK 347 "-" "-" auth_id(-) May 09 06:49:45 xxxxx-imx6ull aziot-edged[1218]: 2022-05-09T06:49:45Z [INFO] - [mgmt] - - - [2022-05-09 06:49:45.358445482 UTC] "GET /modules?api-version=2020-07-07 HTTP/1.1" 200 OK 692 "-" "-" auth_id(-) IoT Edge Agent container logs 2022-05-09 06:37:15 +00:00 Starting Edge Agent 2022-05-09 06:37:16 +00:00 Changing ownership of storage folder: /tmp/edgeAgent to root 2022-05-09 06:37:16 +00:00 Changing ownership of management socket: /var/run/iotedge/mgmt.sock 2022-05-09 06:37:19.085 +00:00 Edge Agent Main() <6> 2022-05-09 06:37:26.390 +00:00 [INF] - Initializing Edge Agent. <6> 2022-05-09 06:37:31.956 +00:00 [INF] - Version - 1.2.9.53764592 (4bbfdb99cf7afb16f23d6177865942cc22e197ce) <6> 2022-05-09 06:37:31.978 +00:00 [INF] - █████╗ ███████╗██╗ ██╗██████╗ ███████╗ ██╔══██╗╚══███╔╝██║ ██║██╔══██╗██╔════╝ ███████║ ███╔╝ ██║ ██║██████╔╝█████╗ ██╔══██║ ███╔╝ ██║ ██║██╔══██╗██╔══╝ ██║ ██║███████╗╚██████╔╝██║ ██║███████╗ ╚═╝ ╚═╝╚══════╝ ╚═════╝ ╚═╝ ╚═╝╚══════╝ ██╗ ██████╗ ████████╗ ███████╗██████╗ ██████╗ ███████╗ ██║██╔═══██╗╚══██╔══╝ ██╔════╝██╔══██╗██╔════╝ ██╔════╝ ██║██║ ██║ ██║ █████╗ ██║ ██║██║ ███╗█████╗ ██║██║ ██║ ██║ ██╔══╝ ██║ ██║██║ ██║██╔══╝ ██║╚██████╔╝ ██║ ███████╗██████╔╝╚██████╔╝███████╗ ╚═╝ ╚═════╝ ╚═╝ ╚══════╝╚═════╝ ╚═════╝ ╚══════╝ <6> 2022-05-09 06:37:33.511 +00:00 [INF] - Experimental features configuration: {"Enabled":false,"DisableCloudSubscriptions":false} <6> 2022-05-09 06:37:38.536 +00:00 [INF] - Installing certificates [CN=iotedged workload ca xxxxx-imx6ull:08/03/2022 09:37:30] to Root <6> 2022-05-09 06:37:43.744 +00:00 [INF] - Starting metrics listener on Host: *, Port: 9600, Suffix: metrics <6> 2022-05-09 06:37:44.488 +00:00 [INF] - Updating performance metrics every 05m:00s <6> 2022-05-09 06:37:44.655 +00:00 [INF] - Started operation Get system resources <6> 2022-05-09 06:37:44.703 +00:00 [INF] - Collecting metadata metrics <6> 2022-05-09 06:37:48.241 +00:00 [INF] - Set metadata metrics: 1.2.9.53764592 (4bbfdb99cf7afb16f23d6177865942cc22e197ce), {"Enabled":false,"DisableCloudSubscriptions":false}, {"OperatingSystemType":"linux","Architecture":"armv7l","Version":"1.2.6","AdditionalProperties":{}}, True <6> 2022-05-09 06:37:49.154 +00:00 [INF] - Started operation Checkpoint Availability <6> 2022-05-09 06:37:49.397 +00:00 [INF] - Started operation refresh twin config <6> 2022-05-09 06:37:51.253 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <6> 2022-05-09 06:38:13.433 +00:00 [INF] - Created persistent store at /tmp/edgeAgent <6> 2022-05-09 06:38:15.990 +00:00 [INF] - Started operation Metrics Scrape <6> 2022-05-09 06:38:16.016 +00:00 [INF] - Started operation Metrics Upload Scraping frequency: 01:00:00 Upload Frequency: 1.00:00:00 <6> 2022-05-09 06:38:31.929 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <6> 2022-05-09 06:38:37.373 +00:00 [INF] - Registering request handler UploadModuleLogs <6> 2022-05-09 06:38:37.406 +00:00 [INF] - Registering request handler GetModuleLogs <6> 2022-05-09 06:38:37.418 +00:00 [INF] - Registering request handler UploadSupportBundle <6> 2022-05-09 06:38:37.426 +00:00 [INF] - Registering request handler RestartModule <4> 2022-05-09 06:38:46.251 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:38:46.380 +00:00 [INF] - Edge agent config backup file does not exist - /tmp/edgeAgent/backup.json <4> 2022-05-09 06:38:56.914 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:39:07.220 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:39:12.732 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (1978011664): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <6> 2022-05-09 06:39:13.615 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:39:17.480 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:39:27.732 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:39:37.827 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:39:45.166 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <4> 2022-05-09 06:39:47.910 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:39:57.977 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:40:08.129 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:40:18.321 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:40:18.832 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (0x80004005): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <6> 2022-05-09 06:40:25.409 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:40:28.467 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:40:38.546 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:40:48.657 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:40:58.765 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:40:59.285 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <4> 2022-05-09 06:41:08.877 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:41:18.985 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:41:29.042 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:41:32.303 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (0x80004005): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <4> 2022-05-09 06:41:39.079 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:41:45.686 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:41:49.180 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:41:59.221 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:42:09.267 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:42:19.295 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:42:19.431 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <4> 2022-05-09 06:42:29.383 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:42:39.446 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:42:49.486 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:42:54.557 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (0x80004005): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <4> 2022-05-09 06:42:59.535 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:43:09.578 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:43:19.608 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:43:28.225 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:43:29.646 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:43:39.682 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:43:49.760 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:43:59.770 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:44:00.635 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <4> 2022-05-09 06:44:09.784 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:44:19.817 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:44:29.840 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:44:34.505 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (0x80004005): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <4> 2022-05-09 06:44:39.842 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:44:49.856 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:44:59.879 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:45:09.895 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:45:19.906 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:45:29.917 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:45:34.774 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:45:39.936 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:45:49.966 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:45:59.985 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:46:06.385 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <4> 2022-05-09 06:46:09.986 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:46:19.987 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:46:30.002 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:46:38.737 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 230 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (0x80004005): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 234 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <4> 2022-05-09 06:46:40.015 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:46:50.047 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:47:00.057 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:47:10.072 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:47:20.089 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:47:30.118 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:47:39.001 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:47:40.145 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:47:50.146 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:48:00.152 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:48:10.152 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:48:10.422 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_WebSocket_Only... <4> 2022-05-09 06:48:20.164 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:48:30.175 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:48:40.198 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:48:42.526 +00:00 [WRN] - Error creating a device-to-cloud connection System.AggregateException: One or more errors occurred. (Transient network error occurred, please retry.) (Transient network error occurred, please retry.) ---> Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 234 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__2>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 188 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options) --- End of inner exception stack trace --- ---> (Inner Exception #1) Microsoft.Azure.Devices.Client.Exceptions.IotHubCommunicationException: Transient network error occurred, please retry. ---> System.Net.WebSockets.WebSocketException (0x80004005): Unable to connect to the remote server ---> System.Net.Http.HttpRequestException: Resource temporarily unavailable ---> System.Net.Sockets.SocketException (11): Resource temporarily unavailable at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) --- End of inner exception stack trace --- at System.Net.Http.ConnectHelper.ConnectAsync(String host, Int32 port, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean allowHttp2, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.GetHttpConnectionAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean doRequestAuth, CancellationToken cancellationToken) at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, CancellationToken cancellationToken) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.WebSocketHandle.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken, ClientWebSocketOptions options) at System.Net.WebSockets.ClientWebSocket.ConnectAsyncCore(Uri uri, CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketAsync(Uri websocketUri, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.CreateClientWebSocketTransportAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpIoTTransport.InitializeAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpIoTConnector.OpenConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.EnsureConnectionAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpConnectionHolder.OpenSessionAsync(DeviceIdentity deviceIdentity, TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.EnsureSessionIsOpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.AmqpIoT.AmqpUnit.OpenAsync(TimeSpan timeout) at Microsoft.Azure.Devices.Client.Transport.Amqp.AmqpTransportHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ProtocolRoutingDelegatingHandler.OpenAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.<>c__DisplayClass27_0.<<ExecuteWithErrorHandlingAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) --- End of inner exception stack trace --- at Microsoft.Azure.Devices.Client.Transport.ErrorDelegatingHandler.ExecuteWithErrorHandlingAsync[T](Func`1 asyncOperation) at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.<>c__DisplayClass38_0.<<OpenInternalAsync>b__0>d.MoveNext() --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Client.Transport.RetryDelegatingHandler.EnsureOpenedAsync(CancellationToken cancellationToken) at Microsoft.Azure.Devices.Client.InternalClient.OpenAsync() at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.CreateAndOpenSdkModuleClient(UpstreamProtocol upstreamProtocol, ConnectionStatusChangesHandler statusChangedHandler) in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 234 at Microsoft.Azure.Devices.Edge.Agent.IoTHub.ModuleClientProvider.<>c__DisplayClass20_1.<<CreateSdkModuleClient>b__3>d.MoveNext() in /mnt/vss/_work/1/s/edge-agent/src/Microsoft.Azure.Devices.Edge.Agent.IoTHub/ModuleClientProvider.cs:line 194 --- End of stack trace from previous location where exception was thrown --- at Microsoft.Azure.Devices.Edge.Util.Fallback.ExecuteAsync[T](Func`1[] options)<--- <4> 2022-05-09 06:48:50.203 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:49:00.227 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:49:10.241 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:49:20.254 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:49:30.266 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:49:40.277 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <6> 2022-05-09 06:49:42.702 +00:00 [INF] - Edge agent attempting to connect to IoT Hub via Amqp_Tcp_Only... <4> 2022-05-09 06:49:50.305 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead <4> 2022-05-09 06:50:00.321 +00:00 [WRN] - Empty edge agent config was received. Attempting to read config from backup (/tmp/edgeAgent/backup.json) instead
jiannapatel
May 09, 2022 Place Azure IoT
2.9KViews
0likes
0Comments
IoT Edge Modules - C2D Messages - Availability date?
Hi, I was trying to implement C2D message handlers in IoT Edge modules. After a while trying to understand why my code did not work, I have just figured out that they are not available for IoT Edge modules (they are already available for regular IoT modules). Link mentioning the limitation: https://docs.microsoft.com/en-us/azure/iot-edge/module-development Are C2D messages going to be added to IoT Edge modules? If so, any idea about the release date? Thanks.
jonmikeli
Dec 22, 2019 Place Azure IoT
1.2KViews
0likes
0Comments
Debug remote IoT Edge C# Module (Linux) with Visual Studio 2019 (16.3)
There is no doubt now IoT Edge has proven its place and its importance in the IoT ecosystem. Do you want to know how to create your own C# IoT Edge Module for Linux and debug it remotely with Visual Studio 2019? Renlong Tu gives you the steps to follow in his post.
jonmikeli
Nov 23, 2019 Place Azure IoT
813Views
0likes
0Comments
Azure Event Grid on Azure IoT Edge
Hi all, I just wanted to share something I had missed and that could be useful: Azure Event Grid on Azure IoT Edge. This could be used for building event-driven architectures between the edge and the cloud, to trigger simple workflows between models, extend functionality between edge devices, etc. https://docs.microsoft.com/en-us/azure/event-grid/edge/overview
jonmikeli
Oct 28, 2019 Place Azure IoT
644Views
0likes
0Comments
Step-by-step guide to installing OPC Publisher on Azure IoT Edge
Of all the Azure IoT Edge modules, the OPC Publisher is one of the more challenging modules to setup. I've had a number of Industrial IoT partners struggle with connecting an OPC UA server, such as a Historian or SCADA system, to Azure IoT Hub using Azure IoT Edge and our open source OPC Publisher module. So I recently wrote a step-by-step blog post on LinkedIn on how to do this. If you don't have a physical device to act as the IoT Edge gateway, you can use a virtual machine running on-premise or in Azure as an alternative. Hopefully the blog post will help you and save you some headaches when connecting using OPC UA.
khilscher
Sep 06, 2019 Place Azure IoT
9.3KViews
3likes
0Comments
Azure IoT Edge Blob storage module, introduction and cloud synchronization
Recently, the Azure UiT Edge Blob storage module was made generally available. If you need local blob storage on your IoT Edge device with the same programming model as the cloud version, check out this introduction: Introducing Blob storage in Azure IoT on the Edge The local blob storage now comes with automatic synchronization to the cloud. Read about it in: Azure IoT Edge Blob module posts BlockBlobs blocks dosed in Storage
Sander van de Velde
Aug 31, 2019 Place Azure IoT
1.2KViews
0likes
0Comments