[01:51:16.354] New invocation is queued and will start shortly
[01:51:17.419] Starting the invocation (attempt 1)
[01:51:17.454] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9122d3b64237f59ae24cc6e10a093992487f1793"
[01:51:17.454] Popped gitiles commit info from properties and tags
[01:51:17.454] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:51:17.454] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:51:17.454] Buildbucket request:
{
"requestId": "8898756105585665696",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898756105585665696",
"job": "chromium-m131/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb84a835ee597f7adda60563145e054d8d830fbf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@cb84a835ee597f7adda60563145e054d8d830fbf",
"title": "cb84a835ee597f7adda60563145e054d8d830fbf",
"url": "https://chromium.googlesource.com/chromium/src/+/cb84a835ee597f7adda60563145e054d8d830fbf"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ae9350eff04cc27a675a574b4c6d8f61308bd10a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@ae9350eff04cc27a675a574b4c6d8f61308bd10a",
"title": "ae9350eff04cc27a675a574b4c6d8f61308bd10a",
"url": "https://chromium.googlesource.com/chromium/src/+/ae9350eff04cc27a675a574b4c6d8f61308bd10a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "448cfc907f5e3b7407dcd576040554fa3817e09b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@448cfc907f5e3b7407dcd576040554fa3817e09b",
"title": "448cfc907f5e3b7407dcd576040554fa3817e09b",
"url": "https://chromium.googlesource.com/chromium/src/+/448cfc907f5e3b7407dcd576040554fa3817e09b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8233f2187351c55eaa12a3527f0d894a1b9d38f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8233f2187351c55eaa12a3527f0d894a1b9d38f3",
"title": "8233f2187351c55eaa12a3527f0d894a1b9d38f3",
"url": "https://chromium.googlesource.com/chromium/src/+/8233f2187351c55eaa12a3527f0d894a1b9d38f3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9be2ea15dc94fa829cafa531413d2ce91a889139"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@9be2ea15dc94fa829cafa531413d2ce91a889139",
"title": "9be2ea15dc94fa829cafa531413d2ce91a889139",
"url": "https://chromium.googlesource.com/chromium/src/+/9be2ea15dc94fa829cafa531413d2ce91a889139"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85b4fa0112391f7c05c8ff1c888fca9a29c22fd9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@85b4fa0112391f7c05c8ff1c888fca9a29c22fd9",
"title": "85b4fa0112391f7c05c8ff1c888fca9a29c22fd9",
"url": "https://chromium.googlesource.com/chromium/src/+/85b4fa0112391f7c05c8ff1c888fca9a29c22fd9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0c59c4d2c7c83bac10b4aedcd98530e5048f55da"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@0c59c4d2c7c83bac10b4aedcd98530e5048f55da",
"title": "0c59c4d2c7c83bac10b4aedcd98530e5048f55da",
"url": "https://chromium.googlesource.com/chromium/src/+/0c59c4d2c7c83bac10b4aedcd98530e5048f55da"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f05c93a4ac0ad4865e0d30890cc9df8e221fb663"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@f05c93a4ac0ad4865e0d30890cc9df8e221fb663",
"title": "f05c93a4ac0ad4865e0d30890cc9df8e221fb663",
"url": "https://chromium.googlesource.com/chromium/src/+/f05c93a4ac0ad4865e0d30890cc9df8e221fb663"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fdc3a1ebe8a77ac2d60d11c5903be5eb82b362cc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@fdc3a1ebe8a77ac2d60d11c5903be5eb82b362cc",
"title": "fdc3a1ebe8a77ac2d60d11c5903be5eb82b362cc",
"url": "https://chromium.googlesource.com/chromium/src/+/fdc3a1ebe8a77ac2d60d11c5903be5eb82b362cc"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6462e5de6e2fb5d24f86d9b5120892bfaaae517e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@6462e5de6e2fb5d24f86d9b5120892bfaaae517e",
"title": "6462e5de6e2fb5d24f86d9b5120892bfaaae517e",
"url": "https://chromium.googlesource.com/chromium/src/+/6462e5de6e2fb5d24f86d9b5120892bfaaae517e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9122d3b64237f59ae24cc6e10a093992487f1793"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@9122d3b64237f59ae24cc6e10a093992487f1793",
"title": "9122d3b64237f59ae24cc6e10a093992487f1793",
"url": "https://chromium.googlesource.com/chromium/src/+/9122d3b64237f59ae24cc6e10a093992487f1793"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9122d3b64237f59ae24cc6e10a093992487f1793",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898756105585665696"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:51:18.012] Scheduled build:
{
"id": "8733326043696909553",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win32-official"
},
"number": 44,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-23T01:51:17.532724561Z",
"updateTime": "2024-10-23T01:51:17.532724561Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9122d3b64237f59ae24cc6e10a093992487f1793",
"ref": "refs/branch-heads/6778"
}
}
}
[01:51:18.012] Task URL: https://cr-buildbucket.appspot.com/build/8733326043696909553
[01:51:18.012] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:2:0) after 7m3s
[01:51:18.956] Received PubSub notification, asking Buildbucket for the build status
[01:51:18.986] Build status: SCHEDULED
[01:51:25.089] Received PubSub notification, asking Buildbucket for the build status
[01:51:25.143] Build status: STARTED
[01:58:21.032] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:2:0)
[01:58:21.048] Build status: STARTED
[01:58:21.048] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:5:0) after 9m27s
[02:07:48.077] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:5:0)
[02:07:48.105] Build status: STARTED
[02:07:48.105] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:6:0) after 7m25s
[02:15:13.120] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:6:0)
[02:15:13.137] Build status: STARTED
[02:15:13.137] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:7:0) after 7m14s
[02:22:27.306] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:7:0)
[02:22:27.332] Build status: STARTED
[02:22:27.332] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:8:0) after 9m37s
[02:32:04.500] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:8:0)
[02:32:04.519] Build status: STARTED
[02:32:04.519] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:9:0) after 7m3s
[02:39:07.575] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:9:0)
[02:39:07.589] Build status: STARTED
[02:39:07.589] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:10:0) after 1m53s
[02:41:00.607] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:10:0)
[02:41:00.624] Build status: STARTED
[02:41:00.624] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:11:0) after 9m39s
[02:50:39.758] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:11:0)
[02:50:39.778] Build status: STARTED
[02:50:39.778] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:12:0) after 9m21s
[03:00:01.205] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:12:0)
[03:00:01.223] Build status: STARTED
[03:00:01.223] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:13:0) after 8m35s
[03:08:36.406] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:13:0)
[03:08:36.429] Build status: STARTED
[03:08:36.429] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:14:0) after 7m51s
[03:16:27.453] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:14:0)
[03:16:27.471] Build status: STARTED
[03:16:27.471] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:15:0) after 1m57s
[03:18:24.494] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:15:0)
[03:18:24.522] Build status: STARTED
[03:18:24.522] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:16:0) after 4m7s
[03:22:31.539] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:16:0)
[03:22:31.556] Build status: STARTED
[03:22:31.556] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:17:0) after 5m34s
[03:28:05.586] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:17:0)
[03:28:05.628] Build status: STARTED
[03:28:05.628] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:18:0) after 4m11s
[03:32:16.644] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:18:0)
[03:32:16.670] Build status: STARTED
[03:32:16.670] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:19:0) after 1m0s
[03:33:16.928] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:19:0)
[03:33:16.959] Build status: STARTED
[03:33:16.959] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:20:0) after 1m5s
[03:34:22.028] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:20:0)
[03:34:22.054] Build status: STARTED
[03:34:22.054] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:21:0) after 3m57s
[03:38:19.073] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:21:0)
[03:38:19.094] Build status: STARTED
[03:38:19.094] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:22:0) after 3m9s
[03:41:28.389] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:22:0)
[03:41:28.409] Build status: STARTED
[03:41:28.409] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:23:0) after 1m49s
[03:43:17.595] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:23:0)
[03:43:17.614] Build status: STARTED
[03:43:17.614] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:24:0) after 2m46s
[03:46:03.632] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:24:0)
[03:46:03.656] Build status: STARTED
[03:46:03.656] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:25:0) after 5m50s
[03:51:54.109] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:25:0)
[03:51:54.131] Build status: STARTED
[03:51:54.131] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:26:0) after 5m12s
[03:57:06.159] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:26:0)
[03:57:06.179] Build status: STARTED
[03:57:06.179] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:27:0) after 6m34s
[04:03:40.298] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:27:0)
[04:03:40.315] Build status: STARTED
[04:03:40.315] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:28:0) after 6m41s
[04:10:21.358] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:28:0)
[04:10:21.377] Build status: STARTED
[04:10:21.377] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:29:0) after 9m27s
[04:19:48.643] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:29:0)
[04:19:48.676] Build status: STARTED
[04:19:48.676] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:30:0) after 8m26s
[04:28:14.720] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:30:0)
[04:28:14.740] Build status: STARTED
[04:28:14.740] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:31:0) after 7m12s
[04:35:26.780] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:31:0)
[04:35:26.799] Build status: STARTED
[04:35:26.799] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:32:0) after 4m42s
[04:40:08.820] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:32:0)
[04:40:08.860] Build status: STARTED
[04:40:08.860] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:33:0) after 8m26s
[04:48:35.086] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:33:0)
[04:48:35.142] Build status: STARTED
[04:48:35.142] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:34:0) after 9m57s
[04:58:32.181] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:34:0)
[04:58:32.201] Build status: STARTED
[04:58:32.202] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:35:0) after 1m36s
[05:00:08.435] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:35:0)
[05:00:08.455] Build status: STARTED
[05:00:08.455] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:36:0) after 8m3s
[05:08:11.844] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:36:0)
[05:08:11.862] Build status: STARTED
[05:08:11.862] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:37:0) after 1m39s
[05:09:51.052] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:37:0)
[05:09:51.072] Build status: STARTED
[05:09:51.072] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:38:0) after 3m8s
[05:12:59.333] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:38:0)
[05:12:59.355] Build status: STARTED
[05:12:59.355] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:39:0) after 5m53s
[05:18:53.044] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:39:0)
[05:18:53.215] Build status: STARTED
[05:18:53.215] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:40:0) after 4m13s
[05:23:06.234] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:40:0)
[05:23:06.262] Build status: STARTED
[05:23:06.262] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:41:0) after 7m53s
[05:30:59.417] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:41:0)
[05:30:59.436] Build status: STARTED
[05:30:59.436] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:42:0) after 1m18s
[05:32:17.539] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:42:0)
[05:32:17.565] Build status: STARTED
[05:32:17.565] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:43:0) after 4m0s
[05:36:17.868] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:43:0)
[05:36:17.947] Build status: STARTED
[05:36:17.947] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:44:0) after 8m1s
[05:44:19.015] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:44:0)
[05:44:19.068] Build status: STARTED
[05:44:19.068] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:45:0) after 3m53s
[05:48:12.087] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:45:0)
[05:48:12.128] Build status: STARTED
[05:48:12.128] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:46:0) after 3m11s
[05:51:23.502] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:46:0)
[05:51:23.523] Build status: STARTED
[05:51:23.523] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:47:0) after 5m13s
[05:56:36.550] Handling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:47:0)
[05:56:36.569] Build status: STARTED
[05:56:36.569] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win32-official:8898756105585665696:48:0) after 5m4s
[05:59:45.999] Received PubSub notification, asking Buildbucket for the build status
[05:59:46.017] Build:
{
"id": "8733326043696909553",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win32-official"
},
"number": 44,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-23T01:51:17.532724561Z",
"startTime": "2024-10-23T01:51:24.900643025Z",
"endTime": "2024-10-23T05:59:38.445856449Z",
"updateTime": "2024-10-23T05:59:38.445856449Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9122d3b64237f59ae24cc6e10a093992487f1793",
"ref": "refs/branch-heads/6778"
}
}
}
[05:59:46.017] Invocation finished in 4h8m29.669798978s with status SUCCEEDED