[12:05:14.626] New invocation is queued and will start shortly
[12:05:15.759] Starting the invocation (attempt 1)
[12:05:15.882] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/b637535b08691b9aad2b7de2217494bf6322702f"
[12:05:15.882] Popped gitiles commit info from properties and tags
[12:05:15.882] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:05:15.882] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:05:15.882] Buildbucket request:
{
"requestId": "8981613702123830592",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8981613702123830592",
"job": "r8/linux-internal",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "a12d1cf68ec39c0fff6a7c374adbd5c76641db7d"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@a12d1cf68ec39c0fff6a7c374adbd5c76641db7d",
"title": "a12d1cf68ec39c0fff6a7c374adbd5c76641db7d",
"url": "https://r8.googlesource.com/r8/+/a12d1cf68ec39c0fff6a7c374adbd5c76641db7d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "7fc02a3d13e3033421e275fb60fe751df060a07e"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@7fc02a3d13e3033421e275fb60fe751df060a07e",
"title": "7fc02a3d13e3033421e275fb60fe751df060a07e",
"url": "https://r8.googlesource.com/r8/+/7fc02a3d13e3033421e275fb60fe751df060a07e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "e1fa9de710690eb940a7506ba6bc74d53af36979"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@e1fa9de710690eb940a7506ba6bc74d53af36979",
"title": "e1fa9de710690eb940a7506ba6bc74d53af36979",
"url": "https://r8.googlesource.com/r8/+/e1fa9de710690eb940a7506ba6bc74d53af36979"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "9d56eece753c97793160e29957e3a555d5762e82"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@9d56eece753c97793160e29957e3a555d5762e82",
"title": "9d56eece753c97793160e29957e3a555d5762e82",
"url": "https://r8.googlesource.com/r8/+/9d56eece753c97793160e29957e3a555d5762e82"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "6919cb99d79a983598360c74afbe37bf07338f95"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@6919cb99d79a983598360c74afbe37bf07338f95",
"title": "6919cb99d79a983598360c74afbe37bf07338f95",
"url": "https://r8.googlesource.com/r8/+/6919cb99d79a983598360c74afbe37bf07338f95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "b637535b08691b9aad2b7de2217494bf6322702f"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@b637535b08691b9aad2b7de2217494bf6322702f",
"title": "b637535b08691b9aad2b7de2217494bf6322702f",
"url": "https://r8.googlesource.com/r8/+/b637535b08691b9aad2b7de2217494bf6322702f"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "b637535b08691b9aad2b7de2217494bf6322702f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8981613702123830592"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:05:16.523] Scheduled build:
{
"id": "8816183639982580833",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2500,
"createdBy": "project:r8",
"createTime": "2022-04-22T12:05:16.035330922Z",
"updateTime": "2022-04-22T12:05:16.035330922Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "b637535b08691b9aad2b7de2217494bf6322702f",
"ref": "refs/heads/main"
}
}
}
[12:05:16.523] Task URL: https://cr-buildbucket.appspot.com/build/8816183639982580833
[12:05:16.523] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:2:0) after 8m25s
[12:13:41.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:2:0)
[12:13:41.641] Build status: SCHEDULED
[12:13:41.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:3:0) after 7m5s
[12:20:46.713] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:3:0)
[12:20:46.774] Build status: SCHEDULED
[12:20:46.774] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:4:0) after 9m35s
[12:30:21.777] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:4:0)
[12:30:21.818] Build status: SCHEDULED
[12:30:21.818] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:5:0) after 9m4s
[12:39:26.275] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:5:0)
[12:39:26.325] Build status: SCHEDULED
[12:39:26.325] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:6:0) after 8m1s
[12:47:27.318] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:6:0)
[12:47:27.349] Build status: SCHEDULED
[12:47:27.349] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:7:0) after 2m40s
[12:50:07.472] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:7:0)
[12:50:07.507] Build status: SCHEDULED
[12:50:07.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:8:0) after 8m27s
[12:58:34.547] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:8:0)
[12:58:34.576] Build status: SCHEDULED
[12:58:34.576] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:9:0) after 1m26s
[13:00:00.652] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:9:0)
[13:00:00.741] Build status: SCHEDULED
[13:00:00.741] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:10:0) after 1m15s
[13:01:15.795] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:10:0)
[13:01:15.824] Build status: SCHEDULED
[13:01:15.824] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:11:0) after 1m45s
[13:03:00.800] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:11:0)
[13:03:00.944] Build status: SCHEDULED
[13:03:00.944] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:12:0) after 8m40s
[13:11:40.968] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:12:0)
[13:11:40.998] Build status: SCHEDULED
[13:11:40.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:13:0) after 5m19s
[13:17:00.080] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:13:0)
[13:17:00.108] Build status: SCHEDULED
[13:17:00.108] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:14:0) after 7m25s
[13:24:25.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:14:0)
[13:24:25.277] Build status: SCHEDULED
[13:24:25.277] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:15:0) after 2m15s
[13:26:40.323] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:15:0)
[13:26:40.351] Build status: SCHEDULED
[13:26:40.351] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:16:0) after 4m39s
[13:31:19.544] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:16:0)
[13:31:19.743] Build status: SCHEDULED
[13:31:19.743] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:17:0) after 5m17s
[13:36:36.761] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:17:0)
[13:36:36.797] Build status: SCHEDULED
[13:36:36.797] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:18:0) after 5m52s
[13:42:28.815] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:18:0)
[13:42:28.859] Build status: SCHEDULED
[13:42:28.859] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:19:0) after 8m50s
[13:51:18.879] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:19:0)
[13:51:18.913] Build status: SCHEDULED
[13:51:18.914] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:20:0) after 3m13s
[13:54:31.947] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:20:0)
[13:54:32.009] Build status: SCHEDULED
[13:54:32.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:21:0) after 9m31s
[14:04:03.073] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:21:0)
[14:04:03.138] Build status: SCHEDULED
[14:04:03.138] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:22:0) after 1m22s
[14:05:25.257] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:22:0)
[14:05:25.284] Build status: SCHEDULED
[14:05:25.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:23:0) after 8m47s
[14:14:12.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:23:0)
[14:14:12.348] Build status: SCHEDULED
[14:14:12.348] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:24:0) after 4m5s
[14:18:17.429] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:24:0)
[14:18:17.473] Build status: SCHEDULED
[14:18:17.473] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:25:0) after 7m33s
[14:25:50.492] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:25:0)
[14:25:50.662] Build status: SCHEDULED
[14:25:50.662] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:26:0) after 1m51s
[14:27:41.699] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:26:0)
[14:27:41.737] Build status: SCHEDULED
[14:27:41.737] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:27:0) after 1m7s
[14:28:48.831] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:27:0)
[14:28:48.851] Build status: SCHEDULED
[14:28:48.851] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:28:0) after 7m8s
[14:35:57.080] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:28:0)
[14:35:57.105] Build status: SCHEDULED
[14:35:57.105] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:29:0) after 7m14s
[14:43:11.198] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:29:0)
[14:43:11.295] Build status: SCHEDULED
[14:43:11.295] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:30:0) after 2m33s
[14:45:44.316] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:30:0)
[14:45:44.349] Build status: SCHEDULED
[14:45:44.349] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:31:0) after 9m28s
[14:55:12.373] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:31:0)
[14:55:12.412] Build status: SCHEDULED
[14:55:12.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:32:0) after 5m34s
[15:00:46.587] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:32:0)
[15:00:46.846] Build status: SCHEDULED
[15:00:46.846] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:33:0) after 1m47s
[15:02:33.867] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:33:0)
[15:02:33.907] Build status: SCHEDULED
[15:02:33.907] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:34:0) after 7m42s
[15:10:16.278] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:34:0)
[15:10:16.332] Build status: SCHEDULED
[15:10:16.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:35:0) after 3m53s
[15:14:09.402] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:35:0)
[15:14:09.437] Build status: SCHEDULED
[15:14:09.437] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:36:0) after 5m39s
[15:19:48.472] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:36:0)
[15:19:48.501] Build status: SCHEDULED
[15:19:48.501] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:37:0) after 5m59s
[15:25:47.538] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:37:0)
[15:25:47.560] Build status: SCHEDULED
[15:25:47.560] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:38:0) after 9m47s
[15:35:34.633] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:38:0)
[15:35:34.665] Build status: SCHEDULED
[15:35:34.665] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:39:0) after 8m39s
[15:44:13.937] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:39:0)
[15:44:13.971] Build status: SCHEDULED
[15:44:13.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:40:0) after 5m30s
[15:49:44.089] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:40:0)
[15:49:44.133] Build status: SCHEDULED
[15:49:44.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:41:0) after 3m57s
[15:53:41.501] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:41:0)
[15:53:41.534] Build status: SCHEDULED
[15:53:41.534] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:42:0) after 4m48s
[15:58:29.725] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:42:0)
[15:58:29.771] Build status: SCHEDULED
[15:58:29.771] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:43:0) after 7m16s
[16:05:45.795] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:43:0)
[16:05:45.830] Build status: SCHEDULED
[16:05:45.830] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:44:0) after 1m20s
[16:07:05.842] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:44:0)
[16:07:05.875] Build status: SCHEDULED
[16:07:05.875] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:45:0) after 8m17s
[16:15:22.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:45:0)
[16:15:22.942] Build status: SCHEDULED
[16:15:22.942] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:46:0) after 8m24s
[16:23:47.355] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:46:0)
[16:23:47.391] Build status: SCHEDULED
[16:23:47.391] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:47:0) after 5m54s
[16:29:41.472] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:47:0)
[16:29:41.496] Build status: SCHEDULED
[16:29:41.496] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:48:0) after 6m46s
[16:36:27.703] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:48:0)
[16:36:27.755] Build status: SCHEDULED
[16:36:27.755] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:49:0) after 9m52s
[16:46:19.892] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:49:0)
[16:46:19.976] Build status: SCHEDULED
[16:46:19.976] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:50:0) after 3m32s
[16:49:52.020] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:50:0)
[16:49:52.096] Build status: SCHEDULED
[16:49:52.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:51:0) after 8m12s
[16:55:23.239] Received PubSub notification, asking Buildbucket for the build status
[16:55:23.300] Build status: STARTED
[16:58:04.362] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:51:0)
[16:58:04.385] Build status: STARTED
[16:58:04.385] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:53:0) after 1m7s
[16:59:11.601] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:53:0)
[16:59:11.636] Build status: STARTED
[16:59:11.636] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:54:0) after 3m29s
[17:02:40.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:54:0)
[17:02:40.766] Build status: STARTED
[17:02:40.766] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:55:0) after 5m50s
[17:08:30.773] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:55:0)
[17:08:30.809] Build status: STARTED
[17:08:30.809] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:56:0) after 2m52s
[17:11:22.803] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:56:0)
[17:11:22.836] Build status: STARTED
[17:11:22.836] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:57:0) after 9m38s
[17:21:01.081] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:57:0)
[17:21:01.121] Build status: STARTED
[17:21:01.121] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:58:0) after 1m34s
[17:22:35.288] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:58:0)
[17:22:35.319] Build status: STARTED
[17:22:35.319] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:59:0) after 4m4s
[17:26:39.436] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:59:0)
[17:26:39.470] Build status: STARTED
[17:26:39.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:60:0) after 3m3s
[17:29:42.525] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:60:0)
[17:29:42.558] Build status: STARTED
[17:29:42.558] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:61:0) after 5m34s
[17:35:16.633] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:61:0)
[17:35:16.660] Build status: STARTED
[17:35:16.660] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:62:0) after 9m36s
[17:44:52.684] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:62:0)
[17:44:52.847] Build status: STARTED
[17:44:52.847] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:63:0) after 6m26s
[17:51:18.914] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:63:0)
[17:51:18.955] Build status: STARTED
[17:51:18.955] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:64:0) after 3m51s
[17:55:09.975] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:64:0)
[17:55:10.009] Build status: STARTED
[17:55:10.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:65:0) after 9m7s
[18:04:17.036] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:65:0)
[18:04:17.059] Build status: STARTED
[18:04:17.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:66:0) after 7m8s
[18:11:25.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:66:0)
[18:11:25.695] Build status: STARTED
[18:11:25.695] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:67:0) after 1m18s
[18:12:43.818] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:67:0)
[18:12:43.863] Build status: STARTED
[18:12:43.863] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:68:0) after 7m3s
[18:19:47.130] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:68:0)
[18:19:47.253] Build status: STARTED
[18:19:47.253] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:69:0) after 2m32s
[18:22:19.693] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:69:0)
[18:22:19.735] Build status: STARTED
[18:22:19.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:70:0) after 2m17s
[18:24:36.761] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:70:0)
[18:24:36.798] Build status: STARTED
[18:24:36.798] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:71:0) after 4m52s
[18:29:29.595] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:71:0)
[18:29:29.626] Build status: STARTED
[18:29:29.626] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:72:0) after 5m53s
[18:35:23.257] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:72:0)
[18:35:23.291] Build status: STARTED
[18:35:23.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:73:0) after 5m38s
[18:41:01.322] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:73:0)
[18:41:01.360] Build status: STARTED
[18:41:01.360] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:74:0) after 3m12s
[18:44:13.584] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:74:0)
[18:44:13.621] Build status: STARTED
[18:44:13.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:75:0) after 6m58s
[18:51:11.641] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:75:0)
[18:51:11.680] Build status: STARTED
[18:51:11.680] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:76:0) after 8m21s
[18:59:32.702] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:76:0)
[18:59:32.735] Build status: STARTED
[18:59:32.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:77:0) after 5m11s
[19:04:43.819] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:77:0)
[19:04:44.023] Build status: STARTED
[19:04:44.023] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:78:0) after 3m34s
[19:08:18.098] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:78:0)
[19:08:18.135] Build status: STARTED
[19:08:18.135] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:79:0) after 7m38s
[19:15:56.363] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:79:0)
[19:15:56.512] Build status: STARTED
[19:15:56.512] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:80:0) after 9m45s
[19:25:41.643] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:80:0)
[19:25:41.675] Build status: STARTED
[19:25:41.675] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:81:0) after 5m14s
[19:30:55.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:81:0)
[19:30:55.840] Build status: STARTED
[19:30:55.840] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:82:0) after 8m15s
[19:39:10.934] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:82:0)
[19:39:10.971] Build status: STARTED
[19:39:10.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:83:0) after 2m31s
[19:41:42.179] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:83:0)
[19:41:42.215] Build status: STARTED
[19:41:42.215] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:84:0) after 5m22s
[19:47:04.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:84:0)
[19:47:04.933] Build status: STARTED
[19:47:04.933] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:85:0) after 3m12s
[19:50:16.953] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:85:0)
[19:50:16.989] Build status: STARTED
[19:50:16.989] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:86:0) after 1m59s
[19:52:15.969] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:86:0)
[19:52:16.001] Build status: STARTED
[19:52:16.001] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:87:0) after 8m24s
[20:00:40.269] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:87:0)
[20:00:40.302] Build status: STARTED
[20:00:40.303] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:88:0) after 4m11s
[20:04:51.298] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:88:0)
[20:04:51.331] Build status: STARTED
[20:04:51.331] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:89:0) after 2m1s
[20:06:52.482] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:89:0)
[20:06:52.572] Build status: STARTED
[20:06:52.572] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:90:0) after 9m19s
[20:16:11.648] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:90:0)
[20:16:11.896] Build status: STARTED
[20:16:11.896] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:91:0) after 2m55s
[20:19:06.932] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:91:0)
[20:19:06.970] Build status: STARTED
[20:19:06.970] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:92:0) after 7m49s
[20:26:55.993] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:92:0)
[20:26:56.057] Build status: STARTED
[20:26:56.057] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:93:0) after 8m36s
[20:35:32.126] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:93:0)
[20:35:32.149] Build status: STARTED
[20:35:32.149] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:94:0) after 3m49s
[20:39:21.176] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:94:0)
[20:39:21.216] Build status: STARTED
[20:39:21.216] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:95:0) after 8m44s
[20:48:05.379] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:95:0)
[20:48:05.421] Build status: STARTED
[20:48:05.421] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:96:0) after 5m9s
[20:53:14.639] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:96:0)
[20:53:14.666] Build status: STARTED
[20:53:14.666] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:97:0) after 2m58s
[20:56:12.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:97:0)
[20:56:12.730] Build status: STARTED
[20:56:12.730] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:98:0) after 2m10s
[20:58:23.392] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:98:0)
[20:58:23.445] Build status: STARTED
[20:58:23.445] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:99:0) after 4m28s
[21:02:51.430] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:99:0)
[21:02:51.476] Build status: STARTED
[21:02:51.476] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:100:0) after 4m17s
[21:07:08.501] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:100:0)
[21:07:08.536] Build status: STARTED
[21:07:08.536] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:101:0) after 9m10s
[21:16:19.052] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:101:0)
[21:16:19.117] Build status: STARTED
[21:16:19.117] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:102:0) after 9m39s
[21:25:58.645] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:102:0)
[21:25:58.689] Build status: STARTED
[21:25:58.689] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:103:0) after 3m8s
[21:29:06.864] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:103:0)
[21:29:06.897] Build status: STARTED
[21:29:06.897] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:104:0) after 9m7s
[21:38:13.970] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:104:0)
[21:38:14.013] Build status: STARTED
[21:38:14.013] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:105:0) after 4m46s
[21:43:00.136] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:105:0)
[21:43:00.456] Build status: STARTED
[21:43:00.456] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8981613702123830592:106:0) after 4m32s
[21:44:50.874] Received PubSub notification, asking Buildbucket for the build status
[21:44:50.896] Build:
{
"id": "8816183639982580833",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2500,
"createdBy": "project:r8",
"createTime": "2022-04-22T12:05:16.035330922Z",
"startTime": "2022-04-22T16:55:21.900848Z",
"endTime": "2022-04-22T21:44:50.530469315Z",
"updateTime": "2022-04-22T21:44:50.530469315Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "b637535b08691b9aad2b7de2217494bf6322702f",
"ref": "refs/heads/main"
}
}
}
[21:44:50.896] Invocation finished in 9h39m36.285373289s with status SUCCEEDED