[GH-ISSUE #1754] GoogleCloudLoggingFormatter timestamp does not work with Google Cloud Run #741

New issue

Closed

opened 2026-03-04 02:17:33 +03:00 by kerem · 8 comments

kerem commented 2026-03-04 02:17:33 +03:00

Owner

Copy link

Originally created by @rpkamp on GitHub (Aug 26, 2022).
Original GitHub issue: https://github.com/Seldaek/monolog/issues/1754

Monolog version 2 and 3.

In our application we have a fingers crossed handler that passes to a stream handler that writes to php://stderr using the Google Cloud Logging Formatter. This mostly works fine, except that Google Cloud Run does not use the timestamp sent from monolog as the timestamp of the message. It seems to be taking the time it received the message instead.
Most notably when we logged several messages, sleeping for 1 second between those messages, they all more or less the same timestamp in Google Cloud Logging, they certainly weren't a second apart.

Diving into it, it turns out sending messages through php://stderr is different than sending them through the logging API. Instead of timestamp the field time must be used, and the time must be in UTC.

We found that the following works for us when writing to php://stderr on Google Cloud Run (in Monolog 2):

$record['time'] = $record['datetime']->setTimezone(new DateTimeZone('UTC'))->format('Y-m-d\TH:i:s.u\Z');

With this in place the messages were logged seconds apart, even though they were still logged all at once by the fingers crossed handler.

I do wonder whether this should be changed in Monolog, because I don't know if the current implementation has a use case that I'm unaware off that uses the format that is now in the code (something else than Cloud Run and/or writing to php://stderr).

@lcobucci since you wrote the formatter in Monolog, do you have ideas on this?

Originally created by @rpkamp on GitHub (Aug 26, 2022). Original GitHub issue: https://github.com/Seldaek/monolog/issues/1754 Monolog version 2 and 3. In our application we have a fingers crossed handler that passes to a stream handler that writes to `php://stderr` using the Google Cloud Logging Formatter. This mostly works fine, except that Google Cloud Run does not use the `timestamp` sent from monolog as the timestamp of the message. It seems to be taking the time it received the message instead. Most notably when we logged several messages, sleeping for 1 second between those messages, they all more or less the same timestamp in Google Cloud Logging, they certainly weren't a second apart. Diving into it, it turns out sending messages through `php://stderr` is different than sending them through the logging API. Instead of `timestamp` the field `time` must be used, and the time must be in UTC. We found that the following works for us when writing to `php://stderr` on Google Cloud Run (in Monolog 2): ``` $record['time'] = $record['datetime']->setTimezone(new DateTimeZone('UTC'))->format('Y-m-d\TH:i:s.u\Z'); ``` With this in place the messages were logged seconds apart, even though they were still logged all at once by the fingers crossed handler. I do wonder whether this should be changed in Monolog, because I don't know if the current implementation has a use case that I'm unaware off that uses the format that is now in the code (something else than Cloud Run and/or writing to `php://stderr`). @lcobucci since you wrote the formatter in Monolog, do you have ideas on this?

kerem 2026-03-04 02:17:33 +03:00

• closed this issue
• added the
  Bug
  label

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@lcobucci commented on GitHub (Aug 26, 2022):

I'm running this via Google Kubernetes Engine (relying on php://stderr too) and it seems to be working fine. It's interesting that Cloud Run and GKE diverge... well, maybe it's just Google being Google idk 🤷‍♂️

I don't see a reason not to support both, though... maybe $record['time'] = $record['timestamp'] = $record['datetime']->...?

<!-- gh-comment-id:1228585286 --> @lcobucci commented on GitHub (Aug 26, 2022): I'm running this via Google Kubernetes Engine (relying on `php://stderr` too) and it seems to be working fine. It's interesting that Cloud Run and GKE diverge... well, maybe it's just Google being Google idk 🤷‍♂️ I don't see a reason not to support both, though... maybe `$record['time'] = $record['timestamp'] = $record['datetime']->...`?

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@Seldaek commented on GitHub (Aug 31, 2022):

Seems kinda silly to log the time twice there, but if it's gotta be done ..

Any chance that time would also work for GKE @lcobucci ?

<!-- gh-comment-id:1232644741 --> @Seldaek commented on GitHub (Aug 31, 2022): Seems kinda silly to log the time twice there, but if it's gotta be done .. Any chance that `time` would also work for GKE @lcobucci ?

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@lcobucci commented on GitHub (Aug 31, 2022):

I'll test it and come back to you 👍

<!-- gh-comment-id:1232663953 --> @lcobucci commented on GitHub (Aug 31, 2022): I'll test it and come back to you 👍

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@rpkamp commented on GitHub (Sep 4, 2022):

@lcobucci Please also check if it works when logging in UTC time. Cloud Run doesn't like our default timezone (CEST).

(see my first post for how we're doing it now)

<!-- gh-comment-id:1236328310 --> @rpkamp commented on GitHub (Sep 4, 2022): @lcobucci Please also check if it works when logging in UTC time. Cloud Run doesn't like our default timezone (CEST). (see my first post for how we're doing it now)

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@lcobucci commented on GitHub (Sep 8, 2022):

Hey folks, sorry about the delay.

We've tested the format by writing straight to STDERR:

{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T21:02:35.046+00:00","context":{"timeField":"time","skew":-3,"timezone":"UTC","time":"2022-09-04T21:02:35.046+00:00","now":"2022-09-04T21:02:38.047+00:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"time","skew":-3,"timezone":"CEST","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"time","skew":-3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T21:02:38.047+00:00","context":{"timeField":"time","skew":0,"timezone":"UTC","time":"2022-09-04T21:02:38.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"time","skew":0,"timezone":"CEST","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"time","skew":0,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T21:02:41.047+00:00","context":{"timeField":"time","skew":3,"timezone":"UTC","time":"2022-09-04T21:02:41.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"time","skew":3,"timezone":"CEST","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"time","skew":3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T21:02:35.047+00:00","context":{"timeField":"timestamp","skew":-3,"timezone":"UTC","time":"2022-09-04T21:02:35.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"timestamp","skew":-3,"timezone":"CEST","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"timestamp","skew":-3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T21:02:38.047+00:00","context":{"timeField":"timestamp","skew":0,"timezone":"UTC","time":"2022-09-04T21:02:38.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"timestamp","skew":0,"timezone":"CEST","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"timestamp","skew":0,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T21:02:41.047+00:00","context":{"timeField":"timestamp","skew":3,"timezone":"UTC","time":"2022-09-04T21:02:41.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"timestamp","skew":3,"timezone":"CEST","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}
{"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"timestamp","skew":3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}}

The result is that time is indeed the field that respected both skews (both future and past) and different timezones (UTC, CEST, and Europe/Amsterdam).

Looking at https://cloud.google.com/logging/docs/agent/logging/configuration#timestamp-processing the timestamp field has other expectations.

I'll send a PR to fix it, thanks @rpkamp for reporting the issue!

<!-- gh-comment-id:1240638301 --> @lcobucci commented on GitHub (Sep 8, 2022): Hey folks, sorry about the delay. We've tested the format by writing straight to `STDERR`: ```jsonld {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T21:02:35.046+00:00","context":{"timeField":"time","skew":-3,"timezone":"UTC","time":"2022-09-04T21:02:35.046+00:00","now":"2022-09-04T21:02:38.047+00:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"time","skew":-3,"timezone":"CEST","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"time","skew":-3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T21:02:38.047+00:00","context":{"timeField":"time","skew":0,"timezone":"UTC","time":"2022-09-04T21:02:38.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"time","skew":0,"timezone":"CEST","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"time","skew":0,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T21:02:41.047+00:00","context":{"timeField":"time","skew":3,"timezone":"UTC","time":"2022-09-04T21:02:41.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"time","skew":3,"timezone":"CEST","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","time":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"time","skew":3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T21:02:35.047+00:00","context":{"timeField":"timestamp","skew":-3,"timezone":"UTC","time":"2022-09-04T21:02:35.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"timestamp","skew":-3,"timezone":"CEST","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:35.047+02:00","context":{"timeField":"timestamp","skew":-3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:35.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T21:02:38.047+00:00","context":{"timeField":"timestamp","skew":0,"timezone":"UTC","time":"2022-09-04T21:02:38.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"timestamp","skew":0,"timezone":"CEST","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:38.047+02:00","context":{"timeField":"timestamp","skew":0,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:38.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T21:02:41.047+00:00","context":{"timeField":"timestamp","skew":3,"timezone":"UTC","time":"2022-09-04T21:02:41.047+00:00","now":"2022-09-04T21:02:38.047+00:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"timestamp","skew":3,"timezone":"CEST","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} {"message":"Hello there","channel":"app","severity":"INFO","timestamp":"2022-09-04T23:02:41.047+02:00","context":{"timeField":"timestamp","skew":3,"timezone":"Europe\/Amsterdam","time":"2022-09-04T23:02:41.047+02:00","now":"2022-09-04T23:02:38.047+02:00"}} ``` The result is that `time` is indeed the field that respected both skews (both future and past) and different timezones (`UTC`, `CEST`, and `Europe/Amsterdam`). Looking at https://cloud.google.com/logging/docs/agent/logging/configuration#timestamp-processing the `timestamp` field has other expectations. I'll send a PR to fix it, thanks @rpkamp for reporting the issue!

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@lcobucci commented on GitHub (Sep 8, 2022):

Alright, fixes sent... I hope that helps, sorry for the inconvenience!

<!-- gh-comment-id:1240652837 --> @lcobucci commented on GitHub (Sep 8, 2022): Alright, fixes sent... I hope that helps, sorry for the inconvenience!

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@ryokomy commented on GitHub (Sep 13, 2022):

Anyone who uses Laravel with Monolog v2?
I can't see any logs on Google Cloud Logging...
I can see them with Monolog v1.
I hope someone helps me and those who are in the same situation.

<!-- gh-comment-id:1245039293 --> @ryokomy commented on GitHub (Sep 13, 2022): Anyone who uses Laravel with Monolog v2? I can't see any logs on Google Cloud Logging... I can see them with Monolog v1. I hope someone helps me and those who are in the same situation.

kerem commented 2026-03-04 02:17:34 +03:00

Author

Owner

Copy link

@lcobucci commented on GitHub (Sep 13, 2022):

Anyone who uses Laravel with Monolog v2?
I can't see any logs on Google Cloud Logging...
I can see them with Monolog v1.
I hope someone helps me and those who are in the same situation.

@ryokomy that seems like a completely different thing (this formatter is only available for monolog v2 and v3).

<!-- gh-comment-id:1245219103 --> @lcobucci commented on GitHub (Sep 13, 2022): > Anyone who uses Laravel with Monolog v2? > I can't see any logs on Google Cloud Logging... > I can see them with Monolog v1. > I hope someone helps me and those who are in the same situation. @ryokomy that seems like a completely different thing (this formatter is only available for monolog v2 and v3).

kerem referenced this issue 2026-03-04 03:09:35 +03:00

[PR #741] [CLOSED] Update RedisHandler.php #1324

Sign in to join this conversation.

No Branch/Tag specified

Branches Tags

main

esfix

2.x

1.x

3.10.0

2.11.0

3.9.0

3.8.1

3.8.0

2.10.0

3.7.0

3.6.0

2.9.3

3.5.0

2.9.2

3.4.0

3.3.1

2.9.1

3.3.0

2.9.0

3.2.0

2.8.0

3.1.0

2.7.0

1.27.1

3.0.0

2.6.0

3.0.0-RC1

2.5.0

2.4.0

1.27.0

2.3.5

2.3.4

2.3.3

2.3.2

2.3.1

2.3.0

1.26.1

2.2.0

1.26.0

2.1.1

1.25.5

2.1.0

1.25.4

2.0.2

1.25.3

2.0.1

1.25.2

1.25.1

1.25.0

2.0.0

2.0.0-beta2

2.0.0-beta1

1.24.0

1.23.0

1.22.1

1.22.0

1.21.0

1.20.0

1.19.0

1.18.2

1.18.1

1.18.0

1.17.2

1.17.1

1.17.0

1.16.0

1.15.0

1.14.0

1.13.1

1.13.0

1.12.0

1.11.0

1.10.0

1.9.1

1.9.0

1.8.0

1.7.0

1.6.0

1.5.0

1.4.1

1.4.0

1.3.1

1.3.0

1.2.1

1.2.0

1.1.0

1.0.2

1.0.1

1.0.0

1.0.0-RC1

Labels

Clear labels   

Bug

  

Documentation

  

Feature

  

Needs Work

  

Support

  

pull-request

Mirrored from GitHub Pull Request

No labels

Bug

Documentation

Feature

Needs Work

Support

pull-request

Milestone

Clear milestone

No items

No milestone

Projects

Clear projects

No items

No project

Assignees

Clear assignees

No assignees

1 participant

Notifications

Subscribe

Due date

The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference

starred/monolog#741

No description provided.