[17:24:30.741] New invocation is queued and will start shortly
[17:24:32.283] Starting the invocation (attempt 1)
[17:24:32.306] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/198874be70de086406288b633943a92600656256"
[17:24:32.306] Popped gitiles commit info from properties and tags
[17:24:32.306] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:24:32.307] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:24:32.307] Buildbucket request:
{
"requestId": "8898878585154643472",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898878585154643472",
"job": "chromium-m130/linux-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8a0c6ff18b22fb5c8477dc967c1158ace0b91d5f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@8a0c6ff18b22fb5c8477dc967c1158ace0b91d5f",
"title": "8a0c6ff18b22fb5c8477dc967c1158ace0b91d5f",
"url": "https://chromium.googlesource.com/chromium/src/+/8a0c6ff18b22fb5c8477dc967c1158ace0b91d5f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5b6692abc5157dfc5963f5fbf3e42569ee8905ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@5b6692abc5157dfc5963f5fbf3e42569ee8905ed",
"title": "5b6692abc5157dfc5963f5fbf3e42569ee8905ed",
"url": "https://chromium.googlesource.com/chromium/src/+/5b6692abc5157dfc5963f5fbf3e42569ee8905ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a3fd86dfb3ff340cd01e1ced2d780f1bd420f28a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@a3fd86dfb3ff340cd01e1ced2d780f1bd420f28a",
"title": "a3fd86dfb3ff340cd01e1ced2d780f1bd420f28a",
"url": "https://chromium.googlesource.com/chromium/src/+/a3fd86dfb3ff340cd01e1ced2d780f1bd420f28a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "198874be70de086406288b633943a92600656256"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@198874be70de086406288b633943a92600656256",
"title": "198874be70de086406288b633943a92600656256",
"url": "https://chromium.googlesource.com/chromium/src/+/198874be70de086406288b633943a92600656256"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "198874be70de086406288b633943a92600656256",
"ref": "refs/branch-heads/6723"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898878585154643472"
},
{
"key": "scheduler_job_id",
"value": "chromium-m130/linux-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:24:32.879] Scheduled build:
{
"id": "8733448522782768449",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "linux-official"
},
"number": 170,
"createdBy": "project:chromium-m130",
"createTime": "2024-10-21T17:24:32.373738389Z",
"updateTime": "2024-10-21T17:24:32.373738389Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "198874be70de086406288b633943a92600656256",
"ref": "refs/branch-heads/6723"
}
}
}
[17:24:32.879] Task URL: https://cr-buildbucket.appspot.com/build/8733448522782768449
[17:24:32.879] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:2:0) after 3m52s
[17:24:33.796] Received PubSub notification, asking Buildbucket for the build status
[17:24:33.814] Build status: SCHEDULED
[17:26:44.455] Received PubSub notification, asking Buildbucket for the build status
[17:26:44.474] Build status: STARTED
[17:28:24.936] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:2:0)
[17:28:24.959] Build status: STARTED
[17:28:24.959] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:5:0) after 4m30s
[17:32:54.950] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:5:0)
[17:32:54.978] Build status: STARTED
[17:32:54.978] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:6:0) after 5m38s
[17:38:32.992] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:6:0)
[17:38:33.069] Build status: STARTED
[17:38:33.069] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:7:0) after 9m0s
[17:47:33.086] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:7:0)
[17:47:33.109] Build status: STARTED
[17:47:33.109] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:8:0) after 4m23s
[17:51:56.160] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:8:0)
[17:51:56.176] Build status: STARTED
[17:51:56.176] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:9:0) after 9m12s
[18:01:08.193] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:9:0)
[18:01:08.214] Build status: STARTED
[18:01:08.214] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:10:0) after 1m52s
[18:03:00.228] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:10:0)
[18:03:00.250] Build status: STARTED
[18:03:00.250] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:11:0) after 3m12s
[18:06:12.245] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:11:0)
[18:06:12.264] Build status: STARTED
[18:06:12.264] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:12:0) after 9m14s
[18:15:26.323] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:12:0)
[18:15:26.340] Build status: STARTED
[18:15:26.340] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:13:0) after 4m36s
[18:20:02.420] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:13:0)
[18:20:02.440] Build status: STARTED
[18:20:02.440] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:14:0) after 8m37s
[18:28:39.511] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:14:0)
[18:28:39.528] Build status: STARTED
[18:28:39.528] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:15:0) after 5m52s
[18:34:31.634] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:15:0)
[18:34:31.651] Build status: STARTED
[18:34:31.651] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:16:0) after 6m40s
[18:41:11.676] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:16:0)
[18:41:11.702] Build status: STARTED
[18:41:11.702] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:17:0) after 8m46s
[18:49:57.742] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:17:0)
[18:49:57.760] Build status: STARTED
[18:49:57.760] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:18:0) after 4m31s
[18:54:28.795] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:18:0)
[18:54:28.817] Build status: STARTED
[18:54:28.817] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:19:0) after 8m15s
[19:02:43.882] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:19:0)
[19:02:43.906] Build status: STARTED
[19:02:43.906] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:20:0) after 7m50s
[19:10:34.097] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:20:0)
[19:10:34.119] Build status: STARTED
[19:10:34.119] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:21:0) after 2m53s
[19:13:27.135] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:21:0)
[19:13:27.156] Build status: STARTED
[19:13:27.156] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:22:0) after 2m3s
[19:15:30.173] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:22:0)
[19:15:30.192] Build status: STARTED
[19:15:30.192] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:23:0) after 3m2s
[19:18:32.197] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:23:0)
[19:18:32.213] Build status: STARTED
[19:18:32.213] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:24:0) after 6m14s
[19:24:46.232] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:24:0)
[19:24:46.312] Build status: STARTED
[19:24:46.312] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:25:0) after 9m49s
[19:34:35.331] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:25:0)
[19:34:35.351] Build status: STARTED
[19:34:35.351] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:26:0) after 4m50s
[19:39:25.435] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:26:0)
[19:39:25.451] Build status: STARTED
[19:39:25.451] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:27:0) after 2m9s
[19:41:34.465] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:27:0)
[19:41:34.488] Build status: STARTED
[19:41:34.488] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:28:0) after 9m21s
[19:50:55.545] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:28:0)
[19:50:55.564] Build status: STARTED
[19:50:55.564] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:29:0) after 2m1s
[19:52:56.590] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:29:0)
[19:52:56.608] Build status: STARTED
[19:52:56.608] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:30:0) after 2m30s
[19:55:26.643] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:30:0)
[19:55:26.658] Build status: STARTED
[19:55:26.658] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:31:0) after 6m32s
[20:01:58.696] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:31:0)
[20:01:58.743] Build status: STARTED
[20:01:58.743] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:32:0) after 2m6s
[20:04:04.732] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:32:0)
[20:04:04.754] Build status: STARTED
[20:04:04.754] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:33:0) after 3m3s
[20:07:07.775] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:33:0)
[20:07:07.854] Build status: STARTED
[20:07:07.854] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:34:0) after 8m50s
[20:15:57.926] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:34:0)
[20:15:57.947] Build status: STARTED
[20:15:57.948] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:35:0) after 1m56s
[20:17:53.989] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:35:0)
[20:17:54.009] Build status: STARTED
[20:17:54.009] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:36:0) after 3m32s
[20:21:26.145] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:36:0)
[20:21:26.174] Build status: STARTED
[20:21:26.174] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:37:0) after 9m8s
[20:30:34.170] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:37:0)
[20:30:34.215] Build status: STARTED
[20:30:34.215] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:38:0) after 8m15s
[20:38:49.246] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:38:0)
[20:38:49.267] Build status: STARTED
[20:38:49.267] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:39:0) after 6m17s
[20:45:06.282] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:39:0)
[20:45:06.319] Build status: STARTED
[20:45:06.319] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:40:0) after 7m15s
[20:52:21.334] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:40:0)
[20:52:21.359] Build status: STARTED
[20:52:21.359] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:41:0) after 3m24s
[20:55:45.467] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:41:0)
[20:55:45.488] Build status: STARTED
[20:55:45.488] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:42:0) after 9m6s
[21:04:51.512] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:42:0)
[21:04:51.564] Build status: STARTED
[21:04:51.564] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:43:0) after 1m22s
[21:06:13.602] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:43:0)
[21:06:13.627] Build status: STARTED
[21:06:13.627] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:44:0) after 9m8s
[21:15:21.644] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:44:0)
[21:15:21.674] Build status: STARTED
[21:15:21.674] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:45:0) after 4m56s
[21:20:17.738] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:45:0)
[21:20:17.818] Build status: STARTED
[21:20:17.818] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:46:0) after 8m51s
[21:29:08.837] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:46:0)
[21:29:08.881] Build status: STARTED
[21:29:08.881] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:47:0) after 9m1s
[21:38:09.995] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:47:0)
[21:38:10.017] Build status: STARTED
[21:38:10.017] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:48:0) after 5m12s
[21:43:22.050] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:48:0)
[21:43:22.070] Build status: STARTED
[21:43:22.070] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:49:0) after 5m42s
[21:49:04.095] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:49:0)
[21:49:04.119] Build status: STARTED
[21:49:04.119] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:50:0) after 2m16s
[21:51:20.156] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:50:0)
[21:51:20.183] Build status: STARTED
[21:51:20.183] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:51:0) after 7m46s
[21:59:06.203] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:51:0)
[21:59:06.224] Build status: STARTED
[21:59:06.224] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:52:0) after 2m6s
[22:01:12.216] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:52:0)
[22:01:12.234] Build status: STARTED
[22:01:12.234] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:53:0) after 8m30s
[22:09:42.283] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:53:0)
[22:09:42.301] Build status: STARTED
[22:09:42.301] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:54:0) after 8m29s
[22:18:11.272] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:54:0)
[22:18:11.293] Build status: STARTED
[22:18:11.293] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:55:0) after 8m41s
[22:26:52.308] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:55:0)
[22:26:52.577] Build status: STARTED
[22:26:52.577] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:56:0) after 2m52s
[22:29:44.665] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:56:0)
[22:29:44.680] Build status: STARTED
[22:29:44.680] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:57:0) after 1m31s
[22:31:15.750] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:57:0)
[22:31:15.768] Build status: STARTED
[22:31:15.768] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:58:0) after 9m33s
[22:40:48.806] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:58:0)
[22:40:48.824] Build status: STARTED
[22:40:48.824] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:59:0) after 1m13s
[22:42:01.841] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:59:0)
[22:42:01.863] Build status: STARTED
[22:42:01.863] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:60:0) after 1m0s
[22:43:01.870] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:60:0)
[22:43:01.886] Build status: STARTED
[22:43:01.886] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:61:0) after 8m42s
[22:51:43.879] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:61:0)
[22:51:43.898] Build status: STARTED
[22:51:43.898] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:62:0) after 5m19s
[22:57:02.917] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:62:0)
[22:57:02.945] Build status: STARTED
[22:57:02.945] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-official:8898878585154643472:63:0) after 8m6s
[23:04:58.462] Received PubSub notification, asking Buildbucket for the build status
[23:04:58.558] Build:
{
"id": "8733448522782768449",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "linux-official"
},
"number": 170,
"createdBy": "project:chromium-m130",
"createTime": "2024-10-21T17:24:32.373738389Z",
"startTime": "2024-10-21T17:26:44.268418184Z",
"endTime": "2024-10-21T23:04:58.125249483Z",
"updateTime": "2024-10-21T23:04:58.125249483Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "198874be70de086406288b633943a92600656256",
"ref": "refs/branch-heads/6723"
}
}
}
[23:04:58.558] Invocation finished in 5h40m27.830026211s with status SUCCEEDED