[21:45:13.042] New invocation is queued and will start shortly
[21:45:14.624] Starting the invocation (attempt 1)
[21:45:14.653] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4be1384b16751a1c20dbb7de569804461cde4eb9"
[21:45:14.653] Popped gitiles commit info from properties and tags
[21:45:14.653] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:45:14.654] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:45:14.654] Buildbucket request:
{
"requestId": "8880470998835188928",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880470998835188928",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "662c3c12045bc05a0090d52fc9fc073adf9fdb8c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@662c3c12045bc05a0090d52fc9fc073adf9fdb8c",
"title": "662c3c12045bc05a0090d52fc9fc073adf9fdb8c",
"url": "https://chromium.googlesource.com/chromium/src/+/662c3c12045bc05a0090d52fc9fc073adf9fdb8c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "157e6ca9a2b592c83b4726c83811b2b60fc4d877"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@157e6ca9a2b592c83b4726c83811b2b60fc4d877",
"title": "157e6ca9a2b592c83b4726c83811b2b60fc4d877",
"url": "https://chromium.googlesource.com/chromium/src/+/157e6ca9a2b592c83b4726c83811b2b60fc4d877"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1e22f991ccd35abd74caff316c6afe1a53f895f4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@1e22f991ccd35abd74caff316c6afe1a53f895f4",
"title": "1e22f991ccd35abd74caff316c6afe1a53f895f4",
"url": "https://chromium.googlesource.com/chromium/src/+/1e22f991ccd35abd74caff316c6afe1a53f895f4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb8e6769afa8b0a9e626f62c65eb4ac6bb98f6ce"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@bb8e6769afa8b0a9e626f62c65eb4ac6bb98f6ce",
"title": "bb8e6769afa8b0a9e626f62c65eb4ac6bb98f6ce",
"url": "https://chromium.googlesource.com/chromium/src/+/bb8e6769afa8b0a9e626f62c65eb4ac6bb98f6ce"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4be1384b16751a1c20dbb7de569804461cde4eb9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@4be1384b16751a1c20dbb7de569804461cde4eb9",
"title": "4be1384b16751a1c20dbb7de569804461cde4eb9",
"url": "https://chromium.googlesource.com/chromium/src/+/4be1384b16751a1c20dbb7de569804461cde4eb9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4be1384b16751a1c20dbb7de569804461cde4eb9",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880470998835188928"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:45:15.126] Scheduled build:
{
"id": "8715040936431066833",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 245,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-12T21:45:14.704129103Z",
"updateTime": "2025-05-12T21:45:14.704129103Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4be1384b16751a1c20dbb7de569804461cde4eb9",
"ref": "refs/branch-heads/7103"
}
}
}
[21:45:15.126] Task URL: https://cr-buildbucket.appspot.com/build/8715040936431066833
[21:45:15.126] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:2:0) after 1m48s
[21:45:28.379] Received PubSub notification, asking Buildbucket for the build status
[21:45:28.404] Build status: STARTED
[21:45:33.935] Received PubSub notification, asking Buildbucket for the build status
[21:45:33.959] Build status: STARTED
[21:47:03.193] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:2:0)
[21:47:03.222] Build status: STARTED
[21:47:03.222] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:5:0) after 1m20s
[21:48:23.238] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:5:0)
[21:48:23.267] Build status: STARTED
[21:48:23.267] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:6:0) after 1m32s
[21:49:55.352] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:6:0)
[21:49:55.372] Build status: STARTED
[21:49:55.372] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:7:0) after 9m56s
[21:59:51.396] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:7:0)
[21:59:51.423] Build status: STARTED
[21:59:51.423] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:8:0) after 5m14s
[22:05:05.510] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:8:0)
[22:05:05.533] Build status: STARTED
[22:05:05.533] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:9:0) after 4m1s
[22:09:06.586] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:9:0)
[22:09:06.650] Build status: STARTED
[22:09:06.650] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:10:0) after 6m27s
[22:15:33.693] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:10:0)
[22:15:33.716] Build status: STARTED
[22:15:33.716] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:11:0) after 7m45s
[22:23:18.823] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:11:0)
[22:23:18.847] Build status: STARTED
[22:23:18.847] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:12:0) after 5m45s
[22:29:03.908] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:12:0)
[22:29:03.957] Build status: STARTED
[22:29:03.957] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:13:0) after 7m17s
[22:36:21.022] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:13:0)
[22:36:21.056] Build status: STARTED
[22:36:21.056] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:14:0) after 5m39s
[22:42:00.133] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:14:0)
[22:42:00.157] Build status: STARTED
[22:42:00.157] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:15:0) after 6m27s
[22:48:27.192] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:15:0)
[22:48:27.221] Build status: STARTED
[22:48:27.221] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:16:0) after 8m23s
[22:56:50.426] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:16:0)
[22:56:50.486] Build status: STARTED
[22:56:50.486] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:17:0) after 7m11s
[23:04:01.525] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:17:0)
[23:04:01.557] Build status: STARTED
[23:04:01.557] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:18:0) after 2m57s
[23:06:58.534] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:18:0)
[23:06:58.570] Build status: STARTED
[23:06:58.570] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:19:0) after 3m0s
[23:09:58.586] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:19:0)
[23:09:58.693] Build status: STARTED
[23:09:58.693] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:20:0) after 9m20s
[23:19:18.719] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:20:0)
[23:19:18.800] Build status: STARTED
[23:19:18.800] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:21:0) after 2m34s
[23:21:52.817] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:21:0)
[23:21:52.837] Build status: STARTED
[23:21:52.837] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:22:0) after 6m44s
[23:28:37.067] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:22:0)
[23:28:37.085] Build status: STARTED
[23:28:37.085] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:23:0) after 4m53s
[23:33:30.102] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:23:0)
[23:33:30.119] Build status: STARTED
[23:33:30.119] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:24:0) after 6m12s
[23:39:42.254] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:24:0)
[23:39:42.338] Build status: STARTED
[23:39:42.338] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:25:0) after 8m29s
[23:48:11.355] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:25:0)
[23:48:11.452] Build status: STARTED
[23:48:11.452] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:26:0) after 5m55s
[23:54:06.473] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:26:0)
[23:54:06.558] Build status: STARTED
[23:54:06.558] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:27:0) after 5m50s
[23:59:56.589] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:27:0)
[23:59:56.625] Build status: STARTED
[23:59:56.625] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:28:0) after 7m28s
[00:07:24.744] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:28:0)
[00:07:24.819] Build status: STARTED
[00:07:24.819] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:29:0) after 9m13s
[00:16:37.920] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:29:0)
[00:16:37.986] Build status: STARTED
[00:16:37.986] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:30:0) after 4m2s
[00:20:40.005] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:30:0)
[00:20:40.019] Build status: STARTED
[00:20:40.020] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:31:0) after 2m31s
[00:23:11.035] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:31:0)
[00:23:11.134] Build status: STARTED
[00:23:11.134] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:32:0) after 9m17s
[00:32:28.135] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:32:0)
[00:32:28.155] Build status: STARTED
[00:32:28.155] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:33:0) after 9m13s
[00:41:41.173] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:33:0)
[00:41:41.257] Build status: STARTED
[00:41:41.257] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:34:0) after 4m21s
[00:46:02.303] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:34:0)
[00:46:02.317] Build status: STARTED
[00:46:02.317] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:35:0) after 2m56s
[00:48:58.410] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:35:0)
[00:48:58.426] Build status: STARTED
[00:48:58.426] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:36:0) after 9m27s
[00:58:25.542] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:36:0)
[00:58:25.564] Build status: STARTED
[00:58:25.564] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:37:0) after 1m43s
[01:00:08.681] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:37:0)
[01:00:08.764] Build status: STARTED
[01:00:08.764] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8880470998835188928:38:0) after 5m27s
[01:03:21.708] Received PubSub notification, asking Buildbucket for the build status
[01:03:21.726] Build:
{
"id": "8715040936431066833",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 245,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-12T21:45:14.704129103Z",
"startTime": "2025-05-12T21:45:21.814544330Z",
"endTime": "2025-05-13T01:03:21.372103471Z",
"updateTime": "2025-05-13T01:03:21.372103471Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4be1384b16751a1c20dbb7de569804461cde4eb9",
"ref": "refs/branch-heads/7103"
}
}
}
[01:03:21.726] Invocation finished in 3h18m8.697960579s with status SUCCEEDED