search

Azure / Azurite

A lightweight server clone of Azure Storage that simulates most of the commands supported by it with minimal dependencies
MIT License
1.8k stars 320 forks source link

[QUEUE] How to upload a file from Client-side to docker's Azurite when CORS error occour #713

Open WillRock19 opened 3 years ago

WillRock19 commented 3 years ago

Which service(blob, file, queue, table) does this issue concern?

blob

Which version of the Azurite was used?

latest image in docker

Where do you get Azurite? (npm, DockerHub, NuGet, Visual Studio Code Extension)

DockerHub

What's the Node.js version?

What problem was encountered?

The Storage Works, but when I try to upload a file from the client-side (using azure-storage.blob.min.js) using a SAS token, I get the following error:

image

IMPORTANT: The problem IS NOT the SAS token, because when I use the Storage Emulator.

Steps to reproduce the issue?

  1. Execute docker run -p 10000:10000 -p 10001:10001 -v C:\workspace:/workspace mcr.microsoft.com/azure-storage/azurite;
  2. Run the client library in some SPA app;
  3. Try to Upload a file from the browser;

Have you found a mitigation/solution?

Not yet.

blueww commented 3 years ago

@WillRock19 Would you please share the debug log for Azurite? And have you set any cors rules before the failure happen?

Besides that, for Azurite version, would you please check the last image in docker on you machine is 3.11.0? (sometimes there can be some cached docker image)

WillRock19 commented 3 years ago

Hi @blueww, sorry about the delay. Sure I can.

I've made some more tests, tried to execute the azurita with different commands, and noticed that if I execute with the following:

docker run -p 10000:10000 -p 10001:10001 -v C:\workspace:/workspace mcr.microsoft.com/azure-storage/azurite  azurite -l /workspace -d /workspace/debug.log

Despite the debug and all the files being created (and the container starts running), I cannot access the container through the Azure Storage Emulator. It throws an error. So, I could execute it in debug using the following command:

docker run -p 10000:10000 -p 10001:10001 -v C:\workspace:/workspace mcr.microsoft.com/azure-storage/azurite azurite -l /workspace -d /workspace/debug.log --blobHost 0.0.0.0 --queueHost 0.0.0.0 --loose --skipApiVersionCheck

With this, I was able to access the container via the Azure Storage Emulator, and got the CORS error when trying to upload from the browser using the azure-storage.blob.min.js library. The debug output is as follows:

