Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [DBUG] - [hyper::proto::h1::io] flushed 226 bytes
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [DBUG] - [hyper::proto::h1::io] parsed 5 headers
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (451 bytes)
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [ERR!] - Failed to provision with IoT Hub, and no valid device backup was found: DPS client error
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [ERR!] - service encountered an error
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [ERR!] - caused by: DPS client error
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [ERR!] - caused by: Custom allocation failed with status code: 400
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]: 2025-01-13T14:18:12Z [ERR!] -    0: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    1: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    2: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    3: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    4: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    5: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    6: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    7: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    8: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:    9: <unknown>
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:   10: __libc_start_call_main
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:              at usr/src/debug/glibc/2.35-r0/git/csu/../sysdeps/nptl/libc_start_call_main.h:58:16
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:   11: __libc_start_main_impl
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:              at usr/src/debug/glibc/2.35-r0/git/csu/../csu/libc-start.c:389:3
Jan 13 14:18:12 gateway-3002114328 aziot-identityd[13904]:   12: <unknown>
Jan 13 14:18:12 gateway-3002114328 systemd[1]: aziot-identityd.service: Main process exited, code=exited, status=1/FAILURE
Jan 13 14:18:12 gateway-3002114328 systemd[1]: aziot-identityd.service: Failed with result 'exit-code'.
Jan 13 14:18:17 gateway-3002114328 systemd[1]: aziot-identityd.service: Scheduled restart job, restart counter is at 76.
Jan 13 14:18:17 gateway-3002114328 systemd[1]: Stopped Azure IoT Identity Service.
Jan 13 14:18:17 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] parse error (connection error: Connection reset by peer (os error 104)) with 0 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::dispatch] read_head error: connection error: Connection reset by peer (os error 104)
Jan 13 14:18:17 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:17Z [WARN] - Failed to send HTTP request (attempt 1 of 2): connection error: Connection reset by peer (os error 104)
Jan 13 14:18:17 gateway-3002114328 systemd[1]: Started Azure IoT Identity Service.
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [INFO] - Starting service...
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [INFO] - Version - dev build
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [INFO] - Loaded openssl'd Default provider
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [INFO] - Certificate device-id will be auto-renewed. Next renewal at 2025-11-01T14:16:12+00:00.
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [INFO] - Provisioning starting. Reason: Startup
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 75 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - <-- GET /keypair/device-id?api-version=2021-05-01 {"host": "keyd.sock"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 357 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - <-- POST /parameters/algorithm?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 123 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - <-- POST /parameters/rsa-modulus?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 465 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - <-- POST /parameters/rsa-exponent?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] flushed 124 bytes
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [INFO] - Sending DPS registration request.
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:17 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:17Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:17 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:17Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:18 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:18Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:18 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:18Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:18 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:18Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:18 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:18Z [DBUG] - [hyper::proto::h1::io] flushed 385 bytes
Jan 13 14:18:19 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:19Z [DBUG] - [hyper::proto::h1::io] parsed 7 headers
Jan 13 14:18:19 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:19Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (94 bytes)
Jan 13 14:18:19 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:19Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:19 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:19Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:20 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:20Z [DBUG] - [hyper::proto::h1::io] flushed 149 bytes
Jan 13 14:18:24 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:24Z [INFO] - Checking DPS registration status.
Jan 13 14:18:24 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:24Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:24 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:24Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:24 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:24Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:24 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:24Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:24 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:24Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:24 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:24Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:24 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:24Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:25 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:25Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:25 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:25Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:25 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:25Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [DBUG] - [hyper::proto::h1::io] flushed 226 bytes
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [DBUG] - [hyper::proto::h1::io] parsed 5 headers
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (450 bytes)
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [ERR!] - Failed to provision with IoT Hub, and no valid device backup was found: DPS client error
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [ERR!] - service encountered an error
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [ERR!] - caused by: DPS client error
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [ERR!] - caused by: Custom allocation failed with status code: 400
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]: 2025-01-13T14:18:25Z [ERR!] -    0: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    1: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    2: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    3: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    4: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    5: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    6: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    7: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    8: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:    9: <unknown>
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:   10: __libc_start_call_main
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:              at usr/src/debug/glibc/2.35-r0/git/csu/../sysdeps/nptl/libc_start_call_main.h:58:16
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:   11: __libc_start_main_impl
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:              at usr/src/debug/glibc/2.35-r0/git/csu/../csu/libc-start.c:389:3
Jan 13 14:18:25 gateway-3002114328 aziot-identityd[14294]:   12: <unknown>
Jan 13 14:18:25 gateway-3002114328 systemd[1]: aziot-identityd.service: Main process exited, code=exited, status=1/FAILURE
Jan 13 14:18:25 gateway-3002114328 systemd[1]: aziot-identityd.service: Failed with result 'exit-code'.
Jan 13 14:18:30 gateway-3002114328 systemd[1]: aziot-identityd.service: Scheduled restart job, restart counter is at 77.
Jan 13 14:18:30 gateway-3002114328 systemd[1]: Stopped Azure IoT Identity Service.
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] parse error (connection error: Connection reset by peer (os error 104)) with 0 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::dispatch] read_head error: connection error: Connection reset by peer (os error 104)
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [WARN] - Failed to send HTTP request (attempt 2 of 2): connection error: Connection reset by peer (os error 104)
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [WARN] - Failed to obtain device identity: connection error: Connection reset by peer (os error 104)
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [INFO] - Requesting device reprovision
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [WARN] - Failed to clear provisioning cache before reprovision: No such file or directory (os error 2)
Jan 13 14:18:30 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:30Z [WARN] - Failed to send HTTP request (attempt 1 of 2): error trying to connect: Permission denied (os error 13)
Jan 13 14:18:30 gateway-3002114328 systemd[1]: Started Azure IoT Identity Service.
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [INFO] - Starting service...
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [INFO] - Version - dev build
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [INFO] - Loaded openssl'd Default provider
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [INFO] - Certificate device-id will be auto-renewed. Next renewal at 2025-11-01T14:16:12+00:00.
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [INFO] - Provisioning starting. Reason: Startup
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:30 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 75 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - <-- GET /keypair/device-id?api-version=2021-05-01 {"host": "keyd.sock"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 357 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - <-- POST /parameters/algorithm?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 123 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - <-- POST /parameters/rsa-modulus?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 465 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - <-- POST /parameters/rsa-exponent?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] flushed 124 bytes
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [INFO] - Sending DPS registration request.
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:30 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:30Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:30 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:30Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:31 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:31Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:31 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:31Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:31 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:31Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:31 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:31Z [DBUG] - [hyper::proto::h1::io] flushed 385 bytes
Jan 13 14:18:31 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:31Z [DBUG] - [hyper::proto::h1::io] parsed 7 headers
Jan 13 14:18:31 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:31Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (94 bytes)
Jan 13 14:18:31 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:31Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:31 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:31Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:33 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:33Z [DBUG] - [hyper::proto::h1::io] flushed 161 bytes
Jan 13 14:18:36 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:36Z [INFO] - Checking DPS registration status.
Jan 13 14:18:36 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:36Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:36 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:36Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:36 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:36Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:36 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:36Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:36 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:36Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:36 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:36Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:36 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:36Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::io] flushed 226 bytes
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::io] parsed 5 headers
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (556 bytes)
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [INFO] - Assigned to IoT hub: iot-XXX-01.azure-devices.net
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [INFO] - DPS registration complete.
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [INFO] - Successfully provisioned with DPS.
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [INFO] - Provisioning complete.
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [INFO] - Identity reconciliation started. Reason: Startup
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::client::connect::dns] resolving host="iot-XXX-01.azure-devices.net"
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:37 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::io] flushed 159 bytes
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:37 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:37Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::io] parsed 6 headers
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (1450 bytes)
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", iot-XXX-01.azure-devices.net)
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::client::connect::dns] resolving host="iot-XXX-01.azure-devices.net"
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:38 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::io] flushed 324 bytes
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:38 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:38Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 6 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (566 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", iot-XXX-01.azure-devices.net)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 87 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - <-- GET /key/aziot_identityd_master_id?api-version=2021-05-01 {"host": "keyd.sock"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [ERR!] - invalid parameter "id": not found
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - !!! a parameter has an invalid value
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 400 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 163 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (46 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 189 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (75 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - <-- POST /key?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "75"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 373 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (348 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - <-- POST /derivedkey?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "348"}
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (264 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 881 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (772 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 901 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (772 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - <-- POST /derivedkey/export?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "772"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 162 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (54 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 474 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (352 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - <-- POST /derivedkey?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "352"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 889 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (780 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 909 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (780 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - <-- POST /derivedkey/export?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "780"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] flushed 162 bytes
Jan 13 14:18:39 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (54 bytes)
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:39 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:39Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:40 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:40Z [DBUG] - [hyper::client::connect::dns] resolving host="iot-XXX-01.azure-devices.net"
Jan 13 14:18:40 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:40Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:40 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:40Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:40 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:40Z [DBUG] - [hyper::proto::h1::io] flushed 509 bytes
Jan 13 14:18:40 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:40Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:40 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:40Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:40 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:40Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:40 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:40Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::io] parsed 6 headers
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (568 bytes)
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", iot-XXX-01.azure-devices.net)
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [INFO] - Hub identity "IoTHubDeviceUpdate" added
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [INFO] - Identity reconciliation complete.
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [INFO] - Starting server...
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [INFO] - <-- GET http://foo/identities/identity?api-version=2020-09-01 {"host": "/run/aziot/identityd.sock:0", "content-length": "0"}
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::client::connect::dns] resolving host="iot-XXX-01.azure-devices.net"
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (16 bytes)
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [INFO] - <-- POST /identities/device/reprovision?api-version=2020-09-01 {"content-type": "application/json", "host": "identityd.sock", "content-length": "16"}
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:41 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::io] flushed 178 bytes
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:41 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:41Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 6 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (568 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", iot-XXX-01.azure-devices.net)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 87 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- GET /key/aziot_identityd_master_id?api-version=2021-05-01 {"host": "keyd.sock"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 373 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (264 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (772 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (348 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /derivedkey?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "348"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 901 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 881 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (772 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /derivedkey/export?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "772"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 162 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (54 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 474 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (780 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 909 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (352 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /derivedkey?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "352"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 889 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (780 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /derivedkey/export?api-version=2021-05-01 {"content-type": "application/json", "host": "keyd.sock", "content-length": "780"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 162 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (1008 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /sign?api-version=2020-09-01 {"content-type": "application/json", "host": "/run/aziot/keyd.sock:0", "content-length": "1008"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 168 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:42 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:42 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:42Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:42 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (54 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 1158 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [INFO] - Provisioning starting. Reason: Api
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 75 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- GET /keypair/device-id?api-version=2021-05-01 {"host": "keyd.sock"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 357 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /parameters/algorithm?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 123 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /parameters/rsa-modulus?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [INFO] - <-- GET http://foo/identities/identity?api-version=2020-09-01 {"host": "/run/aziot/identityd.sock:0", "content-length": "0"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 465 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /parameters/rsa-exponent?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] flushed 124 bytes
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [INFO] - Sending DPS registration request.
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:42 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:42Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:42 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:42Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:43 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:43Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:43 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:43Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:43 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:43Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:43 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:43Z [DBUG] - [hyper::proto::h1::io] flushed 385 bytes
Jan 13 14:18:43 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:43Z [DBUG] - [hyper::proto::h1::io] parsed 7 headers
Jan 13 14:18:43 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:43Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (94 bytes)
Jan 13 14:18:43 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:43Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:43 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:43Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:48 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:48Z [INFO] - Checking DPS registration status.
Jan 13 14:18:48 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:48Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:48 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:48Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:48 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:48Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:48 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:48Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:48 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:48Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:48 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:48Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:48 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:48Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:49 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:49Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:49 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::io] flushed 226 bytes
Jan 13 14:18:49 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::io] parsed 5 headers
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (451 bytes)
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [INFO] - !!! DPS client error
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [INFO] - !!! caused by: Custom allocation failed with status code: 400
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [INFO] - --> 404 {"content-type": "application/json"}
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::io] flushed 204 bytes
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [INFO] - !!! device identity not found
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [INFO] - --> 400 {"content-type": "application/json"}
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::io] flushed 156 bytes
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:49 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:49 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:49 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (89 bytes)
Jan 13 14:18:49 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:49Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:49 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:49Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", identityd.sock)
Jan 13 14:18:49 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:49Z [WARN] - Failed to reprovision: invalid HTTP status code
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [INFO] - Obtaining Edge device provisioning data...
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 149 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (39 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (16 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [INFO] - <-- POST /identities/device?api-version=2020-09-01 {"content-type": "application/json", "host": "identityd.sock", "content-length": "16"}
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [INFO] - !!! device identity not found
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [INFO] - --> 400 {"content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 156 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", identityd.sock)
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [WARN] - Failed to obtain device identity: device identity not found
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [INFO] - Requesting device reprovision
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [WARN] - Failed to clear provisioning cache before reprovision: No such file or directory (os error 2)
Jan 13 14:18:54 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 161 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (16 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [INFO] - <-- POST /identities/device/reprovision?api-version=2020-09-01 {"content-type": "application/json", "host": "identityd.sock", "content-length": "16"}
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [INFO] - Provisioning starting. Reason: Api
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 81 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (2632 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:54 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:54 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:54Z [INFO] - <-- GET /certificates/device-id?api-version=2020-09-01 {"host": "certd.sock"}
Jan 13 14:18:54 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:54Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 2742 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", certd.sock)
Jan 13 14:18:54 gateway-3002114328 aziot-certd[9942]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 75 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 1 headers
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - <-- GET /keypair/device-id?api-version=2021-05-01 {"host": "keyd.sock"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 357 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - <-- POST /parameters/algorithm?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", keyd.sock)
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 123 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - <-- POST /parameters/rsa-modulus?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 465 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (248 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - <-- POST /parameters/rsa-exponent?api-version=2021-05-01 {"content-length": "248", "content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] flushed 124 bytes
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [INFO] - Sending DPS registration request.
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:18:54 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:54Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:18:54 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:54Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:55 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:55Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:18:55 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:18:55 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::io] flushed 385 bytes
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::conn] incoming body is empty
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [INFO] - <-- GET http://foo/identities/identity?api-version=2020-09-01 {"host": "/run/aziot/identityd.sock:0", "content-length": "0"}
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::io] parsed 7 headers
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (94 bytes)
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:18:55 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:18:55Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:19:00 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:00Z [INFO] - Checking DPS registration status.
Jan 13 14:19:00 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:00Z [DBUG] - [hyper::client::connect::dns] resolving host="global.azure-devices-provisioning.net"
Jan 13 14:19:00 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:00Z [DBUG] - [hyper::client::connect::http] connecting to XXX.XXX.XXX.XXX:443
Jan 13 14:19:00 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:00Z [DBUG] - [hyper::client::connect::http] connected to XXX.XXX.XXX.XXX:443
Jan 13 14:19:00 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:00Z [DBUG] - [hyper::proto::h1::io] parsed 2 headers
Jan 13 14:19:00 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:00Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (636 bytes)
Jan 13 14:19:00 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:00Z [INFO] - <-- POST /encrypt?api-version=2021-05-01 {"content-length": "636", "content-type": "application/json"}
Jan 13 14:19:00 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:00Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:19:01 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:01Z [INFO] - --> 200 {"content-type": "application/json"}
Jan 13 14:19:01 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::io] flushed 470 bytes
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::io] flushed 226 bytes
Jan 13 14:19:01 gateway-3002114328 aziot-keyd[9950]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::io] parsed 5 headers
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (450 bytes)
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::client::pool] pooling idle connection for ("https", global.azure-devices-provisioning.net)
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [INFO] - !!! DPS client error
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [INFO] - !!! caused by: Custom allocation failed with status code: 400
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [INFO] - --> 404 {"content-type": "application/json"}
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::io] flushed 204 bytes
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [INFO] - !!! device identity not found
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [INFO] - --> 400 {"content-type": "application/json"}
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::io] flushed 156 bytes
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:19:01 gateway-3002114328 aziot-identityd[14686]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] read eof
Jan 13 14:19:01 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:19:01 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (89 bytes)
Jan 13 14:19:01 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:01Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:19:01 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:01Z [WARN] - Failed to reprovision: invalid HTTP status code
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [INFO] - Obtaining Edge device provisioning data...
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [DBUG] - [hyper::proto::h1::io] flushed 149 bytes
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [DBUG] - [hyper::proto::h1::io] parsed 3 headers
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [DBUG] - [hyper::proto::h1::conn] incoming body is content-length (39 bytes)
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [DBUG] - [hyper::proto::h1::conn] incoming body completed
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [DBUG] - [hyper::client::pool] pooling idle connection for ("http", identityd.sock)
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [WARN] - Failed to obtain device identity: device identity not found
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [INFO] - Requesting device reprovision
Jan 13 14:19:06 gateway-3002114328 aziot-edged[9927]: 2025-01-13T14:19:06Z [WARN] - Failed to clear provisioning cache before reprovision: No such file or directory (os error 2)