Azurite Repro

README.md

Context

I'm using Azurite for some integration tests, and I noticed some odd behavior with the Blob Batch API when using a hostname instead of an IP address.

Related to:

• Azure/Azurite#1039 (specifically this comment)

The subrequests will fail when calling delete with a hostname with ResourceNotFound because it's incorrectly calculating the account and container names.

For example, for the request:

RequestMethod=DELETE RequestURL=http://devstoreaccount1.blob.localhost:52183/repro/foo/bar/file2.txt RequestHeaders:{...}

The middleware is incorrectly interpretting the URL and thinks the first piece of the URL path is the storage account:

2023-09-27T02:30:27.860Z 4b6c35e3-6e0e-48d1-a794-fe7f354fb012 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container foo
2023-09-27T02:30:27.860Z 4b6c35e3-6e0e-48d1-a794-fe7f354fb012 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T02:30:27.860Z 4b6c35e3-6e0e-48d1-a794-fe7f354fb012 error: BlobSharedKeyAuthenticator:validate() Invalid storage account repro.
2023-09-27T02:30:27.861Z 4b6c35e3-6e0e-48d1-a794-fe7f354fb012 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2023-09-27T02:30:27.861Z 4b6c35e3-6e0e-48d1-a794-fe7f354fb012 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified resource does not exist.  ErrorHTTPStatusCode=404

Minimal Repro

I have a snippet using the Azure SDK for Go that also spins up a fresh azurite container:

• https://gist.github.com/robherley/143b649a6382f686c0aca9d2d1a72196

You'll need to have the docker daemon running and just run go run main.go.

I have a few parameters at the top of the file to help enumerate all the scenarios:

// Toggle between IP and Hostname
UseIP = false
// Toggle between batch API and one-by-one delete
UseBatchDelete = true

The above case is the failure condition. If UseIP is true, the process will complete fine, since the account name is interpreted correctly. If the UseBatchDelete is false, it will also complete fine by calling delete blob one by one instead of the batch API. The issue only occurs with the blob batch APIs.

For convience the code also creates a log/ directory in cwd and will write the Azurite debug log to it.

go.mod

module github.com/robherley/azurite-repro

go 1.20

require (
	github.com/Azure/azure-sdk-for-go/sdk/storage/azblob v1.1.0
	github.com/testcontainers/testcontainers-go v0.24.1
)

require (
	dario.cat/mergo v1.0.0 // indirect
	github.com/Azure/azure-sdk-for-go/sdk/azcore v1.6.0 // indirect
	github.com/Azure/azure-sdk-for-go/sdk/internal v1.3.0 // indirect
	github.com/Azure/go-ansiterm v0.0.0-20210617225240-d185dfc1b5a1 // indirect
	github.com/Microsoft/go-winio v0.6.1 // indirect
	github.com/Microsoft/hcsshim v0.11.0 // indirect
	github.com/cenkalti/backoff/v4 v4.2.1 // indirect
	github.com/containerd/containerd v1.7.6 // indirect
	github.com/cpuguy83/dockercfg v0.3.1 // indirect
	github.com/docker/distribution v2.8.2+incompatible // indirect
	github.com/docker/docker v24.0.6+incompatible // indirect
	github.com/docker/go-connections v0.4.0 // indirect
	github.com/docker/go-units v0.5.0 // indirect
	github.com/go-ole/go-ole v1.2.6 // indirect
	github.com/gogo/protobuf v1.3.2 // indirect
	github.com/golang/protobuf v1.5.3 // indirect
	github.com/google/uuid v1.3.1 // indirect
	github.com/klauspost/compress v1.16.0 // indirect
	github.com/lufia/plan9stats v0.0.0-20211012122336-39d0f177ccd0 // indirect
	github.com/magiconair/properties v1.8.7 // indirect
	github.com/moby/patternmatcher v0.5.0 // indirect
	github.com/moby/sys/sequential v0.5.0 // indirect
	github.com/moby/term v0.5.0 // indirect
	github.com/morikuni/aec v1.0.0 // indirect
	github.com/opencontainers/go-digest v1.0.0 // indirect
	github.com/opencontainers/image-spec v1.1.0-rc4 // indirect
	github.com/opencontainers/runc v1.1.5 // indirect
	github.com/pkg/errors v0.9.1 // indirect
	github.com/power-devops/perfstat v0.0.0-20210106213030-5aafc221ea8c // indirect
	github.com/shirou/gopsutil/v3 v3.23.7 // indirect
	github.com/shoenig/go-m1cpu v0.1.6 // indirect
	github.com/sirupsen/logrus v1.9.0 // indirect
	github.com/tklauser/go-sysconf v0.3.11 // indirect
	github.com/tklauser/numcpus v0.6.0 // indirect
	github.com/yusufpapurcu/wmi v1.2.3 // indirect
	golang.org/x/exp v0.0.0-20230510235704-dd950f8aeaea // indirect
	golang.org/x/mod v0.9.0 // indirect
	golang.org/x/net v0.10.0 // indirect
	golang.org/x/sys v0.11.0 // indirect
	golang.org/x/text v0.9.0 // indirect
	golang.org/x/tools v0.7.0 // indirect
	google.golang.org/genproto/googleapis/rpc v0.0.0-20230525234030-28d5490b6b19 // indirect
	google.golang.org/grpc v1.57.0 // indirect
	google.golang.org/protobuf v1.30.0 // indirect
)

main.go

package main

import (
	"context"
	"fmt"
	"log"
	"os"
	"path/filepath"

	"github.com/Azure/azure-sdk-for-go/sdk/storage/azblob"
	"github.com/Azure/azure-sdk-for-go/sdk/storage/azblob/bloberror"
	"github.com/testcontainers/testcontainers-go"
	"github.com/testcontainers/testcontainers-go/wait"
)

