Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Looking for help: not receiving any trace data from collector #1557

Closed
mchiaramonte opened this issue Oct 14, 2024 · 21 comments
Closed

Looking for help: not receiving any trace data from collector #1557

mchiaramonte opened this issue Oct 14, 2024 · 21 comments

Comments

@mchiaramonte
Copy link

Hi,

I'll preface this by saying this is likely something I'm doing wrong, but basically the issue is I'm never able to receive traces from Lambda. If I don't use a collector, I see the metrics endpoint invoked from (presumably) the auto-instrumentation coming in via the open telemetry node layer. My lambda isn't even really code- it's just the default example Amazon provides:

export const handler = async (event, _context) => {
  // TODO implement
  const response = {
    statusCode: 200,
    body: JSON.stringify('Hello from Lambda!'),
  };
  return response;
};

And I've added the layers opentelemetry-nodejs-0_10_0 and opentelemetry-collector-amd64-0_11_0, as per the directions. I've setup only these two environment variables:

AWS_LAMBDA_EXEC_WRAPPER=/opt/otel-handler
OPENTELEMETRY_COLLECTOR_CONFIG_URI=/var/task/config.yaml

And then my config.yaml is configured as follows:

receivers:
  otlp:
    protocols:
      grpc:
        endpoint: 0.0.0.0:4317
      http:
        endpoint: 0.0.0.0:4318

processors:
  batch:
  decouple:

exporters:
  otlphttp:
    traces_endpoint: "http://<myipaddressandport>/v1/traces"
    compression: none
  debug:
    verbosity: detailed

service:
  pipelines:
    traces:
      receivers: [otlp]
      exporters: [otlphttp, debug]
      processors: [decouple]

