[14:22:06.469] New invocation is queued and will start shortly
[14:22:07.527] Starting the invocation (attempt 1)
[14:22:07.553] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99f1539217284759a6e2c698abc616fcf15550cb"
[14:22:07.553] Popped gitiles commit info from properties and tags
[14:22:07.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:22:07.553] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:22:07.554] Buildbucket request:
{
"requestId": "8917190648269890464",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-arm64-rel-cft"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "99f1539217284759a6e2c698abc616fcf15550cb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917190648269890464"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-arm64-rel-cft"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:22:08.883] Scheduled build:
{
"id": "8751760586359110929",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-arm64-rel-cft"
},
"number": 8581,
"createdBy": "project:chromium",
"createTime": "2024-04-02T14:22:07.660657299Z",
"updateTime": "2024-04-02T14:22:07.660657299Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "99f1539217284759a6e2c698abc616fcf15550cb",
"ref": "refs/heads/main"
}
}
}
[14:22:08.883] Task URL: https://cr-buildbucket.appspot.com/build/8751760586359110929
[14:22:08.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:2:0) after 3m22s
[14:22:11.209] Received PubSub notification, asking Buildbucket for the build status
[14:22:11.239] Build status: SCHEDULED
[14:23:54.847] Received PubSub notification, asking Buildbucket for the build status
[14:23:54.881] Build status: STARTED
[14:25:30.958] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:2:0)
[14:25:30.981] Build status: STARTED
[14:25:30.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:5:0) after 9m1s
[14:34:32.059] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:5:0)
[14:34:32.098] Build status: STARTED
[14:34:32.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:6:0) after 4m32s
[14:39:04.182] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:6:0)
[14:39:04.223] Build status: STARTED
[14:39:04.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:7:0) after 4m39s
[14:43:43.282] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:7:0)
[14:43:43.304] Build status: STARTED
[14:43:43.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:8:0) after 2m48s
[14:46:31.323] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:8:0)
[14:46:31.348] Build status: STARTED
[14:46:31.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:9:0) after 6m24s
[14:52:55.382] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:9:0)
[14:52:55.406] Build status: STARTED
[14:52:55.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:10:0) after 6m23s
[14:59:18.434] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:10:0)
[14:59:18.495] Build status: STARTED
[14:59:18.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:11:0) after 7m38s
[15:06:56.566] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:11:0)
[15:06:56.585] Build status: STARTED
[15:06:56.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:12:0) after 8m49s
[15:15:45.612] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:12:0)
[15:15:45.643] Build status: STARTED
[15:15:45.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:13:0) after 9m45s
[15:25:30.663] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:13:0)
[15:25:30.690] Build status: STARTED
[15:25:30.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:14:0) after 2m3s
[15:27:33.718] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:14:0)
[15:27:33.741] Build status: STARTED
[15:27:33.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:15:0) after 1m34s
[15:29:07.776] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:15:0)
[15:29:07.793] Build status: STARTED
[15:29:07.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:16:0) after 7m54s
[15:37:01.794] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:16:0)
[15:37:01.815] Build status: STARTED
[15:37:01.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:17:0) after 5m23s
[15:42:24.835] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:17:0)
[15:42:24.855] Build status: STARTED
[15:42:24.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:18:0) after 7m24s
[15:49:48.934] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:18:0)
[15:49:48.967] Build status: STARTED
[15:49:48.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:19:0) after 6m21s
[15:56:10.032] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:19:0)
[15:56:10.068] Build status: STARTED
[15:56:10.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:20:0) after 4m4s
[16:00:14.157] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:20:0)
[16:00:14.179] Build status: STARTED
[16:00:14.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:21:0) after 6m11s
[16:06:25.231] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:21:0)
[16:06:25.282] Build status: STARTED
[16:06:25.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:22:0) after 1m55s
[16:08:20.331] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:22:0)
[16:08:20.377] Build status: STARTED
[16:08:20.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:23:0) after 5m13s
[16:13:33.398] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:23:0)
[16:13:33.423] Build status: STARTED
[16:13:33.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:24:0) after 9m57s
[16:23:30.450] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:24:0)
[16:23:30.474] Build status: STARTED
[16:23:30.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:25:0) after 2m20s
[16:25:50.489] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:25:0)
[16:25:50.509] Build status: STARTED
[16:25:50.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:26:0) after 5m53s
[16:31:43.528] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:26:0)
[16:31:43.550] Build status: STARTED
[16:31:43.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:27:0) after 7m28s
[16:39:11.639] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:27:0)
[16:39:11.665] Build status: STARTED
[16:39:11.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:28:0) after 3m34s
[16:42:45.685] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:28:0)
[16:42:45.707] Build status: STARTED
[16:42:45.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:29:0) after 1m41s
[16:44:26.745] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:29:0)
[16:44:26.767] Build status: STARTED
[16:44:26.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:30:0) after 9m45s
[16:54:11.796] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:30:0)
[16:54:11.813] Build status: STARTED
[16:54:11.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:31:0) after 9m44s
[17:03:56.161] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:31:0)
[17:03:56.205] Build status: STARTED
[17:03:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:32:0) after 6m47s
[17:10:43.227] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:32:0)
[17:10:43.252] Build status: STARTED
[17:10:43.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8917190648269890464:33:0) after 4m33s
[17:14:49.790] Received PubSub notification, asking Buildbucket for the build status
[17:14:49.812] Build:
{
"id": "8751760586359110929",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-arm64-rel-cft"
},
"number": 8581,
"createdBy": "project:chromium",
"createTime": "2024-04-02T14:22:07.660657299Z",
"startTime": "2024-04-02T14:23:48.251035671Z",
"endTime": "2024-04-02T17:14:49.268176586Z",
"updateTime": "2024-04-02T17:14:49.268176586Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "99f1539217284759a6e2c698abc616fcf15550cb",
"ref": "refs/heads/main"
}
}
}
[17:14:49.812] Invocation finished in 2h52m43.357687796s with status FAILED