const (
	// Toggle between IP and Hostname
	UseIP = false
	// Toggle between batch API and one-by-one delete
	UseBatchDelete = true

	AccountName   = "devstoreaccount1"
	ContainerName = "repro"
	AccountKey    = "Eby8vdM02xNOcqFlqUwJPLlmEtlCDXJ1OUzFT50uSRZ6IFsuFq2UVErCz4I6tq/K1SZFPTOtr/KBHBeksoGMGw=="
)

var (
	Files = [][2]string{
		{"foo/bar/file1.txt", "This is file 1"},
		{"foo/bar/file2.txt", "This is file 2"},
		{"foo/bar/file3.txt", "This is file 3"},
	}
)

func createAzuriteContainer(ctx context.Context) (string, testcontainers.Container) {
	if err := os.Mkdir("log", 0755); err != nil && !os.IsExist(err) {
		log.Fatal(err)
	}

	dir, err := os.Getwd()
	if err != nil {
		log.Fatal(err)
	}

	req := testcontainers.ContainerRequest{
		Image:        "mcr.microsoft.com/azure-storage/azurite",
		ExposedPorts: []string{"10000/tcp"},
		Cmd: []string{
			"azurite-blob",
			"--blobHost",
			"0.0.0.0",
			"--blobPort",
			"10000",
			"--debug",
			"/tmp/log/debug.log",
		},
		Mounts: testcontainers.ContainerMounts{
			{
				Source: testcontainers.GenericBindMountSource{
					HostPath: filepath.Join(dir, "log"),
				},
				Target: "/tmp/log/",
			},
		},
		WaitingFor: wait.ForListeningPort("10000/tcp"),
	}
	azuriteContainer, err := testcontainers.GenericContainer(ctx, testcontainers.GenericContainerRequest{
		ContainerRequest: req,
		Started:          true,
	})
	if err != nil {
		log.Fatal(err)
	}

	port, err := azuriteContainer.MappedPort(ctx, "10000/tcp")
	if err != nil {
		log.Fatal(err)
	}

	var storageAccountURL string
	if UseIP {
		storageAccountURL = fmt.Sprintf("http://127.0.0.1:%s/%s", port.Port(), AccountName)
	} else {
		storageAccountURL = fmt.Sprintf("http://%s.blob.localhost:%s", AccountName, port.Port())
	}
	return storageAccountURL, azuriteContainer
}

func authenticate(url string) *azblob.Client {
	cred, err := azblob.NewSharedKeyCredential(AccountName, AccountKey)
	if err != nil {
		log.Fatal(err)
	}

	client, err := azblob.NewClientWithSharedKeyCredential(url, cred, nil)
	if err != nil {
		log.Fatal(err)
	}

	return client
}

func createContainerWithFiles(ctx context.Context, client *azblob.Client) {
	_, err := client.CreateContainer(ctx, ContainerName, nil)
	if err != nil && !bloberror.HasCode(err, bloberror.ContainerAlreadyExists) {
		log.Fatal(err)
	}

	for _, file := range Files {
		log.Printf("uploading %q with content %q", file[0], file[1])
		_, err := client.UploadBuffer(ctx, ContainerName, file[0], []byte(file[1]), nil)
		if err != nil {
			log.Fatal(err)
		}
	}
}

func sequentiallyDeleteBlobs(ctx context.Context, client *azblob.Client, paths ...string) {
	for _, path := range paths {
		log.Printf("deleting %q", path)
		_, err := client.DeleteBlob(ctx, ContainerName, path, nil)
		if err != nil {
			log.Fatal(err)
		}
	}
}

func batchDeleteBlobs(ctx context.Context, client *azblob.Client, paths ...string) {
	svcClient := client.ServiceClient()

	batchBuilder, err := svcClient.NewBatchBuilder()
	if err != nil {
		log.Fatal(err)
	}

	for _, path := range paths {
		log.Printf("adding %q to delete batch", path)
		err := batchBuilder.Delete(ContainerName, path, nil)
		if err != nil {
			log.Fatal(err)
		}
	}

	response, err := svcClient.SubmitBatch(ctx, batchBuilder, nil)
	if err != nil {
		log.Fatal(err)
	}

	for _, res := range response.Responses {
		if err := res.Error; err != nil {
			log.Fatal(err)
		}
	}
}

func listBlobs(ctx context.Context, client *azblob.Client, prefix string) {
	pager := client.NewListBlobsFlatPager(ContainerName, &azblob.ListBlobsFlatOptions{
		Prefix: &prefix,
	})

	for pager.More() {
		page, err := pager.NextPage(ctx)
		if err != nil {
			log.Fatal(err)
		}

		for _, blob := range page.Segment.BlobItems {
			log.Printf("- %s\n", *blob.Name)
		}
	}
}

func main() {
	ctx := context.Background()
	url, container := createAzuriteContainer(ctx)
	defer container.Terminate(ctx)

	client := authenticate(url)
	createContainerWithFiles(ctx, client)
	listBlobs(ctx, client, "")
	if UseBatchDelete {
		batchDeleteBlobs(ctx, client, Files[0][0], Files[1][0])
	} else {
		sequentiallyDeleteBlobs(ctx, client, Files[0][0], Files[1][0])
	}
	listBlobs(ctx, client, "")
}

zzz_debug.log

