|
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"} |