[12:26:38.889] New invocation is queued and will start shortly
[12:26:40.057] Starting the invocation (attempt 1)
[12:26:40.091] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/fb2583ad11b67b4d7d85fa8bfc7cdf651f760241"
[12:26:40.091] Popped gitiles commit info from properties and tags
[12:26:40.091] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:26:40.091] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:26:40.091] Buildbucket request:
{
"requestId": "8980343997990468320",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8980343997990468320",
"job": "r8/linux-internal",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "114a8eab88f3a689be9dfd689d03d4aa5517bbb3"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@114a8eab88f3a689be9dfd689d03d4aa5517bbb3",
"title": "114a8eab88f3a689be9dfd689d03d4aa5517bbb3",
"url": "https://r8.googlesource.com/r8/+/114a8eab88f3a689be9dfd689d03d4aa5517bbb3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "f4419c34eb23ad5735b6dddc980022b84dfabaf8"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@f4419c34eb23ad5735b6dddc980022b84dfabaf8",
"title": "f4419c34eb23ad5735b6dddc980022b84dfabaf8",
"url": "https://r8.googlesource.com/r8/+/f4419c34eb23ad5735b6dddc980022b84dfabaf8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "8c2796909a1895dad52d102d9ce23ce2bd9f1eed"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@8c2796909a1895dad52d102d9ce23ce2bd9f1eed",
"title": "8c2796909a1895dad52d102d9ce23ce2bd9f1eed",
"url": "https://r8.googlesource.com/r8/+/8c2796909a1895dad52d102d9ce23ce2bd9f1eed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "84d513bf8bcf726a4f4fc409e7e40e3957d65526"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@84d513bf8bcf726a4f4fc409e7e40e3957d65526",
"title": "84d513bf8bcf726a4f4fc409e7e40e3957d65526",
"url": "https://r8.googlesource.com/r8/+/84d513bf8bcf726a4f4fc409e7e40e3957d65526"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "d0391a5f06c06464d910ac8618300f4427a26351"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@d0391a5f06c06464d910ac8618300f4427a26351",
"title": "d0391a5f06c06464d910ac8618300f4427a26351",
"url": "https://r8.googlesource.com/r8/+/d0391a5f06c06464d910ac8618300f4427a26351"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "5ca4c974a3b2035a84e1bfe1753b50fff6b1e1d0"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@5ca4c974a3b2035a84e1bfe1753b50fff6b1e1d0",
"title": "5ca4c974a3b2035a84e1bfe1753b50fff6b1e1d0",
"url": "https://r8.googlesource.com/r8/+/5ca4c974a3b2035a84e1bfe1753b50fff6b1e1d0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "28f5d1b91f954e18c85d3347564f92ba29dd766d"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@28f5d1b91f954e18c85d3347564f92ba29dd766d",
"title": "28f5d1b91f954e18c85d3347564f92ba29dd766d",
"url": "https://r8.googlesource.com/r8/+/28f5d1b91f954e18c85d3347564f92ba29dd766d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "2029c6b5b6fa4600446d41ec88e822616bd55531"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@2029c6b5b6fa4600446d41ec88e822616bd55531",
"title": "2029c6b5b6fa4600446d41ec88e822616bd55531",
"url": "https://r8.googlesource.com/r8/+/2029c6b5b6fa4600446d41ec88e822616bd55531"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "c49911df99a063d7680db1a0698507628319b690"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@c49911df99a063d7680db1a0698507628319b690",
"title": "c49911df99a063d7680db1a0698507628319b690",
"url": "https://r8.googlesource.com/r8/+/c49911df99a063d7680db1a0698507628319b690"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "eb1b0bce63762b310c1d5adf1d18fd6550dc5872"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@eb1b0bce63762b310c1d5adf1d18fd6550dc5872",
"title": "eb1b0bce63762b310c1d5adf1d18fd6550dc5872",
"url": "https://r8.googlesource.com/r8/+/eb1b0bce63762b310c1d5adf1d18fd6550dc5872"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "9620d986b550d126361ac0d0b604bb4aebd3fd3f"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@9620d986b550d126361ac0d0b604bb4aebd3fd3f",
"title": "9620d986b550d126361ac0d0b604bb4aebd3fd3f",
"url": "https://r8.googlesource.com/r8/+/9620d986b550d126361ac0d0b604bb4aebd3fd3f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "adafbdac59b720faad86822fefa8e8cf8e436046"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@adafbdac59b720faad86822fefa8e8cf8e436046",
"title": "adafbdac59b720faad86822fefa8e8cf8e436046",
"url": "https://r8.googlesource.com/r8/+/adafbdac59b720faad86822fefa8e8cf8e436046"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "6b97a95611185674292cf3dbddaa29e517f9b720"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@6b97a95611185674292cf3dbddaa29e517f9b720",
"title": "6b97a95611185674292cf3dbddaa29e517f9b720",
"url": "https://r8.googlesource.com/r8/+/6b97a95611185674292cf3dbddaa29e517f9b720"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "7fb2927f5bb63bd2f8ee0ab4ac9cbb6d9628ca7e"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@7fb2927f5bb63bd2f8ee0ab4ac9cbb6d9628ca7e",
"title": "7fb2927f5bb63bd2f8ee0ab4ac9cbb6d9628ca7e",
"url": "https://r8.googlesource.com/r8/+/7fb2927f5bb63bd2f8ee0ab4ac9cbb6d9628ca7e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "3545a8205135bec20ba6a14148182182a0dd09af"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@3545a8205135bec20ba6a14148182182a0dd09af",
"title": "3545a8205135bec20ba6a14148182182a0dd09af",
"url": "https://r8.googlesource.com/r8/+/3545a8205135bec20ba6a14148182182a0dd09af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "024e93124c9757141df63e6233af622fcd2aa418"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@024e93124c9757141df63e6233af622fcd2aa418",
"title": "024e93124c9757141df63e6233af622fcd2aa418",
"url": "https://r8.googlesource.com/r8/+/024e93124c9757141df63e6233af622fcd2aa418"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "f1e5f8d015fa7408bf11a4f94c13de73c39ca9c9"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@f1e5f8d015fa7408bf11a4f94c13de73c39ca9c9",
"title": "f1e5f8d015fa7408bf11a4f94c13de73c39ca9c9",
"url": "https://r8.googlesource.com/r8/+/f1e5f8d015fa7408bf11a4f94c13de73c39ca9c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "47ecf0b666eb183c828d86289c361540b7eb7904"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@47ecf0b666eb183c828d86289c361540b7eb7904",
"title": "47ecf0b666eb183c828d86289c361540b7eb7904",
"url": "https://r8.googlesource.com/r8/+/47ecf0b666eb183c828d86289c361540b7eb7904"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "784d5b2d9a060c3c0b27fcbe8824c1986a6280a5"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@784d5b2d9a060c3c0b27fcbe8824c1986a6280a5",
"title": "784d5b2d9a060c3c0b27fcbe8824c1986a6280a5",
"url": "https://r8.googlesource.com/r8/+/784d5b2d9a060c3c0b27fcbe8824c1986a6280a5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "fb2583ad11b67b4d7d85fa8bfc7cdf651f760241"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@fb2583ad11b67b4d7d85fa8bfc7cdf651f760241",
"title": "fb2583ad11b67b4d7d85fa8bfc7cdf651f760241",
"url": "https://r8.googlesource.com/r8/+/fb2583ad11b67b4d7d85fa8bfc7cdf651f760241"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "fb2583ad11b67b4d7d85fa8bfc7cdf651f760241",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8980343997990468320"
},
{
"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:26:40.642] Scheduled build:
{
"id": "8814913935958872353",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2533,
"createdBy": "project:r8",
"createTime": "2022-05-06T12:26:40.152239108Z",
"updateTime": "2022-05-06T12:26:40.152239108Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "fb2583ad11b67b4d7d85fa8bfc7cdf651f760241",
"ref": "refs/heads/main"
}
}
}
[12:26:40.642] Task URL: https://cr-buildbucket.appspot.com/build/8814913935958872353
[12:26:40.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:2:0) after 4m51s
[12:31:31.686] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:2:0)
[12:31:31.744] Build status: SCHEDULED
[12:31:31.744] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:3:0) after 8m55s
[12:40:26.887] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:3:0)
[12:40:26.915] Build status: SCHEDULED
[12:40:26.915] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:4:0) after 3m17s
[12:43:43.934] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:4:0)
[12:43:43.968] Build status: SCHEDULED
[12:43:43.968] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:5:0) after 3m35s
[12:47:19.442] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:5:0)
[12:47:19.479] Build status: SCHEDULED
[12:47:19.479] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:6:0) after 9m45s
[12:57:04.453] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:6:0)
[12:57:04.485] Build status: SCHEDULED
[12:57:04.485] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:7:0) after 1m12s
[12:58:16.651] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:7:0)
[12:58:16.691] Build status: SCHEDULED
[12:58:16.691] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:8:0) after 8m15s
[13:06:31.836] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:8:0)
[13:06:31.881] Build status: SCHEDULED
[13:06:31.881] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:9:0) after 2m13s
[13:08:44.897] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:9:0)
[13:08:44.929] Build status: SCHEDULED
[13:08:44.929] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:10:0) after 5m25s
[13:14:09.951] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:10:0)
[13:14:09.984] Build status: SCHEDULED
[13:14:09.984] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:11:0) after 9m13s
[13:23:23.035] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:11:0)
[13:23:23.164] Build status: SCHEDULED
[13:23:23.164] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:12:0) after 5m45s
[13:29:08.195] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:12:0)
[13:29:08.229] Build status: SCHEDULED
[13:29:08.230] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:13:0) after 4m35s
[13:33:43.258] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:13:0)
[13:33:43.335] Build status: SCHEDULED
[13:33:43.335] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:14:0) after 8m17s
[13:42:00.454] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:14:0)
[13:42:00.519] Build status: SCHEDULED
[13:42:00.519] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:15:0) after 7m24s
[13:49:24.539] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:15:0)
[13:49:24.573] Build status: SCHEDULED
[13:49:24.573] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:16:0) after 6m15s
[13:55:39.720] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:16:0)
[13:55:39.769] Build status: SCHEDULED
[13:55:39.769] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:17:0) after 6m33s
[14:02:12.754] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:17:0)
[14:02:12.782] Build status: SCHEDULED
[14:02:12.782] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:18:0) after 2m38s
[14:04:50.816] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:18:0)
[14:04:50.863] Build status: SCHEDULED
[14:04:50.863] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:19:0) after 9m47s
[14:14:38.051] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:19:0)
[14:14:38.081] Build status: SCHEDULED
[14:14:38.081] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:20:0) after 5m0s
[14:19:38.400] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:20:0)
[14:19:38.462] Build status: SCHEDULED
[14:19:38.462] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:21:0) after 6m44s
[14:26:22.774] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:21:0)
[14:26:22.842] Build status: SCHEDULED
[14:26:22.842] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:22:0) after 8m3s
[14:34:25.947] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:22:0)
[14:34:25.977] Build status: SCHEDULED
[14:34:25.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:23:0) after 8m38s
[14:43:04.003] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:23:0)
[14:43:04.063] Build status: SCHEDULED
[14:43:04.063] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:24:0) after 9m51s
[14:52:55.087] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:24:0)
[14:52:55.110] Build status: SCHEDULED
[14:52:55.110] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:25:0) after 5m31s
[14:58:26.150] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:25:0)
[14:58:26.171] Build status: SCHEDULED
[14:58:26.171] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:26:0) after 6m1s
[15:04:27.278] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:26:0)
[15:04:27.301] Build status: SCHEDULED
[15:04:27.301] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:27:0) after 8m34s
[15:13:01.341] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:27:0)
[15:13:01.386] Build status: SCHEDULED
[15:13:01.386] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:28:0) after 4m23s
[15:17:24.407] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:28:0)
[15:17:24.439] Build status: SCHEDULED
[15:17:24.439] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:29:0) after 5m56s
[15:23:20.463] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:29:0)
[15:23:20.500] Build status: SCHEDULED
[15:23:20.500] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:30:0) after 7m16s
[15:30:36.636] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:30:0)
[15:30:36.791] Build status: SCHEDULED
[15:30:36.791] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:31:0) after 6m58s
[15:37:34.814] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:31:0)
[15:37:34.849] Build status: SCHEDULED
[15:37:34.849] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:32:0) after 3m41s
[15:41:15.937] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:32:0)
[15:41:15.973] Build status: SCHEDULED
[15:41:15.973] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:33:0) after 5m55s
[15:47:11.009] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:33:0)
[15:47:11.101] Build status: SCHEDULED
[15:47:11.101] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:34:0) after 3m57s
[15:51:08.128] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:34:0)
[15:51:08.167] Build status: SCHEDULED
[15:51:08.167] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:35:0) after 7m29s
[15:58:37.951] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:35:0)
[15:58:37.990] Build status: SCHEDULED
[15:58:37.990] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:36:0) after 6m32s
[16:05:10.515] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:36:0)
[16:05:10.568] Build status: SCHEDULED
[16:05:10.568] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:37:0) after 3m51s
[16:09:02.336] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:37:0)
[16:09:02.377] Build status: SCHEDULED
[16:09:02.377] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:38:0) after 5m57s
[16:14:59.395] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:38:0)
[16:14:59.432] Build status: SCHEDULED
[16:14:59.432] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:39:0) after 1m47s
[16:16:46.450] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:39:0)
[16:16:46.489] Build status: SCHEDULED
[16:16:46.489] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:40:0) after 5m19s
[16:22:05.566] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:40:0)
[16:22:05.614] Build status: SCHEDULED
[16:22:05.614] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:41:0) after 3m31s
[16:25:36.640] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:41:0)
[16:25:36.683] Build status: SCHEDULED
[16:25:36.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:42:0) after 1m33s
[16:27:09.819] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:42:0)
[16:27:09.854] Build status: SCHEDULED
[16:27:09.854] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:43:0) after 1m41s
[16:28:50.973] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:43:0)
[16:28:51.010] Build status: SCHEDULED
[16:28:51.010] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:44:0) after 6m28s
[16:35:19.034] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:44:0)
[16:35:19.087] Build status: SCHEDULED
[16:35:19.087] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:45:0) after 6m22s
[16:41:41.111] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:45:0)
[16:41:41.162] Build status: SCHEDULED
[16:41:41.162] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:46:0) after 6m57s
[16:48:38.211] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:46:0)
[16:48:38.259] Build status: SCHEDULED
[16:48:38.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:47:0) after 8m49s
[16:57:27.284] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:47:0)
[16:57:27.316] Build status: SCHEDULED
[16:57:27.316] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:48:0) after 6m42s
[17:04:09.362] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:48:0)
[17:04:09.397] Build status: SCHEDULED
[17:04:09.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:49:0) after 5m12s
[17:09:21.386] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:49:0)
[17:09:21.421] Build status: SCHEDULED
[17:09:21.421] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:50:0) after 4m24s
[17:13:45.468] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:50:0)
[17:13:45.507] Build status: SCHEDULED
[17:13:45.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:51:0) after 8m11s
[17:21:56.577] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:51:0)
[17:21:56.621] Build status: SCHEDULED
[17:21:56.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:52:0) after 6m45s
[17:26:06.138] Received PubSub notification, asking Buildbucket for the build status
[17:26:06.171] Build status: STARTED
[17:28:41.617] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:52:0)
[17:28:41.651] Build status: STARTED
[17:28:41.652] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:54:0) after 4m45s
[17:33:26.683] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:54:0)
[17:33:26.717] Build status: STARTED
[17:33:26.717] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:55:0) after 2m51s
[17:36:17.709] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:55:0)
[17:36:17.768] Build status: STARTED
[17:36:17.768] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:56:0) after 7m53s
[17:44:10.844] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:56:0)
[17:44:10.883] Build status: STARTED
[17:44:10.883] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:57:0) after 1m16s
[17:45:26.878] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:57:0)
[17:45:26.910] Build status: STARTED
[17:45:26.910] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:58:0) after 1m30s
[17:46:56.929] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:58:0)
[17:46:56.962] Build status: STARTED
[17:46:56.962] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:59:0) after 1m21s
[17:48:17.988] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:59:0)
[17:48:18.010] Build status: STARTED
[17:48:18.010] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:60:0) after 7m50s
[17:56:08.108] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:60:0)
[17:56:08.147] Build status: STARTED
[17:56:08.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:61:0) after 3m10s
[17:59:18.209] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:61:0)
[17:59:18.325] Build status: STARTED
[17:59:18.325] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:62:0) after 7m26s
[18:06:44.352] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:62:0)
[18:06:44.435] Build status: STARTED
[18:06:44.435] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:63:0) after 4m51s
[18:11:35.446] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:63:0)
[18:11:35.488] Build status: STARTED
[18:11:35.488] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:64:0) after 5m24s
[18:16:59.562] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:64:0)
[18:16:59.603] Build status: STARTED
[18:16:59.603] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:65:0) after 1m49s
[18:18:48.634] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:65:0)
[18:18:48.675] Build status: STARTED
[18:18:48.675] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:66:0) after 3m54s
[18:22:42.745] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:66:0)
[18:22:42.802] Build status: STARTED
[18:22:42.803] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:67:0) after 8m35s
[18:31:17.832] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:67:0)
[18:31:17.903] Build status: STARTED
[18:31:17.903] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:68:0) after 7m37s
[18:38:54.924] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:68:0)
[18:38:54.971] Build status: STARTED
[18:38:54.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:69:0) after 9m49s
[18:48:43.992] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:69:0)
[18:48:44.025] Build status: STARTED
[18:48:44.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:70:0) after 7m7s
[18:55:51.073] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:70:0)
[18:55:51.143] Build status: STARTED
[18:55:51.143] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:71:0) after 3m45s
[18:59:36.165] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:71:0)
[18:59:36.264] Build status: STARTED
[18:59:36.264] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:72:0) after 3m13s
[19:02:49.366] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:72:0)
[19:02:49.408] Build status: STARTED
[19:02:49.408] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:73:0) after 9m23s
[19:12:12.387] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:73:0)
[19:12:12.425] Build status: STARTED
[19:12:12.425] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:74:0) after 1m15s
[19:13:27.445] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:74:0)
[19:13:27.482] Build status: STARTED
[19:13:27.482] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:75:0) after 7m5s
[19:20:32.852] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:75:0)
[19:20:32.883] Build status: STARTED
[19:20:32.883] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:76:0) after 3m43s
[19:24:16.071] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:76:0)
[19:24:16.117] Build status: STARTED
[19:24:16.117] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:77:0) after 3m2s
[19:27:18.347] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:77:0)
[19:27:18.387] Build status: STARTED
[19:27:18.387] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:78:0) after 2m14s
[19:29:32.506] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:78:0)
[19:29:32.656] Build status: STARTED
[19:29:32.656] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:79:0) after 2m20s
[19:31:52.782] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:79:0)
[19:31:52.820] Build status: STARTED
[19:31:52.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:80:0) after 8m38s
[19:40:30.937] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:80:0)
[19:40:30.975] Build status: STARTED
[19:40:30.975] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:81:0) after 6m8s
[19:46:39.035] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:81:0)
[19:46:39.096] Build status: STARTED
[19:46:39.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:82:0) after 7m49s
[19:54:28.216] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:82:0)
[19:54:28.246] Build status: STARTED
[19:54:28.246] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:83:0) after 4m2s
[19:58:30.433] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:83:0)
[19:58:30.566] Build status: STARTED
[19:58:30.566] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:84:0) after 2m22s
[20:00:53.443] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:84:0)
[20:00:53.476] Build status: STARTED
[20:00:53.476] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:85:0) after 4m20s
[20:05:13.522] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:85:0)
[20:05:13.562] Build status: STARTED
[20:05:13.562] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:86:0) after 8m33s
[20:13:46.629] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:86:0)
[20:13:46.668] Build status: STARTED
[20:13:46.668] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:87:0) after 5m22s
[20:19:08.764] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:87:0)
[20:19:08.851] Build status: STARTED
[20:19:08.851] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:88:0) after 9m59s
[20:29:07.910] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:88:0)
[20:29:07.966] Build status: STARTED
[20:29:07.966] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:89:0) after 2m16s
[20:31:24.073] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:89:0)
[20:31:25.693] Build status: STARTED
[20:31:25.693] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:90:0) after 3m51s
[20:35:16.712] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:90:0)
[20:35:16.746] Build status: STARTED
[20:35:16.746] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:91:0) after 5m34s
[20:40:50.781] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:91:0)
[20:40:50.803] Build status: STARTED
[20:40:50.803] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:92:0) after 8m2s
[20:48:52.829] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:92:0)
[20:48:52.858] Build status: STARTED
[20:48:52.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:93:0) after 7m17s
[20:56:10.156] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:93:0)
[20:56:10.197] Build status: STARTED
[20:56:10.197] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:94:0) after 6m46s
[21:02:56.265] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:94:0)
[21:02:56.380] Build status: STARTED
[21:02:56.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:95:0) after 1m13s
[21:04:09.490] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:95:0)
[21:04:09.547] Build status: STARTED
[21:04:09.547] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:96:0) after 4m54s
[21:09:03.568] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:96:0)
[21:09:03.609] Build status: STARTED
[21:09:03.609] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:97:0) after 3m59s
[21:13:03.132] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:97:0)
[21:13:03.208] Build status: STARTED
[21:13:03.208] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:98:0) after 7m7s
[21:20:10.227] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:98:0)
[21:20:10.277] Build status: STARTED
[21:20:10.277] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:99:0) after 6m19s
[21:26:29.299] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:99:0)
[21:26:29.334] Build status: STARTED
[21:26:29.334] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:100:0) after 4m59s
[21:31:28.786] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:100:0)
[21:31:28.858] Build status: STARTED
[21:31:28.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:101:0) after 2m51s
[21:34:19.906] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:101:0)
[21:34:19.955] Build status: STARTED
[21:34:19.955] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:102:0) after 1m20s
[21:35:39.975] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:102:0)
[21:35:40.010] Build status: STARTED
[21:35:40.010] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:103:0) after 2m59s
[21:38:38.994] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:103:0)
[21:38:39.029] Build status: STARTED
[21:38:39.029] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:104:0) after 9m35s
[21:48:14.087] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:104:0)
[21:48:14.170] Build status: STARTED
[21:48:14.170] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:105:0) after 2m26s
[21:50:40.383] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:105:0)
[21:50:40.438] Build status: STARTED
[21:50:40.438] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:106:0) after 7m55s
[21:58:35.569] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:106:0)
[21:58:35.606] Build status: STARTED
[21:58:35.606] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8980343997990468320:107:0) after 9m41s
[22:08:16.709] Received PubSub notification, asking Buildbucket for the build status
[22:08:16.753] Build:
{
"id": "8814913935958872353",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2533,
"createdBy": "project:r8",
"createTime": "2022-05-06T12:26:40.152239108Z",
"startTime": "2022-05-06T17:26:02.710997Z",
"endTime": "2022-05-06T22:08:14.825716449Z",
"updateTime": "2022-05-06T22:08:14.825716449Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "fb2583ad11b67b4d7d85fa8bfc7cdf651f760241",
"ref": "refs/heads/main"
}
}
}
[22:08:16.753] Invocation finished in 9h41m37.921899547s with status SUCCEEDED