[23:51:36.595] New invocation is queued and will start shortly
[23:51:37.988] Starting the invocation (attempt 1)
[23:51:38.032] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c8d462f713a1d0251099f3f5869386b9ed5211df"
[23:51:38.032] Popped gitiles commit info from properties and tags
[23:51:38.033] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:51:38.035] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:51:38.035] Buildbucket request:
{
"requestId": "8983834185566556016",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c8d462f713a1d0251099f3f5869386b9ed5211df",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8983834185566556016"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:51:38.505] Scheduled build:
{
"id": "8818404123335236897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4670,
"createdBy": "project:chromium",
"createTime": "2022-03-28T23:51:38.092151994Z",
"updateTime": "2022-03-28T23:51:38.092151994Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c8d462f713a1d0251099f3f5869386b9ed5211df",
"ref": "refs/heads/main"
}
}
}
[23:51:38.505] Task URL: https://cr-buildbucket.appspot.com/build/8818404123335236897
[23:51:38.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:2:0) after 3m48s
[23:55:26.529] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:2:0)
[23:55:26.649] Build status: SCHEDULED
[23:55:26.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:3:0) after 1m55s
[23:56:09.595] Received PubSub notification, asking Buildbucket for the build status
[23:56:09.615] Build status: STARTED
[23:57:21.869] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:3:0)
[23:57:22.031] Build status: STARTED
[23:57:22.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:5:0) after 8m44s
[00:06:06.516] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:5:0)
[00:06:06.546] Build status: STARTED
[00:06:06.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:6:0) after 1m7s
[00:07:13.571] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:6:0)
[00:07:13.626] Build status: STARTED
[00:07:13.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:7:0) after 1m8s
[00:08:21.649] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:7:0)
[00:08:21.669] Build status: STARTED
[00:08:21.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:8:0) after 7m1s
[00:15:22.857] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:8:0)
[00:15:22.880] Build status: STARTED
[00:15:22.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:9:0) after 6m36s
[00:21:58.928] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:9:0)
[00:21:58.950] Build status: STARTED
[00:21:58.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:10:0) after 3m9s
[00:25:07.996] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:10:0)
[00:25:08.028] Build status: STARTED
[00:25:08.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:11:0) after 1m13s
[00:26:21.347] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:11:0)
[00:26:21.372] Build status: STARTED
[00:26:21.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:12:0) after 8m32s
[00:34:53.445] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:12:0)
[00:34:53.465] Build status: STARTED
[00:34:53.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:13:0) after 2m20s
[00:37:13.488] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:13:0)
[00:37:13.505] Build status: STARTED
[00:37:13.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:14:0) after 2m16s
[00:39:29.482] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:14:0)
[00:39:29.588] Build status: STARTED
[00:39:29.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:15:0) after 9m33s
[00:49:02.669] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:15:0)
[00:49:02.689] Build status: STARTED
[00:49:02.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:16:0) after 3m0s
[00:52:02.810] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:16:0)
[00:52:02.831] Build status: STARTED
[00:52:02.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:17:0) after 9m53s
[01:01:55.932] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:17:0)
[01:01:55.953] Build status: STARTED
[01:01:55.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:18:0) after 4m34s
[01:06:30.119] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:18:0)
[01:06:30.141] Build status: STARTED
[01:06:30.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:19:0) after 7m18s
[01:13:48.190] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:19:0)
[01:13:48.214] Build status: STARTED
[01:13:48.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:20:0) after 2m34s
[01:16:22.274] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:20:0)
[01:16:22.320] Build status: STARTED
[01:16:22.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:21:0) after 3m29s
[01:19:51.426] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:21:0)
[01:19:51.453] Build status: STARTED
[01:19:51.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:22:0) after 7m42s
[01:27:33.856] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:22:0)
[01:27:33.883] Build status: STARTED
[01:27:33.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:23:0) after 8m3s
[01:35:36.903] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:23:0)
[01:35:36.927] Build status: STARTED
[01:35:36.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:24:0) after 7m1s
[01:42:37.954] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:24:0)
[01:42:37.975] Build status: STARTED
[01:42:37.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:25:0) after 1m12s
[01:43:50.055] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:25:0)
[01:43:50.078] Build status: STARTED
[01:43:50.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:26:0) after 1m13s
[01:45:03.121] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:26:0)
[01:45:03.159] Build status: STARTED
[01:45:03.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:27:0) after 3m3s
[01:48:06.710] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:27:0)
[01:48:06.731] Build status: STARTED
[01:48:06.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:28:0) after 4m15s
[01:52:22.201] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:28:0)
[01:52:22.227] Build status: STARTED
[01:52:22.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:29:0) after 7m30s
[01:59:52.249] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:29:0)
[01:59:52.278] Build status: STARTED
[01:59:52.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:30:0) after 1m41s
[02:01:33.555] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:30:0)
[02:01:33.589] Build status: STARTED
[02:01:33.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:31:0) after 9m49s
[02:11:22.776] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:31:0)
[02:11:22.869] Build status: STARTED
[02:11:22.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:32:0) after 3m1s
[02:14:23.952] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:32:0)
[02:14:23.973] Build status: STARTED
[02:14:23.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:33:0) after 1m58s
[02:16:22.193] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:33:0)
[02:16:22.217] Build status: STARTED
[02:16:22.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:34:0) after 6m35s
[02:22:57.263] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:34:0)
[02:22:57.304] Build status: STARTED
[02:22:57.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:35:0) after 4m8s
[02:27:05.421] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:35:0)
[02:27:05.450] Build status: STARTED
[02:27:05.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:36:0) after 4m15s
[02:31:20.479] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:36:0)
[02:31:20.500] Build status: STARTED
[02:31:20.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:37:0) after 3m36s
[02:34:56.541] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:37:0)
[02:34:56.569] Build status: STARTED
[02:34:56.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:38:0) after 3m13s
[02:38:09.572] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:38:0)
[02:38:09.593] Build status: STARTED
[02:38:09.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:39:0) after 7m19s
[02:45:28.618] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:39:0)
[02:45:28.639] Build status: STARTED
[02:45:28.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:40:0) after 9m54s
[02:55:22.766] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:40:0)
[02:55:22.786] Build status: STARTED
[02:55:22.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:41:0) after 9m26s
[03:04:48.913] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:41:0)
[03:04:48.934] Build status: STARTED
[03:04:48.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:42:0) after 4m47s
[03:09:35.957] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:42:0)
[03:09:35.976] Build status: STARTED
[03:09:35.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:43:0) after 6m5s
[03:15:41.095] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:43:0)
[03:15:41.124] Build status: STARTED
[03:15:41.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:44:0) after 7m1s
[03:22:42.159] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:44:0)
[03:22:42.179] Build status: STARTED
[03:22:42.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:45:0) after 5m55s
[03:28:37.586] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:45:0)
[03:28:37.645] Build status: STARTED
[03:28:37.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:46:0) after 6m5s
[03:34:42.835] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:46:0)
[03:34:42.869] Build status: STARTED
[03:34:42.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:47:0) after 8m14s
[03:42:56.903] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:47:0)
[03:42:56.928] Build status: STARTED
[03:42:56.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:48:0) after 7m18s
[03:50:14.912] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:48:0)
[03:50:14.935] Build status: STARTED
[03:50:14.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:49:0) after 6m20s
[03:56:35.105] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:49:0)
[03:56:35.165] Build status: STARTED
[03:56:35.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:50:0) after 1m21s
[03:57:56.225] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:50:0)
[03:57:56.256] Build status: STARTED
[03:57:56.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:51:0) after 1m18s
[03:59:14.379] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:51:0)
[03:59:14.402] Build status: STARTED
[03:59:14.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:52:0) after 7m35s
[04:06:49.674] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:52:0)
[04:06:49.698] Build status: STARTED
[04:06:49.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:53:0) after 8m16s
[04:15:05.734] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:53:0)
[04:15:05.770] Build status: STARTED
[04:15:05.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:54:0) after 2m41s
[04:17:46.893] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:54:0)
[04:17:46.929] Build status: STARTED
[04:17:46.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8983834185566556016:55:0) after 4m41s
[04:17:47.398] Received PubSub notification, asking Buildbucket for the build status
[04:17:47.416] Build:
{
"id": "8818404123335236897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4670,
"createdBy": "project:chromium",
"createTime": "2022-03-28T23:51:38.092151994Z",
"startTime": "2022-03-28T23:56:08.829446Z",
"endTime": "2022-03-29T04:17:47.169729302Z",
"updateTime": "2022-03-29T04:17:47.169729302Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c8d462f713a1d0251099f3f5869386b9ed5211df",
"ref": "refs/heads/main"
}
}
}
[04:17:47.416] Invocation finished in 4h26m10.836686767s with status SUCCEEDED