2021-03-07T13:05:29.464Z     info: Azurite Blob service is starting on 0.0.0.0:10000
2021-03-07T13:05:29.466Z     info: AccountDataStore:init() Refresh accounts from environment variable AZURITE_ACCOUNTS with value undefined
2021-03-07T13:05:29.466Z     info: AccountDataStore:init() Fallback to default emulator account devstoreaccount1.
2021-03-07T13:05:29.498Z     info: BlobGCManager:start() Starting BlobGCManager. Set status to Initializing.
2021-03-07T13:05:29.498Z     info: BlobGCManager:start() Trigger mark and sweep loop. Set status to Running.
2021-03-07T13:05:29.499Z     info: BlobGCManager:markSweepLoop() Start next mark and sweep.
2021-03-07T13:05:29.499Z     info: BlobGCManager:markSweep() Get all extents.
2021-03-07T13:05:29.500Z     info: BlobGCManager:start() BlobGCManager successfully started.
2021-03-07T13:05:29.502Z     info: BlobGCManager:markSweep() Got 0 extents.
2021-03-07T13:05:29.502Z     info: BlobGCManager:markSweep() Get referred extents.
2021-03-07T13:05:29.502Z     info: BlobGCManager:markSweep() Got referred extents, unreferenced extents count is 0.
2021-03-07T13:05:29.502Z     info: BlobGCManager:markSweepLoop() Mark and sweep finished, taken 3ms.
2021-03-07T13:05:29.502Z     info: BlobGCManager:markSweepLoop() Sleep for 600000ms.
2021-03-07T13:05:29.503Z     info: Azurite Blob service successfully listens on http://0.0.0.0:10000
2021-03-07T13:05:29.504Z     info: Azurite Queue service is starting on 0.0.0.0:10001
2021-03-07T13:05:29.504Z     info: AccountDataStore:init() Refresh accounts from environment variable AZURITE_ACCOUNTS with value undefined
2021-03-07T13:05:29.504Z     info: AccountDataStore:init() Fallback to default emulator account devstoreaccount1.
2021-03-07T13:05:29.537Z     info: QueueGCManager:start() Starting QueueGCManager, set status to Initializing
2021-03-07T13:05:29.537Z     info: QueueGCManager:start() Trigger mark and sweep loop, set status to Running.
2021-03-07T13:05:29.537Z     info: QueueGCManager:markSweepLoop() Start new mark and sweep.
2021-03-07T13:05:29.537Z     info: QueueGCManger:markSweep() Get all extents.
2021-03-07T13:05:29.538Z     info: QueueGCManager:start() QueueGCManager successfully started.
2021-03-07T13:05:29.538Z     info: QueueGCManager:marksweep() Get 0 extents.
2021-03-07T13:05:29.538Z     info: QueueGCManager:markSweep() Get referred extents, then remove from allExtents.
2021-03-07T13:05:29.538Z     info: QueueGCManager:markSweep() Got referred extents, unreferenced extents count is 0.
2021-03-07T13:05:29.538Z     info: QueueGCManager:markSweepLoop() Mark and sweep finished, take 1ms.
2021-03-07T13:05:29.538Z     info: QueueGCManager:markSweepLoop() Sleep for 60000
2021-03-07T13:05:29.539Z     info: Azurite Queue service successfully listens on http://0.0.0.0:10001
2021-03-07T13:05:54.995Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/records?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"c8a69d4f-3eb1-49d9-be40-c4a74cd7ed2a","x-ms-date":"Mon, 08 Mar 2021 11:29:39 GMT","authorization":"SharedKey devstoreaccount1:jT2CMl+k3YRoQnUrtflvnhQ9B61BK353XgsOgNreygY=","host":"127.0.0.1:10000","request-id":"|7c8e254e-851d-4a89-a954-69c3aba9c9eb.961273750aa2ac4c.","traceparent":"00-334a18dc14bb844ca6c9d8393fc544ec-961273750aa2ac4c-00","content-length":"0","connection":"Keep-Alive"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:05:54.995Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=
2021-03-07T13:05:54.996Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:05:54.998Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:05:54.999Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:05:54.999Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:05:54.999Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:05:54.999Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 
2021-03-07T13:05:55.003Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:05:55.004Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:05:55.012Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:c8a69d4f-3eb1-49d9-be40-c4a74cd7ed2a\nx-ms-date:Mon, 08 Mar 2021 11:29:39 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records\nrestype:container"
2021-03-07T13:05:55.014Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:jT2CMl+k3YRoQnUrtflvnhQ9B61BK353XgsOgNreygY=
2021-03-07T13:05:55.014Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:05:55.014Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:05:55.015Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"c8a69d4f-3eb1-49d9-be40-c4a74cd7ed2a"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:05:55.017Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:05:55.017Z d8afa12d-3272-4bff-b2bc-cb4ca0192afe info: EndMiddleware: End response. TotalTimeInMS=22 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x1D85729FF6A26A0\"","last-modified":"Sun, 07 Mar 2021 13:05:54 GMT","x-ms-client-request-id":"c8a69d4f-3eb1-49d9-be40-c4a74cd7ed2a","x-ms-request-id":"d8afa12d-3272-4bff-b2bc-cb4ca0192afe","x-ms-version":"2020-06-12"}
2021-03-07T13:05:55.048Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: BlobStorageContextMiddleware: RequestMethod=GET RequestURL=http://127.0.0.1/devstoreaccount1/records?restype=container&comp=acl RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"007e335c-45a7-48d6-9dce-bd40c39ad797","x-ms-date":"Mon, 08 Mar 2021 11:29:39 GMT","authorization":"SharedKey devstoreaccount1:MMLDrmXHaQ8IOCkl3H9RjynXZ7pg0Z26/qNYaR3Lej0=","host":"127.0.0.1:10000","request-id":"|7c8e254e-851d-4a89-a954-69c3aba9c9eb.92fc90bef7a4464a.","traceparent":"00-334a18dc14bb844ca6c9d8393fc544ec-92fc90bef7a4464a-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:05:55.048Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=
2021-03-07T13:05:55.049Z 715964f7-f7cb-41e1-9e66-8075e3c86083 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:05:55.049Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: DispatchMiddleware: Operation=Container_GetAccessPolicy
2021-03-07T13:05:55.049Z 715964f7-f7cb-41e1-9e66-8075e3c86083 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:05:55.049Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:05:55.049Z 715964f7-f7cb-41e1-9e66-8075e3c86083 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:05:55.049Z 715964f7-f7cb-41e1-9e66-8075e3c86083 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 
2021-03-07T13:05:55.050Z 715964f7-f7cb-41e1-9e66-8075e3c86083 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:05:55.050Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:05:55.050Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"GET\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:007e335c-45a7-48d6-9dce-bd40c39ad797\nx-ms-date:Mon, 08 Mar 2021 11:29:39 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records\ncomp:acl\nrestype:container"
2021-03-07T13:05:55.050Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:MMLDrmXHaQ8IOCkl3H9RjynXZ7pg0Z26/qNYaR3Lej0=
2021-03-07T13:05:55.050Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:05:55.051Z 715964f7-f7cb-41e1-9e66-8075e3c86083 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:05:55.051Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"007e335c-45a7-48d6-9dce-bd40c39ad797","leaseAccessConditions":{}},"restype":"container","comp":"acl","version":"2018-03-28"}
2021-03-07T13:05:55.051Z 715964f7-f7cb-41e1-9e66-8075e3c86083 verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:05:55.052Z 715964f7-f7cb-41e1-9e66-8075e3c86083 debug: Serializer: Raw response body string is <?xml version="1.0" encoding="UTF-8" standalone="yes"?><SignedIdentifiers/>
2021-03-07T13:05:55.052Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: Serializer: Start returning stream body.
2021-03-07T13:05:55.052Z 715964f7-f7cb-41e1-9e66-8075e3c86083 info: EndMiddleware: End response. TotalTimeInMS=4 StatusCode=200 StatusMessage=OK Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x1D85729FF6A26A0\"","last-modified":"Sun, 07 Mar 2021 13:05:54 GMT","x-ms-client-request-id":"007e335c-45a7-48d6-9dce-bd40c39ad797","x-ms-request-id":"715964f7-f7cb-41e1-9e66-8075e3c86083","x-ms-version":"2020-06-12","date":"Sun, 07 Mar 2021 13:05:54 GMT","content-type":"application/xml"}
2021-03-07T13:05:55.082Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/records?restype=container&comp=acl RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"88b228ba-da67-4240-ba84-68a6d3065cb5","x-ms-date":"Mon, 08 Mar 2021 11:29:39 GMT","authorization":"SharedKey devstoreaccount1:+TnyRvCXWphVrYst4ZqQE+xU7v98oWdl37RFYvSubbg=","host":"127.0.0.1:10000","request-id":"|7c8e254e-851d-4a89-a954-69c3aba9c9eb.f7bc34b6ac2a6c43.","traceparent":"00-334a18dc14bb844ca6c9d8393fc544ec-f7bc34b6ac2a6c43-00","content-length":"214"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: DispatchMiddleware: Operation=Container_SetAccessPolicy
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:05:55.083Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 
2021-03-07T13:05:55.084Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:05:55.084Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:05:55.084Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n214\n\n\n\n\n\n\n\n\nx-ms-client-request-id:88b228ba-da67-4240-ba84-68a6d3065cb5\nx-ms-date:Mon, 08 Mar 2021 11:29:39 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records\ncomp:acl\nrestype:container"
2021-03-07T13:05:55.084Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:+TnyRvCXWphVrYst4ZqQE+xU7v98oWdl37RFYvSubbg=
2021-03-07T13:05:55.084Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:05:55.084Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:05:55.085Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 debug: deserialize(): Raw request body string is <?xml version="1.0" encoding="utf-8"?><SignedIdentifiers><SignedIdentifier><Id>ReadOnlyPolicy</Id><AccessPolicy><Start /><Expiry /><Permission>r</Permission></AccessPolicy></SignedIdentifier></SignedIdentifiers>
2021-03-07T13:05:55.090Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"88b228ba-da67-4240-ba84-68a6d3065cb5","leaseAccessConditions":{},"modifiedAccessConditions":{},"containerAcl":[{"id":"ReadOnlyPolicy","accessPolicy":{"permission":"r"}}]},"restype":"container","comp":"acl","version":"2018-03-28","body":"ReadableStream"}
2021-03-07T13:05:55.091Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:05:55.092Z 7c0d34d1-ff33-48d8-bca6-b8c2035d17c3 info: EndMiddleware: End response. TotalTimeInMS=10 StatusCode=200 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x1B9988E3837FFB0\"","last-modified":"Sun, 07 Mar 2021 13:05:55 GMT","x-ms-client-request-id":"88b228ba-da67-4240-ba84-68a6d3065cb5","x-ms-request-id":"7c0d34d1-ff33-48d8-bca6-b8c2035d17c3","x-ms-version":"2020-06-12","date":"Sun, 07 Mar 2021 13:05:55 GMT"}
2021-03-07T13:05:55.118Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/records/121/36949338.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"3e32a5d7-e620-4ebe-8d2e-8d6df7f0f4e0","x-ms-date":"Mon, 08 Mar 2021 11:29:39 GMT","authorization":"SharedKey devstoreaccount1:D1dRuekUTPLxdpiwKSHLzpNi+FMuZtCOEV9D6qASYEs=","host":"127.0.0.1:10000","request-id":"|7c8e254e-851d-4a89-a954-69c3aba9c9eb.aba309f05a1bae47.","traceparent":"00-334a18dc14bb844ca6c9d8393fc544ec-aba309f05a1bae47-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:05:55.118Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=121/36949338.json
2021-03-07T13:05:55.118Z 48c43ed7-a35a-4be4-8d26-994667f8a165 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:05:55.119Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:05:55.119Z 48c43ed7-a35a-4be4-8d26-994667f8a165 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:05:55.119Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:05:55.119Z 48c43ed7-a35a-4be4-8d26-994667f8a165 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:05:55.119Z 48c43ed7-a35a-4be4-8d26-994667f8a165 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 121/36949338.json
2021-03-07T13:05:55.120Z 48c43ed7-a35a-4be4-8d26-994667f8a165 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:05:55.120Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:05:55.120Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:3e32a5d7-e620-4ebe-8d2e-8d6df7f0f4e0\nx-ms-date:Mon, 08 Mar 2021 11:29:39 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records/121/36949338.json"
2021-03-07T13:05:55.120Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:D1dRuekUTPLxdpiwKSHLzpNi+FMuZtCOEV9D6qASYEs=
2021-03-07T13:05:55.120Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:05:55.120Z 48c43ed7-a35a-4be4-8d26-994667f8a165 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:05:55.121Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"3e32a5d7-e620-4ebe-8d2e-8d6df7f0f4e0","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:05:55.122Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"48c43ed7-a35a-4be4-8d26-994667f8a165"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:48c43ed7-a35a-4be4-8d26-994667f8a165\nTime:2021-03-07T13:05:55.121Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=48c43ed7-a35a-4be4-8d26-994667f8a165
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:48c43ed7-a35a-4be4-8d26-994667f8a165\nTime:2021-03-07T13:05:55.121Z</Message>\n</Error>"
2021-03-07T13:05:55.123Z 48c43ed7-a35a-4be4-8d26-994667f8a165 info: EndMiddleware: End response. TotalTimeInMS=5 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"48c43ed7-a35a-4be4-8d26-994667f8a165"}
2021-03-07T13:05:55.273Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/records?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"f74122a2-60dc-4878-802b-24e78da83819","x-ms-date":"Mon, 08 Mar 2021 11:29:40 GMT","authorization":"SharedKey devstoreaccount1:1ZTBX06kbqHq1P5gHFv54j7Ddti6VRLXiEmILVTh+kk=","host":"127.0.0.1:10000","request-id":"|7c8e254e-851d-4a89-a954-69c3aba9c9eb.07377d7c484bb64d.","traceparent":"00-334a18dc14bb844ca6c9d8393fc544ec-07377d7c484bb64d-00","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:05:55.274Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=
2021-03-07T13:05:55.274Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:f74122a2-60dc-4878-802b-24e78da83819\nx-ms-date:Mon, 08 Mar 2021 11:29:40 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records\nrestype:container"
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:1ZTBX06kbqHq1P5gHFv54j7Ddti6VRLXiEmILVTh+kk=
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:05:55.275Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"f74122a2-60dc-4878-802b-24e78da83819"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified container already exists.  ErrorHTTPStatusCode=409 ErrorHTTPStatusMessage=The specified container already exists. ErrorHTTPHeaders={"x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"3d0a4bf2-ef38-45bf-b79c-923709822f82"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:3d0a4bf2-ef38-45bf-b79c-923709822f82\nTime:2021-03-07T13:05:55.276Z</Message>\n</Error>" ErrorStack="StorageError: The specified container already exists.\n    at Function.getContainerAlreadyExists (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:23:16)\n    at LokiBlobMetadataStore.createContainer (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:292:49)\n    at ContainerHandler.create (/opt/azurite/dist/src/blob/handlers/ContainerHandler.js:35:34)\n    at /opt/azurite/dist/src/blob/generated/middleware/HandlerMiddlewareFactory.js:58:18\n    at /opt/azurite/dist/src/blob/generated/ExpressMiddlewareFactory.js:77:63\n    at Layer.handle [as handle_request] (/opt/azurite/node_modules/express/lib/router/layer.js:95:5)\n    at trim_prefix (/opt/azurite/node_modules/express/lib/router/index.js:317:13)\n    at /opt/azurite/node_modules/express/lib/router/index.js:284:7\n    at Function.process_params (/opt/azurite/node_modules/express/lib/router/index.js:335:12)\n    at next (/opt/azurite/node_modules/express/lib/router/index.js:275:10)"
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Set HTTP code: 409
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Set HTTP status message: The specified container already exists.
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ContainerAlreadyExists
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=3d0a4bf2-ef38-45bf-b79c-923709822f82
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:3d0a4bf2-ef38-45bf-b79c-923709822f82\nTime:2021-03-07T13:05:55.276Z</Message>\n</Error>"
2021-03-07T13:05:55.276Z 3d0a4bf2-ef38-45bf-b79c-923709822f82 info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=409 StatusMessage=The specified container already exists. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"3d0a4bf2-ef38-45bf-b79c-923709822f82","content-type":"application/xml"}
2021-03-07T13:05:55.505Z bef76def-70df-4d97-b9ea-ccbc28398206 info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/records/121/36949324.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"249de5c9-036a-40e3-889b-b6e82dcdaf0f","x-ms-date":"Mon, 08 Mar 2021 11:29:40 GMT","authorization":"SharedKey devstoreaccount1:+W1UWyk+euYFSQEluy6wg+kp3la9zO0By/tEuJ6nYtw=","host":"127.0.0.1:10000","request-id":"|7c8e254e-851d-4a89-a954-69c3aba9c9eb.605867759015c84d.","traceparent":"00-334a18dc14bb844ca6c9d8393fc544ec-605867759015c84d-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:05:55.505Z bef76def-70df-4d97-b9ea-ccbc28398206 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=121/36949324.json
2021-03-07T13:05:55.505Z bef76def-70df-4d97-b9ea-ccbc28398206 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:05:55.507Z bef76def-70df-4d97-b9ea-ccbc28398206 info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 121/36949324.json
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:249de5c9-036a-40e3-889b-b6e82dcdaf0f\nx-ms-date:Mon, 08 Mar 2021 11:29:40 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records/121/36949324.json"
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:+W1UWyk+euYFSQEluy6wg+kp3la9zO0By/tEuJ6nYtw=
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:05:55.508Z bef76def-70df-4d97-b9ea-ccbc28398206 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"249de5c9-036a-40e3-889b-b6e82dcdaf0f","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"bef76def-70df-4d97-b9ea-ccbc28398206"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:bef76def-70df-4d97-b9ea-ccbc28398206\nTime:2021-03-07T13:05:55.508Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=bef76def-70df-4d97-b9ea-ccbc28398206
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:bef76def-70df-4d97-b9ea-ccbc28398206\nTime:2021-03-07T13:05:55.508Z</Message>\n</Error>"
2021-03-07T13:05:55.509Z bef76def-70df-4d97-b9ea-ccbc28398206 info: EndMiddleware: End response. TotalTimeInMS=4 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"bef76def-70df-4d97-b9ea-ccbc28398206"}
2021-03-07T13:06:02.302Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/records?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"179b4656-31a6-4789-a16f-4b451d1d59dd","x-ms-date":"Mon, 08 Mar 2021 11:29:47 GMT","authorization":"SharedKey devstoreaccount1:bi235jNFFsxHjuyLuDKBiD0bw2aCSsEAFjltK0UnMIc=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.06aaa02a7a595b48.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-06aaa02a7a595b48-00","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:02.302Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=
2021-03-07T13:06:02.302Z 5195e517-6ccc-41eb-b35a-056b4b142f3e verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:179b4656-31a6-4789-a16f-4b451d1d59dd\nx-ms-date:Mon, 08 Mar 2021 11:29:47 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records\nrestype:container"
2021-03-07T13:06:02.303Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:bi235jNFFsxHjuyLuDKBiD0bw2aCSsEAFjltK0UnMIc=
2021-03-07T13:06:02.304Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:02.304Z 5195e517-6ccc-41eb-b35a-056b4b142f3e verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:02.304Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"179b4656-31a6-4789-a16f-4b451d1d59dd"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:06:02.304Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:02.304Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified container already exists.  ErrorHTTPStatusCode=409 ErrorHTTPStatusMessage=The specified container already exists. ErrorHTTPHeaders={"x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"5195e517-6ccc-41eb-b35a-056b4b142f3e"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:5195e517-6ccc-41eb-b35a-056b4b142f3e\nTime:2021-03-07T13:06:02.304Z</Message>\n</Error>" ErrorStack="StorageError: The specified container already exists.\n    at Function.getContainerAlreadyExists (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:23:16)\n    at LokiBlobMetadataStore.createContainer (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:292:49)\n    at ContainerHandler.create (/opt/azurite/dist/src/blob/handlers/ContainerHandler.js:35:34)\n    at /opt/azurite/dist/src/blob/generated/middleware/HandlerMiddlewareFactory.js:58:18\n    at /opt/azurite/dist/src/blob/generated/ExpressMiddlewareFactory.js:77:63\n    at Layer.handle [as handle_request] (/opt/azurite/node_modules/express/lib/router/layer.js:95:5)\n    at trim_prefix (/opt/azurite/node_modules/express/lib/router/index.js:317:13)\n    at /opt/azurite/node_modules/express/lib/router/index.js:284:7\n    at Function.process_params (/opt/azurite/node_modules/express/lib/router/index.js:335:12)\n    at next (/opt/azurite/node_modules/express/lib/router/index.js:275:10)"
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Set HTTP code: 409
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Set HTTP status message: The specified container already exists.
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ContainerAlreadyExists
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=5195e517-6ccc-41eb-b35a-056b4b142f3e
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:5195e517-6ccc-41eb-b35a-056b4b142f3e\nTime:2021-03-07T13:06:02.304Z</Message>\n</Error>"
2021-03-07T13:06:02.305Z 5195e517-6ccc-41eb-b35a-056b4b142f3e info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=409 StatusMessage=The specified container already exists. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"5195e517-6ccc-41eb-b35a-056b4b142f3e","content-type":"application/xml"}
2021-03-07T13:06:02.533Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/records/121/36949324.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"272cf5c5-2f6e-4485-be26-91abbc9ba4ae","x-ms-date":"Mon, 08 Mar 2021 11:29:47 GMT","authorization":"SharedKey devstoreaccount1:C1ufgSrUyd6eZLS2fpT/FyokTH19yR8u3MR3T2haoYY=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.afb7b06c5e996041.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-afb7b06c5e996041-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:02.533Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=records Blob=121/36949324.json
2021-03-07T13:06:02.533Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: records, blob: 121/36949324.json
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container records
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:272cf5c5-2f6e-4485-be26-91abbc9ba4ae\nx-ms-date:Mon, 08 Mar 2021 11:29:47 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/records/121/36949324.json"
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:C1ufgSrUyd6eZLS2fpT/FyokTH19yR8u3MR3T2haoYY=
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:02.534Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"272cf5c5-2f6e-4485-be26-91abbc9ba4ae","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"74fd3e8e-9dad-4326-80b1-d49e36441d94"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:74fd3e8e-9dad-4326-80b1-d49e36441d94\nTime:2021-03-07T13:06:02.535Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=74fd3e8e-9dad-4326-80b1-d49e36441d94
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:74fd3e8e-9dad-4326-80b1-d49e36441d94\nTime:2021-03-07T13:06:02.535Z</Message>\n</Error>"
2021-03-07T13:06:02.535Z 74fd3e8e-9dad-4326-80b1-d49e36441d94 info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"74fd3e8e-9dad-4326-80b1-d49e36441d94"}
2021-03-07T13:06:03.123Z fab27828-552f-4071-81ff-9e191e5bc8ba info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/documents?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"3c426078-fb93-49f2-8bf9-fd52398e15f9","x-ms-date":"Mon, 08 Mar 2021 11:29:47 GMT","authorization":"SharedKey devstoreaccount1:/R4vInqLlXOnm2PBEysKumeKkJmFCwqbV3fkef81hk4=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.924fd8011f4fd442.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-924fd8011f4fd442-00","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:03.123Z fab27828-552f-4071-81ff-9e191e5bc8ba info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=
2021-03-07T13:06:03.123Z fab27828-552f-4071-81ff-9e191e5bc8ba verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:03.124Z fab27828-552f-4071-81ff-9e191e5bc8ba info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:06:03.124Z fab27828-552f-4071-81ff-9e191e5bc8ba verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:03.124Z fab27828-552f-4071-81ff-9e191e5bc8ba info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:03.124Z fab27828-552f-4071-81ff-9e191e5bc8ba debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:03.124Z fab27828-552f-4071-81ff-9e191e5bc8ba debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:3c426078-fb93-49f2-8bf9-fd52398e15f9\nx-ms-date:Mon, 08 Mar 2021 11:29:47 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents\nrestype:container"
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:/R4vInqLlXOnm2PBEysKumeKkJmFCwqbV3fkef81hk4=
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"3c426078-fb93-49f2-8bf9-fd52398e15f9"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:06:03.125Z fab27828-552f-4071-81ff-9e191e5bc8ba info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x2302206D6760080\"","last-modified":"Sun, 07 Mar 2021 13:06:03 GMT","x-ms-client-request-id":"3c426078-fb93-49f2-8bf9-fd52398e15f9","x-ms-request-id":"fab27828-552f-4071-81ff-9e191e5bc8ba","x-ms-version":"2020-06-12"}
2021-03-07T13:06:03.131Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: BlobStorageContextMiddleware: RequestMethod=GET RequestURL=http://127.0.0.1/devstoreaccount1/documents?restype=container&comp=acl RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"6b9dbf43-4ac6-49e0-946d-5b1e26c64e87","x-ms-date":"Mon, 08 Mar 2021 11:29:47 GMT","authorization":"SharedKey devstoreaccount1:EAdWt3Yu/aKx2dor06Z2V1FQgVrpaiIt1pcPMLcifhI=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.c56b2ee14f55fc47.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-c56b2ee14f55fc47-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:03.131Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=
2021-03-07T13:06:03.131Z 596f9404-7ab4-486f-8dc0-eced6eb51660 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:03.132Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: DispatchMiddleware: Operation=Container_GetAccessPolicy
2021-03-07T13:06:03.132Z 596f9404-7ab4-486f-8dc0-eced6eb51660 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:03.132Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:03.132Z 596f9404-7ab4-486f-8dc0-eced6eb51660 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:03.132Z 596f9404-7ab4-486f-8dc0-eced6eb51660 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 
2021-03-07T13:06:03.132Z 596f9404-7ab4-486f-8dc0-eced6eb51660 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"GET\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:6b9dbf43-4ac6-49e0-946d-5b1e26c64e87\nx-ms-date:Mon, 08 Mar 2021 11:29:47 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents\ncomp:acl\nrestype:container"
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:EAdWt3Yu/aKx2dor06Z2V1FQgVrpaiIt1pcPMLcifhI=
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"6b9dbf43-4ac6-49e0-946d-5b1e26c64e87","leaseAccessConditions":{}},"restype":"container","comp":"acl","version":"2018-03-28"}
2021-03-07T13:06:03.133Z 596f9404-7ab4-486f-8dc0-eced6eb51660 verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:06:03.134Z 596f9404-7ab4-486f-8dc0-eced6eb51660 debug: Serializer: Raw response body string is <?xml version="1.0" encoding="UTF-8" standalone="yes"?><SignedIdentifiers/>
2021-03-07T13:06:03.134Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: Serializer: Start returning stream body.
2021-03-07T13:06:03.134Z 596f9404-7ab4-486f-8dc0-eced6eb51660 info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=200 StatusMessage=OK Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x2302206D6760080\"","last-modified":"Sun, 07 Mar 2021 13:06:03 GMT","x-ms-client-request-id":"6b9dbf43-4ac6-49e0-946d-5b1e26c64e87","x-ms-request-id":"596f9404-7ab4-486f-8dc0-eced6eb51660","x-ms-version":"2020-06-12","date":"Sun, 07 Mar 2021 13:06:03 GMT","content-type":"application/xml"}
2021-03-07T13:06:03.140Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/documents?restype=container&comp=acl RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"6f91b616-545b-45e9-a464-11c1f00d9033","x-ms-date":"Mon, 08 Mar 2021 11:29:47 GMT","authorization":"SharedKey devstoreaccount1:V9qfWwIkZSemHSy3gyMXiLEFUPQvRvUG6PFy0a4M7EE=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.521c3012cbfe944c.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-521c3012cbfe944c-00","content-length":"214"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:03.140Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=
2021-03-07T13:06:03.140Z 7cffa56d-38e4-4273-baad-0d93b732bbcc verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: DispatchMiddleware: Operation=Container_SetAccessPolicy
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n214\n\n\n\n\n\n\n\n\nx-ms-client-request-id:6f91b616-545b-45e9-a464-11c1f00d9033\nx-ms-date:Mon, 08 Mar 2021 11:29:47 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents\ncomp:acl\nrestype:container"
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:V9qfWwIkZSemHSy3gyMXiLEFUPQvRvUG6PFy0a4M7EE=
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:03.141Z 7cffa56d-38e4-4273-baad-0d93b732bbcc verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:03.142Z 7cffa56d-38e4-4273-baad-0d93b732bbcc debug: deserialize(): Raw request body string is <?xml version="1.0" encoding="utf-8"?><SignedIdentifiers><SignedIdentifier><Id>ReadOnlyPolicy</Id><AccessPolicy><Start /><Expiry /><Permission>r</Permission></AccessPolicy></SignedIdentifier></SignedIdentifiers>
2021-03-07T13:06:03.142Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"6f91b616-545b-45e9-a464-11c1f00d9033","leaseAccessConditions":{},"modifiedAccessConditions":{},"containerAcl":[{"id":"ReadOnlyPolicy","accessPolicy":{"permission":"r"}}]},"restype":"container","comp":"acl","version":"2018-03-28","body":"ReadableStream"}
2021-03-07T13:06:03.142Z 7cffa56d-38e4-4273-baad-0d93b732bbcc verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:06:03.142Z 7cffa56d-38e4-4273-baad-0d93b732bbcc info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=200 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x22EFDB4E468E940\"","last-modified":"Sun, 07 Mar 2021 13:06:03 GMT","x-ms-client-request-id":"6f91b616-545b-45e9-a464-11c1f00d9033","x-ms-request-id":"7cffa56d-38e4-4273-baad-0d93b732bbcc","x-ms-version":"2020-06-12","date":"Sun, 07 Mar 2021 13:06:03 GMT"}
2021-03-07T13:06:03.148Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/documents/121/36949326.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"2c8ac754-b3cc-425c-86dd-5f219c0ec3c9","x-ms-date":"Mon, 08 Mar 2021 11:29:47 GMT","authorization":"SharedKey devstoreaccount1:UPcL1HWuhp5O4YK5BudqDALSpzKUB/FjK/KQA/Teouc=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.ac5375e54f041247.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-ac5375e54f041247-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:03.148Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=121/36949326.json
2021-03-07T13:06:03.148Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 121/36949326.json
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:03.149Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:2c8ac754-b3cc-425c-86dd-5f219c0ec3c9\nx-ms-date:Mon, 08 Mar 2021 11:29:47 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents/121/36949326.json"
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:UPcL1HWuhp5O4YK5BudqDALSpzKUB/FjK/KQA/Teouc=
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"2c8ac754-b3cc-425c-86dd-5f219c0ec3c9","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"4c6fc478-ad8a-4e78-a6d0-bd52525c94db"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:4c6fc478-ad8a-4e78-a6d0-bd52525c94db\nTime:2021-03-07T13:06:03.150Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:06:03.150Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:06:03.151Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=4c6fc478-ad8a-4e78-a6d0-bd52525c94db
2021-03-07T13:06:03.151Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:4c6fc478-ad8a-4e78-a6d0-bd52525c94db\nTime:2021-03-07T13:06:03.150Z</Message>\n</Error>"
2021-03-07T13:06:03.151Z 4c6fc478-ad8a-4e78-a6d0-bd52525c94db info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"4c6fc478-ad8a-4e78-a6d0-bd52525c94db"}
2021-03-07T13:06:03.552Z 062b820d-4c39-4274-850b-130de8719bbf info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/documents?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"418801be-ca2e-4552-848b-ea4353ba00ca","x-ms-date":"Mon, 08 Mar 2021 11:29:48 GMT","authorization":"SharedKey devstoreaccount1:m24w8GSUectjkscZsn/WbwjqSMK2v4/WsKMydG232wk=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.faa05d9050b7f543.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-faa05d9050b7f543-00","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:03.552Z 062b820d-4c39-4274-850b-130de8719bbf info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=
2021-03-07T13:06:03.552Z 062b820d-4c39-4274-850b-130de8719bbf verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:418801be-ca2e-4552-848b-ea4353ba00ca\nx-ms-date:Mon, 08 Mar 2021 11:29:48 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents\nrestype:container"
2021-03-07T13:06:03.553Z 062b820d-4c39-4274-850b-130de8719bbf info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:m24w8GSUectjkscZsn/WbwjqSMK2v4/WsKMydG232wk=
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"418801be-ca2e-4552-848b-ea4353ba00ca"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified container already exists.  ErrorHTTPStatusCode=409 ErrorHTTPStatusMessage=The specified container already exists. ErrorHTTPHeaders={"x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"062b820d-4c39-4274-850b-130de8719bbf"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:062b820d-4c39-4274-850b-130de8719bbf\nTime:2021-03-07T13:06:03.554Z</Message>\n</Error>" ErrorStack="StorageError: The specified container already exists.\n    at Function.getContainerAlreadyExists (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:23:16)\n    at LokiBlobMetadataStore.createContainer (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:292:49)\n    at ContainerHandler.create (/opt/azurite/dist/src/blob/handlers/ContainerHandler.js:35:34)\n    at /opt/azurite/dist/src/blob/generated/middleware/HandlerMiddlewareFactory.js:58:18\n    at /opt/azurite/dist/src/blob/generated/ExpressMiddlewareFactory.js:77:63\n    at Layer.handle [as handle_request] (/opt/azurite/node_modules/express/lib/router/layer.js:95:5)\n    at trim_prefix (/opt/azurite/node_modules/express/lib/router/index.js:317:13)\n    at /opt/azurite/node_modules/express/lib/router/index.js:284:7\n    at Function.process_params (/opt/azurite/node_modules/express/lib/router/index.js:335:12)\n    at next (/opt/azurite/node_modules/express/lib/router/index.js:275:10)"
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Set HTTP code: 409
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Set HTTP status message: The specified container already exists.
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ContainerAlreadyExists
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=062b820d-4c39-4274-850b-130de8719bbf
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:062b820d-4c39-4274-850b-130de8719bbf\nTime:2021-03-07T13:06:03.554Z</Message>\n</Error>"
2021-03-07T13:06:03.554Z 062b820d-4c39-4274-850b-130de8719bbf info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=409 StatusMessage=The specified container already exists. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"062b820d-4c39-4274-850b-130de8719bbf","content-type":"application/xml"}
2021-03-07T13:06:03.777Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/documents/121/36949330.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"b0b3ad32-6e84-4125-98ad-36f9b3928122","x-ms-date":"Mon, 08 Mar 2021 11:29:48 GMT","authorization":"SharedKey devstoreaccount1:52M8sTNUrzPd+Usc6BwPzXC40y9/tSzWATUEQIgZFeA=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.cbadd6e9a0c5724b.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-cbadd6e9a0c5724b-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:03.777Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=121/36949330.json
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 121/36949330.json
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:b0b3ad32-6e84-4125-98ad-36f9b3928122\nx-ms-date:Mon, 08 Mar 2021 11:29:48 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents/121/36949330.json"
2021-03-07T13:06:03.778Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:52M8sTNUrzPd+Usc6BwPzXC40y9/tSzWATUEQIgZFeA=
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"b0b3ad32-6e84-4125-98ad-36f9b3928122","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"bc3b5189-ba48-4737-8e00-a57a922cfe79"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:bc3b5189-ba48-4737-8e00-a57a922cfe79\nTime:2021-03-07T13:06:03.779Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:06:03.779Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=bc3b5189-ba48-4737-8e00-a57a922cfe79
2021-03-07T13:06:03.780Z bc3b5189-ba48-4737-8e00-a57a922cfe79 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:bc3b5189-ba48-4737-8e00-a57a922cfe79\nTime:2021-03-07T13:06:03.779Z</Message>\n</Error>"
2021-03-07T13:06:03.781Z bc3b5189-ba48-4737-8e00-a57a922cfe79 info: EndMiddleware: End response. TotalTimeInMS=4 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"bc3b5189-ba48-4737-8e00-a57a922cfe79"}
2021-03-07T13:06:04.021Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/documents?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"59759117-55f9-4e4a-b7f9-2d38c2d477ba","x-ms-date":"Mon, 08 Mar 2021 11:29:48 GMT","authorization":"SharedKey devstoreaccount1:gJ72QVbWbAYys/QCEUrlAk1m8Lp29sBvTVMt67BoFhk=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.c3abf9c1facbb74e.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-c3abf9c1facbb74e-00","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:04.021Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=
2021-03-07T13:06:04.021Z e34e9d0d-68de-49ce-a90d-843e19377fd1 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:04.021Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:06:04.021Z e34e9d0d-68de-49ce-a90d-843e19377fd1 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:59759117-55f9-4e4a-b7f9-2d38c2d477ba\nx-ms-date:Mon, 08 Mar 2021 11:29:48 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents\nrestype:container"
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:gJ72QVbWbAYys/QCEUrlAk1m8Lp29sBvTVMt67BoFhk=
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:04.022Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"59759117-55f9-4e4a-b7f9-2d38c2d477ba"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified container already exists.  ErrorHTTPStatusCode=409 ErrorHTTPStatusMessage=The specified container already exists. ErrorHTTPHeaders={"x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"e34e9d0d-68de-49ce-a90d-843e19377fd1"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:e34e9d0d-68de-49ce-a90d-843e19377fd1\nTime:2021-03-07T13:06:04.022Z</Message>\n</Error>" ErrorStack="StorageError: The specified container already exists.\n    at Function.getContainerAlreadyExists (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:23:16)\n    at LokiBlobMetadataStore.createContainer (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:292:49)\n    at ContainerHandler.create (/opt/azurite/dist/src/blob/handlers/ContainerHandler.js:35:34)\n    at /opt/azurite/dist/src/blob/generated/middleware/HandlerMiddlewareFactory.js:58:18\n    at /opt/azurite/dist/src/blob/generated/ExpressMiddlewareFactory.js:77:63\n    at Layer.handle [as handle_request] (/opt/azurite/node_modules/express/lib/router/layer.js:95:5)\n    at trim_prefix (/opt/azurite/node_modules/express/lib/router/index.js:317:13)\n    at /opt/azurite/node_modules/express/lib/router/index.js:284:7\n    at Function.process_params (/opt/azurite/node_modules/express/lib/router/index.js:335:12)\n    at next (/opt/azurite/node_modules/express/lib/router/index.js:275:10)"
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Set HTTP code: 409
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Set HTTP status message: The specified container already exists.
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ContainerAlreadyExists
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=e34e9d0d-68de-49ce-a90d-843e19377fd1
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:e34e9d0d-68de-49ce-a90d-843e19377fd1\nTime:2021-03-07T13:06:04.022Z</Message>\n</Error>"
2021-03-07T13:06:04.023Z e34e9d0d-68de-49ce-a90d-843e19377fd1 info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=409 StatusMessage=The specified container already exists. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"e34e9d0d-68de-49ce-a90d-843e19377fd1","content-type":"application/xml"}
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/documents/121/36949333.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"56141611-072c-4714-912c-5576134ebcf5","x-ms-date":"Mon, 08 Mar 2021 11:29:49 GMT","authorization":"SharedKey devstoreaccount1:v0odXEr4ZJDwiQnIVEKaxOXde4qI/hwO8Pzfz1oQ7A4=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.9499012c2cb8d048.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-9499012c2cb8d048-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=121/36949333.json
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 121/36949333.json
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:04.257Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:56141611-072c-4714-912c-5576134ebcf5\nx-ms-date:Mon, 08 Mar 2021 11:29:49 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents/121/36949333.json"
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:v0odXEr4ZJDwiQnIVEKaxOXde4qI/hwO8Pzfz1oQ7A4=
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"56141611-072c-4714-912c-5576134ebcf5","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"9f8038cc-d923-4e28-a939-1019bc603bbe"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:9f8038cc-d923-4e28-a939-1019bc603bbe\nTime:2021-03-07T13:06:04.258Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:06:04.258Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:06:04.260Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=9f8038cc-d923-4e28-a939-1019bc603bbe
2021-03-07T13:06:04.260Z 9f8038cc-d923-4e28-a939-1019bc603bbe error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:9f8038cc-d923-4e28-a939-1019bc603bbe\nTime:2021-03-07T13:06:04.258Z</Message>\n</Error>"
2021-03-07T13:06:04.260Z 9f8038cc-d923-4e28-a939-1019bc603bbe info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"9f8038cc-d923-4e28-a939-1019bc603bbe"}
2021-03-07T13:06:04.403Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/documents?restype=container RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"8c941e00-47fe-4b21-be20-1d8cb42ddb13","x-ms-date":"Mon, 08 Mar 2021 11:29:49 GMT","authorization":"SharedKey devstoreaccount1:8XWIPB73Xyrenrpn+J867ACoEP/BPzSFDr4ewc6KLls=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.ab636a82461e8c42.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-ab636a82461e8c42-00","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 
2021-03-07T13:06:04.404Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:04.405Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:04.405Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:8c941e00-47fe-4b21-be20-1d8cb42ddb13\nx-ms-date:Mon, 08 Mar 2021 11:29:49 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents\nrestype:container"
2021-03-07T13:06:04.405Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:8XWIPB73Xyrenrpn+J867ACoEP/BPzSFDr4ewc6KLls=
2021-03-07T13:06:04.405Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:04.405Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:04.405Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"8c941e00-47fe-4b21-be20-1d8cb42ddb13"},"restype":"container","version":"2018-03-28"}
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified container already exists.  ErrorHTTPStatusCode=409 ErrorHTTPStatusMessage=The specified container already exists. ErrorHTTPHeaders={"x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"16bbede2-8158-411e-84f0-c68fd1cb9d80"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:16bbede2-8158-411e-84f0-c68fd1cb9d80\nTime:2021-03-07T13:06:04.406Z</Message>\n</Error>" ErrorStack="StorageError: The specified container already exists.\n    at Function.getContainerAlreadyExists (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:23:16)\n    at LokiBlobMetadataStore.createContainer (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:292:49)\n    at ContainerHandler.create (/opt/azurite/dist/src/blob/handlers/ContainerHandler.js:35:34)\n    at /opt/azurite/dist/src/blob/generated/middleware/HandlerMiddlewareFactory.js:58:18\n    at /opt/azurite/dist/src/blob/generated/ExpressMiddlewareFactory.js:77:63\n    at Layer.handle [as handle_request] (/opt/azurite/node_modules/express/lib/router/layer.js:95:5)\n    at trim_prefix (/opt/azurite/node_modules/express/lib/router/index.js:317:13)\n    at /opt/azurite/node_modules/express/lib/router/index.js:284:7\n    at Function.process_params (/opt/azurite/node_modules/express/lib/router/index.js:335:12)\n    at next (/opt/azurite/node_modules/express/lib/router/index.js:275:10)"
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Set HTTP code: 409
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Set HTTP status message: The specified container already exists.
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ContainerAlreadyExists
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=16bbede2-8158-411e-84f0-c68fd1cb9d80
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerAlreadyExists</Code>\n  <Message>The specified container already exists.\nRequestId:16bbede2-8158-411e-84f0-c68fd1cb9d80\nTime:2021-03-07T13:06:04.406Z</Message>\n</Error>"
2021-03-07T13:06:04.406Z 16bbede2-8158-411e-84f0-c68fd1cb9d80 info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=409 StatusMessage=The specified container already exists. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"ContainerAlreadyExists","x-ms-request-id":"16bbede2-8158-411e-84f0-c68fd1cb9d80","content-type":"application/xml"}
2021-03-07T13:06:04.651Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: BlobStorageContextMiddleware: RequestMethod=HEAD RequestURL=http://127.0.0.1/devstoreaccount1/documents/121/36949350.json RequestHeaders:{"user-agent":"Azure-Storage/9.3.2 (.NET CLR 4.0.30319.42000; Win32NT 10.0.18363.0)","x-ms-version":"2018-03-28","x-ms-client-request-id":"6fa66b71-7d36-454f-9584-b652acda3fb7","x-ms-date":"Mon, 08 Mar 2021 11:29:49 GMT","authorization":"SharedKey devstoreaccount1:wpHWhWdpzaq+roIw9z6alJzhld/LsbUBgLzJ0uGw9aY=","host":"127.0.0.1:10000","request-id":"|b0f36646-9aaf-400e-ba2f-563267f9b368.1f29f5cf7ce34046.","traceparent":"00-21e2018f9322a4448eab2b874d8fa409-1f29f5cf7ce34046-00"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:04.651Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=documents Blob=121/36949350.json
2021-03-07T13:06:04.651Z 2b1216c3-69a2-4442-9f91-205c698a5118 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: DispatchMiddleware: Operation=Blob_GetProperties
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: documents, blob: 121/36949350.json
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container documents
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"HEAD\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:6fa66b71-7d36-454f-9584-b652acda3fb7\nx-ms-date:Mon, 08 Mar 2021 11:29:49 GMT\nx-ms-version:2018-03-28\n/devstoreaccount1/devstoreaccount1/documents/121/36949350.json"
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:wpHWhWdpzaq+roIw9z6alJzhld/LsbUBgLzJ0uGw9aY=
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:04.652Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"6fa66b71-7d36-454f-9584-b652acda3fb7","leaseAccessConditions":{},"cpkInfo":{},"modifiedAccessConditions":{}},"version":"2018-03-28"}
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified blob does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified blob does not exist. ErrorHTTPHeaders={"x-ms-error-code":"BlobNotFound","x-ms-request-id":"2b1216c3-69a2-4442-9f91-205c698a5118"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:2b1216c3-69a2-4442-9f91-205c698a5118\nTime:2021-03-07T13:06:04.653Z</Message>\n</Error>" ErrorStack="StorageError: The specified blob does not exist.\n    at Function.getBlobNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:29:16)\n    at LokiBlobMetadataStore.getBlobProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:804:49)\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)\n    at async BlobHandler.getProperties (/opt/azurite/dist/src/blob/handlers/BlobHandler.js:79:21)"
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: Set HTTP status message: The specified blob does not exist.
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=BlobNotFound
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=2b1216c3-69a2-4442-9f91-205c698a5118
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>BlobNotFound</Code>\n  <Message>The specified blob does not exist.\nRequestId:2b1216c3-69a2-4442-9f91-205c698a5118\nTime:2021-03-07T13:06:04.653Z</Message>\n</Error>"
2021-03-07T13:06:04.653Z 2b1216c3-69a2-4442-9f91-205c698a5118 info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=404 StatusMessage=The specified blob does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"BlobNotFound","x-ms-request-id":"2b1216c3-69a2-4442-9f91-205c698a5118"}
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: BlobStorageContextMiddleware: RequestMethod=GET RequestURL=http://127.0.0.1/devstoreaccount1/uploads?restype=container RequestHeaders:{"x-ms-version":"2020-04-08","x-ms-client-request-id":"5b885851-54b8-4fdf-b846-38326583faed","x-ms-return-client-request-id":"true","user-agent":"azsdk-net-Storage.Blobs/12.8.0 (.NET Framework 4.8.4300.0; Microsoft Windows 10.0.18363 )","x-ms-date":"Mon, 08 Mar 2021 11:30:12 GMT","authorization":"SharedKey devstoreaccount1:QK2gr9+0i6DpmRSRDi8FkgiyIa0fbbU9/DTCQPg+2rA=","traceparent":"00-02f4d91b5f8b5f4ca55934733b78a24d-f1d821e6be7d7943-00","host":"127.0.0.1:10000"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=uploads Blob=
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: DispatchMiddleware: Operation=Container_GetProperties
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:27.803Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: uploads, blob: 
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container uploads
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"GET\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:5b885851-54b8-4fdf-b846-38326583faed\nx-ms-date:Mon, 08 Mar 2021 11:30:12 GMT\nx-ms-return-client-request-id:true\nx-ms-version:2020-04-08\n/devstoreaccount1/devstoreaccount1/uploads\nrestype:container"
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:QK2gr9+0i6DpmRSRDi8FkgiyIa0fbbU9/DTCQPg+2rA=
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"5b885851-54b8-4fdf-b846-38326583faed","leaseAccessConditions":{}},"restype":"container","version":"2020-04-08"}
2021-03-07T13:06:27.804Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified container does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified container does not exist. ErrorHTTPHeaders={"x-ms-error-code":"ContainerNotFound","x-ms-request-id":"54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerNotFound</Code>\n  <Message>The specified container does not exist.\nRequestId:54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4\nTime:2021-03-07T13:06:27.804Z</Message>\n</Error>" ErrorStack="StorageError: The specified container does not exist.\n    at Function.getContainerNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:14:16)\n    at LokiBlobMetadataStore.getContainerWithLeaseUpdated (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:1944:53)\n    at LokiBlobMetadataStore.getContainerProperties (/opt/azurite/dist/src/blob/persistence/LokiBlobMetadataStore.js:307:32)\n    at ContainerHandler.getProperties (/opt/azurite/dist/src/blob/handlers/ContainerHandler.js:71:62)\n    at /opt/azurite/dist/src/blob/generated/middleware/HandlerMiddlewareFactory.js:58:18\n    at /opt/azurite/dist/src/blob/generated/ExpressMiddlewareFactory.js:77:63\n    at Layer.handle [as handle_request] (/opt/azurite/node_modules/express/lib/router/layer.js:95:5)\n    at trim_prefix (/opt/azurite/node_modules/express/lib/router/index.js:317:13)\n    at /opt/azurite/node_modules/express/lib/router/index.js:284:7\n    at Function.process_params (/opt/azurite/node_modules/express/lib/router/index.js:335:12)"
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Set HTTP code: 404
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Set HTTP status message: The specified container does not exist.
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ContainerNotFound
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ContainerNotFound</Code>\n  <Message>The specified container does not exist.\nRequestId:54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4\nTime:2021-03-07T13:06:27.804Z</Message>\n</Error>"
2021-03-07T13:06:27.805Z 54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4 info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=404 StatusMessage=The specified container does not exist. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"ContainerNotFound","x-ms-request-id":"54c6d05c-bc75-4a7e-87da-6d0fbe74f1b4","content-type":"application/xml"}
2021-03-07T13:06:28.234Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/uploads?restype=container RequestHeaders:{"x-ms-version":"2020-04-08","x-ms-client-request-id":"d591d767-7bd9-48b1-997d-8246dfd436b7","x-ms-return-client-request-id":"true","user-agent":"azsdk-net-Storage.Blobs/12.8.0 (.NET Framework 4.8.4300.0; Microsoft Windows 10.0.18363 )","x-ms-date":"Mon, 08 Mar 2021 11:30:12 GMT","authorization":"SharedKey devstoreaccount1:Dw+6bWgyPmDn/MVN6s/X1iVu/vnTS/gS7fIUqqnQu0w=","traceparent":"00-02f4d91b5f8b5f4ca55934733b78a24d-8b7e16865293f748-00","host":"127.0.0.1:10000","content-length":"0"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:28.234Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=uploads Blob=
2021-03-07T13:06:28.234Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:28.235Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: DispatchMiddleware: Operation=Container_Create
2021-03-07T13:06:28.235Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:28.235Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:28.235Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:28.235Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: uploads, blob: 
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container uploads
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-client-request-id:d591d767-7bd9-48b1-997d-8246dfd436b7\nx-ms-date:Mon, 08 Mar 2021 11:30:12 GMT\nx-ms-return-client-request-id:true\nx-ms-version:2020-04-08\n/devstoreaccount1/devstoreaccount1/uploads\nrestype:container"
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:Dw+6bWgyPmDn/MVN6s/X1iVu/vnTS/gS7fIUqqnQu0w=
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"requestId":"d591d767-7bd9-48b1-997d-8246dfd436b7"},"restype":"container","version":"2020-04-08"}
2021-03-07T13:06:28.236Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:06:28.237Z 4cca5ed7-2be3-42a1-957f-e2d2aa031c7f info: EndMiddleware: End response. TotalTimeInMS=3 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x1DD23346C0C1ED0\"","last-modified":"Sun, 07 Mar 2021 13:06:28 GMT","x-ms-client-request-id":"d591d767-7bd9-48b1-997d-8246dfd436b7","x-ms-request-id":"4cca5ed7-2be3-42a1-957f-e2d2aa031c7f","x-ms-version":"2020-06-12"}
2021-03-07T13:06:28.266Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://127.0.0.1/devstoreaccount1/uploads?restype=container&comp=acl RequestHeaders:{"x-ms-version":"2020-04-08","content-type":"application/xml","x-ms-client-request-id":"45763dc7-5765-48b2-9b58-40322a184109","x-ms-return-client-request-id":"true","user-agent":"azsdk-net-Storage.Blobs/12.8.0 (.NET Framework 4.8.4300.0; Microsoft Windows 10.0.18363 )","x-ms-date":"Mon, 08 Mar 2021 11:30:13 GMT","authorization":"SharedKey devstoreaccount1:pffB3jhAK/dRHcs96cwVfJnFPsq4+Pe1/rehvrWcl/Q=","traceparent":"00-02f4d91b5f8b5f4ca55934733b78a24d-d1e5a6bc01926a4a-00","host":"127.0.0.1:10000","content-length":"157"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:28.266Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=uploads Blob=
2021-03-07T13:06:28.266Z 2eb79722-30a4-4a2c-a43d-d39e54758850 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: DispatchMiddleware: Operation=Container_SetAccessPolicy
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: PublicAccessAuthenticator:validate() Start validation against public access.
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 debug: PublicAccessAuthenticator:validate() Getting account properties...
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: uploads, blob: 
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container uploads
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n157\n\napplication/xml\n\n\n\n\n\n\nx-ms-client-request-id:45763dc7-5765-48b2-9b58-40322a184109\nx-ms-date:Mon, 08 Mar 2021 11:30:13 GMT\nx-ms-return-client-request-id:true\nx-ms-version:2020-04-08\n/devstoreaccount1/devstoreaccount1/uploads\ncomp:acl\nrestype:container"
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:pffB3jhAK/dRHcs96cwVfJnFPsq4+Pe1/rehvrWcl/Q=
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2021-03-07T13:06:28.267Z 2eb79722-30a4-4a2c-a43d-d39e54758850 verbose: DeserializerMiddleware: Start deserializing...
2021-03-07T13:06:28.268Z 2eb79722-30a4-4a2c-a43d-d39e54758850 debug: deserialize(): Raw request body string is <SignedIdentifiers><SignedIdentifier><Id>ReadOnlyPolicy</Id><AccessPolicy><Permission>rade</Permission></AccessPolicy></SignedIdentifier></SignedIdentifiers>
2021-03-07T13:06:28.268Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: HandlerMiddleware: DeserializedParameters={"options":{"requestId":"45763dc7-5765-48b2-9b58-40322a184109","leaseAccessConditions":{},"modifiedAccessConditions":{},"containerAcl":[{"id":"ReadOnlyPolicy","accessPolicy":{"permission":"rade"}}]},"restype":"container","comp":"acl","version":"2020-04-08","body":"ReadableStream"}
2021-03-07T13:06:28.268Z 2eb79722-30a4-4a2c-a43d-d39e54758850 verbose: SerializerMiddleware: Start serializing...
2021-03-07T13:06:28.268Z 2eb79722-30a4-4a2c-a43d-d39e54758850 info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=200 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.11.0","etag":"\"0x1D65C6163DE4CF0\"","last-modified":"Sun, 07 Mar 2021 13:06:28 GMT","x-ms-client-request-id":"45763dc7-5765-48b2-9b58-40322a184109","x-ms-request-id":"2eb79722-30a4-4a2c-a43d-d39e54758850","x-ms-version":"2020-06-12","date":"Sun, 07 Mar 2021 13:06:28 GMT"}
2021-03-07T13:06:29.466Z     info: AccountDataStore:init() Refresh accounts from environment variable AZURITE_ACCOUNTS with value undefined
2021-03-07T13:06:29.466Z     info: AccountDataStore:init() Fallback to default emulator account devstoreaccount1.
2021-03-07T13:06:29.504Z     info: AccountDataStore:init() Refresh accounts from environment variable AZURITE_ACCOUNTS with value undefined
2021-03-07T13:06:29.504Z     info: AccountDataStore:init() Fallback to default emulator account devstoreaccount1.
2021-03-07T13:06:29.540Z     info: QueueGCManager:markSweepLoop() Start new mark and sweep.
2021-03-07T13:06:29.540Z     info: QueueGCManger:markSweep() Get all extents.
2021-03-07T13:06:29.540Z     info: QueueGCManager:marksweep() Get 0 extents.
2021-03-07T13:06:29.540Z     info: QueueGCManager:markSweep() Get referred extents, then remove from allExtents.
2021-03-07T13:06:29.540Z     info: QueueGCManager:markSweep() Got referred extents, unreferenced extents count is 0.
2021-03-07T13:06:29.540Z     info: QueueGCManager:markSweepLoop() Mark and sweep finished, take 0ms.
2021-03-07T13:06:29.540Z     info: QueueGCManager:markSweepLoop() Sleep for 60000
2021-03-07T13:06:32.139Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 info: BlobStorageContextMiddleware: RequestMethod=OPTIONS RequestURL=http://127.0.0.1/devstoreaccount1/uploads/d694dc51-ae64-4df2-99be-255334f22e19?sv=2020-04-08&se=2021-03-15T11%3A30%3A13Z&sr=c&sp=w&sig=KT0axgHTydOS4oO1Y%2FsF4rVtBz9Lk1FwES4C6BqgqFE%3D&api-version=2018-03-28 RequestHeaders:{"host":"127.0.0.1:10000","connection":"keep-alive","accept":"*/*","access-control-request-method":"PUT","access-control-request-headers":"content-type,x-ms-blob-content-md5,x-ms-blob-content-type,x-ms-blob-type,x-ms-client-request-id,x-ms-date,x-ms-version","origin":"http://localhost:9060","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/88.0.4324.190 Safari/537.36","sec-fetch-mode":"cors","sec-fetch-site":"cross-site","sec-fetch-dest":"empty","referer":"http://localhost:9060/","accept-encoding":"gzip, deflate, br","accept-language":"pt-BR,pt;q=0.9,en-US;q=0.8,en;q=0.7"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2021-03-07T13:06:32.139Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=uploads Blob=d694dc51-ae64-4df2-99be-255334f22e19
2021-03-07T13:06:32.139Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 verbose: DispatchMiddleware: Dispatching request...
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: DispatchMiddleware: Incoming URL doesn't match any of swagger defined request patterns.
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 info: PreflightMiddlewareFactory.createOptionsHandlerMiddleware(): OPTIONS request.
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=CORS not enabled or no matching rule found for this request.  ErrorHTTPStatusCode=403 ErrorHTTPStatusMessage=CORS not enabled or no matching rule found for this request. ErrorHTTPHeaders={"x-ms-error-code":"CorsPreflightFailure","x-ms-request-id":"6b3e985d-b395-4ed2-b5f7-4eae3857b686"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>CorsPreflightFailure</Code>\n  <Message>CORS not enabled or no matching rule found for this request.\nRequestId:6b3e985d-b395-4ed2-b5f7-4eae3857b686\nTime:2021-03-07T13:06:32.140Z</Message>\n  <MessageDetails>No CORS rules matches this request</MessageDetails>\n</Error>" ErrorStack="StorageError: CORS not enabled or no matching rule found for this request.\n    at Function.corsPreflightFailure (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:222:16)\n    at /opt/azurite/dist/src/blob/middlewares/PreflightMiddlewareFactory.js:39:67\n    at processTicksAndRejections (internal/process/task_queues.js:97:5)"
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Set HTTP code: 403
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Set HTTP status message: CORS not enabled or no matching rule found for this request.
2021-03-07T13:06:32.140Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=CorsPreflightFailure
2021-03-07T13:06:32.141Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=6b3e985d-b395-4ed2-b5f7-4eae3857b686
2021-03-07T13:06:32.141Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Set content type: application/xml
2021-03-07T13:06:32.141Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>CorsPreflightFailure</Code>\n  <Message>CORS not enabled or no matching rule found for this request.\nRequestId:6b3e985d-b395-4ed2-b5f7-4eae3857b686\nTime:2021-03-07T13:06:32.140Z</Message>\n  <MessageDetails>No CORS rules matches this request</MessageDetails>\n</Error>"
2021-03-07T13:06:32.141Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 info: EndMiddleware: End response. TotalTimeInMS=2 StatusCode=403 StatusMessage=CORS not enabled or no matching rule found for this request. Headers={"server":"Azurite-Blob/3.11.0","x-ms-error-code":"CorsPreflightFailure","x-ms-request-id":"6b3e985d-b395-4ed2-b5f7-4eae3857b686","content-type":"application/xml"}

I didn't configured anything about CORS... and, about the image version, it is as follows:

image

I'm new at docker, so I'm not sure if there is other way to check it. I search for It, and think this is the only one. If you need more info, I'm at your dispose. Thanks for the help!

blueww commented 3 years ago

@WillRock19 From the debug log, we can see the error report for the last request as following.

This is preflight request. From the rest API doc , yu can see "The Preflight Blob Request operation queries the Cross-Origin Resource Sharing (CORS) rules for the Blob service prior to sending the actual request. ". So you need enable Cors before use it.

2021-03-07T13:06:32.139Z 6b3e985d-b395-4ed2-b5f7-4eae3857b686 info: BlobStorageContextMiddleware: RequestMethod=OPTIONS RequestURL=http://127.0.0.1/devstoreaccount1/uploads/d694dc51-ae64-4df2-99be-255334f22e19?sv=2020-04-08&se=2021-03-15T11%3A30%3A13Z&sr=c&sp=w&sig=KT0axgHTydOS4oO1Y%2FsF4rVtBz9Lk1FwES4C6BqgqFE%3D&api-version=2018-03-28 RequestHeaders:{"host":"127.0.0.1:10000","connection":"keep-alive","accept":"/","access-control-request-method":"PUT","access-control-request-headers":"content-type,x-ms-blob-content-md5,x-ms-blob-content-type,x-ms-blob-type,x-ms-client-request-id,x-ms-date,x-ms-version","origin":"http://localhost:9060","user-agent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/88.0.4324.190 Safari/537.36","sec-fetch-mode":"cors","sec-fetch-site":"cross-site","sec-fetch-dest":"empty","referer":"http://localhost:9060/","accept-encoding":"gzip, deflate, br","accept-language":"pt-BR,pt;q=0.9,en-US;q=0.8,en;q=0.7"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1

WillRock19 commented 3 years ago

Hi, @blueww, thanks for the feedback!

Sorry for my delay; I had to work in another issues and it all demanded a lot of attention.

When I try to execute the same code with the now deprecated Azure Emulator, it always works. I've had to configure the CORS in the Azure Emulator (via Azure Explorer), so I imagine that I have to configure the CORS in the container too... but I wasn't able to discover how to do it in the docker Azurite :/.

Am I right in this assumption? If so, could you please tell me where I can find a way to configure the Azurite's container when starting it? I'm quite lost to be honest rs

blueww commented 3 years ago

@WillRock19

I am not so family with the deprecated Azure Emulator, but I think you can run success on it since it might miss some check for cors which exist in Azure server.

As you are using blob service, you need set CORS rules to blob services properties. See more details for CORS in doc

I can successfully add Cors rules to Azurite with Azure storage explorer (version 1.18.1), see following picture. image

WillRock19 commented 3 years ago

@blueww

Thanks for the feedback! Just a last question: there is any way to do it inside the docker command that will start the container? I'd rather do it that way because if I do via Azure Explorer, I'll have to do this setup each time I create a new container - and in the project I'm working on, it may happen many times.

blueww commented 3 years ago

@WillRock19 If you don't want UI operation to set CORS: You can write your own code with storage SDK to set CORS, here's a sample: https://github.com/Azure/azure-sdk-for-net/blob/1814567d1f6cfb4905539e71d54377ab46ed6737/sdk/storage/Azure.Storage.Blobs/tests/ServiceClientTests.cs#L429

You can also use Powershell script to set cors, here's a sample https://docs.microsoft.com/en-us/powershell/module/az.storage/set-azstoragecorsrule?view=azps-5.6.0#example-1--assign-cors-rules-to-the-blob-service

stale[bot] commented 2 years ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.