[10:59:30.472] New invocation is queued and will start shortly
[10:59:31.516] Starting the invocation (attempt 1)
[10:59:31.551] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/de6c0cb6769a7fc590bb6912624e5fa13c4dfcca"
[10:59:31.552] Popped gitiles commit info from properties and tags
[10:59:31.552] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:59:31.552] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:59:31.552] Buildbucket request:
{
"requestId": "9003904691603196144",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "c9e52f710d7449d43e1f95852fcc0e1ea7778da1"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@c9e52f710d7449d43e1f95852fcc0e1ea7778da1",
"title": "c9e52f710d7449d43e1f95852fcc0e1ea7778da1",
"url": "https://r8.googlesource.com/r8/+/c9e52f710d7449d43e1f95852fcc0e1ea7778da1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "c257fa927499a201643e32db1d8114420f85b26c"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@c257fa927499a201643e32db1d8114420f85b26c",
"title": "c257fa927499a201643e32db1d8114420f85b26c",
"url": "https://r8.googlesource.com/r8/+/c257fa927499a201643e32db1d8114420f85b26c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "dd26ff178c33e32396be00ba7484fe39e2141d20"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@dd26ff178c33e32396be00ba7484fe39e2141d20",
"title": "dd26ff178c33e32396be00ba7484fe39e2141d20",
"url": "https://r8.googlesource.com/r8/+/dd26ff178c33e32396be00ba7484fe39e2141d20"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "de6c0cb6769a7fc590bb6912624e5fa13c4dfcca"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@de6c0cb6769a7fc590bb6912624e5fa13c4dfcca",
"title": "de6c0cb6769a7fc590bb6912624e5fa13c4dfcca",
"url": "https://r8.googlesource.com/r8/+/de6c0cb6769a7fc590bb6912624e5fa13c4dfcca"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "de6c0cb6769a7fc590bb6912624e5fa13c4dfcca",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9003904691603196144"
},
{
"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"
}
}
[10:59:32.071] Scheduled build:
{
"id": "8838474629677423680",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 1983,
"createdBy": "project:r8",
"createTime": "2021-08-19T10:59:31.676855Z",
"updateTime": "2021-08-19T10:59:31.970211Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "de6c0cb6769a7fc590bb6912624e5fa13c4dfcca",
"ref": "refs/heads/main"
}
}
}
[10:59:32.071] Task URL: https://cr-buildbucket.appspot.com/build/8838474629677423680
[10:59:32.071] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:2:0) after 1m11s
[11:00:43.107] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:2:0)
[11:00:43.142] Build status: SCHEDULED
[11:00:43.142] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:3:0) after 7m33s
[11:08:16.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:3:0)
[11:08:16.205] Build status: SCHEDULED
[11:08:16.206] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:4:0) after 9m29s
[11:17:45.283] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:4:0)
[11:17:45.312] Build status: SCHEDULED
[11:17:45.312] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:5:0) after 9m33s
[11:27:18.406] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:5:0)
[11:27:18.441] Build status: SCHEDULED
[11:27:18.441] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:6:0) after 5m16s
[11:32:34.457] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:6:0)
[11:32:34.502] Build status: SCHEDULED
[11:32:34.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:7:0) after 9m59s
[11:42:33.536] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:7:0)
[11:42:33.567] Build status: SCHEDULED
[11:42:33.567] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:8:0) after 3m8s
[11:45:41.584] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:8:0)
[11:45:41.642] Build status: SCHEDULED
[11:45:41.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:9:0) after 6m1s
[11:51:42.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:9:0)
[11:51:42.757] Build status: SCHEDULED
[11:51:42.757] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:10:0) after 2m25s
[11:54:07.770] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:10:0)
[11:54:07.798] Build status: SCHEDULED
[11:54:07.798] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:11:0) after 3m47s
[11:57:54.890] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:11:0)
[11:57:54.920] Build status: SCHEDULED
[11:57:54.920] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:12:0) after 5m1s
[12:02:55.938] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:12:0)
[12:02:55.970] Build status: SCHEDULED
[12:02:55.970] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:13:0) after 2m7s
[12:05:02.981] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:13:0)
[12:05:03.017] Build status: SCHEDULED
[12:05:03.017] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:14:0) after 7m11s
[12:12:14.039] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:14:0)
[12:12:14.064] Build status: SCHEDULED
[12:12:14.064] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:15:0) after 8m37s
[12:20:51.157] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:15:0)
[12:20:51.184] Build status: SCHEDULED
[12:20:51.184] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:16:0) after 1m40s
[12:22:31.300] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:16:0)
[12:22:31.328] Build status: SCHEDULED
[12:22:31.328] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:17:0) after 1m51s
[12:24:22.348] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:17:0)
[12:24:22.397] Build status: SCHEDULED
[12:24:22.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:18:0) after 8m9s
[12:32:31.511] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:18:0)
[12:32:31.539] Build status: SCHEDULED
[12:32:31.539] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:19:0) after 7m30s
[12:40:01.728] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:19:0)
[12:40:01.751] Build status: SCHEDULED
[12:40:01.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:20:0) after 4m59s
[12:45:00.876] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:20:0)
[12:45:00.925] Build status: SCHEDULED
[12:45:00.925] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:21:0) after 6m8s
[12:51:09.034] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:21:0)
[12:51:09.071] Build status: SCHEDULED
[12:51:09.071] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:22:0) after 7m52s
[12:59:01.120] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:22:0)
[12:59:01.147] Build status: SCHEDULED
[12:59:01.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:23:0) after 2m12s
[13:01:13.172] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:23:0)
[13:01:13.205] Build status: SCHEDULED
[13:01:13.205] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:24:0) after 2m40s
[13:03:53.227] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:24:0)
[13:03:53.292] Build status: SCHEDULED
[13:03:53.292] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:25:0) after 6m5s
[13:09:58.390] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:25:0)
[13:09:58.454] Build status: SCHEDULED
[13:09:58.454] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:26:0) after 2m46s
[13:12:44.582] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:26:0)
[13:12:44.613] Build status: SCHEDULED
[13:12:44.613] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:27:0) after 1m35s
[13:14:19.864] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:27:0)
[13:14:19.899] Build status: SCHEDULED
[13:14:19.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:28:0) after 4m19s
[13:18:38.890] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:28:0)
[13:18:38.926] Build status: SCHEDULED
[13:18:38.926] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:29:0) after 2m2s
[13:20:41.086] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:29:0)
[13:20:41.112] Build status: SCHEDULED
[13:20:41.112] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:30:0) after 3m27s
[13:24:08.131] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:30:0)
[13:24:08.156] Build status: SCHEDULED
[13:24:08.156] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:31:0) after 7m55s
[13:32:03.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:31:0)
[13:32:03.337] Build status: SCHEDULED
[13:32:03.337] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:32:0) after 1m53s
[13:33:56.363] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:32:0)
[13:33:56.392] Build status: SCHEDULED
[13:33:56.392] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:33:0) after 9m11s
[13:43:07.532] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:33:0)
[13:43:07.570] Build status: SCHEDULED
[13:43:07.570] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:34:0) after 5m4s
[13:48:11.791] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:34:0)
[13:48:11.815] Build status: SCHEDULED
[13:48:11.815] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:35:0) after 4m16s
[13:52:27.865] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:35:0)
[13:52:27.892] Build status: SCHEDULED
[13:52:27.892] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:36:0) after 1m27s
[13:53:54.972] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:36:0)
[13:53:55.001] Build status: SCHEDULED
[13:53:55.001] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:37:0) after 4m38s
[13:58:33.212] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:37:0)
[13:58:33.254] Build status: SCHEDULED
[13:58:33.254] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:38:0) after 9m10s
[14:07:43.278] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:38:0)
[14:07:43.305] Build status: SCHEDULED
[14:07:43.306] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:39:0) after 1m41s
[14:09:24.385] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:39:0)
[14:09:24.414] Build status: SCHEDULED
[14:09:24.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:40:0) after 4m41s
[14:14:05.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:40:0)
[14:14:05.562] Build status: SCHEDULED
[14:14:05.562] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:41:0) after 9m59s
[14:24:05.061] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:41:0)
[14:24:05.097] Build status: SCHEDULED
[14:24:05.097] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:42:0) after 3m31s
[14:27:36.144] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:42:0)
[14:27:36.176] Build status: SCHEDULED
[14:27:36.177] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:43:0) after 6m14s
[14:33:50.186] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:43:0)
[14:33:50.214] Build status: SCHEDULED
[14:33:50.214] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:44:0) after 1m46s
[14:35:36.522] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:44:0)
[14:35:36.551] Build status: SCHEDULED
[14:35:36.551] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:45:0) after 3m44s
[14:39:20.565] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:45:0)
[14:39:20.594] Build status: SCHEDULED
[14:39:20.594] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:46:0) after 1m50s
[14:41:10.869] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:46:0)
[14:41:10.898] Build status: SCHEDULED
[14:41:10.898] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:47:0) after 6m49s
[14:47:59.918] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:47:0)
[14:47:59.950] Build status: SCHEDULED
[14:47:59.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:48:0) after 8m11s
[14:56:11.080] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:48:0)
[14:56:11.154] Build status: SCHEDULED
[14:56:11.154] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:49:0) after 3m28s
[14:59:39.679] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:49:0)
[14:59:39.707] Build status: SCHEDULED
[14:59:39.707] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:50:0) after 9m9s
[15:08:48.730] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:50:0)
[15:08:48.758] Build status: SCHEDULED
[15:08:48.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:51:0) after 2m33s
[15:11:21.790] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:51:0)
[15:11:21.831] Build status: SCHEDULED
[15:11:21.831] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:52:0) after 9m25s
[15:20:47.015] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:52:0)
[15:20:47.078] Build status: SCHEDULED
[15:20:47.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:53:0) after 1m40s
[15:22:27.464] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:53:0)
[15:22:28.932] Build status: SCHEDULED
[15:22:28.932] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:54:0) after 7m22s
[15:29:50.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:54:0)
[15:29:51.005] Build status: SCHEDULED
[15:29:51.005] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:55:0) after 1m36s
[15:31:27.695] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:55:0)
[15:31:27.729] Build status: SCHEDULED
[15:31:27.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:56:0) after 1m49s
[15:33:16.849] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:56:0)
[15:33:16.940] Build status: SCHEDULED
[15:33:16.940] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:57:0) after 9m3s
[15:42:19.961] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:57:0)
[15:42:19.990] Build status: SCHEDULED
[15:42:19.990] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:58:0) after 5m8s
[15:47:28.150] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:58:0)
[15:47:28.176] Build status: SCHEDULED
[15:47:28.176] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:59:0) after 4m25s
[15:51:53.179] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:59:0)
[15:51:53.224] Build status: SCHEDULED
[15:51:53.224] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:60:0) after 5m49s
[15:57:42.264] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:60:0)
[15:57:42.292] Build status: SCHEDULED
[15:57:42.292] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:61:0) after 7m55s
[16:05:37.319] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:61:0)
[16:05:37.348] Build status: SCHEDULED
[16:05:37.348] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:62:0) after 9m44s
[16:15:21.606] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:62:0)
[16:15:21.683] Build status: SCHEDULED
[16:15:21.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:63:0) after 4m47s
[16:20:08.806] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:63:0)
[16:20:08.838] Build status: SCHEDULED
[16:20:08.838] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:64:0) after 1m14s
[16:21:22.958] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:64:0)
[16:21:22.988] Build status: SCHEDULED
[16:21:22.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:65:0) after 5m21s
[16:26:44.011] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:65:0)
[16:26:44.040] Build status: SCHEDULED
[16:26:44.040] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:66:0) after 2m53s
[16:29:37.249] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:66:0)
[16:29:37.280] Build status: SCHEDULED
[16:29:37.280] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:67:0) after 7m1s
[16:34:44.736] Received PubSub notification, asking Buildbucket for the build status
[16:34:44.764] Build status: STARTED
[16:36:38.314] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:67:0)
[16:36:38.348] Build status: STARTED
[16:36:38.348] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:69:0) after 5m25s
[16:42:03.393] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:69:0)
[16:42:03.425] Build status: STARTED
[16:42:03.425] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:70:0) after 7m29s
[16:49:32.547] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:70:0)
[16:49:32.573] Build status: STARTED
[16:49:32.573] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:71:0) after 1m18s
[16:50:50.602] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:71:0)
[16:50:50.633] Build status: STARTED
[16:50:50.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:72:0) after 8m39s
[16:59:29.996] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:72:0)
[16:59:30.024] Build status: STARTED
[16:59:30.024] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:73:0) after 5m7s
[17:04:37.090] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:73:0)
[17:04:37.122] Build status: STARTED
[17:04:37.122] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:74:0) after 3m17s
[17:07:54.646] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:74:0)
[17:07:54.682] Build status: STARTED
[17:07:54.682] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:75:0) after 9m49s
[17:17:43.702] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:75:0)
[17:17:43.734] Build status: STARTED
[17:17:43.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:76:0) after 2m58s
[17:20:41.755] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:76:0)
[17:20:41.798] Build status: STARTED
[17:20:41.798] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:77:0) after 7m36s
[17:28:17.823] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:77:0)
[17:28:17.850] Build status: STARTED
[17:28:17.850] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:78:0) after 9m10s
[17:37:28.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:78:0)
[17:37:28.262] Build status: STARTED
[17:37:28.262] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:79:0) after 2m48s
[17:40:16.360] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:79:0)
[17:40:16.420] Build status: STARTED
[17:40:16.420] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:80:0) after 1m24s
[17:41:40.445] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:80:0)
[17:41:40.488] Build status: STARTED
[17:41:40.488] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:81:0) after 1m59s
[17:43:39.636] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:81:0)
[17:43:39.678] Build status: STARTED
[17:43:39.678] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:82:0) after 6m11s
[17:49:50.964] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:82:0)
[17:49:50.988] Build status: STARTED
[17:49:50.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:83:0) after 1m42s
[17:51:33.119] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:83:0)
[17:51:33.153] Build status: STARTED
[17:51:33.153] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:84:0) after 4m1s
[17:55:34.191] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:84:0)
[17:55:34.239] Build status: STARTED
[17:55:34.239] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:85:0) after 1m8s
[17:56:42.365] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:85:0)
[17:56:42.393] Build status: STARTED
[17:56:42.393] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:86:0) after 4m12s
[18:00:54.431] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:86:0)
[18:00:54.461] Build status: STARTED
[18:00:54.461] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:87:0) after 4m30s
[18:05:24.496] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:87:0)
[18:05:24.525] Build status: STARTED
[18:05:24.525] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:88:0) after 7m2s
[18:12:26.553] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:88:0)
[18:12:26.660] Build status: STARTED
[18:12:26.661] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:89:0) after 1m44s
[18:14:10.875] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:89:0)
[18:14:10.907] Build status: STARTED
[18:14:10.907] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:90:0) after 1m7s
[18:15:17.957] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:90:0)
[18:15:18.005] Build status: STARTED
[18:15:18.005] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:91:0) after 4m55s
[18:20:13.095] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:91:0)
[18:20:13.188] Build status: STARTED
[18:20:13.188] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:92:0) after 4m2s
[18:24:15.965] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:92:0)
[18:24:15.992] Build status: STARTED
[18:24:15.992] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:93:0) after 2m29s
[18:26:45.324] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:93:0)
[18:26:45.370] Build status: STARTED
[18:26:45.370] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:94:0) after 4m32s
[18:31:17.392] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:94:0)
[18:31:17.420] Build status: STARTED
[18:31:17.420] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:95:0) after 5m14s
[18:36:32.067] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:95:0)
[18:36:32.101] Build status: STARTED
[18:36:32.101] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:96:0) after 9m22s
[18:45:54.131] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:96:0)
[18:45:54.156] Build status: STARTED
[18:45:54.156] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:97:0) after 2m51s
[18:48:45.760] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:97:0)
[18:48:45.801] Build status: STARTED
[18:48:45.801] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:98:0) after 9m22s
[18:58:07.830] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:98:0)
[18:58:07.861] Build status: STARTED
[18:58:07.861] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:99:0) after 6m37s
[19:04:45.174] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:99:0)
[19:04:45.206] Build status: STARTED
[19:04:45.206] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:100:0) after 3m17s
[19:08:02.440] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:100:0)
[19:08:02.487] Build status: STARTED
[19:08:02.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:101:0) after 8m7s
[19:16:09.553] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:101:0)
[19:16:09.617] Build status: STARTED
[19:16:09.617] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:102:0) after 2m47s
[19:18:56.618] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:102:0)
[19:18:56.664] Build status: STARTED
[19:18:56.665] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:103:0) after 3m19s
[19:22:15.689] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:103:0)
[19:22:15.715] Build status: STARTED
[19:22:15.715] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:104:0) after 4m26s
[19:26:42.024] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:104:0)
[19:26:42.064] Build status: STARTED
[19:26:42.064] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:105:0) after 7m54s
[19:34:36.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:105:0)
[19:34:36.257] Build status: STARTED
[19:34:36.257] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:106:0) after 9m54s
[19:44:30.384] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:106:0)
[19:44:30.419] Build status: STARTED
[19:44:30.419] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:107:0) after 5m45s
[19:50:16.021] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:107:0)
[19:50:16.086] Build status: STARTED
[19:50:16.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:108:0) after 8m42s
[19:58:58.362] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:108:0)
[19:58:58.490] Build status: STARTED
[19:58:58.490] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:109:0) after 3m54s
[20:02:52.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:109:0)
[20:02:52.683] Build status: STARTED
[20:02:52.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:110:0) after 1m12s
[20:04:04.750] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:110:0)
[20:04:04.805] Build status: STARTED
[20:04:04.805] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:111:0) after 9m57s
[20:14:01.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:111:0)
[20:14:01.859] Build status: STARTED
[20:14:01.859] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:112:0) after 8m34s
[20:22:36.136] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:112:0)
[20:22:36.168] Build status: STARTED
[20:22:36.168] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:113:0) after 8m34s
[20:31:10.293] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:113:0)
[20:31:10.326] Build status: STARTED
[20:31:10.326] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:114:0) after 8m42s
[20:39:52.354] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:114:0)
[20:39:52.387] Build status: STARTED
[20:39:52.387] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:115:0) after 8m18s
[20:48:10.488] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:115:0)
[20:48:10.519] Build status: STARTED
[20:48:10.519] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:116:0) after 4m15s
[20:52:25.629] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:116:0)
[20:52:25.681] Build status: STARTED
[20:52:25.681] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:117:0) after 2m55s
[20:55:20.998] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:117:0)
[20:55:21.027] Build status: STARTED
[20:55:21.027] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003904691603196144:118:0) after 9m9s
[21:00:15.328] Received PubSub notification, asking Buildbucket for the build status
[21:00:15.362] Build:
{
"id": "8838474629677423680",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 1983,
"createdBy": "project:r8",
"createTime": "2021-08-19T10:59:31.676855Z",
"startTime": "2021-08-19T16:34:43.798511Z",
"endTime": "2021-08-19T21:00:14.219505Z",
"updateTime": "2021-08-19T21:00:15.008545Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "de6c0cb6769a7fc590bb6912624e5fa13c4dfcca",
"ref": "refs/heads/main"
}
}
}
[21:00:15.362] Invocation finished in 10h0m44.906402351s with status SUCCEEDED