[05:45:14.963] New invocation is queued and will start shortly
[05:45:16.008] Starting the invocation (attempt 1)
[05:45:16.033] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4a38a8dcbe1ca7e378a6ee52ba52d4e087919e30"
[05:45:16.033] Popped gitiles commit info from properties and tags
[05:45:16.033] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:45:16.033] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:45:16.033] Buildbucket request:
{
"requestId": "8874823785912573824",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4a38a8dcbe1ca7e378a6ee52ba52d4e087919e30",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874823785912573824"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:45:16.572] Scheduled build:
{
"id": "8709393724067646513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 30061,
"createdBy": "project:chromium",
"createTime": "2025-07-14T05:45:16.094290615Z",
"updateTime": "2025-07-14T05:45:16.094290615Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4a38a8dcbe1ca7e378a6ee52ba52d4e087919e30",
"ref": "refs/heads/main"
}
}
}
[05:45:16.572] Task URL: https://cr-buildbucket.appspot.com/build/8709393724067646513
[05:45:16.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:2:0) after 8m9s
[05:46:06.579] Received PubSub notification, asking Buildbucket for the build status
[05:46:06.624] Build status: STARTED
[05:46:12.679] Received PubSub notification, asking Buildbucket for the build status
[05:46:12.705] Build status: STARTED
[05:53:25.593] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:2:0)
[05:53:25.620] Build status: STARTED
[05:53:25.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:5:0) after 5m22s
[05:58:47.895] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:5:0)
[05:58:47.924] Build status: STARTED
[05:58:47.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:6:0) after 4m5s
[06:02:52.953] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:6:0)
[06:02:52.987] Build status: STARTED
[06:02:52.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:7:0) after 4m34s
[06:07:27.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:7:0)
[06:07:27.237] Build status: STARTED
[06:07:27.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:8:0) after 5m54s
[06:13:21.271] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:8:0)
[06:13:21.302] Build status: STARTED
[06:13:21.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:9:0) after 5m11s
[06:18:32.328] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:9:0)
[06:18:32.356] Build status: STARTED
[06:18:32.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:10:0) after 4m13s
[06:22:45.397] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:10:0)
[06:22:45.471] Build status: STARTED
[06:22:45.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:11:0) after 9m22s
[06:32:07.602] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:11:0)
[06:32:07.629] Build status: STARTED
[06:32:07.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:12:0) after 4m28s
[06:36:35.802] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:12:0)
[06:36:35.834] Build status: STARTED
[06:36:35.834] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:13:0) after 4m42s
[06:41:17.857] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:13:0)
[06:41:17.885] Build status: STARTED
[06:41:17.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:14:0) after 5m47s
[06:47:04.901] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:14:0)
[06:47:04.928] Build status: STARTED
[06:47:04.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:15:0) after 2m48s
[06:49:52.952] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:15:0)
[06:49:52.968] Build status: STARTED
[06:49:52.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:16:0) after 7m34s
[06:57:26.984] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:16:0)
[06:57:27.012] Build status: STARTED
[06:57:27.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:17:0) after 3m47s
[07:01:14.031] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:17:0)
[07:01:14.053] Build status: STARTED
[07:01:14.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:18:0) after 3m30s
[07:04:44.057] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:18:0)
[07:04:44.117] Build status: STARTED
[07:04:44.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:19:0) after 9m6s
[07:13:50.148] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:19:0)
[07:13:50.178] Build status: STARTED
[07:13:50.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:20:0) after 6m11s
[07:20:01.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:20:0)
[07:20:01.232] Build status: STARTED
[07:20:01.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:21:0) after 2m12s
[07:22:13.293] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:21:0)
[07:22:13.333] Build status: STARTED
[07:22:13.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:22:0) after 8m42s
[07:30:55.376] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:22:0)
[07:30:55.395] Build status: STARTED
[07:30:55.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:23:0) after 5m28s
[07:36:23.417] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:23:0)
[07:36:23.449] Build status: STARTED
[07:36:23.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:24:0) after 5m3s
[07:41:26.454] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:24:0)
[07:41:26.508] Build status: STARTED
[07:41:26.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:25:0) after 9m1s
[07:50:27.585] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:25:0)
[07:50:27.605] Build status: STARTED
[07:50:27.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:26:0) after 2m37s
[07:53:04.653] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:26:0)
[07:53:04.692] Build status: STARTED
[07:53:04.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:27:0) after 9m58s
[08:03:02.759] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:27:0)
[08:03:02.810] Build status: STARTED
[08:03:02.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:28:0) after 3m12s
[08:06:14.872] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:28:0)
[08:06:14.901] Build status: STARTED
[08:06:14.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:29:0) after 8m0s
[08:14:14.935] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:29:0)
[08:14:14.962] Build status: STARTED
[08:14:14.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:30:0) after 9m42s
[08:23:57.027] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:30:0)
[08:23:57.054] Build status: STARTED
[08:23:57.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:31:0) after 8m18s
[08:32:15.063] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:31:0)
[08:32:15.097] Build status: STARTED
[08:32:15.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:32:0) after 4m32s
[08:36:47.322] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:32:0)
[08:36:47.337] Build status: STARTED
[08:36:47.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:33:0) after 9m18s
[08:46:05.399] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:33:0)
[08:46:05.420] Build status: STARTED
[08:46:05.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:34:0) after 3m18s
[08:49:23.514] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:34:0)
[08:49:23.534] Build status: STARTED
[08:49:23.534] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:35:0) after 6m46s
[08:56:09.869] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:35:0)
[08:56:09.890] Build status: STARTED
[08:56:09.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:36:0) after 7m52s
[09:04:01.927] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:36:0)
[09:04:01.949] Build status: STARTED
[09:04:01.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:37:0) after 9m54s
[09:13:56.049] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:37:0)
[09:13:56.138] Build status: STARTED
[09:13:56.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:38:0) after 3m25s
[09:17:21.161] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:38:0)
[09:17:21.221] Build status: STARTED
[09:17:21.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:39:0) after 1m17s
[09:18:38.262] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:39:0)
[09:18:38.278] Build status: STARTED
[09:18:38.278] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:40:0) after 3m45s
[09:22:23.307] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:40:0)
[09:22:23.336] Build status: STARTED
[09:22:23.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:41:0) after 3m35s
[09:25:58.352] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:41:0)
[09:25:58.371] Build status: STARTED
[09:25:58.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:42:0) after 6m55s
[09:32:53.348] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:42:0)
[09:32:53.367] Build status: STARTED
[09:32:53.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:43:0) after 9m26s
[09:42:19.383] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:43:0)
[09:42:19.515] Build status: STARTED
[09:42:19.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:44:0) after 6m37s
[09:48:56.590] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:44:0)
[09:48:56.615] Build status: STARTED
[09:48:56.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:45:0) after 1m21s
[09:50:17.640] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:45:0)
[09:50:17.662] Build status: STARTED
[09:50:17.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:46:0) after 8m12s
[09:58:29.691] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:46:0)
[09:58:29.715] Build status: STARTED
[09:58:29.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:47:0) after 8m36s
[10:07:05.788] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:47:0)
[10:07:05.805] Build status: STARTED
[10:07:05.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8874823785912573824:48:0) after 1m57s
[10:08:51.809] Received PubSub notification, asking Buildbucket for the build status
[10:08:51.828] Build:
{
"id": "8709393724067646513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 30061,
"createdBy": "project:chromium",
"createTime": "2025-07-14T05:45:16.094290615Z",
"startTime": "2025-07-14T05:45:20.984806293Z",
"endTime": "2025-07-14T10:08:51.302740453Z",
"updateTime": "2025-07-14T10:08:51.302740453Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4a38a8dcbe1ca7e378a6ee52ba52d4e087919e30",
"ref": "refs/heads/main"
}
}
}
[10:08:51.828] Invocation finished in 4h23m36.876670403s with status SUCCEEDED