[00:54:26.542] New invocation is queued and will start shortly
[00:54:28.429] Starting the invocation (attempt 1)
[00:54:28.468] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/cdd13bb473cd90601ad0470a653c357712bddeb6"
[00:54:28.468] Popped gitiles commit info from properties and tags
[00:54:28.468] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:54:28.469] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:54:28.469] Buildbucket request:
{
"requestId": "8981202920465869376",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8981202920465869376",
"job": "r8/linux-internal_release",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/3.3",
"repo": "https://r8.googlesource.com/r8",
"revision": "cdd13bb473cd90601ad0470a653c357712bddeb6"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/3.3@cdd13bb473cd90601ad0470a653c357712bddeb6",
"title": "cdd13bb473cd90601ad0470a653c357712bddeb6",
"url": "https://r8.googlesource.com/r8/+/cdd13bb473cd90601ad0470a653c357712bddeb6"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "cdd13bb473cd90601ad0470a653c357712bddeb6",
"ref": "refs/heads/3.3"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8981202920465869376"
},
{
"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"
}
}
[00:54:29.053] Scheduled build:
{
"id": "8815772857681539937",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 620,
"createdBy": "project:r8",
"createTime": "2022-04-27T00:54:28.565285240Z",
"updateTime": "2022-04-27T00:54:28.565285240Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "cdd13bb473cd90601ad0470a653c357712bddeb6",
"ref": "refs/heads/3.3"
}
}
}
[00:54:29.053] Task URL: https://cr-buildbucket.appspot.com/build/8815772857681539937
[00:54:29.053] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:2:0) after 3m14s
[00:57:43.408] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:2:0)
[00:57:43.441] Build status: SCHEDULED
[00:57:43.441] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:3:0) after 6m19s
[01:04:02.876] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:3:0)
[01:04:02.896] Build status: SCHEDULED
[01:04:02.896] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:4:0) after 1m17s
[01:05:20.074] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:4:0)
[01:05:20.104] Build status: SCHEDULED
[01:05:20.104] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:5:0) after 9m31s
[01:14:51.918] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:5:0)
[01:14:51.954] Build status: SCHEDULED
[01:14:51.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:6:0) after 9m11s
[01:24:03.067] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:6:0)
[01:24:03.153] Build status: SCHEDULED
[01:24:03.153] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:7:0) after 8m32s
[01:32:35.170] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:7:0)
[01:32:35.215] Build status: SCHEDULED
[01:32:35.215] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:8:0) after 5m11s
[01:37:46.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:8:0)
[01:37:46.575] Build status: SCHEDULED
[01:37:46.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:9:0) after 8m29s
[01:46:15.593] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:9:0)
[01:46:15.621] Build status: SCHEDULED
[01:46:15.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:10:0) after 6m40s
[01:52:55.591] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:10:0)
[01:52:55.609] Build status: SCHEDULED
[01:52:55.609] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:11:0) after 1m14s
[01:54:09.704] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:11:0)
[01:54:09.732] Build status: SCHEDULED
[01:54:09.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:12:0) after 2m17s
[01:56:26.796] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:12:0)
[01:56:26.826] Build status: SCHEDULED
[01:56:26.826] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:13:0) after 2m49s
[01:59:16.038] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:13:0)
[01:59:16.070] Build status: SCHEDULED
[01:59:16.070] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:14:0) after 6m3s
[02:05:19.331] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:14:0)
[02:05:19.379] Build status: SCHEDULED
[02:05:19.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:15:0) after 8m51s
[02:14:10.398] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:15:0)
[02:14:10.429] Build status: SCHEDULED
[02:14:10.429] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:16:0) after 3m37s
[02:17:47.767] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:16:0)
[02:17:47.804] Build status: SCHEDULED
[02:17:47.804] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:17:0) after 9m39s
[02:27:26.980] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:17:0)
[02:27:27.038] Build status: SCHEDULED
[02:27:27.038] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:18:0) after 1m7s
[02:28:34.281] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:18:0)
[02:28:34.313] Build status: SCHEDULED
[02:28:34.313] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:19:0) after 8m21s
[02:36:55.388] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:19:0)
[02:36:55.426] Build status: SCHEDULED
[02:36:55.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:20:0) after 8m56s
[02:45:51.642] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:20:0)
[02:45:51.681] Build status: SCHEDULED
[02:45:51.681] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:21:0) after 8m8s
[02:53:59.942] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:21:0)
[02:53:59.986] Build status: SCHEDULED
[02:53:59.986] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:22:0) after 3m49s
[02:57:48.983] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:22:0)
[02:57:49.203] Build status: SCHEDULED
[02:57:49.203] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:23:0) after 2m58s
[03:00:47.369] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:23:0)
[03:00:47.390] Build status: SCHEDULED
[03:00:47.390] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:24:0) after 7m42s
[03:08:29.779] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:24:0)
[03:08:29.816] Build status: SCHEDULED
[03:08:29.816] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:25:0) after 6m28s
[03:14:57.832] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:25:0)
[03:14:57.874] Build status: SCHEDULED
[03:14:57.874] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:26:0) after 7m32s
[03:22:29.988] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:26:0)
[03:22:30.040] Build status: SCHEDULED
[03:22:30.040] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:27:0) after 1m20s
[03:23:50.058] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:27:0)
[03:23:50.094] Build status: SCHEDULED
[03:23:50.094] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:28:0) after 7m35s
[03:31:25.114] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:28:0)
[03:31:25.164] Build status: SCHEDULED
[03:31:25.165] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:29:0) after 2m47s
[03:34:12.336] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:29:0)
[03:34:12.365] Build status: SCHEDULED
[03:34:12.365] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:30:0) after 6m11s
[03:40:23.496] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:30:0)
[03:40:23.529] Build status: SCHEDULED
[03:40:23.529] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:31:0) after 1m47s
[03:42:10.784] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:31:0)
[03:42:10.886] Build status: SCHEDULED
[03:42:10.887] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:32:0) after 7m54s
[03:50:04.911] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:32:0)
[03:50:04.967] Build status: SCHEDULED
[03:50:04.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:33:0) after 5m54s
[03:55:59.223] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:33:0)
[03:55:59.252] Build status: SCHEDULED
[03:55:59.252] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:34:0) after 5m41s
[04:01:40.701] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:34:0)
[04:01:40.722] Build status: SCHEDULED
[04:01:40.723] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:35:0) after 4m57s
[04:06:37.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:35:0)
[04:06:37.800] Build status: SCHEDULED
[04:06:37.800] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:36:0) after 1m44s
[04:08:21.894] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:36:0)
[04:08:21.934] Build status: SCHEDULED
[04:08:21.934] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:37:0) after 5m57s
[04:14:19.197] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:37:0)
[04:14:19.237] Build status: SCHEDULED
[04:14:19.237] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:38:0) after 7m17s
[04:21:36.342] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:38:0)
[04:21:36.537] Build status: SCHEDULED
[04:21:36.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:39:0) after 8m36s
[04:30:12.592] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:39:0)
[04:30:12.655] Build status: SCHEDULED
[04:30:12.655] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:40:0) after 2m26s
[04:32:38.932] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:40:0)
[04:32:38.959] Build status: SCHEDULED
[04:32:38.959] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:41:0) after 3m27s
[04:36:06.011] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:41:0)
[04:36:06.050] Build status: SCHEDULED
[04:36:06.050] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:42:0) after 1m0s
[04:37:06.288] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:42:0)
[04:37:06.317] Build status: SCHEDULED
[04:37:06.317] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:43:0) after 7m28s
[04:44:34.486] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:43:0)
[04:44:34.520] Build status: SCHEDULED
[04:44:34.520] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:44:0) after 2m9s
[04:46:43.631] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:44:0)
[04:46:43.655] Build status: SCHEDULED
[04:46:43.655] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:45:0) after 4m33s
[04:51:16.674] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:45:0)
[04:51:16.696] Build status: SCHEDULED
[04:51:16.696] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:46:0) after 2m52s
[04:54:08.812] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:46:0)
[04:54:08.846] Build status: SCHEDULED
[04:54:08.846] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:47:0) after 3m18s
[04:57:26.967] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:47:0)
[04:57:27.002] Build status: SCHEDULED
[04:57:27.002] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:48:0) after 2m0s
[04:59:27.077] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:48:0)
[04:59:27.108] Build status: SCHEDULED
[04:59:27.108] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:49:0) after 9m27s
[05:08:54.310] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:49:0)
[05:08:54.340] Build status: SCHEDULED
[05:08:54.340] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:50:0) after 7m30s
[05:16:24.367] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:50:0)
[05:16:24.389] Build status: SCHEDULED
[05:16:24.389] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:51:0) after 8m7s
[05:24:31.936] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:51:0)
[05:24:31.969] Build status: SCHEDULED
[05:24:31.969] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:52:0) after 3m33s
[05:28:05.110] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:52:0)
[05:28:05.140] Build status: SCHEDULED
[05:28:05.140] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:53:0) after 8m49s
[05:36:54.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:53:0)
[05:36:54.209] Build status: SCHEDULED
[05:36:54.209] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:54:0) after 1m50s
[05:38:44.226] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:54:0)
[05:38:44.253] Build status: SCHEDULED
[05:38:44.253] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:55:0) after 1m39s
[05:40:23.281] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:55:0)
[05:40:23.302] Build status: SCHEDULED
[05:40:23.302] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:56:0) after 2m1s
[05:42:24.840] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:56:0)
[05:42:24.874] Build status: SCHEDULED
[05:42:24.874] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:57:0) after 5m43s
[05:48:06.775] Received PubSub notification, asking Buildbucket for the build status
[05:48:06.806] Build status: STARTED
[05:48:08.143] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:57:0)
[05:48:08.173] Build status: STARTED
[05:48:08.173] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:59:0) after 4m43s
[05:52:51.333] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:59:0)
[05:52:51.370] Build status: STARTED
[05:52:51.370] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:60:0) after 6m0s
[05:58:51.590] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:60:0)
[05:58:51.618] Build status: STARTED
[05:58:51.618] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:61:0) after 9m46s
[06:08:37.959] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:61:0)
[06:08:38.000] Build status: STARTED
[06:08:38.000] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:62:0) after 7m14s
[06:15:52.026] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:62:0)
[06:15:52.083] Build status: STARTED
[06:15:52.083] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:63:0) after 9m21s
[06:25:13.116] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:63:0)
[06:25:13.171] Build status: STARTED
[06:25:13.171] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:64:0) after 9m20s
[06:34:33.190] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:64:0)
[06:34:33.223] Build status: STARTED
[06:34:33.223] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:65:0) after 6m25s
[06:40:58.244] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:65:0)
[06:40:58.293] Build status: STARTED
[06:40:58.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:66:0) after 4m43s
[06:45:41.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:66:0)
[06:45:41.334] Build status: STARTED
[06:45:41.334] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:67:0) after 8m11s
[06:53:52.366] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:67:0)
[06:53:52.416] Build status: STARTED
[06:53:52.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:68:0) after 8m25s
[07:02:17.451] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:68:0)
[07:02:17.502] Build status: STARTED
[07:02:17.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:69:0) after 2m5s
[07:04:22.549] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:69:0)
[07:04:22.589] Build status: STARTED
[07:04:22.589] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:70:0) after 4m9s
[07:08:31.731] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:70:0)
[07:08:31.762] Build status: STARTED
[07:08:31.762] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:71:0) after 7m2s
[07:15:33.949] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:71:0)
[07:15:33.982] Build status: STARTED
[07:15:33.982] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:72:0) after 6m16s
[07:21:50.376] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:72:0)
[07:21:50.458] Build status: STARTED
[07:21:50.458] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:73:0) after 4m11s
[07:26:01.541] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:73:0)
[07:26:01.610] Build status: STARTED
[07:26:01.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:74:0) after 7m59s
[07:34:00.719] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:74:0)
[07:34:00.750] Build status: STARTED
[07:34:00.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:75:0) after 4m30s
[07:38:30.778] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:75:0)
[07:38:30.804] Build status: STARTED
[07:38:30.804] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:76:0) after 1m33s
[07:40:03.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:76:0)
[07:40:03.865] Build status: STARTED
[07:40:03.865] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:77:0) after 7m2s
[07:47:05.921] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:77:0)
[07:47:05.998] Build status: STARTED
[07:47:05.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:78:0) after 5m7s
[07:52:13.507] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:78:0)
[07:52:13.560] Build status: STARTED
[07:52:13.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:79:0) after 9m59s
[08:02:12.719] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:79:0)
[08:02:12.759] Build status: STARTED
[08:02:12.759] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:80:0) after 7m7s
[08:09:19.935] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:80:0)
[08:09:19.969] Build status: STARTED
[08:09:19.969] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:81:0) after 3m54s
[08:13:14.077] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:81:0)
[08:13:14.118] Build status: STARTED
[08:13:14.118] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:82:0) after 9m49s
[08:23:03.172] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:82:0)
[08:23:03.199] Build status: STARTED
[08:23:03.199] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:83:0) after 1m57s
[08:25:00.327] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:83:0)
[08:25:00.359] Build status: STARTED
[08:25:00.359] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:84:0) after 6m21s
[08:31:21.381] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:84:0)
[08:31:21.416] Build status: STARTED
[08:31:21.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:85:0) after 5m29s
[08:36:50.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:85:0)
[08:36:50.599] Build status: STARTED
[08:36:50.599] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:86:0) after 1m59s
[08:38:49.621] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:86:0)
[08:38:49.669] Build status: STARTED
[08:38:49.669] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:87:0) after 9m24s
[08:48:13.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:87:0)
[08:48:13.792] Build status: STARTED
[08:48:13.792] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:88:0) after 4m3s
[08:52:17.118] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:88:0)
[08:52:17.175] Build status: STARTED
[08:52:17.175] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:89:0) after 2m2s
[08:54:19.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:89:0)
[08:54:19.230] Build status: STARTED
[08:54:19.230] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:90:0) after 6m41s
[09:01:00.447] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:90:0)
[09:01:00.476] Build status: STARTED
[09:01:00.476] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:91:0) after 5m16s
[09:06:16.496] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:91:0)
[09:06:16.528] Build status: STARTED
[09:06:16.528] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:92:0) after 5m21s
[09:11:37.724] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:92:0)
[09:11:37.757] Build status: STARTED
[09:11:37.757] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:93:0) after 9m17s
[09:20:55.034] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:93:0)
[09:20:55.065] Build status: STARTED
[09:20:55.065] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:94:0) after 2m47s
[09:23:42.155] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:94:0)
[09:23:42.186] Build status: STARTED
[09:23:42.186] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:95:0) after 9m38s
[09:33:20.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:95:0)
[09:33:20.259] Build status: STARTED
[09:33:20.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:96:0) after 7m17s
[09:40:37.298] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:96:0)
[09:40:37.320] Build status: STARTED
[09:40:37.320] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:97:0) after 8m45s
[09:49:22.351] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:97:0)
[09:49:22.416] Build status: STARTED
[09:49:22.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:98:0) after 6m31s
[09:55:53.627] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:98:0)
[09:55:53.678] Build status: STARTED
[09:55:53.678] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:99:0) after 1m40s
[09:57:33.897] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:99:0)
[09:57:34.068] Build status: STARTED
[09:57:34.068] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:100:0) after 8m2s
[10:05:36.173] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:100:0)
[10:05:36.208] Build status: STARTED
[10:05:36.208] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:101:0) after 2m5s
[10:07:41.478] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:101:0)
[10:07:41.517] Build status: STARTED
[10:07:41.517] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:102:0) after 8m46s
[10:16:27.537] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:102:0)
[10:16:27.574] Build status: STARTED
[10:16:27.574] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:103:0) after 8m21s
[10:24:48.682] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:103:0)
[10:24:48.716] Build status: STARTED
[10:24:48.716] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:104:0) after 1m5s
[10:25:53.792] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:104:0)
[10:25:53.821] Build status: STARTED
[10:25:53.821] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:105:0) after 6m11s
[10:32:04.942] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:105:0)
[10:32:04.975] Build status: STARTED
[10:32:04.975] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:106:0) after 7m52s
[10:39:56.995] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:106:0)
[10:39:57.024] Build status: STARTED
[10:39:57.024] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8981202920465869376:107:0) after 1m2s
[10:40:05.856] Received PubSub notification, asking Buildbucket for the build status
[10:40:05.886] Build:
{
"id": "8815772857681539937",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 620,
"createdBy": "project:r8",
"createTime": "2022-04-27T00:54:28.565285240Z",
"startTime": "2022-04-27T05:47:35.993038Z",
"endTime": "2022-04-27T10:40:05.387897322Z",
"updateTime": "2022-04-27T10:40:05.387897322Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "cdd13bb473cd90601ad0470a653c357712bddeb6",
"ref": "refs/heads/3.3"
}
}
}
[10:40:05.886] Invocation finished in 9h45m39.360095371s with status SUCCEEDED