[18:45:49.064] New invocation is queued and will start shortly
[18:45:50.151] Starting the invocation (attempt 1)
[18:45:50.185] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/88a2af96006074cb62ac8673c5befb6a7fe37b29"
[18:45:50.185] Popped gitiles commit info from properties and tags
[18:45:50.185] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:45:50.185] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:45:50.186] Buildbucket request:
{
"requestId": "8907389584697190576",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-siso"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "88a2af96006074cb62ac8673c5befb6a7fe37b29",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8907389584697190576"
},
{
"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"
}
}
[18:45:50.922] Scheduled build:
{
"id": "8741959522791628161",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-siso"
},
"number": 1558,
"createdBy": "project:chromium",
"createTime": "2024-07-19T18:45:50.258041325Z",
"updateTime": "2024-07-19T18:45:50.258041325Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "88a2af96006074cb62ac8673c5befb6a7fe37b29",
"ref": "refs/heads/main"
}
}
}
[18:45:50.922] Task URL: https://cr-buildbucket.appspot.com/build/8741959522791628161
[18:45:50.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:2:0) after 9m1s
[18:45:51.853] Received PubSub notification, asking Buildbucket for the build status
[18:45:51.881] Build status: SCHEDULED
[18:45:56.436] Received PubSub notification, asking Buildbucket for the build status
[18:45:56.453] Build status: STARTED
[18:54:51.940] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:2:0)
[18:54:51.979] Build status: STARTED
[18:54:51.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:5:0) after 4m27s
[18:59:19.008] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:5:0)
[18:59:19.036] Build status: STARTED
[18:59:19.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:6:0) after 8m1s
[19:07:20.055] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:6:0)
[19:07:20.098] Build status: STARTED
[19:07:20.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:7:0) after 2m56s
[19:10:16.130] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:7:0)
[19:10:16.161] Build status: STARTED
[19:10:16.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:8:0) after 8m34s
[19:18:50.234] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:8:0)
[19:18:50.257] Build status: STARTED
[19:18:50.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:9:0) after 5m34s
[19:24:24.287] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:9:0)
[19:24:24.311] Build status: STARTED
[19:24:24.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:10:0) after 1m23s
[19:25:47.312] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:10:0)
[19:25:47.334] Build status: STARTED
[19:25:47.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:11:0) after 3m3s
[19:28:50.372] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:11:0)
[19:28:50.391] Build status: STARTED
[19:28:50.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:12:0) after 2m57s
[19:31:47.399] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:12:0)
[19:31:47.418] Build status: STARTED
[19:31:47.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:13:0) after 1m52s
[19:33:39.437] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:13:0)
[19:33:39.473] Build status: STARTED
[19:33:39.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:14:0) after 6m17s
[19:39:56.551] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:14:0)
[19:39:56.572] Build status: STARTED
[19:39:56.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:15:0) after 6m34s
[19:46:30.651] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:15:0)
[19:46:30.670] Build status: STARTED
[19:46:30.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:16:0) after 9m5s
[19:55:35.696] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:16:0)
[19:55:35.770] Build status: STARTED
[19:55:35.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:17:0) after 1m13s
[19:56:48.791] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:17:0)
[19:56:48.809] Build status: STARTED
[19:56:48.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:18:0) after 6m22s
[20:03:10.830] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:18:0)
[20:03:10.853] Build status: STARTED
[20:03:10.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:19:0) after 1m17s
[20:04:27.878] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:19:0)
[20:04:27.899] Build status: STARTED
[20:04:27.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:20:0) after 1m50s
[20:06:17.882] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:20:0)
[20:06:17.914] Build status: STARTED
[20:06:17.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:21:0) after 8m2s
[20:14:19.974] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:21:0)
[20:14:20.003] Build status: STARTED
[20:14:20.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:22:0) after 5m9s
[20:19:29.062] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:22:0)
[20:19:29.135] Build status: STARTED
[20:19:29.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:23:0) after 2m28s
[20:21:57.240] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:23:0)
[20:21:57.260] Build status: STARTED
[20:21:57.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:24:0) after 3m21s
[20:25:18.352] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:24:0)
[20:25:18.408] Build status: STARTED
[20:25:18.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:25:0) after 9m22s
[20:34:40.430] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:25:0)
[20:34:40.450] Build status: STARTED
[20:34:40.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:26:0) after 8m10s
[20:42:50.468] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:26:0)
[20:42:50.486] Build status: STARTED
[20:42:50.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:27:0) after 6m25s
[20:49:15.564] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:27:0)
[20:49:15.581] Build status: STARTED
[20:49:15.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:28:0) after 8m7s
[20:57:22.601] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:28:0)
[20:57:22.624] Build status: STARTED
[20:57:22.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:29:0) after 6m48s
[21:04:10.746] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:29:0)
[21:04:10.827] Build status: STARTED
[21:04:10.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:30:0) after 3m10s
[21:07:20.865] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:30:0)
[21:07:20.903] Build status: STARTED
[21:07:20.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:31:0) after 9m30s
[21:16:50.925] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:31:0)
[21:16:50.956] Build status: STARTED
[21:16:50.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:32:0) after 9m55s
[21:26:45.990] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:32:0)
[21:26:46.012] Build status: STARTED
[21:26:46.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:33:0) after 4m30s
[21:31:16.031] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:33:0)
[21:31:16.056] Build status: STARTED
[21:31:16.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-siso:8907389584697190576:34:0) after 3m13s
[21:33:03.633] Received PubSub notification, asking Buildbucket for the build status
[21:33:03.655] Build:
{
"id": "8741959522791628161",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-siso"
},
"number": 1558,
"createdBy": "project:chromium",
"createTime": "2024-07-19T18:45:50.258041325Z",
"startTime": "2024-07-19T18:45:56.200552865Z",
"endTime": "2024-07-19T21:33:03.317255094Z",
"updateTime": "2024-07-19T21:33:03.317255094Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "88a2af96006074cb62ac8673c5befb6a7fe37b29",
"ref": "refs/heads/main"
}
}
}
[21:33:03.655] Invocation finished in 2h47m14.597642172s with status SUCCEEDED