(Yes I realize I'm not using batch, but I was trying it out at one point and it made no difference that I could tell)

I am not seeing anything from the collector coming in at all. As I mentioned, if I don't use the collector, I do see the v1/metrics endpoint get invoked, but never the v1/traces endpoint. I've tried many permutations of the above configuration but none of them seem to make any difference. I've spent several days trying out different things and nothing is working. I'm at a total loss at this point.

The local software running that receives the traces is functioning properly, because if I run a standard node auto-instrumented app I see the data coming in. I can confirm that I can get external traffic from the address and port that are open.

Any help or advice that can be provided would be appreciated. Thanks so much!

@serkan-ozal
Copy link
Contributor

@mchiaramonte can you try by setting OTEL_TRACES_SAMPLER environment variable to always_on?

@mchiaramonte
Copy link
Author

@serkan-ozal Yes, Let me give that a try. I had tried that at some point but not with this more minimal configuration. Give me a bit to try this out. Thanks for responding.

@mchiaramonte
Copy link
Author

Okay, I tried it with no change. Here's log output from when I call the lambda. There was an error regarding metric export, which I'm not sure is related all. I don't actually care about the metrics, so I'm only including it for completeness.

| 2024-10-17T16:31:39.695Z | START RequestId: 1cbe71db-b7cb-4dca-99fb-5f138743b60d Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:39.696Z | {"level":"info","ts":1729182699.6962602,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:39.722Z | {"level":"info","ts":1729182699.7224755,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:39.723Z | END RequestId: 1cbe71db-b7cb-4dca-99fb-5f138743b60d | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:39.723Z | REPORT RequestId: 1cbe71db-b7cb-4dca-99fb-5f138743b60d Duration: 27.04 ms Billed Duration: 28 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:40.497Z | START RequestId: 39c51260-1112-4f94-ae88-d781b8baf571 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:40.497Z | {"level":"info","ts":1729182700.4975564,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:40.524Z | {"level":"info","ts":1729182700.5241935,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:40.525Z | END RequestId: 39c51260-1112-4f94-ae88-d781b8baf571 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:31:40.525Z | REPORT RequestId: 39c51260-1112-4f94-ae88-d781b8baf571 Duration: 27.24 ms Billed Duration: 28 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:32:03.137Z | START RequestId: b43ef4ba-ea0b-48ce-85e9-3af6dedbd1e3 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:32:03.138Z | {"level":"info","ts":1729182723.1380053,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:32:03.216Z | 2024/10/17 16:32:03 http: superfluous response.WriteHeader call from go.opentelemetry.io/contrib/instrumentation/net/http/otelhttp/internal/request.(*RespWriterWrapper).writeHeader (resp_writer_wrapper.go:78) | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:32:03.238Z | 2024-10-17T16:32:03.238Z b43ef4ba-ea0b-48ce-85e9-3af6dedbd1e3 ERROR {"stack":"Error: PeriodicExportingMetricReader: metrics export failed (error OTLPExporterError: Not Found)\n    at doExport (/opt/nodejs/node_modules/@opentelemetry/sdk-metrics/build/src/export/PeriodicExportingMetricReader.js:76:23)\n    at process.processTicksAndRejections (node:internal/process/task_queues:95:5)\n    at async PeriodicExportingMetricReader._doRun (/opt/nodejs/node_modules/@opentelemetry/sdk-metrics/build/src/export/PeriodicExportingMetricReader.js:84:13)\n    at async PeriodicExportingMetricReader._runOnce (/opt/nodejs/node_modules/@opentelemetry/sdk-metrics/build/src/export/PeriodicExportingMetricReader.js:55:13)","message":"PeriodicExportingMetricReader: metrics export failed (error OTLPExporterError: Not Found)","name":"Error"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:32:03.242Z | {"level":"info","ts":1729182723.2420955,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |
| 2024-10-17T16:32:03.296Z | END RequestId: b43ef4ba-ea0b-48ce-85e9-3af6dedbd1e3 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]738e9759b0a5445697f15b6d9a522de5 |

@serkan-ozal
Copy link
Contributor

Hmm, @mchiaramonte can you also try by setting OTEL_LOG_LEVEL env var to DEBUG to get more details

@serkan-ozal
Copy link
Contributor

@mchiaramonte, I think metrics are failing because, there is no metrics pipeline in the collector, but traces pipeline. So, no metrics endpoint should be available

@mchiaramonte
Copy link
Author

Here's the log info after adding debug logging and making 10 requests:

| 2024-10-17T17:01:10.480Z | INIT_START Runtime Version: nodejs:20.v42 Runtime Version ARN: arn:aws:lambda:us-east-1::runtime:af2737389420ddcdbcdc4db3585842c334ad2482127c15295905f54a62feefc6 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.810Z | {"level":"info","ts":1729184470.8107276,"msg":"Launching OpenTelemetry Lambda extension","version":"UNSET"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.822Z | {"level":"info","ts":1729184470.8228767,"logger":"telemetryAPI.Listener","msg":"Listening for requests","address":"sandbox.localdomain:53612"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.823Z | {"level":"info","ts":1729184470.8230717,"logger":"telemetryAPI.Client","msg":"Subscribing","baseURL":"http://169.254.100.1:9001/2022-07-01/telemetry"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.824Z | TELEMETRY Name: collector State: Subscribed Types: [Platform] | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.824Z | {"level":"info","ts":1729184470.82485,"logger":"telemetryAPI.Client","msg":"Subscription success","response":"\"OK\""} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.824Z | {"level":"info","ts":1729184470.8249536,"logger":"NewCollector","msg":"Using config URI from environment variable","uri":"/var/task/config.yaml"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.842Z | {"level":"info","ts":1729184470.842285,"caller":"[email protected]/service.go:129","msg":"Setting up own telemetry..."} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.860Z | {"level":"warn","ts":1729184470.8608057,"caller":"[email protected]/service.go:196","msg":"service::telemetry::metrics::address is being deprecated in favor of service::telemetry::metrics::readers"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.860Z | {"level":"info","ts":1729184470.8609447,"caller":"[email protected]/telemetry.go:98","msg":"Serving metrics","address":":8888","metrics level":"Normal"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.864Z | {"level":"info","ts":1729184470.863417,"caller":"builders/builders.go:26","msg":"Development component. May change in the future.","kind":"exporter","data_type":"traces","name":"debug"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.864Z | {"level":"info","ts":1729184470.864846,"caller":"builders/builders.go:26","msg":"Development component. May change in the future.","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.866Z | {"level":"info","ts":1729184470.8663735,"caller":"[email protected]/service.go:213","msg":"Starting otelcol-lambda...","Version":"UNSET","NumCPU":2} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.866Z | {"level":"info","ts":1729184470.8664794,"caller":"extensions/extensions.go:39","msg":"Starting extensions..."} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.866Z | {"level":"info","ts":1729184470.866678,"caller":"[email protected]/otlp.go:103","msg":"Starting GRPC server","kind":"receiver","name":"otlp","data_type":"traces","endpoint":"0.0.0.0:4317"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.866Z | {"level":"info","ts":1729184470.866885,"caller":"[email protected]/otlp.go:153","msg":"Starting HTTP server","kind":"receiver","name":"otlp","data_type":"traces","endpoint":"0.0.0.0:4318"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.866Z | {"level":"info","ts":1729184470.866951,"caller":"[email protected]/service.go:239","msg":"Everything is ready. Begin running and processing data."} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:10.867Z | {"level":"info","ts":1729184470.8670046,"caller":"localhostgate/featuregate.go:63","msg":"The default endpoints for all servers in components have changed to use localhost instead of 0.0.0.0. Disable the feature gate to temporarily revert to the previous default.","feature gate ID":"component.UseLocalHostAsDefaultHost"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:11.853Z | Registering OpenTelemetry | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.292Z | @opentelemetry/api: Registered a global for diag v1.9.0. | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.300Z | @opentelemetry/api: Registered a global for trace v1.9.0. | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.300Z | @opentelemetry/api: Registered a global for context v1.9.0. | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.300Z | @opentelemetry/api: Registered a global for propagation v1.9.0. | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.304Z | @opentelemetry/api: Registered a global for metrics v1.9.0. | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | AwsLambdaDetector found resource. Resource { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | _attributes: { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'cloud.provider': 'aws', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'cloud.platform': 'aws_lambda', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'cloud.region': 'us-east-1', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'faas.name': 'vFunLambda', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'faas.version': '$LATEST' | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | }, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | asyncAttributesPending: false, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | _syncAttributes: {}, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | _asyncAttributesPromise: Promise { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'cloud.provider': 'aws', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'cloud.platform': 'aws_lambda', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'cloud.region': 'us-east-1', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'faas.name': 'vFunLambda', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | 'faas.version': '$LATEST' | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.342Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.343Z | EnvDetector found resource. Resource { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.343Z | _attributes: { 'service.name': 'vFunLambda' }, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.343Z | asyncAttributesPending: false, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.343Z | _syncAttributes: {}, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.343Z | _asyncAttributesPromise: Promise { { 'service.name': 'vFunLambda' } } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.343Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | ProcessDetector found resource. Resource { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | _attributes: { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.pid': 9, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.executable.name': '/var/lang/bin/node', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.executable.path': '/var/lang/bin/node', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.command_args': [ | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '/var/lang/bin/node', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '--expose-gc', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '--max-http-header-size', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '81920', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '--max-semi-space-size=2', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '--max-old-space-size=116', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | '/var/runtime/index.mjs' | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | ], | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.runtime.version': '20.17.0', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.runtime.name': 'nodejs', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.runtime.description': 'Node.js', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.command': '/var/runtime/index.mjs', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.owner': 'sbx_user1051' | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | }, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | asyncAttributesPending: false, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | _syncAttributes: {}, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | _asyncAttributesPromise: Promise { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | { | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.pid': 9, | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.executable.name': '/var/lang/bin/node', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.executable.path': '/var/lang/bin/node', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.command_args': [Array], | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.runtime.version': '20.17.0', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.runtime.name': 'nodejs', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.runtime.description': 'Node.js', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.command': '/var/runtime/index.mjs', | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | 'process.owner': 'sbx_user1051' | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.344Z | } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.351Z | @opentelemetry/instrumentation-net Applying instrumentation patch for nodejs core module on require hook { module: 'net' } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.354Z | 2024-10-17T17:01:12.354Z undefined DEBUG @opentelemetry/instrumentation-http Applying instrumentation patch for nodejs core module on require hook { module: 'http' } | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.364Z | EXTENSION Name: collector State: Ready Events: [INVOKE, SHUTDOWN] | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:12.367Z | START RequestId: e8f2a5bb-6419-45fc-8a15-6d33817e0c8d Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:13.400Z | {"level":"info","ts":1729184473.3407009,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:13.400Z | {"level":"info","ts":1729184473.4005768,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:13.402Z | END RequestId: e8f2a5bb-6419-45fc-8a15-6d33817e0c8d | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:13.402Z | REPORT RequestId: e8f2a5bb-6419-45fc-8a15-6d33817e0c8d Duration: 1033.81 ms Billed Duration: 1034 ms Memory Size: 128 MB Max Memory Used: 120 MB Init Duration: 1885.12 ms | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.035Z | START RequestId: 23e0c4c1-22da-485d-b890-3ad1a9e01239 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.036Z | {"level":"info","ts":1729184488.0360115,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.520Z | {"level":"info","ts":1729184488.519993,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.521Z | END RequestId: 23e0c4c1-22da-485d-b890-3ad1a9e01239 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.521Z | REPORT RequestId: 23e0c4c1-22da-485d-b890-3ad1a9e01239 Duration: 484.96 ms Billed Duration: 485 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.700Z | START RequestId: db64bdbe-80f0-4980-bd39-448d74ee0e94 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.700Z | {"level":"info","ts":1729184488.700428,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.920Z | {"level":"info","ts":1729184488.9199922,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.921Z | END RequestId: db64bdbe-80f0-4980-bd39-448d74ee0e94 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:28.921Z | REPORT RequestId: db64bdbe-80f0-4980-bd39-448d74ee0e94 Duration: 220.31 ms Billed Duration: 221 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:29.344Z | START RequestId: ee63adea-6958-483f-b93a-3a39826a7b0a Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:29.344Z | {"level":"info","ts":1729184489.3446076,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:29.560Z | {"level":"info","ts":1729184489.5599935,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:29.561Z | END RequestId: ee63adea-6958-483f-b93a-3a39826a7b0a | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:29.561Z | REPORT RequestId: ee63adea-6958-483f-b93a-3a39826a7b0a Duration: 216.05 ms Billed Duration: 217 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.009Z | START RequestId: 07b112b3-2c91-4db1-a8f0-6a94ca5e15a2 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.010Z | {"level":"info","ts":1729184490.0100014,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.280Z | {"level":"info","ts":1729184490.2800968,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.281Z | END RequestId: 07b112b3-2c91-4db1-a8f0-6a94ca5e15a2 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.281Z | REPORT RequestId: 07b112b3-2c91-4db1-a8f0-6a94ca5e15a2 Duration: 271.15 ms Billed Duration: 272 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.704Z | START RequestId: ea06ec1a-4a72-48f4-9b27-1a51c881a53a Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.705Z | {"level":"info","ts":1729184490.7051487,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.966Z | {"level":"info","ts":1729184490.9660985,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.967Z | END RequestId: ea06ec1a-4a72-48f4-9b27-1a51c881a53a | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:30.967Z | REPORT RequestId: ea06ec1a-4a72-48f4-9b27-1a51c881a53a Duration: 261.66 ms Billed Duration: 262 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:31.516Z | START RequestId: c69c44a3-7c6c-4ac9-9f49-5ca3c5fdd954 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:31.516Z | {"level":"info","ts":1729184491.5167987,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:31.560Z | {"level":"info","ts":1729184491.5600924,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:31.561Z | END RequestId: c69c44a3-7c6c-4ac9-9f49-5ca3c5fdd954 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:31.561Z | REPORT RequestId: c69c44a3-7c6c-4ac9-9f49-5ca3c5fdd954 Duration: 44.03 ms Billed Duration: 45 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.208Z | START RequestId: a8aeafd8-edb1-48a4-b70a-c29b7bfd88fe Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.209Z | {"level":"info","ts":1729184492.2089684,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.740Z | {"level":"info","ts":1729184492.7400064,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.741Z | END RequestId: a8aeafd8-edb1-48a4-b70a-c29b7bfd88fe | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.741Z | REPORT RequestId: a8aeafd8-edb1-48a4-b70a-c29b7bfd88fe Duration: 532.32 ms Billed Duration: 533 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.918Z | START RequestId: c3c0f3ed-c3c3-41b9-a4d3-132341a37f4c Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.922Z | {"level":"info","ts":1729184492.921962,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.986Z | {"level":"info","ts":1729184492.9859827,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.986Z | END RequestId: c3c0f3ed-c3c3-41b9-a4d3-132341a37f4c | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:32.987Z | REPORT RequestId: c3c0f3ed-c3c3-41b9-a4d3-132341a37f4c Duration: 67.82 ms Billed Duration: 68 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:33.577Z | START RequestId: 92d09ea7-cf5a-43ad-98d9-2760b9207b39 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:33.577Z | {"level":"info","ts":1729184493.577519,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:33.603Z | {"level":"info","ts":1729184493.6035419,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:33.604Z | END RequestId: 92d09ea7-cf5a-43ad-98d9-2760b9207b39 | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:33.604Z | REPORT RequestId: 92d09ea7-cf5a-43ad-98d9-2760b9207b39 Duration: 26.81 ms Billed Duration: 27 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:34.222Z | START RequestId: f6d27969-100d-466d-999f-5fa29d10dc3b Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:34.222Z | {"level":"info","ts":1729184494.222668,"caller":"[email protected]/processor.go:83","msg":"started forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:34.248Z | {"level":"info","ts":1729184494.2484572,"caller":"[email protected]/processor.go:93","msg":"stopped forwarding data","kind":"processor","name":"decouple","pipeline":"traces"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:34.249Z | END RequestId: f6d27969-100d-466d-999f-5fa29d10dc3b | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |
| 2024-10-17T17:01:34.249Z | REPORT RequestId: f6d27969-100d-466d-999f-5fa29d10dc3b Duration: 26.46 ms Billed Duration: 27 ms Memory Size: 128 MB Max Memory Used: 120 MB | 874898300625:/aws/lambda/vFunLambda | 2024/10/17/[$LATEST]a5b3102e81804cf1b7f3b3310673cb64 |

@serkan-ozal
Copy link
Contributor

serkan-ozal commented Oct 20, 2024

@mchiaramonte Let's investigate step by step by starting from simplest form of configuations:

  • Remove OPENTELEMETRY_COLLECTOR_CONFIG_URI env var, so default collector configurations will be used.
  • Only keep AWS_LAMBDA_EXEC_WRAPPER=/opt/otel-handler and OTEL_TRACES_SAMPLER=always_on environment variables, remove all the others.

With these configurations, traces and metrics should be printed out to the stdout and so Cloudwatch logs.

For example:

2024-10-20T06:53:48.443Z | START RequestId: f9103d16-bfa1-4135-a4c6-d45a1945acd1 Version: $LATEST
2024-10-20T06:53:48.554Z | {"level":"info","ts":1729407228.5547547,"msg":"TracesExporter","kind":"exporter","data_type":"traces","name":"debug","resource spans":1,"spans":1}
2024-10-20T06:53:48.555Z | 2024/10/20 06:53:48 http: superfluous response.WriteHeader call from go.opentelemetry.io/contrib/instrumentation/net/http/otelhttp/internal/request.(*RespWriterWrapper).writeHeader (resp_writer_wrapper.go:78)
2024-10-20T06:53:48.571Z | {"level":"info","ts":1729407228.571803,"msg":"ResourceSpans #0\nResource SchemaURL: \nResource attributes:\n -> service.name: Str(hello-nodejs-awssdk)\n -> telemetry.sdk.language: Str(nodejs)\n -> telemetry.sdk.name: Str(opentelemetry)\n -> telemetry.sdk.version: Str(1.25.1)\n -> cloud.provider: Str(aws)\n -> cloud.platform: Str(aws_lambda)\n -> cloud.region: Str(us-west-2)\n -> faas.name: Str(hello-nodejs-awssdk)\n -> faas.version: Str($LATEST)\n -> process.pid: Int(15)\n -> process.executable.name: Str(/var/lang/bin/node)\n -> process.executable.path: Str(/var/lang/bin/node)\n -> process.command_args: Slice([\"/var/lang/bin/node\",\"--expose-gc\",\"--max-http-header-size\",\"81920\",\"--max-semi-space-size=19\",\"--max-old-space-size=346\",\"/var/runtime/index.mjs\"])\n -> process.runtime.version: Str(16.20.2)\n -> process.runtime.name: Str(nodejs)\n -> process.runtime.description: Str(Node.js)\n -> process.command: Str(/var/runtime/index.mjs)\n -> process.owner: Str(sbx_user1051)\nScopeSpans #0\nScopeSpans SchemaURL: \nInstrumentationScope @opentelemetry/instrumentation-aws-lambda 0.43.0\nSpan #0\n Trace ID : 6714a8fa79b1a4df11dd08656fcd14bc\n Parent ID : 3e9fffaea54dc6f0\n ID : 66a9b3a390a742b6\n Name : hello-nodejs-awssdk\n Kind : Server\n Start time : 2024-10-20 06:53:48.446 +0000 UTC\n End time : 2024-10-20 06:53:48.451453895 +0000 UTC\n Status code : Unset\n Status message : \nAttributes:\n -> faas.execution: Str(f9103d16-bfa1-4135-a4c6-d45a1945acd1)\n -> faas.id: Str(arn:aws:lambda:us-west-2:482514484979:function:hello-nodejs-awssdk)\n -> cloud.account.id: Str(482514484979)\n","kind":"exporter","data_type":"traces","name":"debug"}
2024-10-20T06:53:48.574Z | 2024/10/20 06:53:48 http: superfluous response.WriteHeader call from go.opentelemetry.io/contrib/instrumentation/net/http/otelhttp/internal/request.(*RespWriterWrapper).writeHeader (resp_writer_wrapper.go:78)
2024-10-20T06:53:48.641Z | END RequestId: f9103d16-bfa1-4135-a4c6-d45a1945acd1
2024-10-20T06:53:48.641Z | REPORT RequestId: f9103d16-bfa1-4135-a4c6-d45a1945acd1 Duration: 196.13 ms Billed Duration: 197 ms Memory Size: 384 MB Max Memory Used: 133 MB

Let's verify these configurations first and let us whether it works as expected. Then we can continue by using custom collector configuration with OPENTELEMETRY_COLLECTOR_CONFIG_URI env var.

@mchiaramonte
Copy link
Author

Okay- let me take a look and see if I can get this done today. Thanks!

@mchiaramonte
Copy link
Author

Keeping everything else the same, I removed all the environment variables except AWS_LAMBDA_EXEC_WRAPPER and OTEL_TRACES_SAMPLER and invoked the lambda via postman. This is what it printed in cloudwatch logs:


| 2024-10-24T23:57:13.136Z | INIT_START Runtime Version: nodejs:20.v42 Runtime Version ARN: arn:aws:lambda:us-east-1::runtime:af2737389420ddcdbcdc4db3585842c334ad2482127c15295905f54a62feefc6 | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.407Z | {"level":"info","ts":1729814233.4076462,"msg":"Launching OpenTelemetry Lambda extension","version":"UNSET"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.413Z | {"level":"info","ts":1729814233.4133914,"logger":"telemetryAPI.Listener","msg":"Listening for requests","address":"sandbox.localdomain:53612"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.415Z | {"level":"info","ts":1729814233.4148903,"logger":"telemetryAPI.Client","msg":"Subscribing","baseURL":"http://169.254.100.1:9001/2022-07-01/telemetry"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.417Z | TELEMETRY Name: collector State: Subscribed Types: [Platform] | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.418Z | {"level":"info","ts":1729814233.417536,"logger":"telemetryAPI.Client","msg":"Subscription success","response":"\"OK\""} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.418Z | {"level":"info","ts":1729814233.417679,"logger":"NewCollector","msg":"Using default config URI","uri":"/opt/collector-config/config.yaml"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.438Z | {"level":"info","ts":1729814233.4382193,"caller":"[email protected]/service.go:129","msg":"Setting up own telemetry..."} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.438Z | {"level":"warn","ts":1729814233.438524,"caller":"[email protected]/service.go:196","msg":"service::telemetry::metrics::address is being deprecated in favor of service::telemetry::metrics::readers"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.438Z | {"level":"info","ts":1729814233.4385998,"caller":"[email protected]/telemetry.go:98","msg":"Serving metrics","address":"localhost:8888","metrics level":"Normal"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.455Z | {"level":"info","ts":1729814233.4401307,"caller":"builders/builders.go:26","msg":"Development component. May change in the future.","kind":"exporter","data_type":"metrics","name":"debug"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.475Z | {"level":"info","ts":1729814233.4750388,"caller":"builders/builders.go:26","msg":"Development component. May change in the future.","kind":"exporter","data_type":"traces","name":"debug"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.476Z | {"level":"info","ts":1729814233.4761539,"caller":"[email protected]/service.go:213","msg":"Starting otelcol-lambda...","Version":"UNSET","NumCPU":2} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.476Z | {"level":"info","ts":1729814233.4762855,"caller":"extensions/extensions.go:39","msg":"Starting extensions..."} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.476Z | {"level":"info","ts":1729814233.4764836,"caller":"[email protected]/otlp.go:103","msg":"Starting GRPC server","kind":"receiver","name":"otlp","data_type":"traces","endpoint":"localhost:4317"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.476Z | {"level":"info","ts":1729814233.4766638,"caller":"[email protected]/otlp.go:153","msg":"Starting HTTP server","kind":"receiver","name":"otlp","data_type":"traces","endpoint":"localhost:4318"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.476Z | {"level":"info","ts":1729814233.4767473,"caller":"[email protected]/service.go:239","msg":"Everything is ready. Begin running and processing data."} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:13.476Z | {"level":"info","ts":1729814233.4768035,"caller":"localhostgate/featuregate.go:63","msg":"The default endpoints for all servers in components have changed to use localhost instead of 0.0.0.0. Disable the feature gate to temporarily revert to the previous default.","feature gate ID":"component.UseLocalHostAsDefaultHost"} | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:14.340Z | Registering OpenTelemetry | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:14.824Z | EXTENSION Name: collector State: Ready Events: [INVOKE, SHUTDOWN] | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:14.827Z | START RequestId: c94949e7-695d-4cc4-8f22-54596ea01a58 Version: $LATEST | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:15.895Z | END RequestId: c94949e7-695d-4cc4-8f22-54596ea01a58 | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |
| 2024-10-24T23:57:15.895Z | REPORT RequestId: c94949e7-695d-4cc4-8f22-54596ea01a58 Duration: 1067.00 ms Billed Duration: 1067 ms Memory Size: 128 MB Max Memory Used: 116 MB Init Duration: 1688.92 ms | 874898300625:/aws/lambda/vFunLambda | 2024/10/24/[$LATEST]63344b7930bb425bbc3ac9fc89dc3469 |

image

@mchiaramonte
Copy link
Author

Also, not sure if it will matter, but just so you can see here are the layers that are part of this lambda:

image

@serkan-ozal
Copy link
Contributor

serkan-ozal commented Oct 25, 2024

@mchiaramonte it is really weird. No AWS Lambda instrumentation is in place.

Is it possible to share the sample Lambda artifact zip file you upload to Lambda (along with handler name you set) with us so we can try to reproduce the issue.

As far as I see, somehow, your handler is not automatically wrapped/instrumented.

@serkan-ozal
Copy link
Contributor

serkan-ozal commented Oct 25, 2024

@mchiaramonte and in the meantime, can you also try with the previous layer version: arn:aws:lambda:us-east-1:184161586896:layer:opentelemetry-nodejs-0_9_0:4

@serkan-ozal
Copy link
Contributor

serkan-ozal commented Oct 25, 2024

@mchiaramonte and is your handler an ES (EcmaScript) module (generated handler file name ends with .mjs)?

@mchiaramonte
Copy link
Author

@serkan-ozal Yes, it's a .mjs file - it's the default file generated by Amazon when you create the lambda for node.js. I can try the older layer if you like. As for the artifact zip- we're not even there yet. This is just me working with it directly in the editor within Amazon. I was just trying to prove out that this even works. That code I initially provided is all that's there. It's just the index.mjs file.

@serkan-ozal
Copy link
Contributor

@mchiaramonte Ok, I think that explains everything. AFAIK, ES modules are not supported by OpenTelemetry AWS Lambda instrumentation. Is it possible to produce a common JS file (.js or .cjs) and try with that.

@mchiaramonte
Copy link
Author

@serkan-ozal Ah okay. Let me try that.

@mchiaramonte
Copy link
Author

@serkan-ozal Yes, there we go. That's what the problem was. Okay- did I just miss this in the documentation somewhere? Or is this not explicitly called out?

@serkan-ozal
Copy link
Contributor

@mchiaramonte I don't think it is mentioned at somewhere. I have just found it out from the AWS Lambda instrumentation source code here: https://github.com/open-telemetry/opentelemetry-js-contrib/blob/7633caee19a7f04c5cc5e191d1ae7745ac3739f1/plugins/node/opentelemetry-instrumentation-aws-lambda/src/instrumentation.ts#L119

Only .js or .cjs files are taken care of for auto wrapping. Once I have time, I will also check how it is easy to add ES module support for auto wrapping of ES based Lambda handlers.

@mchiaramonte
Copy link
Author

Great, @serkan-ozal - thank you so much for all your help with this. It's greatly appreciated.

@serkan-ozal
Copy link
Contributor

@mchiaramonte
Copy link
Author

Hi @serkan-ozal this is great. Thanks for the update. I really appreciate the help and responsiveness on this.

One other thing I noticed while testing things out is it looks like the fetch API isn't being auto-instrumented. Is that correct? I saw an experimental project for instrumenting fetch here, so I assume this is expected.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants