[13:08:37.824] New invocation is queued and will start shortly
[13:08:39.723] Starting the invocation (attempt 1)
[13:08:39.773] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2"
[13:08:39.773] Popped gitiles commit info from properties and tags
[13:08:39.773] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:08:39.773] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:08:39.773] Buildbucket request:
{
"requestId": "8979254193054060016",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8979254193054060016",
"job": "r8/linux-internal_release",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/3.3",
"repo": "https://r8.googlesource.com/r8",
"revision": "1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/3.3@1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2",
"title": "1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2",
"url": "https://r8.googlesource.com/r8/+/1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2",
"ref": "refs/heads/3.3"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8979254193054060016"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal_release"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:08:40.416] Scheduled build:
{
"id": "8813824130221911761",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 653,
"createdBy": "project:r8",
"createTime": "2022-05-18T13:08:39.893872998Z",
"updateTime": "2022-05-18T13:08:39.893872998Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2",
"ref": "refs/heads/3.3"
}
}
}
[13:08:40.417] Task URL: https://cr-buildbucket.appspot.com/build/8813824130221911761
[13:08:40.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:2:0) after 2m17s
[13:10:57.430] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:2:0)
[13:10:57.472] Build status: SCHEDULED
[13:10:57.472] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:3:0) after 5m25s
[13:16:22.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:3:0)
[13:16:22.567] Build status: SCHEDULED
[13:16:22.567] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:4:0) after 7m56s
[13:24:18.804] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:4:0)
[13:24:18.841] Build status: SCHEDULED
[13:24:18.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:5:0) after 6m7s
[13:30:25.878] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:5:0)
[13:30:25.912] Build status: SCHEDULED
[13:30:25.912] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:6:0) after 9m32s
[13:39:58.027] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:6:0)
[13:39:58.065] Build status: SCHEDULED
[13:39:58.065] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:7:0) after 7m24s
[13:47:22.180] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:7:0)
[13:47:22.200] Build status: SCHEDULED
[13:47:22.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:8:0) after 7m24s
[13:54:46.222] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:8:0)
[13:54:46.250] Build status: SCHEDULED
[13:54:46.250] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:9:0) after 5m26s
[14:00:12.365] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:9:0)
[14:00:12.393] Build status: SCHEDULED
[14:00:12.393] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:10:0) after 4m30s
[14:04:42.511] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:10:0)
[14:04:42.538] Build status: SCHEDULED
[14:04:42.538] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:11:0) after 8m12s
[14:12:54.708] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:11:0)
[14:12:54.743] Build status: SCHEDULED
[14:12:54.743] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:12:0) after 1m32s
[14:14:26.736] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:12:0)
[14:14:26.772] Build status: SCHEDULED
[14:14:26.772] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:13:0) after 2m57s
[14:17:23.790] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:13:0)
[14:17:23.817] Build status: SCHEDULED
[14:17:23.817] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:14:0) after 8m8s
[14:25:31.917] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:14:0)
[14:25:31.977] Build status: SCHEDULED
[14:25:31.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:15:0) after 8m47s
[14:34:19.017] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:15:0)
[14:34:19.047] Build status: SCHEDULED
[14:34:19.047] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:16:0) after 9m35s
[14:43:54.269] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:16:0)
[14:43:54.366] Build status: SCHEDULED
[14:43:54.366] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:17:0) after 8m23s
[14:52:17.812] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:17:0)
[14:52:17.847] Build status: SCHEDULED
[14:52:17.847] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:18:0) after 1m14s
[14:53:31.863] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:18:0)
[14:53:31.894] Build status: SCHEDULED
[14:53:31.894] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:19:0) after 8m43s
[15:02:15.151] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:19:0)
[15:02:15.183] Build status: SCHEDULED
[15:02:15.183] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:20:0) after 4m5s
[15:06:20.207] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:20:0)
[15:06:20.247] Build status: SCHEDULED
[15:06:20.247] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:21:0) after 1m45s
[15:08:05.287] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:21:0)
[15:08:05.319] Build status: SCHEDULED
[15:08:05.319] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:22:0) after 1m50s
[15:09:55.483] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:22:0)
[15:09:55.519] Build status: SCHEDULED
[15:09:55.519] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:23:0) after 3m39s
[15:13:34.671] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:23:0)
[15:13:34.770] Build status: SCHEDULED
[15:13:34.770] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:24:0) after 2m51s
[15:16:25.836] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:24:0)
[15:16:25.868] Build status: SCHEDULED
[15:16:25.868] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:25:0) after 8m11s
[15:24:36.874] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:25:0)
[15:24:36.917] Build status: SCHEDULED
[15:24:36.917] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:26:0) after 9m13s
[15:33:50.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:26:0)
[15:33:50.134] Build status: SCHEDULED
[15:33:50.134] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:27:0) after 6m35s
[15:40:25.157] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:27:0)
[15:40:25.194] Build status: SCHEDULED
[15:40:25.194] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:28:0) after 8m50s
[15:49:15.217] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:28:0)
[15:49:15.244] Build status: SCHEDULED
[15:49:15.244] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:29:0) after 8m12s
[15:57:27.843] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:29:0)
[15:57:27.866] Build status: SCHEDULED
[15:57:27.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:30:0) after 5m35s
[16:03:02.919] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:30:0)
[16:03:02.952] Build status: SCHEDULED
[16:03:02.952] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:31:0) after 1m6s
[16:04:09.288] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:31:0)
[16:04:09.319] Build status: SCHEDULED
[16:04:09.319] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:32:0) after 1m17s
[16:05:26.318] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:32:0)
[16:05:26.346] Build status: SCHEDULED
[16:05:26.346] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:33:0) after 2m36s
[16:08:02.642] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:33:0)
[16:08:02.696] Build status: SCHEDULED
[16:08:02.696] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:34:0) after 2m39s
[16:10:42.037] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:34:0)
[16:10:42.059] Build status: SCHEDULED
[16:10:42.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:35:0) after 6m50s
[16:17:32.094] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:35:0)
[16:17:32.126] Build status: SCHEDULED
[16:17:32.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:36:0) after 6m19s
[16:23:51.220] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:36:0)
[16:23:51.254] Build status: SCHEDULED
[16:23:51.254] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:37:0) after 9m31s
[16:33:22.606] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:37:0)
[16:33:22.674] Build status: SCHEDULED
[16:33:22.674] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:38:0) after 9m22s
[16:42:44.793] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:38:0)
[16:42:44.828] Build status: SCHEDULED
[16:42:44.828] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:39:0) after 9m1s
[16:51:46.185] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:39:0)
[16:51:46.240] Build status: SCHEDULED
[16:51:46.240] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:40:0) after 9m59s
[17:01:45.526] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:40:0)
[17:01:45.578] Build status: SCHEDULED
[17:01:45.578] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:41:0) after 9m18s
[17:11:03.854] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:41:0)
[17:11:03.948] Build status: SCHEDULED
[17:11:03.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:42:0) after 9m4s
[17:20:08.069] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:42:0)
[17:20:08.112] Build status: SCHEDULED
[17:20:08.112] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:43:0) after 2m24s
[17:22:32.380] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:43:0)
[17:22:32.416] Build status: SCHEDULED
[17:22:32.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:44:0) after 2m54s
[17:25:26.436] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:44:0)
[17:25:26.470] Build status: SCHEDULED
[17:25:26.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:45:0) after 2m48s
[17:28:14.492] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:45:0)
[17:28:14.518] Build status: SCHEDULED
[17:28:14.518] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:46:0) after 8m18s
[17:36:32.537] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:46:0)
[17:36:32.583] Build status: SCHEDULED
[17:36:32.583] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:47:0) after 7m20s
[17:43:52.636] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:47:0)
[17:43:52.666] Build status: SCHEDULED
[17:43:52.666] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:48:0) after 5m39s
[17:49:31.686] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:48:0)
[17:49:31.740] Build status: SCHEDULED
[17:49:31.740] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:49:0) after 1m57s
[17:51:28.759] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:49:0)
[17:51:28.782] Build status: SCHEDULED
[17:51:28.782] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:50:0) after 2m26s
[17:53:54.802] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:50:0)
[17:53:54.861] Build status: SCHEDULED
[17:53:54.861] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:51:0) after 3m38s
[17:57:32.880] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:51:0)
[17:57:32.908] Build status: SCHEDULED
[17:57:32.908] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:52:0) after 6m19s
[18:03:52.330] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:52:0)
[18:03:52.368] Build status: SCHEDULED
[18:03:52.368] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:53:0) after 8m35s
[18:12:27.517] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:53:0)
[18:12:27.549] Build status: SCHEDULED
[18:12:27.549] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:54:0) after 7m56s
[18:20:23.828] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:54:0)
[18:20:23.867] Build status: SCHEDULED
[18:20:23.867] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:55:0) after 4m41s
[18:25:05.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:55:0)
[18:25:05.205] Build status: SCHEDULED
[18:25:05.205] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:56:0) after 1m18s
[18:26:23.340] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:56:0)
[18:26:23.378] Build status: SCHEDULED
[18:26:23.378] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:57:0) after 7m44s
[18:34:07.598] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:57:0)
[18:34:07.631] Build status: SCHEDULED
[18:34:07.631] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:58:0) after 8m1s
[18:42:08.664] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:58:0)
[18:42:08.697] Build status: SCHEDULED
[18:42:08.697] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:59:0) after 5m24s
[18:47:32.720] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:59:0)
[18:47:32.755] Build status: SCHEDULED
[18:47:32.755] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:60:0) after 4m54s
[18:52:26.780] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:60:0)
[18:52:26.808] Build status: SCHEDULED
[18:52:26.808] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:61:0) after 1m44s
[18:54:10.833] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:61:0)
[18:54:10.866] Build status: SCHEDULED
[18:54:10.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:62:0) after 3m30s
[18:57:40.928] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:62:0)
[18:57:40.948] Build status: SCHEDULED
[18:57:40.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:63:0) after 4m41s
[19:02:21.987] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:63:0)
[19:02:22.016] Build status: SCHEDULED
[19:02:22.016] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:64:0) after 5m30s
[19:07:52.036] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:64:0)
[19:07:52.069] Build status: SCHEDULED
[19:07:52.069] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:65:0) after 2m56s
[19:10:48.134] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:65:0)
[19:10:48.156] Build status: SCHEDULED
[19:10:48.156] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:66:0) after 7m39s
[19:18:27.211] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:66:0)
[19:18:27.471] Build status: SCHEDULED
[19:18:27.471] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:67:0) after 1m6s
[19:19:33.488] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:67:0)
[19:19:33.530] Build status: SCHEDULED
[19:19:33.530] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:68:0) after 8m15s
[19:27:48.563] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:68:0)
[19:27:48.612] Build status: SCHEDULED
[19:27:48.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:69:0) after 9m51s
[19:37:39.631] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:69:0)
[19:37:39.665] Build status: SCHEDULED
[19:37:39.665] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:70:0) after 5m54s
[19:43:33.685] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:70:0)
[19:43:33.714] Build status: SCHEDULED
[19:43:33.714] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:71:0) after 2m17s
[19:45:50.935] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:71:0)
[19:45:50.971] Build status: SCHEDULED
[19:45:50.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:72:0) after 9m7s
[19:54:58.095] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:72:0)
[19:54:58.139] Build status: SCHEDULED
[19:54:58.140] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:73:0) after 5m45s
[20:00:43.622] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:73:0)
[20:00:43.766] Build status: SCHEDULED
[20:00:43.766] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:74:0) after 1m48s
[20:02:31.791] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:74:0)
[20:02:31.834] Build status: SCHEDULED
[20:02:31.834] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:75:0) after 6m11s
[20:08:43.054] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:75:0)
[20:08:43.089] Build status: SCHEDULED
[20:08:43.089] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:76:0) after 8m17s
[20:17:00.285] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:76:0)
[20:17:00.315] Build status: SCHEDULED
[20:17:00.315] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:77:0) after 9m36s
[20:26:36.395] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:77:0)
[20:26:36.458] Build status: SCHEDULED
[20:26:36.458] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:78:0) after 3m35s
[20:30:11.578] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:78:0)
[20:30:11.616] Build status: SCHEDULED
[20:30:11.616] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:79:0) after 6m5s
[20:36:16.713] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:79:0)
[20:36:16.744] Build status: SCHEDULED
[20:36:16.744] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:80:0) after 4m32s
[20:40:48.764] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:80:0)
[20:40:48.793] Build status: SCHEDULED
[20:40:48.793] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:81:0) after 9m11s
[20:50:00.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:81:0)
[20:50:00.342] Build status: SCHEDULED
[20:50:00.342] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:82:0) after 4m32s
[20:54:32.361] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:82:0)
[20:54:32.395] Build status: SCHEDULED
[20:54:32.395] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:83:0) after 3m14s
[20:57:46.472] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:83:0)
[20:57:46.505] Build status: SCHEDULED
[20:57:46.505] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:84:0) after 9m17s
[21:07:03.602] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:84:0)
[21:07:03.675] Build status: SCHEDULED
[21:07:03.675] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:85:0) after 8m31s
[21:15:34.719] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:85:0)
[21:15:34.753] Build status: SCHEDULED
[21:15:34.753] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:86:0) after 3m56s
[21:19:30.911] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:86:0)
[21:19:30.945] Build status: SCHEDULED
[21:19:30.945] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:87:0) after 9m42s
[21:29:12.978] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:87:0)
[21:29:13.009] Build status: SCHEDULED
[21:29:13.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:88:0) after 5m22s
[21:34:35.588] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:88:0)
[21:34:35.621] Build status: SCHEDULED
[21:34:35.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:89:0) after 5m13s
[21:39:48.637] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:89:0)
[21:39:48.669] Build status: SCHEDULED
[21:39:48.669] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:90:0) after 9m41s
[21:49:29.687] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:90:0)
[21:49:29.722] Build status: SCHEDULED
[21:49:29.722] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:91:0) after 8m39s
[21:58:08.821] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:91:0)
[21:58:08.861] Build status: SCHEDULED
[21:58:08.861] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:92:0) after 2m5s
[22:00:13.882] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:92:0)
[22:00:13.917] Build status: SCHEDULED
[22:00:13.917] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:93:0) after 8m10s
[22:08:23.936] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:93:0)
[22:08:23.976] Build status: SCHEDULED
[22:08:23.976] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:94:0) after 5m0s
[22:13:23.994] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:94:0)
[22:13:24.025] Build status: SCHEDULED
[22:13:24.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:95:0) after 9m21s
[22:22:45.082] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:95:0)
[22:22:45.111] Build status: SCHEDULED
[22:22:45.111] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:96:0) after 6m4s
[22:28:49.134] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:96:0)
[22:28:49.163] Build status: SCHEDULED
[22:28:49.163] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:97:0) after 6m18s
[22:35:07.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:97:0)
[22:35:07.531] Build status: SCHEDULED
[22:35:07.531] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:98:0) after 2m35s
[22:37:42.552] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:98:0)
[22:37:42.585] Build status: SCHEDULED
[22:37:42.585] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:99:0) after 6m51s
[22:44:33.605] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:99:0)
[22:44:33.634] Build status: SCHEDULED
[22:44:33.634] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:100:0) after 7m28s
[22:52:01.699] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:100:0)
[22:52:01.728] Build status: SCHEDULED
[22:52:01.728] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:101:0) after 6m57s
[22:58:58.903] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:101:0)
[22:58:58.950] Build status: SCHEDULED
[22:58:58.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:102:0) after 5m8s
[23:04:06.971] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:102:0)
[23:04:06.998] Build status: SCHEDULED
[23:04:06.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:103:0) after 2m23s
[23:06:30.019] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:103:0)
[23:06:30.066] Build status: SCHEDULED
[23:06:30.066] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:104:0) after 9m53s
[23:16:23.428] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:104:0)
[23:16:23.458] Build status: SCHEDULED
[23:16:23.458] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:105:0) after 5m5s
[23:21:28.492] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:105:0)
[23:21:28.528] Build status: SCHEDULED
[23:21:28.528] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:106:0) after 1m23s
[23:22:51.592] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:106:0)
[23:22:51.626] Build status: SCHEDULED
[23:22:51.626] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:107:0) after 2m20s
[23:25:12.162] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:107:0)
[23:25:12.195] Build status: SCHEDULED
[23:25:12.195] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:108:0) after 4m26s
[23:29:38.243] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:108:0)
[23:29:38.280] Build status: SCHEDULED
[23:29:38.280] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:109:0) after 5m43s
[23:35:21.707] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:109:0)
[23:35:21.738] Build status: SCHEDULED
[23:35:21.738] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:110:0) after 3m17s
[23:38:38.950] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:110:0)
[23:38:38.983] Build status: SCHEDULED
[23:38:38.983] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:111:0) after 4m8s
[23:42:47.197] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:111:0)
[23:42:47.227] Build status: SCHEDULED
[23:42:47.227] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:112:0) after 9m38s
[23:52:25.506] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:112:0)
[23:52:25.533] Build status: SCHEDULED
[23:52:25.533] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:113:0) after 6m7s
[23:58:32.554] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:113:0)
[23:58:32.586] Build status: SCHEDULED
[23:58:32.586] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:114:0) after 9m42s
[00:08:14.836] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:114:0)
[00:08:14.870] Build status: SCHEDULED
[00:08:14.870] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:115:0) after 8m2s
[00:16:16.889] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:115:0)
[00:16:16.918] Build status: SCHEDULED
[00:16:16.918] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:116:0) after 4m20s
[00:20:36.945] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:116:0)
[00:20:36.974] Build status: SCHEDULED
[00:20:36.974] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:117:0) after 5m17s
[00:25:54.151] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:117:0)
[00:25:54.185] Build status: SCHEDULED
[00:25:54.185] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:118:0) after 8m3s
[00:33:57.344] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:118:0)
[00:33:57.424] Build status: SCHEDULED
[00:33:57.424] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:119:0) after 1m53s
[00:35:50.778] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:119:0)
[00:35:50.860] Build status: SCHEDULED
[00:35:50.860] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:120:0) after 8m39s
[00:44:29.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:120:0)
[00:44:29.962] Build status: SCHEDULED
[00:44:29.962] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:121:0) after 5m58s
[00:50:28.049] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:121:0)
[00:50:28.117] Build status: SCHEDULED
[00:50:28.117] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:122:0) after 1m53s
[00:52:21.139] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:122:0)
[00:52:21.178] Build status: SCHEDULED
[00:52:21.178] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:123:0) after 1m3s
[00:53:24.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:123:0)
[00:53:24.257] Build status: SCHEDULED
[00:53:24.257] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:124:0) after 8m37s
[01:02:01.320] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:124:0)
[01:02:01.417] Build status: SCHEDULED
[01:02:01.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:125:0) after 1m35s
[01:03:36.468] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:125:0)
[01:03:36.571] Build status: SCHEDULED
[01:03:36.571] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:126:0) after 4m21s
[01:07:57.670] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:126:0)
[01:07:57.710] Build status: SCHEDULED
[01:07:57.710] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:127:0) after 1m23s
[01:09:20.770] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:127:0)
[01:09:20.806] Build status: SCHEDULED
[01:09:20.806] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:128:0) after 8m7s
[01:17:27.979] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:128:0)
[01:17:28.017] Build status: SCHEDULED
[01:17:28.017] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:129:0) after 6m0s
[01:23:28.040] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:129:0)
[01:23:28.072] Build status: SCHEDULED
[01:23:28.072] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:130:0) after 8m0s
[01:31:28.178] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:130:0)
[01:31:28.244] Build status: SCHEDULED
[01:31:28.244] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:131:0) after 2m23s
[01:33:51.265] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:131:0)
[01:33:51.452] Build status: SCHEDULED
[01:33:51.452] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:132:0) after 6m28s
[01:35:32.675] Received PubSub notification, asking Buildbucket for the build status
[01:35:32.713] Build status: STARTED
[01:40:19.534] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:132:0)
[01:40:19.564] Build status: STARTED
[01:40:19.564] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:134:0) after 8m6s
[01:48:25.681] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:134:0)
[01:48:25.718] Build status: STARTED
[01:48:25.718] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:135:0) after 1m4s
[01:49:29.831] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:135:0)
[01:49:29.864] Build status: STARTED
[01:49:29.864] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:136:0) after 1m52s
[01:51:21.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:136:0)
[01:51:21.930] Build status: STARTED
[01:51:21.930] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:137:0) after 5m46s
[01:57:07.993] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:137:0)
[01:57:08.022] Build status: STARTED
[01:57:08.022] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:138:0) after 2m45s
[01:59:53.042] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:138:0)
[01:59:53.074] Build status: STARTED
[01:59:53.074] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:139:0) after 4m49s
[02:04:42.138] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:139:0)
[02:04:42.170] Build status: STARTED
[02:04:42.170] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:140:0) after 4m50s
[02:09:32.288] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:140:0)
[02:09:32.319] Build status: STARTED
[02:09:32.319] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:141:0) after 1m59s
[02:11:31.578] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:141:0)
[02:11:31.610] Build status: STARTED
[02:11:31.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:142:0) after 3m7s
[02:14:38.629] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:142:0)
[02:14:38.742] Build status: STARTED
[02:14:38.742] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:143:0) after 1m47s
[02:16:25.724] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:143:0)
[02:16:25.763] Build status: STARTED
[02:16:25.763] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:144:0) after 6m6s
[02:22:32.064] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:144:0)
[02:22:32.122] Build status: STARTED
[02:22:32.122] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:145:0) after 5m13s
[02:27:45.286] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:145:0)
[02:27:45.318] Build status: STARTED
[02:27:45.318] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:146:0) after 6m46s
[02:34:31.617] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:146:0)
[02:34:31.645] Build status: STARTED
[02:34:31.646] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:147:0) after 5m14s
[02:39:45.665] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:147:0)
[02:39:45.711] Build status: STARTED
[02:39:45.711] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:148:0) after 5m0s
[02:44:46.071] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:148:0)
[02:44:46.103] Build status: STARTED
[02:44:46.103] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:149:0) after 1m8s
[02:45:54.211] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:149:0)
[02:45:54.243] Build status: STARTED
[02:45:54.243] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:150:0) after 2m16s
[02:48:10.261] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:150:0)
[02:48:10.344] Build status: STARTED
[02:48:10.344] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:151:0) after 9m5s
[02:57:15.380] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:151:0)
[02:57:15.416] Build status: STARTED
[02:57:15.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:152:0) after 6m24s
[03:03:39.454] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:152:0)
[03:03:39.486] Build status: STARTED
[03:03:39.486] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:153:0) after 5m27s
[03:09:06.561] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:153:0)
[03:09:06.592] Build status: STARTED
[03:09:06.592] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:154:0) after 8m21s
[03:17:27.611] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:154:0)
[03:17:27.640] Build status: STARTED
[03:17:27.640] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:155:0) after 5m1s
[03:22:29.067] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:155:0)
[03:22:29.096] Build status: STARTED
[03:22:29.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:156:0) after 8m5s
[03:30:34.312] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:156:0)
[03:30:34.341] Build status: STARTED
[03:30:34.341] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:157:0) after 1m35s
[03:32:09.480] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:157:0)
[03:32:09.552] Build status: STARTED
[03:32:09.552] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:158:0) after 9m45s
[03:41:54.570] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:158:0)
[03:41:54.603] Build status: STARTED
[03:41:54.603] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:159:0) after 4m27s
[03:46:21.621] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:159:0)
[03:46:21.656] Build status: STARTED
[03:46:21.656] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:160:0) after 1m10s
[03:47:31.684] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:160:0)
[03:47:31.713] Build status: STARTED
[03:47:31.713] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:161:0) after 1m38s
[03:49:09.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:161:0)
[03:49:09.731] Build status: STARTED
[03:49:09.731] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:162:0) after 2m48s
[03:51:57.766] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:162:0)
[03:51:57.796] Build status: STARTED
[03:51:57.796] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:163:0) after 5m43s
[03:57:40.817] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:163:0)
[03:57:40.865] Build status: STARTED
[03:57:40.865] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:164:0) after 7m33s
[04:05:14.264] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:164:0)
[04:05:14.299] Build status: STARTED
[04:05:14.299] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:165:0) after 7m59s
[04:13:13.513] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:165:0)
[04:13:13.561] Build status: STARTED
[04:13:13.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:166:0) after 1m14s
[04:14:27.586] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:166:0)
[04:14:27.649] Build status: STARTED
[04:14:27.649] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:167:0) after 2m38s
[04:17:05.671] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:167:0)
[04:17:05.704] Build status: STARTED
[04:17:05.704] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:168:0) after 5m16s
[04:22:21.831] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:168:0)
[04:22:21.868] Build status: STARTED
[04:22:21.868] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:169:0) after 9m33s
[04:31:54.890] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:169:0)
[04:31:54.924] Build status: STARTED
[04:31:54.924] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:170:0) after 5m47s
[04:37:41.952] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:170:0)
[04:37:41.986] Build status: STARTED
[04:37:41.986] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:171:0) after 8m59s
[04:46:41.009] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:171:0)
[04:46:41.042] Build status: STARTED
[04:46:41.042] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:172:0) after 4m11s
[04:50:52.096] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:172:0)
[04:50:52.201] Build status: STARTED
[04:50:52.201] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:173:0) after 2m24s
[04:53:16.237] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:173:0)
[04:53:16.270] Build status: STARTED
[04:53:16.270] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:174:0) after 4m31s
[04:57:47.293] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:174:0)
[04:57:47.313] Build status: STARTED
[04:57:47.313] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:175:0) after 2m2s
[04:59:49.291] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:175:0)
[04:59:49.343] Build status: STARTED
[04:59:49.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:176:0) after 5m56s
[05:05:45.465] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:176:0)
[05:05:45.504] Build status: STARTED
[05:05:45.504] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:177:0) after 8m6s
[05:13:51.515] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:177:0)
[05:13:51.545] Build status: STARTED
[05:13:51.545] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:178:0) after 3m26s
[05:17:17.670] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:178:0)
[05:17:17.706] Build status: STARTED
[05:17:17.706] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:179:0) after 3m17s
[05:20:34.886] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:179:0)
[05:20:35.075] Build status: STARTED
[05:20:35.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:180:0) after 4m26s
[05:25:01.432] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:180:0)
[05:25:01.472] Build status: STARTED
[05:25:01.472] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:181:0) after 1m39s
[05:26:40.518] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:181:0)
[05:26:40.570] Build status: STARTED
[05:26:40.570] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:182:0) after 9m29s
[05:36:09.594] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:182:0)
[05:36:09.623] Build status: STARTED
[05:36:09.623] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:183:0) after 3m15s
[05:39:24.721] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:183:0)
[05:39:24.750] Build status: STARTED
[05:39:24.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:184:0) after 8m33s
[05:47:57.814] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:184:0)
[05:47:57.847] Build status: STARTED
[05:47:57.847] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:185:0) after 2m27s
[05:50:24.968] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:185:0)
[05:50:25.004] Build status: STARTED
[05:50:25.004] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:186:0) after 6m28s
[05:56:53.305] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:186:0)
[05:56:53.334] Build status: STARTED
[05:56:53.334] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:187:0) after 4m30s
[06:01:23.469] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:187:0)
[06:01:23.499] Build status: STARTED
[06:01:23.499] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:188:0) after 7m31s
[06:08:54.522] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:188:0)
[06:08:54.554] Build status: STARTED
[06:08:54.554] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:189:0) after 2m26s
[06:11:20.579] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:189:0)
[06:11:20.626] Build status: STARTED
[06:11:20.626] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:190:0) after 8m50s
[06:20:10.665] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:190:0)
[06:20:10.724] Build status: STARTED
[06:20:10.725] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:191:0) after 2m52s
[06:23:02.765] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:191:0)
[06:23:02.813] Build status: STARTED
[06:23:02.813] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8979254193054060016:192:0) after 6m20s
[06:27:00.153] Received PubSub notification, asking Buildbucket for the build status
[06:27:00.180] Build:
{
"id": "8813824130221911761",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 653,
"createdBy": "project:r8",
"createTime": "2022-05-18T13:08:39.893872998Z",
"startTime": "2022-05-19T01:35:31.046184Z",
"endTime": "2022-05-19T06:26:59.760388128Z",
"updateTime": "2022-05-19T06:26:59.760388128Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "1fb54f73d898c9ed8d823a31e99f2dfaa6552dc2",
"ref": "refs/heads/3.3"
}
}
}
[06:27:00.180] Invocation finished in 17h18m22.371412087s with status SUCCEEDED