[17:37:05.468] New invocation is queued and will start shortly
[17:37:07.325] Starting the invocation (attempt 1)
[17:37:07.355] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07a223901c0171dd9d91367454066ab7cfdc7919"
[17:37:07.355] Popped gitiles commit info from properties and tags
[17:37:07.355] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:37:07.355] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:37:07.356] Buildbucket request:
{
"requestId": "8882751533745667296",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882751533745667296",
"job": "chromium-m135/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "083eb2fcf1665ea3feca214d0d46fe7ac2567563"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@083eb2fcf1665ea3feca214d0d46fe7ac2567563",
"title": "083eb2fcf1665ea3feca214d0d46fe7ac2567563",
"url": "https://chromium.googlesource.com/chromium/src/+/083eb2fcf1665ea3feca214d0d46fe7ac2567563"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a2c1aff4e83980a01011877d8cc8f13a42cadbc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@6a2c1aff4e83980a01011877d8cc8f13a42cadbc",
"title": "6a2c1aff4e83980a01011877d8cc8f13a42cadbc",
"url": "https://chromium.googlesource.com/chromium/src/+/6a2c1aff4e83980a01011877d8cc8f13a42cadbc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "edc1d75926c1d781769317731c9bbb1a1e2e717b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@edc1d75926c1d781769317731c9bbb1a1e2e717b",
"title": "edc1d75926c1d781769317731c9bbb1a1e2e717b",
"url": "https://chromium.googlesource.com/chromium/src/+/edc1d75926c1d781769317731c9bbb1a1e2e717b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "659734e0c97a6bab7a6f99034e4bf7da1c95b39c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@659734e0c97a6bab7a6f99034e4bf7da1c95b39c",
"title": "659734e0c97a6bab7a6f99034e4bf7da1c95b39c",
"url": "https://chromium.googlesource.com/chromium/src/+/659734e0c97a6bab7a6f99034e4bf7da1c95b39c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "13e9abfe3b7c329cf86abf2b2bcce24ecdfb6074"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@13e9abfe3b7c329cf86abf2b2bcce24ecdfb6074",
"title": "13e9abfe3b7c329cf86abf2b2bcce24ecdfb6074",
"url": "https://chromium.googlesource.com/chromium/src/+/13e9abfe3b7c329cf86abf2b2bcce24ecdfb6074"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "528d7278686e546f7b9c2898b21af49191e9b23a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@528d7278686e546f7b9c2898b21af49191e9b23a",
"title": "528d7278686e546f7b9c2898b21af49191e9b23a",
"url": "https://chromium.googlesource.com/chromium/src/+/528d7278686e546f7b9c2898b21af49191e9b23a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a8e31288b70be1b22c32ae4eee53c9f85d1f3e10"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@a8e31288b70be1b22c32ae4eee53c9f85d1f3e10",
"title": "a8e31288b70be1b22c32ae4eee53c9f85d1f3e10",
"url": "https://chromium.googlesource.com/chromium/src/+/a8e31288b70be1b22c32ae4eee53c9f85d1f3e10"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b4cefeda6a8799063e96a647f64c677a3048c69"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@8b4cefeda6a8799063e96a647f64c677a3048c69",
"title": "8b4cefeda6a8799063e96a647f64c677a3048c69",
"url": "https://chromium.googlesource.com/chromium/src/+/8b4cefeda6a8799063e96a647f64c677a3048c69"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "00b0f960c974384e5656d5f9aa0cb56f8b5529b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@00b0f960c974384e5656d5f9aa0cb56f8b5529b5",
"title": "00b0f960c974384e5656d5f9aa0cb56f8b5529b5",
"url": "https://chromium.googlesource.com/chromium/src/+/00b0f960c974384e5656d5f9aa0cb56f8b5529b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07a223901c0171dd9d91367454066ab7cfdc7919"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@07a223901c0171dd9d91367454066ab7cfdc7919",
"title": "07a223901c0171dd9d91367454066ab7cfdc7919",
"url": "https://chromium.googlesource.com/chromium/src/+/07a223901c0171dd9d91367454066ab7cfdc7919"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07a223901c0171dd9d91367454066ab7cfdc7919",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882751533745667296"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:37:07.842] Scheduled build:
{
"id": "8717321471020140737",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"number": 276,
"createdBy": "project:chromium-m135",
"createTime": "2025-04-17T17:37:07.439145695Z",
"updateTime": "2025-04-17T17:37:07.439145695Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07a223901c0171dd9d91367454066ab7cfdc7919",
"ref": "refs/branch-heads/7049"
}
}
}
[17:37:07.842] Task URL: https://cr-buildbucket.appspot.com/build/8717321471020140737
[17:37:07.842] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:2:0) after 9m44s
[17:37:08.436] Received PubSub notification, asking Buildbucket for the build status
[17:37:08.461] Build status: SCHEDULED
[17:37:17.810] Received PubSub notification, asking Buildbucket for the build status
[17:37:17.836] Build status: STARTED
[17:46:51.855] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:2:0)
[17:46:51.889] Build status: STARTED
[17:46:51.889] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:5:0) after 7m20s
[17:54:11.913] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:5:0)
[17:54:11.936] Build status: STARTED
[17:54:11.936] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:6:0) after 8m53s
[18:03:05.007] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:6:0)
[18:03:05.026] Build status: STARTED
[18:03:05.026] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:7:0) after 9m5s
[18:12:10.069] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:7:0)
[18:12:10.090] Build status: STARTED
[18:12:10.090] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:8:0) after 9m24s
[18:21:34.120] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:8:0)
[18:21:34.150] Build status: STARTED
[18:21:34.150] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:9:0) after 1m14s
[18:22:48.163] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:9:0)
[18:22:48.178] Build status: STARTED
[18:22:48.178] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:10:0) after 5m13s
[18:28:01.222] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:10:0)
[18:28:01.240] Build status: STARTED
[18:28:01.240] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:11:0) after 2m28s
[18:30:29.348] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:11:0)
[18:30:29.373] Build status: STARTED
[18:30:29.373] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:12:0) after 6m1s
[18:36:30.394] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:12:0)
[18:36:30.419] Build status: STARTED
[18:36:30.419] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:13:0) after 6m36s
[18:43:06.455] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:13:0)
[18:43:06.472] Build status: STARTED
[18:43:06.472] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:14:0) after 9m45s
[18:52:51.600] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:14:0)
[18:52:51.652] Build status: STARTED
[18:52:51.652] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:15:0) after 7m41s
[19:00:32.663] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:15:0)
[19:00:32.682] Build status: STARTED
[19:00:32.682] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:16:0) after 4m25s
[19:04:57.699] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:16:0)
[19:04:57.714] Build status: STARTED
[19:04:57.714] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:17:0) after 1m57s
[19:06:54.816] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:17:0)
[19:06:54.830] Build status: STARTED
[19:06:54.830] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:18:0) after 7m30s
[19:14:24.842] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:18:0)
[19:14:24.861] Build status: STARTED
[19:14:24.861] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:19:0) after 8m14s
[19:22:38.942] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:19:0)
[19:22:38.971] Build status: STARTED
[19:22:38.971] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:20:0) after 3m4s
[19:25:42.996] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:20:0)
[19:25:43.081] Build status: STARTED
[19:25:43.081] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:21:0) after 2m11s
[19:27:54.101] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:21:0)
[19:27:54.116] Build status: STARTED
[19:27:54.116] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:22:0) after 2m1s
[19:29:55.138] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:22:0)
[19:29:55.161] Build status: STARTED
[19:29:55.161] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:23:0) after 3m33s
[19:33:28.204] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:23:0)
[19:33:28.222] Build status: STARTED
[19:33:28.222] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:24:0) after 1m33s
[19:35:01.292] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:24:0)
[19:35:01.310] Build status: STARTED
[19:35:01.310] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:25:0) after 2m5s
[19:37:06.382] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:25:0)
[19:37:06.434] Build status: STARTED
[19:37:06.434] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:26:0) after 2m2s
[19:39:08.476] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:26:0)
[19:39:08.492] Build status: STARTED
[19:39:08.492] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:27:0) after 3m6s
[19:42:14.544] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:27:0)
[19:42:14.558] Build status: STARTED
[19:42:14.558] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:28:0) after 4m30s
[19:46:44.579] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:28:0)
[19:46:44.602] Build status: STARTED
[19:46:44.602] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:29:0) after 1m24s
[19:48:08.625] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:29:0)
[19:48:08.644] Build status: STARTED
[19:48:08.644] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:30:0) after 4m25s
[19:52:33.665] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:30:0)
[19:52:33.694] Build status: STARTED
[19:52:33.694] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:31:0) after 3m23s
[19:55:56.752] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:31:0)
[19:55:56.769] Build status: STARTED
[19:55:56.769] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:32:0) after 1m21s
[19:57:17.842] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:32:0)
[19:57:17.861] Build status: STARTED
[19:57:17.861] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:33:0) after 6m19s
[20:03:36.876] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:33:0)
[20:03:36.908] Build status: STARTED
[20:03:36.908] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:34:0) after 6m50s
[20:10:26.884] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:34:0)
[20:10:26.903] Build status: STARTED
[20:10:26.903] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:35:0) after 4m3s
[20:14:30.086] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:35:0)
[20:14:30.113] Build status: STARTED
[20:14:30.113] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:36:0) after 5m54s
[20:20:24.134] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:36:0)
[20:20:24.200] Build status: STARTED
[20:20:24.201] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:37:0) after 8m48s
[20:29:12.272] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:37:0)
[20:29:12.293] Build status: STARTED
[20:29:12.293] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:38:0) after 2m32s
[20:31:44.307] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:38:0)
[20:31:44.338] Build status: STARTED
[20:31:44.338] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:39:0) after 1m12s
[20:32:56.352] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:39:0)
[20:32:56.455] Build status: STARTED
[20:32:56.455] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:40:0) after 7m45s
[20:40:41.499] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:40:0)
[20:40:41.583] Build status: STARTED
[20:40:41.583] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:41:0) after 7m25s
[20:48:06.741] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:41:0)
[20:48:06.762] Build status: STARTED
[20:48:06.762] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:42:0) after 6m59s
[20:55:05.796] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:42:0)
[20:55:05.818] Build status: STARTED
[20:55:05.818] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:43:0) after 5m20s
[21:00:25.884] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:43:0)
[21:00:25.933] Build status: STARTED
[21:00:25.933] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:44:0) after 6m50s
[21:07:16.098] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:44:0)
[21:07:16.120] Build status: STARTED
[21:07:16.120] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:45:0) after 1m58s
[21:09:14.193] Handling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:45:0)
[21:09:14.219] Build status: STARTED
[21:09:14.219] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win32-official:8882751533745667296:46:0) after 9m57s
[21:12:40.472] Received PubSub notification, asking Buildbucket for the build status
[21:12:40.515] Build:
{
"id": "8717321471020140737",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win32-official"
},
"number": 276,
"createdBy": "project:chromium-m135",
"createTime": "2025-04-17T17:37:07.439145695Z",
"startTime": "2025-04-17T17:37:17.516299378Z",
"endTime": "2025-04-17T21:12:36.269460662Z",
"updateTime": "2025-04-17T21:12:36.269460662Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07a223901c0171dd9d91367454066ab7cfdc7919",
"ref": "refs/branch-heads/7049"
}
}
}
[21:12:40.515] Invocation finished in 3h35m35.059199934s with status SUCCEEDED