[17:53:14.715] New invocation is queued and will start shortly
[17:53:15.951] Starting the invocation (attempt 1)
[17:53:15.982] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f436fda6e25554d4bfa040db8cdaadee5b1578"
[17:53:15.982] Popped gitiles commit info from properties and tags
[17:53:15.982] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:53:15.982] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:53:15.982] Buildbucket request:
{
"requestId": "8882025741682689536",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-siso"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "54f436fda6e25554d4bfa040db8cdaadee5b1578",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882025741682689536"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-build-perf-siso"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:53:16.462] Scheduled build:
{
"id": "8716595679595279089",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-siso"
},
"number": 5402,
"createdBy": "project:chromium",
"createTime": "2025-04-25T17:53:16.080998632Z",
"updateTime": "2025-04-25T17:53:16.080998632Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "54f436fda6e25554d4bfa040db8cdaadee5b1578",
"ref": "refs/heads/main"
}
}
}
[17:53:16.462] Task URL: https://cr-buildbucket.appspot.com/build/8716595679595279089
[17:53:16.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:2:0) after 1m53s
[17:53:18.406] Received PubSub notification, asking Buildbucket for the build status
[17:53:18.489] Build status: SCHEDULED
[17:53:23.266] Received PubSub notification, asking Buildbucket for the build status
[17:53:23.287] Build status: STARTED
[17:55:09.498] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:2:0)
[17:55:09.527] Build status: STARTED
[17:55:09.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:5:0) after 9m3s
[18:04:12.579] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:5:0)
[18:04:12.617] Build status: STARTED
[18:04:12.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:6:0) after 3m0s
[18:07:12.593] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:6:0)
[18:07:12.621] Build status: STARTED
[18:07:12.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:7:0) after 1m31s
[18:08:43.700] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:7:0)
[18:08:43.733] Build status: STARTED
[18:08:43.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:8:0) after 1m56s
[18:10:39.757] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:8:0)
[18:10:39.779] Build status: STARTED
[18:10:39.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:9:0) after 3m33s
[18:14:12.802] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:9:0)
[18:14:12.821] Build status: STARTED
[18:14:12.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:10:0) after 9m40s
[18:23:52.840] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:10:0)
[18:23:52.884] Build status: STARTED
[18:23:52.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:11:0) after 4m11s
[18:28:03.919] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:11:0)
[18:28:03.998] Build status: STARTED
[18:28:03.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:12:0) after 1m33s
[18:29:36.992] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:12:0)
[18:29:37.018] Build status: STARTED
[18:29:37.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:13:0) after 5m26s
[18:35:03.094] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:13:0)
[18:35:03.132] Build status: STARTED
[18:35:03.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:14:0) after 4m36s
[18:39:39.203] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:14:0)
[18:39:39.219] Build status: STARTED
[18:39:39.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:15:0) after 6m7s
[18:45:46.285] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:15:0)
[18:45:46.304] Build status: STARTED
[18:45:46.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:16:0) after 4m37s
[18:50:23.449] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:16:0)
[18:50:23.489] Build status: STARTED
[18:50:23.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:17:0) after 1m59s
[18:52:22.517] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:17:0)
[18:52:22.533] Build status: STARTED
[18:52:22.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:18:0) after 9m51s
[19:02:13.596] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:18:0)
[19:02:13.619] Build status: STARTED
[19:02:13.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:19:0) after 4m31s
[19:06:44.638] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:19:0)
[19:06:44.701] Build status: STARTED
[19:06:44.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:20:0) after 9m25s
[19:16:09.810] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:20:0)
[19:16:09.831] Build status: STARTED
[19:16:09.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:21:0) after 1m40s
[19:17:49.847] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:21:0)
[19:17:49.865] Build status: STARTED
[19:17:49.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:22:0) after 7m7s
[19:24:56.931] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:22:0)
[19:24:56.974] Build status: STARTED
[19:24:56.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:23:0) after 8m46s
[19:33:42.990] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:23:0)
[19:33:43.039] Build status: STARTED
[19:33:43.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:24:0) after 5m1s
[19:38:44.064] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:24:0)
[19:38:44.092] Build status: STARTED
[19:38:44.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:25:0) after 5m1s
[19:43:45.156] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:25:0)
[19:43:45.176] Build status: STARTED
[19:43:45.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8882025741682689536:26:0) after 4m23s
[19:44:28.876] Received PubSub notification, asking Buildbucket for the build status
[19:44:28.925] Build:
{
"id": "8716595679595279089",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-siso"
},
"number": 5402,
"createdBy": "project:chromium",
"createTime": "2025-04-25T17:53:16.080998632Z",
"startTime": "2025-04-25T17:53:21.437415731Z",
"endTime": "2025-04-25T19:44:28.550342449Z",
"updateTime": "2025-04-25T19:44:28.550342449Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "54f436fda6e25554d4bfa040db8cdaadee5b1578",
"ref": "refs/heads/main"
}
}
}
[19:44:28.925] Invocation finished in 1h51m14.219668416s with status SUCCEEDED