[01:56:16.671] New invocation is queued and will start shortly
[01:56:18.114] Starting the invocation (attempt 1)
[01:56:18.146] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b8267b3628c3c2899fbbf1eb077a12d1a9c7fce9"
[01:56:18.146] Popped gitiles commit info from properties and tags
[01:56:18.146] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:56:18.146] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:56:18.146] Buildbucket request:
{
"requestId": "8905459966196960656",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-chromeos-build-perf-siso"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8267b3628c3c2899fbbf1eb077a12d1a9c7fce9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8905459966196960656"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-build-perf-siso"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:56:18.686] Scheduled build:
{
"id": "8740029903898782161",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-chromeos-build-perf-siso"
},
"number": 3389,
"createdBy": "project:chromium",
"createTime": "2024-08-10T01:56:18.237443640Z",
"updateTime": "2024-08-10T01:56:18.237443640Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8267b3628c3c2899fbbf1eb077a12d1a9c7fce9",
"ref": "refs/heads/main"
}
}
}
[01:56:18.686] Task URL: https://cr-buildbucket.appspot.com/build/8740029903898782161
[01:56:18.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:2:0) after 1m3s
[01:56:19.672] Received PubSub notification, asking Buildbucket for the build status
[01:56:19.712] Build status: SCHEDULED
[01:57:21.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:2:0)
[01:57:21.680] Build status: SCHEDULED
[01:57:21.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:4:0) after 4m24s
[01:57:26.422] Received PubSub notification, asking Buildbucket for the build status
[01:57:26.447] Build status: STARTED
[02:01:45.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:4:0)
[02:01:45.720] Build status: STARTED
[02:01:45.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:6:0) after 1m20s
[02:03:06.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:6:0)
[02:03:06.076] Build status: STARTED
[02:03:06.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:7:0) after 7m43s
[02:10:49.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:7:0)
[02:10:49.828] Build status: STARTED
[02:10:49.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:8:0) after 9m56s
[02:20:45.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:8:0)
[02:20:45.869] Build status: STARTED
[02:20:45.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:9:0) after 9m57s
[02:30:43.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:9:0)
[02:30:43.332] Build status: STARTED
[02:30:43.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:10:0) after 7m31s
[02:38:14.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:10:0)
[02:38:14.387] Build status: STARTED
[02:38:14.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:11:0) after 9m8s
[02:47:22.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:11:0)
[02:47:22.522] Build status: STARTED
[02:47:22.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:12:0) after 3m58s
[02:51:20.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:12:0)
[02:51:20.605] Build status: STARTED
[02:51:20.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:13:0) after 4m43s
[02:56:03.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:13:0)
[02:56:03.664] Build status: STARTED
[02:56:03.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:14:0) after 7m14s
[03:03:17.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:14:0)
[03:03:17.929] Build status: STARTED
[03:03:17.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:15:0) after 3m58s
[03:07:16.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:15:0)
[03:07:16.129] Build status: STARTED
[03:07:16.129] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:16:0) after 6m50s
[03:14:06.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:16:0)
[03:14:06.172] Build status: STARTED
[03:14:06.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:17:0) after 5m25s
[03:19:31.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:17:0)
[03:19:31.219] Build status: STARTED
[03:19:31.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:18:0) after 4m13s
[03:23:44.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:18:0)
[03:23:44.483] Build status: STARTED
[03:23:44.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:19:0) after 3m50s
[03:27:34.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:19:0)
[03:27:34.719] Build status: STARTED
[03:27:34.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:20:0) after 8m39s
[03:36:14.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:20:0)
[03:36:14.026] Build status: STARTED
[03:36:14.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:21:0) after 4m32s
[03:40:46.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:21:0)
[03:40:46.551] Build status: STARTED
[03:40:46.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:22:0) after 1m14s
[03:42:00.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:22:0)
[03:42:00.847] Build status: STARTED
[03:42:00.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:23:0) after 3m58s
[03:45:58.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:23:0)
[03:45:58.933] Build status: STARTED
[03:45:58.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:24:0) after 1m33s
[03:47:32.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:24:0)
[03:47:32.375] Build status: STARTED
[03:47:32.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:25:0) after 4m32s
[03:52:04.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:25:0)
[03:52:04.622] Build status: STARTED
[03:52:04.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:26:0) after 1m10s
[03:53:14.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:26:0)
[03:53:14.670] Build status: STARTED
[03:53:14.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:27:0) after 1m39s
[03:54:53.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:27:0)
[03:54:53.707] Build status: STARTED
[03:54:53.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:28:0) after 2m1s
[03:56:54.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:28:0)
[03:56:54.844] Build status: STARTED
[03:56:54.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:29:0) after 1m43s
[03:58:38.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:29:0)
[03:58:38.031] Build status: STARTED
[03:58:38.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:30:0) after 4m5s
[04:02:43.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:30:0)
[04:02:43.322] Build status: STARTED
[04:02:43.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:31:0) after 1m48s
[04:04:31.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:31:0)
[04:04:31.675] Build status: STARTED
[04:04:31.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:32:0) after 4m43s
[04:09:14.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:32:0)
[04:09:14.855] Build status: STARTED
[04:09:14.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:33:0) after 5m34s
[04:14:48.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:33:0)
[04:14:48.889] Build status: STARTED
[04:14:48.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:34:0) after 2m52s
[04:17:41.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:34:0)
[04:17:41.716] Build status: STARTED
[04:17:41.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:35:0) after 4m36s
[04:22:17.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:35:0)
[04:22:17.781] Build status: STARTED
[04:22:17.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:36:0) after 2m49s
[04:25:06.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:36:0)
[04:25:06.984] Build status: STARTED
[04:25:06.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:37:0) after 9m22s
[04:34:28.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:37:0)
[04:34:29.118] Build status: STARTED
[04:34:29.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:38:0) after 9m26s
[04:43:55.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:38:0)
[04:43:55.257] Build status: STARTED
[04:43:55.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:39:0) after 2m2s
[04:45:57.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:39:0)
[04:45:57.581] Build status: STARTED
[04:45:57.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:40:0) after 9m16s
[04:55:13.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:40:0)
[04:55:13.730] Build status: STARTED
[04:55:13.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:41:0) after 9m23s
[05:04:36.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:41:0)
[05:04:36.779] Build status: STARTED
[05:04:36.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:42:0) after 7m24s
[05:12:00.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:42:0)
[05:12:00.989] Build status: STARTED
[05:12:00.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:43:0) after 2m42s
[05:14:43.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:43:0)
[05:14:43.035] Build status: STARTED
[05:14:43.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:44:0) after 6m1s
[05:20:44.165] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:44:0)
[05:20:44.185] Build status: STARTED
[05:20:44.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:45:0) after 4m45s
[05:25:29.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:45:0)
[05:25:29.225] Build status: STARTED
[05:25:29.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:46:0) after 4m22s
[05:29:51.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:46:0)
[05:29:51.308] Build status: STARTED
[05:29:51.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8905459966196960656:47:0) after 4m45s
[05:32:33.335] Received PubSub notification, asking Buildbucket for the build status
[05:32:33.352] Build:
{
"id": "8740029903898782161",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-chromeos-build-perf-siso"
},
"number": 3389,
"createdBy": "project:chromium",
"createTime": "2024-08-10T01:56:18.237443640Z",
"startTime": "2024-08-10T01:57:26.117018882Z",
"endTime": "2024-08-10T05:32:32.677389328Z",
"updateTime": "2024-08-10T05:32:32.677389328Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8267b3628c3c2899fbbf1eb077a12d1a9c7fce9",
"ref": "refs/heads/main"
}
}
}
[05:32:33.352] Invocation finished in 3h36m16.690521086s with status SUCCEEDED