[01:12:51.456] New invocation is queued and will start shortly
[01:12:52.571] Starting the invocation (attempt 1)
[01:12:52.622] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f73b7d6b0f7ea96bf36cd1da3942554ba1f49ac7"
[01:12:52.622] Popped gitiles commit info from properties and tags
[01:12:52.622] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:12:52.622] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:12:52.623] Buildbucket request:
{
"requestId": "8904466131326132736",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-ninja"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f73b7d6b0f7ea96bf36cd1da3942554ba1f49ac7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904466131326132736"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-build-perf-ninja"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:12:53.342] Scheduled build:
{
"id": "8739036069374228257",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-ninja"
},
"number": 2156,
"createdBy": "project:chromium",
"createTime": "2024-08-21T01:12:52.697157044Z",
"updateTime": "2024-08-21T01:12:52.697157044Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f73b7d6b0f7ea96bf36cd1da3942554ba1f49ac7",
"ref": "refs/heads/main"
}
}
}
[01:12:53.342] Task URL: https://cr-buildbucket.appspot.com/build/8739036069374228257
[01:12:53.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:2:0) after 6m56s
[01:12:54.171] Received PubSub notification, asking Buildbucket for the build status
[01:12:54.192] Build status: SCHEDULED
[01:12:58.727] Received PubSub notification, asking Buildbucket for the build status
[01:12:58.746] Build status: STARTED
[01:19:49.372] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:2:0)
[01:19:49.436] Build status: STARTED
[01:19:49.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:5:0) after 6m8s
[01:25:57.457] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:5:0)
[01:25:57.477] Build status: STARTED
[01:25:57.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:6:0) after 7m5s
[01:33:02.551] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:6:0)
[01:33:02.641] Build status: STARTED
[01:33:02.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:7:0) after 5m0s
[01:38:02.649] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:7:0)
[01:38:02.674] Build status: STARTED
[01:38:02.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:8:0) after 2m28s
[01:40:30.693] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:8:0)
[01:40:30.723] Build status: STARTED
[01:40:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:9:0) after 3m17s
[01:43:47.774] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:9:0)
[01:43:47.793] Build status: STARTED
[01:43:47.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:10:0) after 7m43s
[01:51:30.847] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:10:0)
[01:51:30.913] Build status: STARTED
[01:51:30.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:11:0) after 8m0s
[01:59:30.892] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:11:0)
[01:59:30.918] Build status: STARTED
[01:59:30.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:12:0) after 3m5s
[02:02:35.937] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:12:0)
[02:02:36.138] Build status: STARTED
[02:02:36.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:13:0) after 9m7s
[02:11:43.165] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:13:0)
[02:11:43.187] Build status: STARTED
[02:11:43.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:14:0) after 1m44s
[02:13:27.206] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:14:0)
[02:13:27.223] Build status: STARTED
[02:13:27.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:15:0) after 3m13s
[02:16:40.266] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:15:0)
[02:16:40.282] Build status: STARTED
[02:16:40.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:16:0) after 8m32s
[02:25:12.272] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:16:0)
[02:25:12.319] Build status: STARTED
[02:25:12.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:17:0) after 9m0s
[02:34:12.354] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:17:0)
[02:34:12.370] Build status: STARTED
[02:34:12.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:18:0) after 7m0s
[02:41:12.447] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:18:0)
[02:41:12.615] Build status: STARTED
[02:41:12.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:19:0) after 8m45s
[02:49:57.693] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:19:0)
[02:49:57.726] Build status: STARTED
[02:49:57.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-ninja:8904466131326132736:20:0) after 9m28s
[02:57:01.888] Received PubSub notification, asking Buildbucket for the build status
[02:57:01.921] Build:
{
"id": "8739036069374228257",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-build-perf-ninja"
},
"number": 2156,
"createdBy": "project:chromium",
"createTime": "2024-08-21T01:12:52.697157044Z",
"startTime": "2024-08-21T01:12:58.505691263Z",
"endTime": "2024-08-21T02:57:01.569440153Z",
"updateTime": "2024-08-21T02:57:01.569440153Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f73b7d6b0f7ea96bf36cd1da3942554ba1f49ac7",
"ref": "refs/heads/main"
}
}
}
[02:57:01.921] Invocation finished in 1h44m10.468687721s with status SUCCEEDED