[14:28:17.160] New invocation is queued and will start shortly
[14:28:18.256] Starting the invocation (attempt 1)
[14:28:18.280] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34224bb99f8960b69d48894e5b726132d32bc124"
[14:28:18.280] Popped gitiles commit info from properties and tags
[14:28:18.280] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:28:18.280] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:28:18.280] Buildbucket request:
{
"requestId": "8869626852203779024",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-10-x86-fyi-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34224bb99f8960b69d48894e5b726132d32bc124",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8869626852203779024"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-10-x86-fyi-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:28:18.841] Scheduled build:
{
"id": "8704196790316261553",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-10-x86-fyi-rel"
},
"number": 3970,
"createdBy": "project:chromium",
"createTime": "2025-09-09T14:28:18.337705902Z",
"updateTime": "2025-09-09T14:28:18.337705902Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34224bb99f8960b69d48894e5b726132d32bc124",
"ref": "refs/heads/main"
}
}
}
[14:28:18.841] Task URL: https://cr-buildbucket.appspot.com/build/8704196790316261553
[14:28:18.841] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:2:0) after 8m45s
[14:28:19.760] Received PubSub notification, asking Buildbucket for the build status
[14:28:19.814] Build status: SCHEDULED
[14:29:17.095] Received PubSub notification, asking Buildbucket for the build status
[14:29:17.110] Build status: STARTED
[14:37:03.977] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:2:0)
[14:37:04.030] Build status: STARTED
[14:37:04.031] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:5:0) after 3m32s
[14:40:36.051] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:5:0)
[14:40:36.070] Build status: STARTED
[14:40:36.071] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:6:0) after 7m22s
[14:47:58.134] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:6:0)
[14:47:58.148] Build status: STARTED
[14:47:58.148] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:7:0) after 8m43s
[14:56:41.139] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:7:0)
[14:56:41.155] Build status: STARTED
[14:56:41.155] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:8:0) after 2m59s
[14:59:40.172] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:8:0)
[14:59:40.189] Build status: STARTED
[14:59:40.189] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:9:0) after 1m55s
[15:01:35.214] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:9:0)
[15:01:35.231] Build status: STARTED
[15:01:35.231] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:10:0) after 9m50s
[15:11:25.466] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:10:0)
[15:11:25.483] Build status: STARTED
[15:11:25.483] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:11:0) after 9m11s
[15:20:36.545] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:11:0)
[15:20:36.573] Build status: STARTED
[15:20:36.573] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:12:0) after 4m5s
[15:24:41.632] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:12:0)
[15:24:41.657] Build status: STARTED
[15:24:41.657] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:13:0) after 5m46s
[15:30:27.685] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:13:0)
[15:30:27.701] Build status: STARTED
[15:30:27.701] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:14:0) after 6m30s
[15:36:57.800] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:14:0)
[15:36:57.826] Build status: STARTED
[15:36:57.826] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:15:0) after 1m49s
[15:38:46.847] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:15:0)
[15:38:46.865] Build status: STARTED
[15:38:46.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:16:0) after 6m5s
[15:44:51.898] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:16:0)
[15:44:51.919] Build status: STARTED
[15:44:51.919] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:17:0) after 5m11s
[15:50:03.038] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:17:0)
[15:50:03.054] Build status: STARTED
[15:50:03.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:18:0) after 2m48s
[15:52:51.090] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:18:0)
[15:52:51.112] Build status: STARTED
[15:52:51.112] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:19:0) after 9m51s
[16:02:42.230] Handling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:19:0)
[16:02:42.259] Build status: STARTED
[16:02:42.260] Scheduling timer "check-buildbucket-build-status" (chromium/android-10-x86-fyi-rel:8869626852203779024:20:0) after 1m5s
[16:03:14.622] Received PubSub notification, asking Buildbucket for the build status
[16:03:14.636] Build:
{
"id": "8704196790316261553",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-10-x86-fyi-rel"
},
"number": 3970,
"createdBy": "project:chromium",
"createTime": "2025-09-09T14:28:18.337705902Z",
"startTime": "2025-09-09T14:29:16.897388497Z",
"endTime": "2025-09-09T16:03:14.329145888Z",
"updateTime": "2025-09-09T16:03:14.329145888Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34224bb99f8960b69d48894e5b726132d32bc124",
"ref": "refs/heads/main"
}
}
}
[16:03:14.636] Invocation finished in 1h34m57.482099244s with status FAILED