2023-09-27T03:05:57.851Z 	 info: Azurite Blob service is starting on 0.0.0.0:10000
2023-09-27T03:05:57.854Z 	 info: AccountDataStore:init() Refresh accounts from environment variable AZURITE_ACCOUNTS with value undefined
2023-09-27T03:05:57.854Z 	 info: AccountDataStore:init() Fallback to default emulator account devstoreaccount1.
2023-09-27T03:05:57.866Z 	 info: BlobGCManager:start() Starting BlobGCManager. Set status to Initializing.
2023-09-27T03:05:57.866Z 	 info: BlobGCManager:start() Trigger mark and sweep loop. Set status to Running.
2023-09-27T03:05:57.866Z 	 info: BlobGCManager:markSweepLoop() Start next mark and sweep.
2023-09-27T03:05:57.866Z 	 info: BlobGCManager:markSweep() Get all extents.
2023-09-27T03:05:57.867Z 	 info: BlobGCManager:start() BlobGCManager successfully started.
2023-09-27T03:05:57.869Z 	 info: BlobGCManager:markSweep() Got 0 extents.
2023-09-27T03:05:57.869Z 	 info: BlobGCManager:markSweep() Get referred extents.
2023-09-27T03:05:57.870Z 	 info: BlobGCManager:markSweep() Got referred extents, unreferenced extents count is 0.
2023-09-27T03:05:57.870Z 	 info: BlobGCManager:markSweepLoop() Mark and sweep finished, taken 4ms.
2023-09-27T03:05:57.870Z 	 info: BlobGCManager:markSweepLoop() Sleep for 600000ms.
2023-09-27T03:05:57.871Z 	 info: Azurite Blob service successfully listens on http://0.0.0.0:10000
2023-09-27T03:05:58.071Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://devstoreaccount1.blob.localhost/repro?restype=container RequestHeaders:{"host":"devstoreaccount1.blob.localhost:53159","user-agent":"azsdk-go-azblob/v1.1.0 (go1.20.2; darwin)","content-length":"0","accept":"application/xml","authorization":"SharedKey devstoreaccount1:gVBz9V24EgAn1FArA49tA1Az2GETCwGZBTiUqlxKZn8=","x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-version":"2020-10-02","accept-encoding":"gzip"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2023-09-27T03:05:58.072Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=repro Blob=
2023-09-27T03:05:58.072Z b3972c4f-0d1d-4271-bf6f-1208bade0152 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.074Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: DispatchMiddleware: Operation=Container_Create
2023-09-27T03:05:58.075Z b3972c4f-0d1d-4271-bf6f-1208bade0152 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.075Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.076Z b3972c4f-0d1d-4271-bf6f-1208bade0152 debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.076Z b3972c4f-0d1d-4271-bf6f-1208bade0152 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: repro, blob: 
2023-09-27T03:05:58.079Z b3972c4f-0d1d-4271-bf6f-1208bade0152 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container repro
2023-09-27T03:05:58.080Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.090Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n\n\n\n\n\n\n\n\n\nx-ms-date:Wed, 27 Sep 2023 03:05:58 GMT\nx-ms-version:2020-10-02\n/devstoreaccount1/repro\nrestype:container"
2023-09-27T03:05:58.090Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:gVBz9V24EgAn1FArA49tA1Az2GETCwGZBTiUqlxKZn8=
2023-09-27T03:05:58.090Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2023-09-27T03:05:58.091Z b3972c4f-0d1d-4271-bf6f-1208bade0152 verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.092Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"containerCpkScopeInfo":{}},"restype":"container","version":"2020-10-02"}
2023-09-27T03:05:58.093Z b3972c4f-0d1d-4271-bf6f-1208bade0152 verbose: SerializerMiddleware: Start serializing...
2023-09-27T03:05:58.094Z b3972c4f-0d1d-4271-bf6f-1208bade0152 info: EndMiddleware: End response. TotalTimeInMS=23 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.26.0","etag":"\"0x1C5FAF745655EC0\"","last-modified":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-request-id":"b3972c4f-0d1d-4271-bf6f-1208bade0152","x-ms-version":"2023-08-03"}
2023-09-27T03:05:58.098Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://devstoreaccount1.blob.localhost/repro/foo%2Fbar%2Ffile1.txt RequestHeaders:{"host":"devstoreaccount1.blob.localhost:53159","user-agent":"azsdk-go-azblob/v1.1.0 (go1.20.2; darwin)","content-length":"14","accept":"application/xml","authorization":"SharedKey devstoreaccount1:pJdKzeALpGpxmCjh0A0Q2v/dnH0OtY8YTjUbEjahtZA=","content-type":"application/octet-stream","x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-blob-type":"BlockBlob","x-ms-version":"2020-10-02","accept-encoding":"gzip"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2023-09-27T03:05:58.098Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=repro Blob=foo/bar/file1.txt
2023-09-27T03:05:58.098Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.099Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: DispatchMiddleware: Operation=BlockBlob_Upload
2023-09-27T03:05:58.099Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.099Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.099Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.099Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: repro, blob: foo/bar/file1.txt
2023-09-27T03:05:58.100Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container repro
2023-09-27T03:05:58.100Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.100Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n14\n\napplication/octet-stream\n\n\n\n\n\n\nx-ms-blob-type:BlockBlob\nx-ms-date:Wed, 27 Sep 2023 03:05:58 GMT\nx-ms-version:2020-10-02\n/devstoreaccount1/repro/foo%2Fbar%2Ffile1.txt"
2023-09-27T03:05:58.100Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:pJdKzeALpGpxmCjh0A0Q2v/dnH0OtY8YTjUbEjahtZA=
2023-09-27T03:05:58.101Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2023-09-27T03:05:58.101Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.101Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"blobHTTPHeaders":{},"leaseAccessConditions":{},"cpkInfo":{},"cpkScopeInfo":{},"modifiedAccessConditions":{}},"contentLength":14,"version":"2020-10-02","blobType":"BlockBlob","body":"ReadableStream"}
2023-09-27T03:05:58.101Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue.operate() Schedule incoming job 8e4a3006-7765-4438-bcf7-17336c2b4bb9
2023-09-27T03:05:58.102Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:50 operations.length:1
2023-09-27T03:05:58.102Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: FSExtentStore:appendExtent() Select extent from idle location for extent append operation. LocationId:1 extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:0 MAX_EXTENT_SIZE:67108864 
2023-09-27T03:05:58.102Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:appendExtent() Get fd:undefined for extent:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 from cache.
2023-09-27T03:05:58.102Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:appendExtent() Open file:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 for extent:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61, get new fd:21
2023-09-27T03:05:58.103Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:appendExtent() Created write stream for fd:21
2023-09-27T03:05:58.103Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:appendExtent() Start writing to extent e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61
2023-09-27T03:05:58.103Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:streamPipe() Start piping data to write stream
2023-09-27T03:05:58.104Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:streamPipe() Readable stream triggers close event, 14 bytes piped
2023-09-27T03:05:58.104Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:streamPipe() Invoke write stream end()
2023-09-27T03:05:58.105Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:streamPipe() Writable stream triggers finish event, after 14 bytes piped. Flush data to fd:21.
2023-09-27T03:05:58.108Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:streamPipe() Flush data to fd:21 successfully. Resolve streamPipe().
2023-09-27T03:05:58.108Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:appendExtent() Write finish, start updating extent metadata. extent:{"id":"e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61","locationId":"Default","path":"e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61","size":14,"lastModifiedInMS":1695783958108}
2023-09-27T03:05:58.108Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:appendExtent() Update extent metadata done. Resolve()
2023-09-27T03:05:58.108Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue.operate() Job 8e4a3006-7765-4438-bcf7-17336c2b4bb9 completes callback, resolve.
2023-09-27T03:05:58.109Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue.operate() Schedule incoming job fd06d2c6-2247-4a4e-8fdc-0891ff053517
2023-09-27T03:05:58.109Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:100 operations.length:1
2023-09-27T03:05:58.109Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 verbose: FSExtentStore:readExtent() Creating read stream. LocationId:Default extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 path:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:0 count:14 end:13
2023-09-27T03:05:58.109Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue.operate() Job fd06d2c6-2247-4a4e-8fdc-0891ff053517 completes callback, resolve.
2023-09-27T03:05:58.110Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:50 operations.length:0
2023-09-27T03:05:58.110Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue:execute() return. Operation.length === 0
2023-09-27T03:05:58.110Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:100 operations.length:0
2023-09-27T03:05:58.110Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: OperationQueue:execute() return. Operation.length === 0
2023-09-27T03:05:58.112Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 verbose: SerializerMiddleware: Start serializing...
2023-09-27T03:05:58.112Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 info: EndMiddleware: End response. TotalTimeInMS=14 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.26.0","etag":"\"0x1D1B135B4B04D60\"","last-modified":"Wed, 27 Sep 2023 03:05:58 GMT","content-md5":"4dH6q5HrVRuaVm7q0xmgEg==","x-ms-request-id":"2ab8e5e0-6f17-4265-acf2-e85d4104a648","x-ms-version":"2023-08-03","date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-request-server-encrypted":"true"}
2023-09-27T03:05:58.113Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 debug: FSExtentStore:streamPipe() Readable stream triggers close event, 14 bytes piped
2023-09-27T03:05:58.113Z 2ab8e5e0-6f17-4265-acf2-e85d4104a648 verbose: FSExtentStore:readExtent() Read stream closed. LocationId:Default extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 path:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:0 count:14 end:13
2023-09-27T03:05:58.115Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://devstoreaccount1.blob.localhost/repro/foo%2Fbar%2Ffile2.txt RequestHeaders:{"host":"devstoreaccount1.blob.localhost:53159","user-agent":"azsdk-go-azblob/v1.1.0 (go1.20.2; darwin)","content-length":"14","accept":"application/xml","authorization":"SharedKey devstoreaccount1:Z6sd52TBr0HvGFd4CFn7Qu8WuKTIjlws7abNrmbOs3I=","content-type":"application/octet-stream","x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-blob-type":"BlockBlob","x-ms-version":"2020-10-02","accept-encoding":"gzip"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2023-09-27T03:05:58.115Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=repro Blob=foo/bar/file2.txt
2023-09-27T03:05:58.115Z 0ba1911e-218a-4189-baa8-fe0af5825100 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: DispatchMiddleware: Operation=BlockBlob_Upload
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: repro, blob: foo/bar/file2.txt
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container repro
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n14\n\napplication/octet-stream\n\n\n\n\n\n\nx-ms-blob-type:BlockBlob\nx-ms-date:Wed, 27 Sep 2023 03:05:58 GMT\nx-ms-version:2020-10-02\n/devstoreaccount1/repro/foo%2Fbar%2Ffile2.txt"
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:Z6sd52TBr0HvGFd4CFn7Qu8WuKTIjlws7abNrmbOs3I=
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.116Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"blobHTTPHeaders":{},"leaseAccessConditions":{},"cpkInfo":{},"cpkScopeInfo":{},"modifiedAccessConditions":{}},"contentLength":14,"version":"2020-10-02","blobType":"BlockBlob","body":"ReadableStream"}
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue.operate() Schedule incoming job ba8aeff8-d1a0-4deb-a2af-18a567b037e3
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:50 operations.length:1
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: FSExtentStore:appendExtent() Select extent from idle location for extent append operation. LocationId:1 extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:14 MAX_EXTENT_SIZE:67108864 
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:appendExtent() Get fd:21 for extent:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 from cache.
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:appendExtent() Created write stream for fd:21
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:appendExtent() Start writing to extent e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:streamPipe() Start piping data to write stream
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:streamPipe() Readable stream triggers close event, 14 bytes piped
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:streamPipe() Invoke write stream end()
2023-09-27T03:05:58.117Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:streamPipe() Writable stream triggers finish event, after 14 bytes piped. Flush data to fd:21.
2023-09-27T03:05:58.119Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:streamPipe() Flush data to fd:21 successfully. Resolve streamPipe().
2023-09-27T03:05:58.119Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:appendExtent() Write finish, start updating extent metadata. extent:{"id":"e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61","locationId":"Default","path":"e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61","size":28,"lastModifiedInMS":1695783958119}
2023-09-27T03:05:58.119Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:appendExtent() Update extent metadata done. Resolve()
2023-09-27T03:05:58.119Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue.operate() Job ba8aeff8-d1a0-4deb-a2af-18a567b037e3 completes callback, resolve.
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue.operate() Schedule incoming job 2d38d841-bb16-491a-a6b0-e7d8da95123c
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:100 operations.length:1
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 verbose: FSExtentStore:readExtent() Creating read stream. LocationId:Default extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 path:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:14 count:14 end:27
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue.operate() Job 2d38d841-bb16-491a-a6b0-e7d8da95123c completes callback, resolve.
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:50 operations.length:0
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue:execute() return. Operation.length === 0
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:100 operations.length:0
2023-09-27T03:05:58.120Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: OperationQueue:execute() return. Operation.length === 0
2023-09-27T03:05:58.121Z 0ba1911e-218a-4189-baa8-fe0af5825100 verbose: SerializerMiddleware: Start serializing...
2023-09-27T03:05:58.121Z 0ba1911e-218a-4189-baa8-fe0af5825100 info: EndMiddleware: End response. TotalTimeInMS=6 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.26.0","etag":"\"0x1EBB358D8DAB2E0\"","last-modified":"Wed, 27 Sep 2023 03:05:58 GMT","content-md5":"0gs34xCQkDYsApnB5qq29w==","x-ms-request-id":"0ba1911e-218a-4189-baa8-fe0af5825100","x-ms-version":"2023-08-03","date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-request-server-encrypted":"true"}
2023-09-27T03:05:58.121Z 0ba1911e-218a-4189-baa8-fe0af5825100 debug: FSExtentStore:streamPipe() Readable stream triggers close event, 14 bytes piped
2023-09-27T03:05:58.122Z 0ba1911e-218a-4189-baa8-fe0af5825100 verbose: FSExtentStore:readExtent() Read stream closed. LocationId:Default extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 path:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:14 count:14 end:27
2023-09-27T03:05:58.123Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: BlobStorageContextMiddleware: RequestMethod=PUT RequestURL=http://devstoreaccount1.blob.localhost/repro/foo%2Fbar%2Ffile3.txt RequestHeaders:{"host":"devstoreaccount1.blob.localhost:53159","user-agent":"azsdk-go-azblob/v1.1.0 (go1.20.2; darwin)","content-length":"14","accept":"application/xml","authorization":"SharedKey devstoreaccount1:IFuv+v70dC6hV4XFNP0P445OWWbjuwpFafz/MCFJaRQ=","content-type":"application/octet-stream","x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-blob-type":"BlockBlob","x-ms-version":"2020-10-02","accept-encoding":"gzip"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2023-09-27T03:05:58.123Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=repro Blob=foo/bar/file3.txt
2023-09-27T03:05:58.123Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: DispatchMiddleware: Operation=BlockBlob_Upload
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: repro, blob: foo/bar/file3.txt
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container repro
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.124Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"PUT\n\n\n14\n\napplication/octet-stream\n\n\n\n\n\n\nx-ms-blob-type:BlockBlob\nx-ms-date:Wed, 27 Sep 2023 03:05:58 GMT\nx-ms-version:2020-10-02\n/devstoreaccount1/repro/foo%2Fbar%2Ffile3.txt"
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:IFuv+v70dC6hV4XFNP0P445OWWbjuwpFafz/MCFJaRQ=
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: HandlerMiddleware: DeserializedParameters={"options":{"metadata":{},"blobHTTPHeaders":{},"leaseAccessConditions":{},"cpkInfo":{},"cpkScopeInfo":{},"modifiedAccessConditions":{}},"contentLength":14,"version":"2020-10-02","blobType":"BlockBlob","body":"ReadableStream"}
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue.operate() Schedule incoming job 39f4db47-efb1-41db-93f7-fec8192e4e35
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:50 operations.length:1
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: FSExtentStore:appendExtent() Select extent from idle location for extent append operation. LocationId:1 extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:28 MAX_EXTENT_SIZE:67108864 
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:appendExtent() Get fd:21 for extent:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 from cache.
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:appendExtent() Created write stream for fd:21
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:appendExtent() Start writing to extent e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:streamPipe() Start piping data to write stream
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:streamPipe() Readable stream triggers close event, 14 bytes piped
2023-09-27T03:05:58.125Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:streamPipe() Invoke write stream end()
2023-09-27T03:05:58.126Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:streamPipe() Writable stream triggers finish event, after 14 bytes piped. Flush data to fd:21.
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:streamPipe() Flush data to fd:21 successfully. Resolve streamPipe().
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:appendExtent() Write finish, start updating extent metadata. extent:{"id":"e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61","locationId":"Default","path":"e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61","size":42,"lastModifiedInMS":1695783958127}
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:appendExtent() Update extent metadata done. Resolve()
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue.operate() Job 39f4db47-efb1-41db-93f7-fec8192e4e35 completes callback, resolve.
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue.operate() Schedule incoming job 72862550-e169-4c7a-a39f-6993a6f3e54e
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:100 operations.length:1
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 verbose: FSExtentStore:readExtent() Creating read stream. LocationId:Default extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 path:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:28 count:14 end:41
2023-09-27T03:05:58.127Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue.operate() Job 72862550-e169-4c7a-a39f-6993a6f3e54e completes callback, resolve.
2023-09-27T03:05:58.128Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:50 operations.length:0
2023-09-27T03:05:58.128Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue:execute() return. Operation.length === 0
2023-09-27T03:05:58.128Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue:execute() Current runningConcurrency:0 maxConcurrency:100 operations.length:0
2023-09-27T03:05:58.128Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: OperationQueue:execute() return. Operation.length === 0
2023-09-27T03:05:58.129Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 verbose: SerializerMiddleware: Start serializing...
2023-09-27T03:05:58.129Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 info: EndMiddleware: End response. TotalTimeInMS=6 StatusCode=201 StatusMessage=undefined Headers={"server":"Azurite-Blob/3.26.0","etag":"\"0x2068EF315A8AE40\"","last-modified":"Wed, 27 Sep 2023 03:05:58 GMT","content-md5":"tzi9NawV2QQ7OjjjOVTLYA==","x-ms-request-id":"851dfb58-3eb9-4b6a-906f-2abb6bc48cd7","x-ms-version":"2023-08-03","date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-request-server-encrypted":"true"}
2023-09-27T03:05:58.129Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 debug: FSExtentStore:streamPipe() Readable stream triggers close event, 14 bytes piped
2023-09-27T03:05:58.129Z 851dfb58-3eb9-4b6a-906f-2abb6bc48cd7 verbose: FSExtentStore:readExtent() Read stream closed. LocationId:Default extentId:e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 path:/opt/azurite/__blobstorage__/e5c9d8bd-6776-4c2c-b9ad-d7f6cfbfdb61 offset:28 count:14 end:41
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff info: BlobStorageContextMiddleware: RequestMethod=GET RequestURL=http://devstoreaccount1.blob.localhost/repro?comp=list&restype=container RequestHeaders:{"host":"devstoreaccount1.blob.localhost:53159","user-agent":"azsdk-go-azblob/v1.1.0 (go1.20.2; darwin)","accept":"application/xml","authorization":"SharedKey devstoreaccount1:/dH0iivi0rx9bFXTtsNr7Mekljrnbhlr9kEu/+Yly8k=","x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-version":"2020-10-02","accept-encoding":"gzip"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container=repro Blob=
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff info: DispatchMiddleware: Operation=Container_ListBlobFlatSegment
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.131Z 40101b13-2472-4bd4-b0de-e14e250150ff debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: repro, blob: 
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container repro
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"GET\n\n\n\n\n\n\n\n\n\n\n\nx-ms-date:Wed, 27 Sep 2023 03:05:58 GMT\nx-ms-version:2020-10-02\n/devstoreaccount1/repro\ncomp:list\nrestype:container"
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:/dH0iivi0rx9bFXTtsNr7Mekljrnbhlr9kEu/+Yly8k=
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.132Z 40101b13-2472-4bd4-b0de-e14e250150ff info: HandlerMiddleware: DeserializedParameters={"options":{"include":[]},"restype":"container","comp":"list","version":"2020-10-02"}
2023-09-27T03:05:58.134Z 40101b13-2472-4bd4-b0de-e14e250150ff verbose: SerializerMiddleware: Start serializing...
2023-09-27T03:05:58.136Z 40101b13-2472-4bd4-b0de-e14e250150ff debug: Serializer: Raw response body string is <?xml version="1.0" encoding="UTF-8" standalone="yes"?><EnumerationResults ServiceEndpoint="http://devstoreaccount1.blob.localhost:53159/devstoreaccount1" ContainerName="repro"><Prefix/><Marker/><MaxResults>5000</MaxResults><Blobs><Blob><Name>foo/bar/file1.txt</Name><Properties><Creation-Time>Wed, 27 Sep 2023 03:05:58 GMT</Creation-Time><Last-Modified>Wed, 27 Sep 2023 03:05:58 GMT</Last-Modified><Etag>0x1D1B135B4B04D60</Etag><Content-Length>14</Content-Length><Content-Type>application/octet-stream</Content-Type><Content-MD5>4dH6q5HrVRuaVm7q0xmgEg==</Content-MD5><BlobType>BlockBlob</BlobType><LeaseStatus>unlocked</LeaseStatus><LeaseState>available</LeaseState><ServerEncrypted>true</ServerEncrypted><AccessTier>Hot</AccessTier><AccessTierInferred>true</AccessTierInferred><AccessTierChangeTime>Wed, 27 Sep 2023 03:05:58 GMT</AccessTierChangeTime></Properties></Blob><Blob><Name>foo/bar/file2.txt</Name><Properties><Creation-Time>Wed, 27 Sep 2023 03:05:58 GMT</Creation-Time><Last-Modified>Wed, 27 Sep 2023 03:05:58 GMT</Last-Modified><Etag>0x1EBB358D8DAB2E0</Etag><Content-Length>14</Content-Length><Content-Type>application/octet-stream</Content-Type><Content-MD5>0gs34xCQkDYsApnB5qq29w==</Content-MD5><BlobType>BlockBlob</BlobType><LeaseStatus>unlocked</LeaseStatus><LeaseState>available</LeaseState><ServerEncrypted>true</ServerEncrypted><AccessTier>Hot</AccessTier><AccessTierInferred>true</AccessTierInferred><AccessTierChangeTime>Wed, 27 Sep 2023 03:05:58 GMT</AccessTierChangeTime></Properties></Blob><Blob><Name>foo/bar/file3.txt</Name><Properties><Creation-Time>Wed, 27 Sep 2023 03:05:58 GMT</Creation-Time><Last-Modified>Wed, 27 Sep 2023 03:05:58 GMT</Last-Modified><Etag>0x2068EF315A8AE40</Etag><Content-Length>14</Content-Length><Content-Type>application/octet-stream</Content-Type><Content-MD5>tzi9NawV2QQ7OjjjOVTLYA==</Content-MD5><BlobType>BlockBlob</BlobType><LeaseStatus>unlocked</LeaseStatus><LeaseState>available</LeaseState><ServerEncrypted>true</ServerEncrypted><AccessTier>Hot</AccessTier><AccessTierInferred>true</AccessTierInferred><AccessTierChangeTime>Wed, 27 Sep 2023 03:05:58 GMT</AccessTierChangeTime></Properties></Blob></Blobs><NextMarker/></EnumerationResults>
2023-09-27T03:05:58.136Z 40101b13-2472-4bd4-b0de-e14e250150ff info: Serializer: Start returning stream body.
2023-09-27T03:05:58.137Z 40101b13-2472-4bd4-b0de-e14e250150ff info: EndMiddleware: End response. TotalTimeInMS=6 StatusCode=200 StatusMessage=OK Headers={"server":"Azurite-Blob/3.26.0","content-type":"application/xml","x-ms-request-id":"40101b13-2472-4bd4-b0de-e14e250150ff","x-ms-version":"2023-08-03","date":"Wed, 27 Sep 2023 03:05:58 GMT"}
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobStorageContextMiddleware: RequestMethod=POST RequestURL=http://devstoreaccount1.blob.localhost/?comp=batch RequestHeaders:{"host":"devstoreaccount1.blob.localhost:53159","user-agent":"azsdk-go-azblob/v1.1.0 (go1.20.2; darwin)","content-length":"710","accept":"application/xml","authorization":"SharedKey devstoreaccount1:vosaCmhcOu6+F7sZacGpVyioE9OwG+NpteBXTJsA7wg=","content-type":"multipart/mixed; boundary=batch_06b8e165-7367-4010-7815-6ca5ed4e3b3b","x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","x-ms-version":"2020-10-02","accept-encoding":"gzip"} ClientIP=172.17.0.1 Protocol=http HTTPVersion=1.1
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobStorageContextMiddleware: Account=devstoreaccount1 Container= Blob=
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: DispatchMiddleware: Operation=Service_SubmitBatch
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.140Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: devstoreaccount1, container: , blob: 
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container 
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobSharedKeyAuthenticator:validate() [STRING TO SIGN]:"POST\n\n\n710\n\nmultipart/mixed; boundary=batch_06b8e165-7367-4010-7815-6ca5ed4e3b3b\n\n\n\n\n\n\nx-ms-date:Wed, 27 Sep 2023 03:05:58 GMT\nx-ms-version:2020-10-02\n/devstoreaccount1/\ncomp:batch"
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobSharedKeyAuthenticator:validate() Calculated authentication header based on key1: SharedKey devstoreaccount1:vosaCmhcOu6+F7sZacGpVyioE9OwG+NpteBXTJsA7wg=
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobSharedKeyAuthenticator:validate() Signature 1 matched.
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.141Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: HandlerMiddleware: DeserializedParameters={"options":{},"comp":"batch","contentLength":710,"multipartContentType":"multipart/mixed; boundary=batch_06b8e165-7367-4010-7815-6ca5ed4e3b3b","version":"2020-10-02","body":"ReadableStream"}
2023-09-27T03:05:58.144Z f1c9b97d-0611-4dd7-b0e2-937e33b7babf info: BlobStorageContextMiddleware: RequestMethod=DELETE RequestURL=http://devstoreaccount1.blob.localhost:53159/repro/foo/bar/file1.txt RequestHeaders:{"x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","accept":"application/xml","authorization":"SharedKey devstoreaccount1:+iqrH+MDop0bNz9GAVfmo9kEhbfiE0C63P+hP9Ld8Ns="} ClientIP=http://devstoreaccount1.blob.localhost Protocol=http HTTPVersion=version
2023-09-27T03:05:58.144Z f1c9b97d-0611-4dd7-b0e2-937e33b7babf info: BlobStorageContextMiddleware: Account=repro Container=foo Blob=bar/file1.txt
2023-09-27T03:05:58.145Z f1c9b97d-0611-4dd7-b0e2-937e33b7babf verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.145Z f1c9b97d-0611-4dd7-b0e2-937e33b7babf info: DispatchMiddleware: Operation=Blob_Delete
2023-09-27T03:05:58.145Z 81f20fe2-19ee-4e6c-b179-c607017f4df8 info: BlobStorageContextMiddleware: RequestMethod=DELETE RequestURL=http://devstoreaccount1.blob.localhost:53159/repro/foo/bar/file2.txt RequestHeaders:{"x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","accept":"application/xml","authorization":"SharedKey devstoreaccount1:r20kUQYbJgcxNJn2JHA6JLg8+rC/8YnHVyxcGDzbZhc="} ClientIP=http://devstoreaccount1.blob.localhost Protocol=http HTTPVersion=version
2023-09-27T03:05:58.145Z 81f20fe2-19ee-4e6c-b179-c607017f4df8 info: BlobStorageContextMiddleware: Account=repro Container=foo Blob=bar/file2.txt
2023-09-27T03:05:58.145Z 81f20fe2-19ee-4e6c-b179-c607017f4df8 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.146Z 81f20fe2-19ee-4e6c-b179-c607017f4df8 info: DispatchMiddleware: Operation=Blob_Delete
2023-09-27T03:05:58.146Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobBatchHandler: starting on subrequest 0
2023-09-27T03:05:58.146Z 33741c3a-683c-47de-9408-e5565b4d6efe info: BlobStorageContextMiddleware: RequestMethod=DELETE RequestURL=http://devstoreaccount1.blob.localhost:53159/repro/foo/bar/file1.txt RequestHeaders:{"x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","accept":"application/xml","authorization":"SharedKey devstoreaccount1:+iqrH+MDop0bNz9GAVfmo9kEhbfiE0C63P+hP9Ld8Ns="} ClientIP=http://devstoreaccount1.blob.localhost Protocol=http HTTPVersion=version
2023-09-27T03:05:58.146Z 33741c3a-683c-47de-9408-e5565b4d6efe info: BlobStorageContextMiddleware: Account=repro Container=foo Blob=bar/file1.txt
2023-09-27T03:05:58.146Z 33741c3a-683c-47de-9408-e5565b4d6efe verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe info: DispatchMiddleware: Operation=Blob_Delete
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe debug: PublicAccessAuthenticator:validate() Retrieved account name from context: repro, container: foo, blob: bar/file1.txt
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container foo
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.147Z 33741c3a-683c-47de-9408-e5565b4d6efe error: BlobSharedKeyAuthenticator:validate() Invalid storage account repro.
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified resource does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified resource does not exist. ErrorHTTPHeaders={"x-ms-error-code":"ResourceNotFound","x-ms-request-id":"33741c3a-683c-47de-9408-e5565b4d6efe"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ResourceNotFound</Code>\n  <Message>The specified resource does not exist.\nRequestId:33741c3a-683c-47de-9408-e5565b4d6efe\nTime:2023-09-27T03:05:58.148Z</Message>\n</Error>" ErrorStack="StorageError: The specified resource does not exist.\n    at Function.ResourceNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:32:16)\n    at BlobSharedKeyAuthenticator.validate (/opt/azurite/dist/src/blob/authentication/BlobSharedKeyAuthenticator.js:36:49)\n    at AuthenticationMiddlewareFactory.authenticate (/opt/azurite/dist/src/blob/middlewares/AuthenticationMiddlewareFactory.js:35:40)\n    at processTicksAndRejections (internal/process/task_queues.js:95:5)"
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Set HTTP code: 404
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Set HTTP status message: The specified resource does not exist.
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ResourceNotFound
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=33741c3a-683c-47de-9408-e5565b4d6efe
2023-09-27T03:05:58.148Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Set content type: application/xml
2023-09-27T03:05:58.149Z 33741c3a-683c-47de-9408-e5565b4d6efe error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ResourceNotFound</Code>\n  <Message>The specified resource does not exist.\nRequestId:33741c3a-683c-47de-9408-e5565b4d6efe\nTime:2023-09-27T03:05:58.148Z</Message>\n</Error>"
2023-09-27T03:05:58.149Z 33741c3a-683c-47de-9408-e5565b4d6efe verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.149Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobBatchHandler: completed on subrequest 0 33741c3a-683c-47de-9408-e5565b4d6efe
2023-09-27T03:05:58.149Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobBatchHandler: starting on subrequest 1
2023-09-27T03:05:58.149Z ae7861af-4688-4885-83f7-7efca36bfc17 info: BlobStorageContextMiddleware: RequestMethod=DELETE RequestURL=http://devstoreaccount1.blob.localhost:53159/repro/foo/bar/file2.txt RequestHeaders:{"x-ms-date":"Wed, 27 Sep 2023 03:05:58 GMT","accept":"application/xml","authorization":"SharedKey devstoreaccount1:r20kUQYbJgcxNJn2JHA6JLg8+rC/8YnHVyxcGDzbZhc="} ClientIP=http://devstoreaccount1.blob.localhost Protocol=http HTTPVersion=version
2023-09-27T03:05:58.149Z ae7861af-4688-4885-83f7-7efca36bfc17 info: BlobStorageContextMiddleware: Account=repro Container=foo Blob=bar/file2.txt
2023-09-27T03:05:58.149Z ae7861af-4688-4885-83f7-7efca36bfc17 verbose: DispatchMiddleware: Dispatching request...
2023-09-27T03:05:58.149Z ae7861af-4688-4885-83f7-7efca36bfc17 info: DispatchMiddleware: Operation=Blob_Delete
2023-09-27T03:05:58.149Z ae7861af-4688-4885-83f7-7efca36bfc17 verbose: AuthenticationMiddlewareFactory:createAuthenticationMiddleware() Validating authentications.
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 info: PublicAccessAuthenticator:validate() Start validation against public access.
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 debug: PublicAccessAuthenticator:validate() Getting account properties...
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 debug: PublicAccessAuthenticator:validate() Retrieved account name from context: repro, container: foo, blob: bar/file2.txt
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 debug: PublicAccessAuthenticator:validate() Skip public access authentication. Cannot get public access type for container foo
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 info: BlobSharedKeyAuthenticator:validate() Start validation against account shared key authentication.
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: BlobSharedKeyAuthenticator:validate() Invalid storage account repro.
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Received a MiddlewareError, fill error information to HTTP response
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: ErrorName=StorageError ErrorMessage=The specified resource does not exist.  ErrorHTTPStatusCode=404 ErrorHTTPStatusMessage=The specified resource does not exist. ErrorHTTPHeaders={"x-ms-error-code":"ResourceNotFound","x-ms-request-id":"ae7861af-4688-4885-83f7-7efca36bfc17"} ErrorHTTPBody="<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ResourceNotFound</Code>\n  <Message>The specified resource does not exist.\nRequestId:ae7861af-4688-4885-83f7-7efca36bfc17\nTime:2023-09-27T03:05:58.150Z</Message>\n</Error>" ErrorStack="StorageError: The specified resource does not exist.\n    at Function.ResourceNotFound (/opt/azurite/dist/src/blob/errors/StorageErrorFactory.js:32:16)\n    at BlobSharedKeyAuthenticator.validate (/opt/azurite/dist/src/blob/authentication/BlobSharedKeyAuthenticator.js:36:49)\n    at AuthenticationMiddlewareFactory.authenticate (/opt/azurite/dist/src/blob/middlewares/AuthenticationMiddlewareFactory.js:35:40)\n    at processTicksAndRejections (internal/process/task_queues.js:95:5)"
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Set HTTP code: 404
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Set HTTP status message: The specified resource does not exist.
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Set HTTP Header: x-ms-error-code=ResourceNotFound
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Set HTTP Header: x-ms-request-id=ae7861af-4688-4885-83f7-7efca36bfc17
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Set content type: application/xml
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 error: ErrorMiddleware: Set HTTP body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?>\n<Error>\n  <Code>ResourceNotFound</Code>\n  <Message>The specified resource does not exist.\nRequestId:ae7861af-4688-4885-83f7-7efca36bfc17\nTime:2023-09-27T03:05:58.150Z</Message>\n</Error>"
2023-09-27T03:05:58.150Z ae7861af-4688-4885-83f7-7efca36bfc17 verbose: DeserializerMiddleware: Start deserializing...
2023-09-27T03:05:58.150Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: BlobBatchHandler: completed on subrequest 1 ae7861af-4688-4885-83f7-7efca36bfc17
2023-09-27T03:05:58.151Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 verbose: SerializerMiddleware: Start serializing...
2023-09-27T03:05:58.151Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: Serializer: Start returning stream body.
2023-09-27T03:05:58.153Z 9084787b-392f-4ff9-924a-b1fcd1bdca14 info: EndMiddleware: End response. TotalTimeInMS=13 StatusCode=202 StatusMessage=Accepted Headers={"server":"Azurite-Blob/3.26.0","content-type":"multipart/mixed; boundary=batch_06b8e165-7367-4010-7815-6ca5ed4e3b3b","x-ms-request-id":"9084787b-392f-4ff9-924a-b1fcd1bdca14","x-ms-version":"2023-08-03"}