[00:16:04.701] New invocation is queued and will start shortly
[00:16:05.824] Starting the invocation (attempt 1)
[00:16:05.853] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c0224c56e05fe1804cf4842ba89934afeab62fda"
[00:16:05.853] Popped gitiles commit info from properties and tags
[00:16:05.853] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:16:05.853] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:16:05.854] Buildbucket request:
{
"requestId": "8880823895349123664",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880823895349123664",
"job": "chromium-m137/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "88e54d92fbb12500b143dccdfab45426e9101877"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@88e54d92fbb12500b143dccdfab45426e9101877",
"title": "88e54d92fbb12500b143dccdfab45426e9101877",
"url": "https://chromium.googlesource.com/chromium/src/+/88e54d92fbb12500b143dccdfab45426e9101877"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6432c333b6e0c570fefac15ae217a934296d44e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@d6432c333b6e0c570fefac15ae217a934296d44e",
"title": "d6432c333b6e0c570fefac15ae217a934296d44e",
"url": "https://chromium.googlesource.com/chromium/src/+/d6432c333b6e0c570fefac15ae217a934296d44e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "353cb30edb3a2716d5c48e5a31187de9720b13f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@353cb30edb3a2716d5c48e5a31187de9720b13f9",
"title": "353cb30edb3a2716d5c48e5a31187de9720b13f9",
"url": "https://chromium.googlesource.com/chromium/src/+/353cb30edb3a2716d5c48e5a31187de9720b13f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "306cf8d453f9351be0f744fe4aef36c4ef73c864"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@306cf8d453f9351be0f744fe4aef36c4ef73c864",
"title": "306cf8d453f9351be0f744fe4aef36c4ef73c864",
"url": "https://chromium.googlesource.com/chromium/src/+/306cf8d453f9351be0f744fe4aef36c4ef73c864"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9530e958c2d45ca7b212cbe6141b0cea3ce720ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@9530e958c2d45ca7b212cbe6141b0cea3ce720ad",
"title": "9530e958c2d45ca7b212cbe6141b0cea3ce720ad",
"url": "https://chromium.googlesource.com/chromium/src/+/9530e958c2d45ca7b212cbe6141b0cea3ce720ad"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f7d563f25b71ec88a7c9e9de62c2eefb9bc60e95"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@f7d563f25b71ec88a7c9e9de62c2eefb9bc60e95",
"title": "f7d563f25b71ec88a7c9e9de62c2eefb9bc60e95",
"url": "https://chromium.googlesource.com/chromium/src/+/f7d563f25b71ec88a7c9e9de62c2eefb9bc60e95"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "72dd2cd6f66d87a9e7a0af3fb143cad59ca0d99d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@72dd2cd6f66d87a9e7a0af3fb143cad59ca0d99d",
"title": "72dd2cd6f66d87a9e7a0af3fb143cad59ca0d99d",
"url": "https://chromium.googlesource.com/chromium/src/+/72dd2cd6f66d87a9e7a0af3fb143cad59ca0d99d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c0224c56e05fe1804cf4842ba89934afeab62fda"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@c0224c56e05fe1804cf4842ba89934afeab62fda",
"title": "c0224c56e05fe1804cf4842ba89934afeab62fda",
"url": "https://chromium.googlesource.com/chromium/src/+/c0224c56e05fe1804cf4842ba89934afeab62fda"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c0224c56e05fe1804cf4842ba89934afeab62fda",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880823895349123664"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:16:06.561] Scheduled build:
{
"id": "8715393833420614721",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "mac-official"
},
"number": 91,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-09T00:16:05.909818859Z",
"updateTime": "2025-05-09T00:16:05.909818859Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c0224c56e05fe1804cf4842ba89934afeab62fda",
"ref": "refs/branch-heads/7151"
}
}
}
[00:16:06.561] Task URL: https://cr-buildbucket.appspot.com/build/8715393833420614721
[00:16:06.561] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:2:0) after 6m52s
[00:16:07.517] Received PubSub notification, asking Buildbucket for the build status
[00:16:07.535] Build status: SCHEDULED
[00:17:06.608] Received PubSub notification, asking Buildbucket for the build status
[00:17:06.639] Build status: STARTED
[00:22:58.641] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:2:0)
[00:22:58.664] Build status: STARTED
[00:22:58.665] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:5:0) after 5m54s
[00:28:52.828] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:5:0)
[00:28:52.851] Build status: STARTED
[00:28:52.851] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:6:0) after 6m9s
[00:35:01.934] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:6:0)
[00:35:01.987] Build status: STARTED
[00:35:01.987] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:7:0) after 6m25s
[00:41:27.006] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:7:0)
[00:41:27.033] Build status: STARTED
[00:41:27.033] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:8:0) after 7m47s
[00:49:14.263] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:8:0)
[00:49:14.283] Build status: STARTED
[00:49:14.284] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:9:0) after 8m44s
[00:57:58.349] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:9:0)
[00:57:58.368] Build status: STARTED
[00:57:58.368] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:10:0) after 8m28s
[01:06:26.539] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:10:0)
[01:06:26.559] Build status: STARTED
[01:06:26.560] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:11:0) after 1m35s
[01:08:01.580] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:11:0)
[01:08:01.607] Build status: STARTED
[01:08:01.607] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:12:0) after 9m42s
[01:17:43.627] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:12:0)
[01:17:43.651] Build status: STARTED
[01:17:43.651] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:13:0) after 9m2s
[01:26:45.670] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:13:0)
[01:26:45.717] Build status: STARTED
[01:26:45.717] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:14:0) after 8m55s
[01:35:40.820] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:14:0)
[01:35:40.865] Build status: STARTED
[01:35:40.865] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:15:0) after 7m43s
[01:43:23.887] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:15:0)
[01:43:23.973] Build status: STARTED
[01:43:23.973] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:16:0) after 9m10s
[01:52:34.084] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:16:0)
[01:52:34.159] Build status: STARTED
[01:52:34.159] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:17:0) after 2m1s
[01:54:35.313] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:17:0)
[01:54:35.339] Build status: STARTED
[01:54:35.339] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:18:0) after 9m8s
[02:03:43.403] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:18:0)
[02:03:43.423] Build status: STARTED
[02:03:43.423] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:19:0) after 1m44s
[02:05:27.476] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:19:0)
[02:05:27.493] Build status: STARTED
[02:05:27.493] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:20:0) after 4m36s
[02:10:03.518] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:20:0)
[02:10:03.539] Build status: STARTED
[02:10:03.539] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:21:0) after 9m59s
[02:20:02.514] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:21:0)
[02:20:02.531] Build status: STARTED
[02:20:02.531] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:22:0) after 3m34s
[02:23:36.599] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:22:0)
[02:23:36.620] Build status: STARTED
[02:23:36.620] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:23:0) after 7m50s
[02:31:26.705] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:23:0)
[02:31:26.742] Build status: STARTED
[02:31:26.742] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:24:0) after 7m0s
[02:38:26.771] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:24:0)
[02:38:26.814] Build status: STARTED
[02:38:26.814] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:25:0) after 8m54s
[02:47:20.883] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:25:0)
[02:47:20.898] Build status: STARTED
[02:47:20.898] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:26:0) after 4m14s
[02:51:34.963] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:26:0)
[02:51:34.978] Build status: STARTED
[02:51:34.978] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:27:0) after 7m12s
[02:58:47.041] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:27:0)
[02:58:47.063] Build status: STARTED
[02:58:47.063] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:28:0) after 2m47s
[03:01:34.086] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:28:0)
[03:01:34.114] Build status: STARTED
[03:01:34.114] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:29:0) after 5m43s
[03:07:17.141] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:29:0)
[03:07:17.160] Build status: STARTED
[03:07:17.160] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:30:0) after 4m26s
[03:11:43.183] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:30:0)
[03:11:43.206] Build status: STARTED
[03:11:43.206] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:31:0) after 3m11s
[03:14:54.230] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:31:0)
[03:14:54.249] Build status: STARTED
[03:14:54.249] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:32:0) after 3m11s
[03:18:05.304] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:32:0)
[03:18:05.346] Build status: STARTED
[03:18:05.346] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8880823895349123664:33:0) after 4m15s
[03:21:02.937] Received PubSub notification, asking Buildbucket for the build status
[03:21:02.954] Build:
{
"id": "8715393833420614721",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "mac-official"
},
"number": 91,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-09T00:16:05.909818859Z",
"startTime": "2025-05-09T00:17:06.373839608Z",
"endTime": "2025-05-09T03:21:02.616028147Z",
"updateTime": "2025-05-09T03:21:02.616028147Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c0224c56e05fe1804cf4842ba89934afeab62fda",
"ref": "refs/branch-heads/7151"
}
}
}
[03:21:02.954] Invocation finished in 3h4m58.268490809s with status SUCCEEDED