[22:29:40.676] New invocation is queued and will start shortly
[22:29:41.965] Starting the invocation (attempt 1)
[22:29:42.001] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b8281f938fe747823da0518657cbcbf9b8dd99d5"
[22:29:42.001] Popped gitiles commit info from properties and tags
[22:29:42.001] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:29:42.001] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:29:42.001] Buildbucket request:
{
"requestId": "9012286787731804608",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8281f938fe747823da0518657cbcbf9b8dd99d5",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9012286787731804608"
},
{
"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"
}
}
[22:29:43.096] Scheduled build:
{
"id": "8846856725549049104",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"number": 4408,
"createdBy": "project:chromium",
"createTime": "2021-05-18T22:29:42.125632Z",
"updateTime": "2021-05-18T22:29:42.952269Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8281f938fe747823da0518657cbcbf9b8dd99d5",
"ref": "refs/heads/master"
}
}
}
[22:29:43.097] Task URL: https://cr-buildbucket.appspot.com/build/8846856725549049104
[22:29:43.097] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:2:0) after 9m57s
[22:39:40.218] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:2:0)
[22:39:40.353] Build status: SCHEDULED
[22:39:40.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:3:0) after 7m46s
[22:43:45.676] Received PubSub notification, asking Buildbucket for the build status
[22:43:45.740] Build status: STARTED
[22:47:26.426] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:3:0)
[22:47:26.453] Build status: STARTED
[22:47:26.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:5:0) after 3m57s
[22:51:23.488] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:5:0)
[22:51:23.520] Build status: STARTED
[22:51:23.520] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:6:0) after 2m59s
[22:54:22.551] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:6:0)
[22:54:22.632] Build status: STARTED
[22:54:22.632] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:7:0) after 8m18s
[23:02:40.858] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:7:0)
[23:02:40.888] Build status: STARTED
[23:02:40.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:8:0) after 7m21s
[23:10:01.916] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:8:0)
[23:10:01.968] Build status: STARTED
[23:10:01.968] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:9:0) after 8m39s
[23:18:40.990] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:9:0)
[23:18:41.019] Build status: STARTED
[23:18:41.019] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:10:0) after 5m46s
[23:24:27.075] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:10:0)
[23:24:27.128] Build status: STARTED
[23:24:27.128] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:11:0) after 3m21s
[23:27:48.332] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:11:0)
[23:27:48.404] Build status: STARTED
[23:27:48.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:12:0) after 4m17s
[23:32:05.417] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:12:0)
[23:32:05.468] Build status: STARTED
[23:32:05.468] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:13:0) after 1m59s
[23:34:04.647] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:13:0)
[23:34:04.674] Build status: STARTED
[23:34:04.674] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:14:0) after 9m11s
[23:43:15.815] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:14:0)
[23:43:15.845] Build status: STARTED
[23:43:15.845] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:15:0) after 5m54s
[23:49:09.885] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:15:0)
[23:49:10.009] Build status: STARTED
[23:49:10.009] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:16:0) after 7m49s
[23:56:59.029] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:16:0)
[23:56:59.055] Build status: STARTED
[23:56:59.055] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:17:0) after 7m41s
[00:04:40.624] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:17:0)
[00:04:40.655] Build status: STARTED
[00:04:40.655] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:18:0) after 4m33s
[00:09:13.686] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:18:0)
[00:09:13.712] Build status: STARTED
[00:09:13.712] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:19:0) after 7m10s
[00:16:23.744] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:19:0)
[00:16:23.778] Build status: STARTED
[00:16:23.778] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:20:0) after 4m0s
[00:20:24.048] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:20:0)
[00:20:24.082] Build status: STARTED
[00:20:24.082] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:21:0) after 7m33s
[00:27:57.270] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:21:0)
[00:27:57.298] Build status: STARTED
[00:27:57.298] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:22:0) after 1m55s
[00:29:52.316] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:22:0)
[00:29:52.346] Build status: STARTED
[00:29:52.346] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:23:0) after 9m26s
[00:39:18.622] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:23:0)
[00:39:18.650] Build status: STARTED
[00:39:18.650] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:24:0) after 4m17s
[00:43:35.824] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:24:0)
[00:43:35.867] Build status: STARTED
[00:43:35.867] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:25:0) after 5m44s
[00:49:19.920] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:25:0)
[00:49:19.955] Build status: STARTED
[00:49:19.956] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:26:0) after 5m9s
[00:54:29.110] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:26:0)
[00:54:29.223] Build status: STARTED
[00:54:29.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:27:0) after 6m9s
[01:00:38.388] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:27:0)
[01:00:38.419] Build status: STARTED
[01:00:38.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:28:0) after 7m26s
[01:08:04.528] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:28:0)
[01:08:04.565] Build status: STARTED
[01:08:04.565] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:29:0) after 3m32s
[01:11:36.855] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:29:0)
[01:11:36.888] Build status: STARTED
[01:11:36.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:30:0) after 6m1s
[01:17:37.912] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:30:0)
[01:17:37.946] Build status: STARTED
[01:17:37.946] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:31:0) after 9m41s
[01:27:19.059] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:31:0)
[01:27:19.090] Build status: STARTED
[01:27:19.090] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:32:0) after 1m44s
[01:29:03.115] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:32:0)
[01:29:03.142] Build status: STARTED
[01:29:03.142] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:33:0) after 9m52s
[01:38:55.171] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:33:0)
[01:38:55.215] Build status: STARTED
[01:38:55.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:34:0) after 5m3s
[01:43:58.278] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:34:0)
[01:43:58.305] Build status: STARTED
[01:43:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:35:0) after 5m7s
[01:49:05.429] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:35:0)
[01:49:05.458] Build status: STARTED
[01:49:05.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:36:0) after 2m46s
[01:51:51.485] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:36:0)
[01:51:51.531] Build status: STARTED
[01:51:51.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:37:0) after 1m48s
[01:53:39.558] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:37:0)
[01:53:39.595] Build status: STARTED
[01:53:39.595] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:38:0) after 3m34s
[01:57:13.624] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:38:0)
[01:57:13.652] Build status: STARTED
[01:57:13.652] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:39:0) after 4m4s
[02:01:17.732] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:39:0)
[02:01:17.767] Build status: STARTED
[02:01:17.767] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:40:0) after 4m35s
[02:05:52.799] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:40:0)
[02:05:52.837] Build status: STARTED
[02:05:52.837] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:41:0) after 6m32s
[02:12:24.928] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:41:0)
[02:12:24.992] Build status: STARTED
[02:12:24.992] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:42:0) after 3m21s
[02:15:46.112] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:42:0)
[02:15:46.147] Build status: STARTED
[02:15:46.147] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:43:0) after 9m44s
[02:25:30.177] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:43:0)
[02:25:30.202] Build status: STARTED
[02:25:30.203] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:44:0) after 6m45s
[02:32:15.269] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:44:0)
[02:32:15.296] Build status: STARTED
[02:32:15.296] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:45:0) after 4m12s
[02:36:27.439] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:45:0)
[02:36:27.467] Build status: STARTED
[02:36:27.467] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:46:0) after 1m29s
[02:37:56.584] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:46:0)
[02:37:56.640] Build status: STARTED
[02:37:56.640] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:47:0) after 6m26s
[02:44:22.672] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:47:0)
[02:44:22.700] Build status: STARTED
[02:44:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:48:0) after 7m53s
[02:52:15.730] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:48:0)
[02:52:15.766] Build status: STARTED
[02:52:15.766] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:49:0) after 7m56s
[03:00:11.840] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:49:0)
[03:00:11.870] Build status: STARTED
[03:00:11.870] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:50:0) after 7m46s
[03:07:57.896] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:50:0)
[03:07:57.936] Build status: STARTED
[03:07:57.936] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:51:0) after 5m1s
[03:12:59.127] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:51:0)
[03:12:59.160] Build status: STARTED
[03:12:59.160] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:52:0) after 1m32s
[03:14:31.157] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:52:0)
[03:14:31.192] Build status: STARTED
[03:14:31.192] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:53:0) after 6m17s
[03:20:48.239] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:53:0)
[03:20:48.269] Build status: STARTED
[03:20:48.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:9012286787731804608:54:0) after 6m8s
[03:26:21.875] Received PubSub notification, asking Buildbucket for the build status
[03:26:21.904] Build:
{
"id": "8846856725549049104",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"number": 4408,
"createdBy": "project:chromium",
"createTime": "2021-05-18T22:29:42.125632Z",
"startTime": "2021-05-18T22:43:45.078335Z",
"endTime": "2021-05-19T03:26:21.345249649Z",
"updateTime": "2021-05-19T03:26:21.345642232Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8281f938fe747823da0518657cbcbf9b8dd99d5",
"ref": "refs/heads/master"
}
}
}
[03:26:21.904] Invocation finished in 4h56m41.243473357s with status FAILED