[21:01:14.455] New invocation is queued and will start shortly
[21:01:15.642] Starting the invocation (attempt 1)
[21:01:15.680] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13a4dc6a6f5ce9ce5495385a2feec5c4bd67f8a5"
[21:01:15.680] Popped gitiles commit info from properties and tags
[21:01:15.680] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:01:15.680] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:01:15.680] Buildbucket request:
{
"requestId": "8980130428638281168",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "13a4dc6a6f5ce9ce5495385a2feec5c4bd67f8a5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8980130428638281168"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:01:16.463] Scheduled build:
{
"id": "8814700366595284417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-rel"
},
"number": 56633,
"createdBy": "project:chromium",
"createTime": "2022-05-08T21:01:15.769396246Z",
"updateTime": "2022-05-08T21:01:15.769396246Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "13a4dc6a6f5ce9ce5495385a2feec5c4bd67f8a5",
"ref": "refs/heads/main"
}
}
}
[21:01:16.463] Task URL: https://cr-buildbucket.appspot.com/build/8814700366595284417
[21:01:16.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:2:0) after 2m40s
[21:03:56.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:2:0)
[21:03:56.551] Build status: SCHEDULED
[21:03:56.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:3:0) after 5m3s
[21:08:59.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:3:0)
[21:08:59.992] Build status: SCHEDULED
[21:08:59.992] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:4:0) after 3m38s
[21:12:38.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:4:0)
[21:12:38.799] Build status: SCHEDULED
[21:12:38.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:5:0) after 4m30s
[21:17:09.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:5:0)
[21:17:09.296] Build status: SCHEDULED
[21:17:09.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:6:0) after 8m50s
[21:25:59.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:6:0)
[21:25:59.466] Build status: SCHEDULED
[21:25:59.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:7:0) after 3m24s
[21:29:23.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:7:0)
[21:29:23.541] Build status: SCHEDULED
[21:29:23.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:8:0) after 3m39s
[21:32:12.139] Received PubSub notification, asking Buildbucket for the build status
[21:32:12.161] Build status: STARTED
[21:33:02.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:8:0)
[21:33:02.577] Build status: STARTED
[21:33:02.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:10:0) after 1m53s
[21:34:55.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:10:0)
[21:34:55.635] Build status: STARTED
[21:34:55.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:11:0) after 8m2s
[21:42:57.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:11:0)
[21:42:57.698] Build status: STARTED
[21:42:57.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:12:0) after 8m11s
[21:51:08.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:12:0)
[21:51:08.839] Build status: STARTED
[21:51:08.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:13:0) after 9m44s
[22:00:53.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:13:0)
[22:00:53.530] Build status: STARTED
[22:00:53.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:14:0) after 3m0s
[22:03:53.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:14:0)
[22:03:53.581] Build status: STARTED
[22:03:53.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:15:0) after 8m29s
[22:12:22.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:15:0)
[22:12:22.643] Build status: STARTED
[22:12:22.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:16:0) after 4m16s
[22:16:39.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:16:0)
[22:16:39.141] Build status: STARTED
[22:16:39.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:17:0) after 6m51s
[22:23:30.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:17:0)
[22:23:30.237] Build status: STARTED
[22:23:30.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:18:0) after 2m52s
[22:26:22.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:18:0)
[22:26:22.279] Build status: STARTED
[22:26:22.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:19:0) after 7m8s
[22:33:30.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:19:0)
[22:33:30.346] Build status: STARTED
[22:33:30.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:20:0) after 6m41s
[22:40:11.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:20:0)
[22:40:11.389] Build status: STARTED
[22:40:11.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:21:0) after 4m4s
[22:44:15.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:21:0)
[22:44:15.424] Build status: STARTED
[22:44:15.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:22:0) after 4m16s
[22:48:31.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:22:0)
[22:48:31.478] Build status: STARTED
[22:48:31.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:23:0) after 2m53s
[22:51:24.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:23:0)
[22:51:24.529] Build status: STARTED
[22:51:24.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:24:0) after 5m57s
[22:57:21.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:24:0)
[22:57:21.573] Build status: STARTED
[22:57:21.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:25:0) after 1m5s
[22:58:26.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:25:0)
[22:58:26.879] Build status: STARTED
[22:58:26.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:26:0) after 8m51s
[23:07:18.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:26:0)
[23:07:18.329] Build status: STARTED
[23:07:18.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:27:0) after 6m12s
[23:13:30.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:27:0)
[23:13:30.375] Build status: STARTED
[23:13:30.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:8980130428638281168:28:0) after 9m58s
[23:23:10.567] Received PubSub notification, asking Buildbucket for the build status
[23:23:10.602] Build:
{
"id": "8814700366595284417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-rel"
},
"number": 56633,
"createdBy": "project:chromium",
"createTime": "2022-05-08T21:01:15.769396246Z",
"startTime": "2022-05-08T21:32:11.361684Z",
"endTime": "2022-05-08T23:23:09.817746531Z",
"updateTime": "2022-05-08T23:23:09.817746531Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "13a4dc6a6f5ce9ce5495385a2feec5c4bd67f8a5",
"ref": "refs/heads/main"
}
}
}
[23:23:10.603] Invocation finished in 2h21m56.165325724s with status SUCCEEDED