[08:08:20.389] New invocation is queued and will start shortly
[08:08:21.458] Starting the invocation (attempt 1)
[08:08:21.494] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b3aaf564e71e9d0828dc9133d39067cbf43cd40"
[08:08:21.494] Popped gitiles commit info from properties and tags
[08:08:21.494] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:08:21.494] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:08:21.494] Buildbucket request:
{
"requestId": "8995942927520241648",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0b3aaf564e71e9d0828dc9133d39067cbf43cd40",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8995942927520241648"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-arm64-proguard-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:08:21.959] Scheduled build:
{
"id": "8830512865626833345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"number": 5634,
"createdBy": "project:chromium",
"createTime": "2021-11-15T08:08:21.564374758Z",
"updateTime": "2021-11-15T08:08:21.564374758Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0b3aaf564e71e9d0828dc9133d39067cbf43cd40",
"ref": "refs/heads/main"
}
}
}
[08:08:21.959] Task URL: https://cr-buildbucket.appspot.com/build/8830512865626833345
[08:08:21.959] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:2:0) after 6m33s
[08:13:02.895] Received PubSub notification, asking Buildbucket for the build status
[08:13:02.931] Build status: STARTED
[08:14:55.020] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:2:0)
[08:14:55.046] Build status: STARTED
[08:14:55.046] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:4:0) after 4m3s
[08:18:58.069] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:4:0)
[08:18:58.135] Build status: STARTED
[08:18:58.135] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:5:0) after 4m47s
[08:23:45.153] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:5:0)
[08:23:45.298] Build status: STARTED
[08:23:45.298] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:6:0) after 4m36s
[08:28:21.416] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:6:0)
[08:28:21.453] Build status: STARTED
[08:28:21.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:7:0) after 5m33s
[08:33:54.473] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:7:0)
[08:33:54.864] Build status: STARTED
[08:33:54.864] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:8:0) after 7m18s
[08:41:12.958] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:8:0)
[08:41:13.007] Build status: STARTED
[08:41:13.008] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:9:0) after 4m6s
[08:45:19.130] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:9:0)
[08:45:19.161] Build status: STARTED
[08:45:19.161] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:10:0) after 9m58s
[08:55:17.187] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:10:0)
[08:55:17.223] Build status: STARTED
[08:55:17.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:11:0) after 6m37s
[09:01:54.248] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:11:0)
[09:01:54.283] Build status: STARTED
[09:01:54.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:12:0) after 9m5s
[09:10:59.379] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:12:0)
[09:10:59.411] Build status: STARTED
[09:10:59.411] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:13:0) after 5m56s
[09:16:55.529] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:13:0)
[09:16:55.559] Build status: STARTED
[09:16:55.559] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:14:0) after 3m58s
[09:20:53.600] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:14:0)
[09:20:53.631] Build status: STARTED
[09:20:53.631] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:15:0) after 4m59s
[09:25:52.655] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:15:0)
[09:25:52.680] Build status: STARTED
[09:25:52.680] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:16:0) after 7m24s
[09:33:16.862] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:16:0)
[09:33:16.899] Build status: STARTED
[09:33:16.899] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:17:0) after 3m45s
[09:37:02.034] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:17:0)
[09:37:02.071] Build status: STARTED
[09:37:02.071] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:18:0) after 8m37s
[09:45:39.159] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:18:0)
[09:45:39.243] Build status: STARTED
[09:45:39.243] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:19:0) after 3m59s
[09:49:38.822] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:19:0)
[09:49:38.850] Build status: STARTED
[09:49:38.850] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:20:0) after 6m58s
[09:56:36.875] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:20:0)
[09:56:36.908] Build status: STARTED
[09:56:36.908] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:21:0) after 1m13s
[09:57:49.927] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:21:0)
[09:57:49.955] Build status: STARTED
[09:57:49.955] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:22:0) after 9m51s
[10:07:40.976] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:22:0)
[10:07:41.009] Build status: STARTED
[10:07:41.009] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:23:0) after 7m22s
[10:15:03.040] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:23:0)
[10:15:03.158] Build status: STARTED
[10:15:03.158] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:24:0) after 9m29s
[10:24:32.181] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:24:0)
[10:24:32.215] Build status: STARTED
[10:24:32.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:25:0) after 9m58s
[10:34:30.374] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:25:0)
[10:34:30.402] Build status: STARTED
[10:34:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:26:0) after 6m22s
[10:40:52.492] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:26:0)
[10:40:52.521] Build status: STARTED
[10:40:52.521] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:27:0) after 5m30s
[10:46:22.497] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:27:0)
[10:46:22.543] Build status: STARTED
[10:46:22.543] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:28:0) after 8m13s
[10:54:35.801] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:28:0)
[10:54:35.842] Build status: STARTED
[10:54:35.842] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:29:0) after 1m39s
[10:56:14.866] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:29:0)
[10:56:14.923] Build status: STARTED
[10:56:14.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:30:0) after 1m39s
[10:57:53.943] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:30:0)
[10:57:53.979] Build status: STARTED
[10:57:53.979] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:31:0) after 5m9s
[11:03:03.167] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:31:0)
[11:03:03.198] Build status: STARTED
[11:03:03.198] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:32:0) after 2m22s
[11:05:25.221] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:32:0)
[11:05:25.248] Build status: STARTED
[11:05:25.248] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:33:0) after 2m28s
[11:07:53.269] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:33:0)
[11:07:53.297] Build status: STARTED
[11:07:53.297] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:34:0) after 2m21s
[11:10:14.319] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:34:0)
[11:10:14.367] Build status: STARTED
[11:10:14.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:35:0) after 1m5s
[11:11:19.511] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:35:0)
[11:11:19.539] Build status: STARTED
[11:11:19.539] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:36:0) after 7m23s
[11:18:42.563] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:36:0)
[11:18:42.591] Build status: STARTED
[11:18:42.591] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:37:0) after 1m15s
[11:19:57.698] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:37:0)
[11:19:57.730] Build status: STARTED
[11:19:57.730] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:38:0) after 1m8s
[11:21:05.751] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:38:0)
[11:21:06.004] Build status: STARTED
[11:21:06.004] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:39:0) after 4m34s
[11:25:40.041] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:39:0)
[11:25:40.074] Build status: STARTED
[11:25:40.074] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:40:0) after 7m1s
[11:32:41.262] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:40:0)
[11:32:41.289] Build status: STARTED
[11:32:41.289] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:41:0) after 7m1s
[11:39:42.333] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:41:0)
[11:39:42.373] Build status: STARTED
[11:39:42.373] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:42:0) after 4m8s
[11:43:50.383] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:42:0)
[11:43:50.437] Build status: STARTED
[11:43:50.437] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:43:0) after 6m18s
[11:50:08.463] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:43:0)
[11:50:08.495] Build status: STARTED
[11:50:08.495] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:44:0) after 6m47s
[11:56:55.540] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:44:0)
[11:56:55.594] Build status: STARTED
[11:56:55.594] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:45:0) after 6m54s
[12:03:49.718] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:45:0)
[12:03:49.747] Build status: STARTED
[12:03:49.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:46:0) after 8m58s
[12:12:47.823] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:46:0)
[12:12:47.854] Build status: STARTED
[12:12:47.854] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:47:0) after 9m13s
[12:22:00.891] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:47:0)
[12:22:00.925] Build status: STARTED
[12:22:00.925] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:48:0) after 4m59s
[12:26:59.946] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:48:0)
[12:26:59.978] Build status: STARTED
[12:26:59.978] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:49:0) after 8m54s
[12:35:54.001] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:49:0)
[12:35:54.034] Build status: STARTED
[12:35:54.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:50:0) after 7m6s
[12:43:00.057] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:50:0)
[12:43:00.095] Build status: STARTED
[12:43:00.095] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:51:0) after 4m3s
[12:47:03.313] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:51:0)
[12:47:03.350] Build status: STARTED
[12:47:03.350] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:52:0) after 9m19s
[12:56:22.484] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:52:0)
[12:56:22.517] Build status: STARTED
[12:56:22.517] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:53:0) after 3m34s
[12:59:56.614] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:53:0)
[12:59:56.676] Build status: STARTED
[12:59:56.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:54:0) after 8m21s
[13:08:17.694] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:54:0)
[13:08:17.722] Build status: STARTED
[13:08:17.722] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:55:0) after 7m43s
[13:16:00.693] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:55:0)
[13:16:00.725] Build status: STARTED
[13:16:00.725] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:56:0) after 6m15s
[13:22:15.850] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:56:0)
[13:22:15.879] Build status: STARTED
[13:22:15.879] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:57:0) after 6m16s
[13:28:32.024] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:57:0)
[13:28:32.060] Build status: STARTED
[13:28:32.060] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:58:0) after 2m31s
[13:31:03.089] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:58:0)
[13:31:03.121] Build status: STARTED
[13:31:03.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:59:0) after 6m17s
[13:37:20.249] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:59:0)
[13:37:20.275] Build status: STARTED
[13:37:20.275] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:60:0) after 4m15s
[13:41:35.307] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:60:0)
[13:41:35.338] Build status: STARTED
[13:41:35.338] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8995942927520241648:61:0) after 7m46s
[13:44:06.031] Received PubSub notification, asking Buildbucket for the build status
[13:44:06.060] Build:
{
"id": "8830512865626833345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"number": 5634,
"createdBy": "project:chromium",
"createTime": "2021-11-15T08:08:21.564374758Z",
"startTime": "2021-11-15T08:13:01.961550Z",
"endTime": "2021-11-15T13:44:05.778621579Z",
"updateTime": "2021-11-15T13:44:05.778621579Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0b3aaf564e71e9d0828dc9133d39067cbf43cd40",
"ref": "refs/heads/main"
}
}
}
[13:44:06.060] Invocation finished in 5h35m45.685033796s with status FAILED