[15:51:38.904] New invocation is queued and will start shortly
[15:51:40.020] Starting the invocation (attempt 1)
[15:51:40.046] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/de7c8e9d311e828cf803b909f9d4f53320c87ed1"
[15:51:40.046] Popped gitiles commit info from properties and tags
[15:51:40.046] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:51:40.047] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:51:40.047] Buildbucket request:
{
"requestId": "8881489810092958992",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8881489810092958992",
"job": "chromium-m137/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6c9d51425c98b8425ab537d77c7c8362a35d49a8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@6c9d51425c98b8425ab537d77c7c8362a35d49a8",
"title": "6c9d51425c98b8425ab537d77c7c8362a35d49a8",
"url": "https://chromium.googlesource.com/chromium/src/+/6c9d51425c98b8425ab537d77c7c8362a35d49a8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7053b432063521c571c191af7e3c38ff6c7d6bf3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@7053b432063521c571c191af7e3c38ff6c7d6bf3",
"title": "7053b432063521c571c191af7e3c38ff6c7d6bf3",
"url": "https://chromium.googlesource.com/chromium/src/+/7053b432063521c571c191af7e3c38ff6c7d6bf3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e1ad22b00240b519ff752e6a335d94b2423b7ca4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@e1ad22b00240b519ff752e6a335d94b2423b7ca4",
"title": "e1ad22b00240b519ff752e6a335d94b2423b7ca4",
"url": "https://chromium.googlesource.com/chromium/src/+/e1ad22b00240b519ff752e6a335d94b2423b7ca4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6082ce43c4842c397e8842faec509a3d7783965e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@6082ce43c4842c397e8842faec509a3d7783965e",
"title": "6082ce43c4842c397e8842faec509a3d7783965e",
"url": "https://chromium.googlesource.com/chromium/src/+/6082ce43c4842c397e8842faec509a3d7783965e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "484a6f668dea15ce580d081358424d7e7223e72a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@484a6f668dea15ce580d081358424d7e7223e72a",
"title": "484a6f668dea15ce580d081358424d7e7223e72a",
"url": "https://chromium.googlesource.com/chromium/src/+/484a6f668dea15ce580d081358424d7e7223e72a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0d8aba6eeb7cef51cc00a6c6d535d5ab37218434"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@0d8aba6eeb7cef51cc00a6c6d535d5ab37218434",
"title": "0d8aba6eeb7cef51cc00a6c6d535d5ab37218434",
"url": "https://chromium.googlesource.com/chromium/src/+/0d8aba6eeb7cef51cc00a6c6d535d5ab37218434"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d1eb450fea6b5562caf85245a0ee86c06a9b8a9a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@d1eb450fea6b5562caf85245a0ee86c06a9b8a9a",
"title": "d1eb450fea6b5562caf85245a0ee86c06a9b8a9a",
"url": "https://chromium.googlesource.com/chromium/src/+/d1eb450fea6b5562caf85245a0ee86c06a9b8a9a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d069773ca7f4f0c5d7235b4d0f628a60008ae2c0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@d069773ca7f4f0c5d7235b4d0f628a60008ae2c0",
"title": "d069773ca7f4f0c5d7235b4d0f628a60008ae2c0",
"url": "https://chromium.googlesource.com/chromium/src/+/d069773ca7f4f0c5d7235b4d0f628a60008ae2c0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e26b0d6bd178fc59b8e3125738abebfda2d0ca48"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@e26b0d6bd178fc59b8e3125738abebfda2d0ca48",
"title": "e26b0d6bd178fc59b8e3125738abebfda2d0ca48",
"url": "https://chromium.googlesource.com/chromium/src/+/e26b0d6bd178fc59b8e3125738abebfda2d0ca48"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "de7c8e9d311e828cf803b909f9d4f53320c87ed1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@de7c8e9d311e828cf803b909f9d4f53320c87ed1",
"title": "de7c8e9d311e828cf803b909f9d4f53320c87ed1",
"url": "https://chromium.googlesource.com/chromium/src/+/de7c8e9d311e828cf803b909f9d4f53320c87ed1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "de7c8e9d311e828cf803b909f9d4f53320c87ed1",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8881489810092958992"
},
{
"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"
}
}
[15:51:40.532] Scheduled build:
{
"id": "8716059748174969089",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "mac-official"
},
"number": 20,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-01T15:51:40.111129014Z",
"updateTime": "2025-05-01T15:51:40.111129014Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "de7c8e9d311e828cf803b909f9d4f53320c87ed1",
"ref": "refs/branch-heads/7151"
}
}
}
[15:51:40.533] Task URL: https://cr-buildbucket.appspot.com/build/8716059748174969089
[15:51:40.533] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:2:0) after 4m10s
[15:51:40.599] Received PubSub notification, asking Buildbucket for the build status
[15:51:40.621] Build status: SCHEDULED
[15:55:50.552] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:2:0)
[15:55:50.577] Build status: SCHEDULED
[15:55:50.577] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:4:0) after 6m17s
[15:59:12.870] Received PubSub notification, asking Buildbucket for the build status
[15:59:12.888] Build status: STARTED
[16:02:07.648] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:4:0)
[16:02:07.687] Build status: STARTED
[16:02:07.687] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:6:0) after 4m4s
[16:06:11.705] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:6:0)
[16:06:11.729] Build status: STARTED
[16:06:11.729] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:7:0) after 4m16s
[16:10:27.761] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:7:0)
[16:10:27.799] Build status: STARTED
[16:10:27.799] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:8:0) after 6m14s
[16:16:41.818] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:8:0)
[16:16:41.846] Build status: STARTED
[16:16:41.846] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:9:0) after 3m42s
[16:20:23.865] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:9:0)
[16:20:23.913] Build status: STARTED
[16:20:23.913] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:10:0) after 4m38s
[16:25:01.952] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:10:0)
[16:25:01.994] Build status: STARTED
[16:25:01.994] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:11:0) after 3m33s
[16:28:35.037] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:11:0)
[16:28:35.074] Build status: STARTED
[16:28:35.074] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:12:0) after 1m0s
[16:29:35.156] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:12:0)
[16:29:35.192] Build status: STARTED
[16:29:35.192] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:13:0) after 2m56s
[16:32:31.239] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:13:0)
[16:32:31.276] Build status: STARTED
[16:32:31.276] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:14:0) after 8m58s
[16:41:29.459] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:14:0)
[16:41:29.492] Build status: STARTED
[16:41:29.492] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:15:0) after 8m25s
[16:49:54.668] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:15:0)
[16:49:54.695] Build status: STARTED
[16:49:54.695] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:16:0) after 6m44s
[16:56:38.758] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:16:0)
[16:56:38.782] Build status: STARTED
[16:56:38.782] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:17:0) after 2m49s
[16:59:28.065] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:17:0)
[16:59:28.079] Build status: STARTED
[16:59:28.079] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:18:0) after 1m41s
[17:01:09.101] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:18:0)
[17:01:09.140] Build status: STARTED
[17:01:09.140] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:19:0) after 6m13s
[17:07:22.164] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:19:0)
[17:07:22.183] Build status: STARTED
[17:07:22.183] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:20:0) after 9m18s
[17:16:40.206] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:20:0)
[17:16:40.237] Build status: STARTED
[17:16:40.237] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:21:0) after 2m56s
[17:19:36.213] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:21:0)
[17:19:36.239] Build status: STARTED
[17:19:36.239] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:22:0) after 4m21s
[17:23:57.261] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:22:0)
[17:23:57.292] Build status: STARTED
[17:23:57.292] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:23:0) after 6m52s
[17:30:49.345] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:23:0)
[17:30:49.367] Build status: STARTED
[17:30:49.367] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:24:0) after 8m26s
[17:39:15.389] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:24:0)
[17:39:15.427] Build status: STARTED
[17:39:15.427] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:25:0) after 2m23s
[17:41:38.463] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:25:0)
[17:41:38.618] Build status: STARTED
[17:41:38.618] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:26:0) after 6m4s
[17:47:42.679] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:26:0)
[17:47:42.702] Build status: STARTED
[17:47:42.702] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:27:0) after 6m36s
[17:54:18.725] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:27:0)
[17:54:18.740] Build status: STARTED
[17:54:18.740] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:28:0) after 9m39s
[18:03:57.761] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:28:0)
[18:03:57.780] Build status: STARTED
[18:03:57.780] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:29:0) after 5m8s
[18:09:05.800] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:29:0)
[18:09:05.819] Build status: STARTED
[18:09:05.819] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:30:0) after 3m13s
[18:12:18.837] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:30:0)
[18:12:18.867] Build status: STARTED
[18:12:18.867] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:31:0) after 6m19s
[18:18:37.965] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:31:0)
[18:18:37.983] Build status: STARTED
[18:18:37.983] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:32:0) after 5m27s
[18:24:04.988] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:32:0)
[18:24:05.022] Build status: STARTED
[18:24:05.022] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:33:0) after 6m39s
[18:30:44.076] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:33:0)
[18:30:44.093] Build status: STARTED
[18:30:44.093] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:34:0) after 3m11s
[18:33:55.180] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:34:0)
[18:33:55.197] Build status: STARTED
[18:33:55.197] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:35:0) after 8m48s
[18:42:43.230] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:35:0)
[18:42:43.262] Build status: STARTED
[18:42:43.262] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:36:0) after 4m10s
[18:46:53.494] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:36:0)
[18:46:53.513] Build status: STARTED
[18:46:53.513] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:37:0) after 8m42s
[18:55:35.571] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:37:0)
[18:55:35.594] Build status: STARTED
[18:55:35.594] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:38:0) after 6m6s
[19:01:41.620] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:38:0)
[19:01:41.644] Build status: STARTED
[19:01:41.644] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:39:0) after 5m57s
[19:07:38.673] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:39:0)
[19:07:38.702] Build status: STARTED
[19:07:38.702] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:40:0) after 7m26s
[19:15:04.731] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:40:0)
[19:15:04.764] Build status: STARTED
[19:15:04.764] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:41:0) after 6m54s
[19:21:58.836] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:41:0)
[19:21:58.855] Build status: STARTED
[19:21:58.855] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:42:0) after 1m51s
[19:23:49.925] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:42:0)
[19:23:49.943] Build status: STARTED
[19:23:49.943] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:43:0) after 5m23s
[19:29:13.105] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:43:0)
[19:29:13.124] Build status: STARTED
[19:29:13.124] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:44:0) after 4m29s
[19:33:42.224] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:44:0)
[19:33:42.241] Build status: STARTED
[19:33:42.241] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:45:0) after 1m47s
[19:35:29.257] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:45:0)
[19:35:29.316] Build status: STARTED
[19:35:29.316] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:46:0) after 4m15s
[19:39:44.356] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:46:0)
[19:39:44.373] Build status: STARTED
[19:39:44.373] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:47:0) after 5m29s
[19:45:13.398] Handling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:47:0)
[19:45:13.417] Build status: STARTED
[19:45:13.417] Scheduling timer "check-buildbucket-build-status" (chromium-m137/mac-official:8881489810092958992:48:0) after 5m30s
[19:45:37.402] Received PubSub notification, asking Buildbucket for the build status
[19:45:37.474] Build:
{
"id": "8716059748174969089",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "mac-official"
},
"number": 20,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-01T15:51:40.111129014Z",
"startTime": "2025-05-01T15:59:12.685763890Z",
"endTime": "2025-05-01T19:45:37.083527230Z",
"updateTime": "2025-05-01T19:45:37.083527230Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "de7c8e9d311e828cf803b909f9d4f53320c87ed1",
"ref": "refs/branch-heads/7151"
}
}
}
[19:45:37.474] Invocation finished in 3h53m58.576471789s with status SUCCEEDED