[08:18:50.204] New invocation is queued and will start shortly
[08:18:51.709] Starting the invocation (attempt 1)
[08:18:51.734] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f19239c7e458957734584ec99652d6afeed6b53"
[08:18:51.734] Popped gitiles commit info from properties and tags
[08:18:51.734] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:18:51.734] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:18:51.734] Buildbucket request:
{
"requestId": "8885866952933892400",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f19239c7e458957734584ec99652d6afeed6b53",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8885866952933892400"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux Viz"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:18:52.392] Scheduled build:
{
"id": "8720436890616948897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 45536,
"createdBy": "project:chromium",
"createTime": "2025-03-14T08:18:51.785409393Z",
"updateTime": "2025-03-14T08:18:51.785409393Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f19239c7e458957734584ec99652d6afeed6b53",
"ref": "refs/heads/main"
}
}
}
[08:18:52.392] Task URL: https://cr-buildbucket.appspot.com/build/8720436890616948897
[08:18:52.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:2:0) after 2m57s
[08:18:53.811] Received PubSub notification, asking Buildbucket for the build status
[08:18:53.845] Build status: SCHEDULED
[08:21:49.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:2:0)
[08:21:49.453] Build status: SCHEDULED
[08:21:49.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:4:0) after 7m56s
[08:24:42.633] Received PubSub notification, asking Buildbucket for the build status
[08:24:42.655] Build status: STARTED
[08:29:45.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:4:0)
[08:29:45.747] Build status: STARTED
[08:29:45.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:6:0) after 4m59s
[08:34:45.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:6:0)
[08:34:45.048] Build status: STARTED
[08:34:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:7:0) after 7m53s
[08:42:38.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:7:0)
[08:42:38.044] Build status: STARTED
[08:42:38.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:8:0) after 1m8s
[08:43:46.070] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:8:0)
[08:43:46.098] Build status: STARTED
[08:43:46.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:9:0) after 5m48s
[08:49:34.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:9:0)
[08:49:34.139] Build status: STARTED
[08:49:34.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:10:0) after 8m48s
[08:58:22.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:10:0)
[08:58:22.151] Build status: STARTED
[08:58:22.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:11:0) after 4m33s
[09:02:55.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:11:0)
[09:02:55.208] Build status: STARTED
[09:02:55.208] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:12:0) after 5m17s
[09:08:12.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:12:0)
[09:08:12.257] Build status: STARTED
[09:08:12.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:13:0) after 4m35s
[09:12:47.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:13:0)
[09:12:47.342] Build status: STARTED
[09:12:47.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:14:0) after 5m58s
[09:18:45.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:14:0)
[09:18:45.479] Build status: STARTED
[09:18:45.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:15:0) after 8m46s
[09:27:31.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:15:0)
[09:27:31.541] Build status: STARTED
[09:27:31.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:16:0) after 3m2s
[09:30:33.665] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:16:0)
[09:30:33.680] Build status: STARTED
[09:30:33.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:17:0) after 7m31s
[09:38:04.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:17:0)
[09:38:04.845] Build status: STARTED
[09:38:04.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:18:0) after 5m24s
[09:43:28.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:18:0)
[09:43:28.890] Build status: STARTED
[09:43:28.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:19:0) after 4m33s
[09:48:02.118] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:19:0)
[09:48:02.134] Build status: STARTED
[09:48:02.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:20:0) after 1m10s
[09:49:12.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:20:0)
[09:49:12.175] Build status: STARTED
[09:49:12.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:21:0) after 5m37s
[09:54:49.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:21:0)
[09:54:49.334] Build status: STARTED
[09:54:49.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:22:0) after 2m7s
[09:56:56.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:22:0)
[09:56:56.376] Build status: STARTED
[09:56:56.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:23:0) after 3m5s
[10:00:01.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:23:0)
[10:00:01.419] Build status: STARTED
[10:00:01.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:24:0) after 8m32s
[10:08:33.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:24:0)
[10:08:33.515] Build status: STARTED
[10:08:33.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:25:0) after 5m43s
[10:14:16.772] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:25:0)
[10:14:16.793] Build status: STARTED
[10:14:16.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:26:0) after 3m40s
[10:17:56.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:26:0)
[10:17:56.859] Build status: STARTED
[10:17:56.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:27:0) after 4m0s
[10:21:57.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:27:0)
[10:21:57.031] Build status: STARTED
[10:21:57.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:28:0) after 8m38s
[10:30:35.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:28:0)
[10:30:35.301] Build status: STARTED
[10:30:35.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:29:0) after 2m8s
[10:32:43.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:29:0)
[10:32:43.442] Build status: STARTED
[10:32:43.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:30:0) after 7m0s
[10:39:43.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:30:0)
[10:39:43.487] Build status: STARTED
[10:39:43.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:31:0) after 5m22s
[10:45:05.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:31:0)
[10:45:05.628] Build status: STARTED
[10:45:05.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:32:0) after 6m13s
[10:51:18.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:32:0)
[10:51:18.701] Build status: STARTED
[10:51:18.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:33:0) after 7m8s
[10:58:26.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:33:0)
[10:58:26.749] Build status: STARTED
[10:58:26.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:34:0) after 6m32s
[11:04:58.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:34:0)
[11:04:58.791] Build status: STARTED
[11:04:58.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:35:0) after 3m32s
[11:08:30.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:35:0)
[11:08:30.860] Build status: STARTED
[11:08:30.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:36:0) after 4m56s
[11:13:26.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:36:0)
[11:13:26.957] Build status: STARTED
[11:13:26.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8885866952933892400:37:0) after 7m40s
[11:20:36.828] Received PubSub notification, asking Buildbucket for the build status
[11:20:36.846] Build:
{
"id": "8720436890616948897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 45536,
"createdBy": "project:chromium",
"createTime": "2025-03-14T08:18:51.785409393Z",
"startTime": "2025-03-14T08:24:42.418929719Z",
"endTime": "2025-03-14T11:20:36.488588101Z",
"updateTime": "2025-03-14T11:20:36.488588101Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f19239c7e458957734584ec99652d6afeed6b53",
"ref": "refs/heads/main"
}
}
}
[11:20:36.846] Invocation finished in 3h1m46.65590795s with status FAILED