| 2026-04-24 02:26:26.777 |
START RequestId: bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed Version: 109 |
| 2026-04-24 02:26:26.780 |
[INFO] 2026-04-24T02:26:26.779Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.indexer.action_controller Worker handling message SQSFifoMessage(body={'catalog': 'anvil-it', 'operation_id': '529aa38c-b3ed-4826-a928-03a3a8387a66', 'source': {'id': '74089fe0-c732-40b0-b828-d7e091f0f54b', 'spec': 'tdr:bigquery:gcp:datarepo-dev-dd576076:ANVIL_CMG_Sample_1_20230225_ANV5_202512031111', 'prefix': '/1', 'type': 'azul.terra.TDRSourceRef'}, 'prefix': 'a', 'file': {'uuid': '00d3b050-30ec-4eb8-81e3-2a5c2bb9513f', 'name': '10137.merged.matefixed.sorted.markeddups.recal.bam', 'version': '2022-06-01T00:00:00.000000Z', 'drs_uri': 'drs://jade.datarepo-dev.broadinstitute.org/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5', 'size': 1840, 'content_type': None, 'md5': 'a5a2273ce9a6df96e3a0861a12a8a2ba', 'type': 'azul.plugins.metadata.anvil.AnvilFile'}, 'action': 'MirrorFileAction'}, attempts=3, id='cd432568-28c7-4d72-b64c-1d848a2b9453', group_id='f8a3068a-46ab-5a28-8844-892ea2007319', dedup_id='ff18308e-3b27-57cc-a9e8-1fb94efb416a') |
| 2026-04-24 02:26:26.785 |
[INFO] 2026-04-24T02:26:26.785Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: Making HEAD request to https://s3.amazonaws.com/edu-ucsc-gi-platform-anvil-dev-mirror-anvildev.us-east-1/_it/info/a5a2273ce9a6df96e3a0861a12a8a2ba.md5.json |
| 2026-04-24 02:26:26.785 |
[INFO] 2026-04-24T02:26:26.785Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: … without a request body |
| 2026-04-24 02:26:26.860 |
[INFO] 2026-04-24T02:26:26.860Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: … without a response body |
| 2026-04-24 02:26:26.860 |
[INFO] 2026-04-24T02:26:26.860Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: Got 404 response |
| 2026-04-24 02:26:26.861 |
[INFO] 2026-04-24T02:26:26.861Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: Making HEAD request to https://s3.amazonaws.com/edu-ucsc-gi-platform-anvil-dev-mirror-anvildev.us-east-1/_it/file/a5a2273ce9a6df96e3a0861a12a8a2ba.md5 |
| 2026-04-24 02:26:26.861 |
[INFO] 2026-04-24T02:26:26.861Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: … without a request body |
| 2026-04-24 02:26:26.869 |
[INFO] 2026-04-24T02:26:26.869Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: Got 404 response |
| 2026-04-24 02:26:26.869 |
[INFO] 2026-04-24T02:26:26.869Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.boto3 s3.HeadObject: … without a response body |
| 2026-04-24 02:26:26.869 |
[INFO] 2026-04-24T02:26:26.869Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.indexer.mirror_service Mirroring file via standard upload: AnvilFile(uuid='00d3b050-30ec-4eb8-81e3-2a5c2bb9513f', name='10137.merged.matefixed.sorted.markeddups.recal.bam', version='2022-06-01T00:00:00.000000Z', drs_uri='drs://jade.datarepo-dev.broadinstitute.org/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5', size=1840, content_type=None, md5='a5a2273ce9a6df96e3a0861a12a8a2ba') |
| 2026-04-24 02:26:26.870 |
[INFO] 2026-04-24T02:26:26.870Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … without a request body |
| 2026-04-24 02:26:26.870 |
[INFO] 2026-04-24T02:26:26.870Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra Making GET request to 'https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5' |
| 2026-04-24 02:26:26.870 |
[INFO] 2026-04-24T02:26:26.870Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … with request headers [('x-goog-api-client', 'cred-type/sa'), ('authorization', 'REDACTED')] |
| 2026-04-24 02:26:28.087 |
[INFO] 2026-04-24T02:26:28.087Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra Got 200 response after 1.216s from GET to https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5 |
| 2026-04-24 02:26:28.087 |
[INFO] 2026-04-24T02:26:28.087Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … with response headers HTTPHeaderDict({'Date': 'Fri, 24 Apr 2026 02:26:28 GMT', 'Server': 'Apache', 'X-Frame-Options': 'SAMEORIGIN', 'Access-Control-Allow-Headers': 'DNT,User-Agent,X-Requested-With,If-Modified-Since,Cache-Control,Content-Type,Range,Authorization,Accept,Referer,X-App-Id,Origin', 'Access-Control-Allow-Methods': 'GET,POST,DELETE,PUT,PATCH,OPTIONS,HEAD', 'X-Content-Type-Options': 'nosniff', 'Strict-Transport-Security': 'max-age=31536000;includeSubDomains', 'Cache-Control': 'no-cache,no-store,must-revalidate', 'X-Request-ID': 'aKl3QvXe', 'Content-Type': 'application/json', 'Content-Length': '2495', 'Set-Cookie': 'JSESSIONID=09458FE25A62D72F9F6CD2C8BC1155FF; Path=/; Secure; HttpOnly; SameSite=Lax', 'Vary': 'Origin', 'Via': '1.1 google', 'Alt-Svc': 'h3=":443"; ma=2592000'}) |
| 2026-04-24 02:26:28.087 |
[INFO] 2026-04-24T02:26:28.087Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … with a response body of length 2495 starting in b'{"id":"v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5","name":"10137_10137.merged.matefixed.sorted.markeddups.recal.bam","self_uri":"drs://jade.datarepo-dev.broadinstitute.org/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5","size":1840,"created_time":"2023-02-24T20:08:34.007Z","updated_time":"2023-02-24T20:08:34.007Z","version":"0","mime_type":"application/octet-stream","checksums":[{"checksum":"c2c3a933","type":"crc32c"},{"checksum":"a5a2273ce9a6df96e3a0861a12a8a2ba","type":"md5"}],"access_methods":[{"type":"gs","access_url":{"url":"gs://fc-73435f45-96f6-4077-8dea-89782c5c1de5/10137/10137.merged.matefixed.sorted.markeddups.recal.bam","headers":null},"access_id":"gcp-us-central1*74089fe0-c732-40b0-b828-d7e091f0f54b","region":"us-central1","authorizations":{"supported_types":["BearerAuth"],"passport_auth_issuers":null,"bearer_auth_issuers":null}},{"type":"https","access_url":{"url":"https://www.googleapis.com/storage/v1/b/fc-73435f45-96f6-4077-8dea-89782c5c1de5/o/10137%2F10137.merged.matefixed.sorted.markeddups.recal.bam...' |
| 2026-04-24 02:26:28.088 |
[INFO] 2026-04-24T02:26:28.087Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra Making GET request to 'https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5/access/gcp-us-central1*74089fe0-c732-40b0-b828-d7e091f0f54b' |
| 2026-04-24 02:26:28.088 |
[INFO] 2026-04-24T02:26:28.088Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … without a request body |
| 2026-04-24 02:26:28.088 |
[INFO] 2026-04-24T02:26:28.088Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … with request headers [('x-goog-api-client', 'cred-type/sa'), ('authorization', 'REDACTED')] |
| 2026-04-24 02:26:29.304 |
[INFO] 2026-04-24T02:26:29.304Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra Got 400 response after 1.216s from GET to https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5/access/gcp-us-central1*74089fe0-c732-40b0-b828-d7e091f0f54b |
| 2026-04-24 02:26:29.304 |
[INFO] 2026-04-24T02:26:29.304Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … with a response body of length 98 being b'{"msg":"Snapshot requires an x-user-project header for DRS access URL requests","status_code":400}' |
| 2026-04-24 02:26:29.304 |
[INFO] 2026-04-24T02:26:29.304Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.terra … with response headers HTTPHeaderDict({'Date': 'Fri, 24 Apr 2026 02:26:29 GMT', 'Server': 'Apache', 'X-Frame-Options': 'SAMEORIGIN', 'Access-Control-Allow-Headers': 'DNT,User-Agent,X-Requested-With,If-Modified-Since,Cache-Control,Content-Type,Range,Authorization,Accept,Referer,X-App-Id,Origin', 'Access-Control-Allow-Methods': 'GET,POST,DELETE,PUT,PATCH,OPTIONS,HEAD', 'X-Content-Type-Options': 'nosniff', 'Strict-Transport-Security': 'max-age=31536000;includeSubDomains', 'Cache-Control': 'no-cache,no-store,must-revalidate', 'X-Request-ID': '6v5NRGMw', 'Content-Type': 'application/json', 'Content-Length': '98', 'Set-Cookie': 'JSESSIONID=2F4CE8C4D3C2C81301338CE62844CF44; Path=/; Secure; HttpOnly; SameSite=Lax', 'Vary': 'Origin', 'Via': '1.1 google', 'Alt-Svc': 'h3=":443"; ma=2592000'}) |
| 2026-04-24 02:26:29.305 |
[WARNING] 2026-04-24T02:26:29.304Z bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed azul.indexer.action_controller Worker failed to handle message SQSFifoMessage(body={'catalog': 'anvil-it', 'operation_id': '529aa38c-b3ed-4826-a928-03a3a8387a66', 'source': {'id': '74089fe0-c732-40b0-b828-d7e091f0f54b', 'spec': 'tdr:bigquery:gcp:datarepo-dev-dd576076:ANVIL_CMG_Sample_1_20230225_ANV5_202512031111', 'prefix': '/1', 'type': 'azul.terra.TDRSourceRef'}, 'prefix': 'a', 'file': {'uuid': '00d3b050-30ec-4eb8-81e3-2a5c2bb9513f', 'name': '10137.merged.matefixed.sorted.markeddups.recal.bam', 'version': '2022-06-01T00:00:00.000000Z', 'drs_uri': 'drs://jade.datarepo-dev.broadinstitute.org/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5', 'size': 1840, 'content_type': None, 'md5': 'a5a2273ce9a6df96e3a0861a12a8a2ba', 'type': 'azul.plugins.metadata.anvil.AnvilFile'}, 'action': 'MirrorFileAction'}, attempts=3, id='cd432568-28c7-4d72-b64c-1d848a2b9453', group_id='f8a3068a-46ab-5a28-8844-892ea2007319', dedup_id='ff18308e-3b27-57cc-a9e8-1fb94efb416a') Traceback (most recent call last): File "/var/task/azul/indexer/action_controller.py", line 50, in _handle_events message_handler(action) ~~~~~~~~~~~~~~~^^^^^^^^ File "/var/task/azul/indexer/mirror_controller.py", line 75, in action_handler self.service(action.catalog).mirror(action) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^ File "/var/task/azul/indexer/mirror_service.py", line 561, in mirror self._queue_actions(self._mirror(action)) ~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ File "/var/task/azul/indexer/mirror_service.py", line 430, in _queue_actions return self._queues.send_messages(self._mirror_queue(), ~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ map(MirrorAction.to_sqs, actions), ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ rate_limit=rate_limit) ^^^^^^^^^^^^^^^^^^^^^^ File "/var/task/azul/queues.py", line 210, in send_messages for batch in chunked(messages, self.batch_size): ~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/opt/python/more_itertools/recipes.py", line 126, in take return list(islice(iterable, n)) File "/var/task/azul/indexer/mirror_service.py", line 622, in _ self._mirror_file(a.file) ~~~~~~~~~~~~~~~~~^^^^^^^^ File "/var/task/azul/indexer/mirror_service.py", line 636, in _mirror_file file_content = self._download(file) File "/var/task/azul/indexer/mirror_service.py", line 757, in _download url = self._repository_url(file) File "/var/task/azul/indexer/mirror_service.py", line 752, in _repository_url access = object.get(AccessMethod.gs) File "/var/task/azul/drs.py", line 365, in get return self._get(access_method) ~~~~~~~~~^^^^^^^^^^^^^^^ File "/var/task/azul/drs.py", line 387, in _get return self._get_access(access_id, AccessMethod.https) ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/var/task/azul/drs.py", line 427, in _get_access raise DRSStatusException(url, response) azul.drs.DRSStatusException: ('Unexpected response from https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5/access/gcp-us-central1*74089fe0-c732-40b0-b828-d7e091f0f54b', 400, b'{"msg":"Snapshot requires an x-user-project header for DRS access URL requests","status_code":400}') |
| 2026-04-24 02:26:29.306 |
[ERROR] DRSStatusException: ('Unexpected response from https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5/access/gcp-us-central1*74089fe0-c732-40b0-b828-d7e091f0f54b', 400, b'{"msg":"Snapshot requires an x-user-project header for DRS access URL requests","status_code":400}') Traceback (most recent call last): File "/var/task/azul/chalice.py", line 190, in patched_event_source_handler return old_handler(self_, event, context) File "/var/task/chalice/app.py", line 1761, in call return self.handler(event_obj) File "/var/task/azul/indexer/mirror_controller.py", line 67, in mirror self.mirror(event) File "/var/task/azul/indexer/mirror_controller.py", line 77, in mirror self._handle_events(event, action_handler) File "/var/task/azul/indexer/action_controller.py", line 50, in _handle_events message_handler(action) File "/var/task/azul/indexer/mirror_controller.py", line 75, in action_handler self.service(action.catalog).mirror(action) File "/var/task/azul/indexer/mirror_service.py", line 561, in mirror self._queue_actions(self._mirror(action)) File "/var/task/azul/indexer/mirror_service.py", line 430, in _queue_actions return self._queues.send_messages(self._mirror_queue(), File "/var/task/azul/queues.py", line 210, in send_messages for batch in chunked(messages, self.batch_size): File "/opt/python/more_itertools/recipes.py", line 126, in take return list(islice(iterable, n)) File "/var/task/azul/indexer/mirror_service.py", line 622, in _ self._mirror_file(a.file) File "/var/task/azul/indexer/mirror_service.py", line 636, in _mirror_file file_content = self._download(file) File "/var/task/azul/indexer/mirror_service.py", line 757, in _download url = self._repository_url(file) File "/var/task/azul/indexer/mirror_service.py", line 752, in _repository_url access = object.get(AccessMethod.gs) File "/var/task/azul/drs.py", line 365, in get return self._get(access_method) File "/var/task/azul/drs.py", line 387, in _get return self._get_access(access_id, AccessMethod.https) File "/var/task/azul/drs.py", line 427, in _get_access raise DRSStatusException(url, response) |
| 2026-04-24 02:26:29.309 |
REPORT RequestId: bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed Duration: 2531.50 ms Billed Duration: 2532 ms Memory Size: 512 MB Max Memory Used: 171 MB |
| 2026-04-24 02:26:29.309 |
END RequestId: bdd903b0-67c2-5ef9-a8aa-61d4b13c04ed |
… due to "Snapshot requires an
x-user-projectheader for DRS access URL requests".[ERROR] DRSStatusException: ('Unexpected response from https://jade.datarepo-dev.broadinstitute.org/ga4gh/drs/v1/objects/v2_4ffefed7-76a2-4807-8f3b-b4dde17ad3b5/access/gcp-us-central1*74089fe0-c732-40b0-b828-d7e091f0f54b', 400, b'{""msg"":""Snapshot requires an x-user-project header for DRS access URL requests"",""status_code"":400}')CloudWatch Logs Insights
region: us-east-1
log-group-names: /aws/lambda/azul-indexer-anvildev-mirror
data-sources:
facets:
start-time: 2026-04-24T02:25:42.888Z
end-time: 2026-04-24T02:27:36.523Z
query-string: