[02:07:00.117] New invocation is queued and will start shortly
[02:07:01.254] Starting the invocation (attempt 1)
[02:07:01.288] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f20994f701754c1809642d94e43d9d0c1bd27c1"
[02:07:01.288] Popped gitiles commit info from properties and tags
[02:07:01.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:07:01.289] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:07:01.289] Buildbucket request:
{
"requestId": "9008921027132451584",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-bfcache-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4f20994f701754c1809642d94e43d9d0c1bd27c1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9008921027132451584"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-bfcache-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:07:03.107] Scheduled build:
{
"id": "8843490965064285856",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-bfcache-rel"
},
"number": 9920,
"createdBy": "project:chromium",
"createTime": "2021-06-25T02:07:01.455311Z",
"updateTime": "2021-06-25T02:07:02.667936Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4f20994f701754c1809642d94e43d9d0c1bd27c1",
"ref": "refs/heads/main"
}
}
}
[02:07:03.108] Task URL: https://cr-buildbucket.appspot.com/build/8843490965064285856
[02:07:03.108] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:2:0) after 3m16s
[02:07:47.277] Received PubSub notification, asking Buildbucket for the build status
[02:07:47.309] Build status: STARTED
[02:10:19.137] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:2:0)
[02:10:19.174] Build status: STARTED
[02:10:19.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:4:0) after 2m27s
[02:12:46.191] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:4:0)
[02:12:46.222] Build status: STARTED
[02:12:46.222] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:5:0) after 2m50s
[02:15:36.326] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:5:0)
[02:15:36.353] Build status: STARTED
[02:15:36.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:6:0) after 2m56s
[02:18:32.561] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:6:0)
[02:18:32.613] Build status: STARTED
[02:18:32.613] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:7:0) after 2m14s
[02:20:46.811] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:7:0)
[02:20:46.840] Build status: STARTED
[02:20:46.840] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:8:0) after 7m34s
[02:28:20.943] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:8:0)
[02:28:20.973] Build status: STARTED
[02:28:20.973] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:9:0) after 1m57s
[02:30:18.004] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:9:0)
[02:30:18.036] Build status: STARTED
[02:30:18.036] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:10:0) after 3m47s
[02:34:05.224] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:10:0)
[02:34:05.254] Build status: STARTED
[02:34:05.254] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:11:0) after 2m28s
[02:36:33.276] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:11:0)
[02:36:33.308] Build status: STARTED
[02:36:33.309] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:12:0) after 4m34s
[02:41:07.314] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:12:0)
[02:41:07.352] Build status: STARTED
[02:41:07.352] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:13:0) after 5m8s
[02:46:15.374] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:13:0)
[02:46:15.441] Build status: STARTED
[02:46:15.441] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:14:0) after 6m14s
[02:52:29.471] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:14:0)
[02:52:29.506] Build status: STARTED
[02:52:29.506] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:15:0) after 6m31s
[02:59:00.682] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:15:0)
[02:59:00.716] Build status: STARTED
[02:59:00.716] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:16:0) after 8m26s
[03:07:26.760] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:16:0)
[03:07:26.799] Build status: STARTED
[03:07:26.799] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:17:0) after 4m56s
[03:12:22.834] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:17:0)
[03:12:22.872] Build status: STARTED
[03:12:22.872] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:18:0) after 9m11s
[03:21:34.087] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:18:0)
[03:21:34.114] Build status: STARTED
[03:21:34.114] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:19:0) after 1m59s
[03:23:33.147] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:19:0)
[03:23:33.179] Build status: STARTED
[03:23:33.179] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:20:0) after 4m38s
[03:28:11.314] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:20:0)
[03:28:11.358] Build status: STARTED
[03:28:11.358] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:21:0) after 3m12s
[03:31:23.378] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:21:0)
[03:31:23.456] Build status: STARTED
[03:31:23.456] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:22:0) after 1m44s
[03:33:07.473] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:22:0)
[03:33:07.513] Build status: STARTED
[03:33:07.513] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:23:0) after 8m2s
[03:41:09.579] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:23:0)
[03:41:09.630] Build status: STARTED
[03:41:09.630] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:24:0) after 7m59s
[03:49:08.679] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:24:0)
[03:49:08.756] Build status: STARTED
[03:49:08.756] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:25:0) after 9m54s
[03:59:02.781] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:25:0)
[03:59:02.837] Build status: STARTED
[03:59:02.837] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:26:0) after 5m32s
[04:04:34.896] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:26:0)
[04:04:34.930] Build status: STARTED
[04:04:34.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9008921027132451584:27:0) after 5m52s
[04:06:39.228] Received PubSub notification, asking Buildbucket for the build status
[04:06:39.262] Build:
{
"id": "8843490965064285856",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-bfcache-rel"
},
"number": 9920,
"createdBy": "project:chromium",
"createTime": "2021-06-25T02:07:01.455311Z",
"startTime": "2021-06-25T02:07:46.655218Z",
"endTime": "2021-06-25T04:06:38.914853102Z",
"updateTime": "2021-06-25T04:06:38.915338848Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4f20994f701754c1809642d94e43d9d0c1bd27c1",
"ref": "refs/heads/main"
}
}
}
[04:06:39.262] Invocation finished in 1h59m39.162980117s with status FAILED