[12:12:14.157] New invocation is queued and will start shortly
[12:12:15.314] Starting the invocation (attempt 1)
[12:12:15.358] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fb04f491c598dfedb1baca368858f5c47479d849"
[12:12:15.358] Popped gitiles commit info from properties and tags
[12:12:15.358] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:12:15.359] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:12:15.359] Buildbucket request:
{
"requestId": "8882771971859260848",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882771971859260848",
"job": "chromium-m136/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a8ace722fc4d362273180fa4772243758f0e640c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@a8ace722fc4d362273180fa4772243758f0e640c",
"title": "a8ace722fc4d362273180fa4772243758f0e640c",
"url": "https://chromium.googlesource.com/chromium/src/+/a8ace722fc4d362273180fa4772243758f0e640c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "812bccee805f3ad7b7d2dfc10b2bf316d51aefe2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@812bccee805f3ad7b7d2dfc10b2bf316d51aefe2",
"title": "812bccee805f3ad7b7d2dfc10b2bf316d51aefe2",
"url": "https://chromium.googlesource.com/chromium/src/+/812bccee805f3ad7b7d2dfc10b2bf316d51aefe2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "387358c7fd5be2160dfaceb0b36934acbac534ff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@387358c7fd5be2160dfaceb0b36934acbac534ff",
"title": "387358c7fd5be2160dfaceb0b36934acbac534ff",
"url": "https://chromium.googlesource.com/chromium/src/+/387358c7fd5be2160dfaceb0b36934acbac534ff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1d94fcf56cab495f50540964bdacd1a73907e592"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@1d94fcf56cab495f50540964bdacd1a73907e592",
"title": "1d94fcf56cab495f50540964bdacd1a73907e592",
"url": "https://chromium.googlesource.com/chromium/src/+/1d94fcf56cab495f50540964bdacd1a73907e592"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f98e8a2a334a3198de3a3ee8a89faa2ebb480170"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@f98e8a2a334a3198de3a3ee8a89faa2ebb480170",
"title": "f98e8a2a334a3198de3a3ee8a89faa2ebb480170",
"url": "https://chromium.googlesource.com/chromium/src/+/f98e8a2a334a3198de3a3ee8a89faa2ebb480170"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "070f7848dcb123cb777902761ccf4c4865d77d23"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@070f7848dcb123cb777902761ccf4c4865d77d23",
"title": "070f7848dcb123cb777902761ccf4c4865d77d23",
"url": "https://chromium.googlesource.com/chromium/src/+/070f7848dcb123cb777902761ccf4c4865d77d23"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fb04f491c598dfedb1baca368858f5c47479d849"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@fb04f491c598dfedb1baca368858f5c47479d849",
"title": "fb04f491c598dfedb1baca368858f5c47479d849",
"url": "https://chromium.googlesource.com/chromium/src/+/fb04f491c598dfedb1baca368858f5c47479d849"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fb04f491c598dfedb1baca368858f5c47479d849",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882771971859260848"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:12:16.081] Scheduled build:
{
"id": "8717341909854595153",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-official"
},
"number": 133,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-17T12:12:15.447577233Z",
"updateTime": "2025-04-17T12:12:15.447577233Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fb04f491c598dfedb1baca368858f5c47479d849",
"ref": "refs/branch-heads/7103"
}
}
}
[12:12:16.081] Task URL: https://cr-buildbucket.appspot.com/build/8717341909854595153
[12:12:16.081] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:2:0) after 4m10s
[12:12:17.127] Received PubSub notification, asking Buildbucket for the build status
[12:12:17.154] Build status: SCHEDULED
[12:12:21.363] Received PubSub notification, asking Buildbucket for the build status
[12:12:21.386] Build status: STARTED
[12:16:26.126] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:2:0)
[12:16:26.147] Build status: STARTED
[12:16:26.147] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:5:0) after 3m33s
[12:19:59.130] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:5:0)
[12:19:59.179] Build status: STARTED
[12:19:59.179] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:6:0) after 1m57s
[12:21:56.340] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:6:0)
[12:21:56.362] Build status: STARTED
[12:21:56.362] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:7:0) after 5m15s
[12:27:11.478] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:7:0)
[12:27:11.505] Build status: STARTED
[12:27:11.505] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:8:0) after 7m24s
[12:34:35.521] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:8:0)
[12:34:35.552] Build status: STARTED
[12:34:35.552] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:9:0) after 2m16s
[12:36:51.531] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:9:0)
[12:36:51.562] Build status: STARTED
[12:36:51.562] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:10:0) after 4m18s
[12:41:09.598] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:10:0)
[12:41:09.615] Build status: STARTED
[12:41:09.615] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:11:0) after 4m34s
[12:45:43.631] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:11:0)
[12:45:43.659] Build status: STARTED
[12:45:43.659] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:12:0) after 9m40s
[12:55:23.699] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:12:0)
[12:55:23.715] Build status: STARTED
[12:55:23.715] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:13:0) after 8m0s
[13:03:23.736] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:13:0)
[13:03:23.756] Build status: STARTED
[13:03:23.756] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:14:0) after 8m12s
[13:11:35.777] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:14:0)
[13:11:35.795] Build status: STARTED
[13:11:35.795] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:15:0) after 6m24s
[13:17:59.819] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:15:0)
[13:17:59.836] Build status: STARTED
[13:17:59.836] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:16:0) after 3m13s
[13:21:12.861] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:16:0)
[13:21:12.901] Build status: STARTED
[13:21:12.901] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:17:0) after 6m28s
[13:27:41.111] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:17:0)
[13:27:41.126] Build status: STARTED
[13:27:41.126] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:18:0) after 1m34s
[13:29:15.422] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:18:0)
[13:29:15.465] Build status: STARTED
[13:29:15.465] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:19:0) after 4m31s
[13:33:46.488] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:19:0)
[13:33:46.503] Build status: STARTED
[13:33:46.503] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:20:0) after 8m4s
[13:41:50.530] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:20:0)
[13:41:50.595] Build status: STARTED
[13:41:50.596] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:21:0) after 3m18s
[13:45:08.738] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:21:0)
[13:45:08.756] Build status: STARTED
[13:45:08.756] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:22:0) after 6m49s
[13:51:57.879] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:22:0)
[13:51:57.902] Build status: STARTED
[13:51:57.902] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:23:0) after 3m34s
[13:55:32.106] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:23:0)
[13:55:32.129] Build status: STARTED
[13:55:32.129] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:24:0) after 9m47s
[14:05:19.402] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:24:0)
[14:05:19.423] Build status: STARTED
[14:05:19.423] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:25:0) after 6m40s
[14:11:59.443] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:25:0)
[14:11:59.470] Build status: STARTED
[14:11:59.470] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:26:0) after 8m30s
[14:20:30.313] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:26:0)
[14:20:30.330] Build status: STARTED
[14:20:30.330] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:27:0) after 4m31s
[14:25:01.336] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:27:0)
[14:25:01.359] Build status: STARTED
[14:25:01.359] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:28:0) after 3m49s
[14:28:50.443] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:28:0)
[14:28:50.497] Build status: STARTED
[14:28:50.497] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:29:0) after 5m28s
[14:34:19.051] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:29:0)
[14:34:19.080] Build status: STARTED
[14:34:19.080] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:30:0) after 3m43s
[14:38:02.102] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:30:0)
[14:38:02.142] Build status: STARTED
[14:38:02.142] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:31:0) after 7m54s
[14:45:56.150] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:31:0)
[14:45:56.222] Build status: STARTED
[14:45:56.222] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:32:0) after 2m4s
[14:48:00.242] Handling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:32:0)
[14:48:00.262] Build status: STARTED
[14:48:00.262] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-official:8882771971859260848:33:0) after 5m58s
[14:53:14.306] Received PubSub notification, asking Buildbucket for the build status
[14:53:14.321] Build:
{
"id": "8717341909854595153",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-official"
},
"number": 133,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-17T12:12:15.447577233Z",
"startTime": "2025-04-17T12:12:21.159417842Z",
"endTime": "2025-04-17T14:53:13.871904795Z",
"updateTime": "2025-04-17T14:53:13.871904795Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fb04f491c598dfedb1baca368858f5c47479d849",
"ref": "refs/branch-heads/7103"
}
}
}
[14:53:14.321] Invocation finished in 2h41m0.169559938s with status SUCCEEDED