[16:10:27.300] New invocation is queued and will start shortly
[16:10:28.545] Starting the invocation (attempt 1)
[16:10:28.581] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/ead0d8b056fa36d1f8903261f4752908bc81f9c8"
[16:10:28.581] Popped gitiles commit info from properties and tags
[16:10:28.581] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:10:28.581] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:10:28.582] Buildbucket request:
{
"requestId": "9002616770994015648",
"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": "7316c68f93a555890e77bc1d0d65424dba5ea58a"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@7316c68f93a555890e77bc1d0d65424dba5ea58a",
"title": "7316c68f93a555890e77bc1d0d65424dba5ea58a",
"url": "https://r8.googlesource.com/r8/+/7316c68f93a555890e77bc1d0d65424dba5ea58a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "5f4a0a8b4291f09f46ccdc1ba7c72f04b17aa0c3"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@5f4a0a8b4291f09f46ccdc1ba7c72f04b17aa0c3",
"title": "5f4a0a8b4291f09f46ccdc1ba7c72f04b17aa0c3",
"url": "https://r8.googlesource.com/r8/+/5f4a0a8b4291f09f46ccdc1ba7c72f04b17aa0c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "fb2af2e9d9b5aa7af9c00701307c41d23ed9f4b9"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@fb2af2e9d9b5aa7af9c00701307c41d23ed9f4b9",
"title": "fb2af2e9d9b5aa7af9c00701307c41d23ed9f4b9",
"url": "https://r8.googlesource.com/r8/+/fb2af2e9d9b5aa7af9c00701307c41d23ed9f4b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "4e5f8723f73d7020cdab79469c999b068910d55a"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@4e5f8723f73d7020cdab79469c999b068910d55a",
"title": "4e5f8723f73d7020cdab79469c999b068910d55a",
"url": "https://r8.googlesource.com/r8/+/4e5f8723f73d7020cdab79469c999b068910d55a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "029ab7adda8a4936fc64a680fae7d4ff6209cc07"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@029ab7adda8a4936fc64a680fae7d4ff6209cc07",
"title": "029ab7adda8a4936fc64a680fae7d4ff6209cc07",
"url": "https://r8.googlesource.com/r8/+/029ab7adda8a4936fc64a680fae7d4ff6209cc07"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "9b5d211b1c211b7c512bf2f733a6ecdc3cf3f5bc"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@9b5d211b1c211b7c512bf2f733a6ecdc3cf3f5bc",
"title": "9b5d211b1c211b7c512bf2f733a6ecdc3cf3f5bc",
"url": "https://r8.googlesource.com/r8/+/9b5d211b1c211b7c512bf2f733a6ecdc3cf3f5bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "3c94aecba25aedf4b9f8b7a7ced7b8658ab6f5df"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@3c94aecba25aedf4b9f8b7a7ced7b8658ab6f5df",
"title": "3c94aecba25aedf4b9f8b7a7ced7b8658ab6f5df",
"url": "https://r8.googlesource.com/r8/+/3c94aecba25aedf4b9f8b7a7ced7b8658ab6f5df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "df2d04a57cba70fb66c040ec985606eeb0e7a0b9"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@df2d04a57cba70fb66c040ec985606eeb0e7a0b9",
"title": "df2d04a57cba70fb66c040ec985606eeb0e7a0b9",
"url": "https://r8.googlesource.com/r8/+/df2d04a57cba70fb66c040ec985606eeb0e7a0b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "93ef4789c20abf013611e8808ec408fb6bf77acb"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@93ef4789c20abf013611e8808ec408fb6bf77acb",
"title": "93ef4789c20abf013611e8808ec408fb6bf77acb",
"url": "https://r8.googlesource.com/r8/+/93ef4789c20abf013611e8808ec408fb6bf77acb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "ab9f5a35d7868e888e65e7b2bd2cc96209d5fa0c"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@ab9f5a35d7868e888e65e7b2bd2cc96209d5fa0c",
"title": "ab9f5a35d7868e888e65e7b2bd2cc96209d5fa0c",
"url": "https://r8.googlesource.com/r8/+/ab9f5a35d7868e888e65e7b2bd2cc96209d5fa0c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "c4a2921bb5661e4c2e062caf5e348c5d510dffa3"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@c4a2921bb5661e4c2e062caf5e348c5d510dffa3",
"title": "c4a2921bb5661e4c2e062caf5e348c5d510dffa3",
"url": "https://r8.googlesource.com/r8/+/c4a2921bb5661e4c2e062caf5e348c5d510dffa3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "ead0d8b056fa36d1f8903261f4752908bc81f9c8"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@ead0d8b056fa36d1f8903261f4752908bc81f9c8",
"title": "ead0d8b056fa36d1f8903261f4752908bc81f9c8",
"url": "https://r8.googlesource.com/r8/+/ead0d8b056fa36d1f8903261f4752908bc81f9c8"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "ead0d8b056fa36d1f8903261f4752908bc81f9c8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9002616770994015648"
},
{
"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"
}
}
[16:10:29.128] Scheduled build:
{
"id": "8837186708875948241",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2015,
"createdBy": "project:r8",
"createTime": "2021-09-02T16:10:28.666393072Z",
"updateTime": "2021-09-02T16:10:28.954136556Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "ead0d8b056fa36d1f8903261f4752908bc81f9c8",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:10:29.128] Task URL: https://cr-buildbucket.appspot.com/build/8837186708875948241
[16:10:29.128] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:2:0) after 2m19s
[16:12:48.695] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:2:0)
[16:12:48.759] Build status: SCHEDULED
[16:12:48.759] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:3:0) after 1m37s
[16:14:26.241] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:3:0)
[16:14:26.272] Build status: SCHEDULED
[16:14:26.272] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:4:0) after 6m22s
[16:20:48.324] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:4:0)
[16:20:48.355] Build status: SCHEDULED
[16:20:48.355] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:5:0) after 9m46s
[16:30:34.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:5:0)
[16:30:34.855] Build status: SCHEDULED
[16:30:34.855] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:6:0) after 5m30s
[16:36:05.014] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:6:0)
[16:36:05.088] Build status: SCHEDULED
[16:36:05.088] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:7:0) after 7m47s
[16:43:52.373] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:7:0)
[16:43:52.410] Build status: SCHEDULED
[16:43:52.410] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:8:0) after 7m52s
[16:51:44.701] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:8:0)
[16:51:44.732] Build status: SCHEDULED
[16:51:44.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:9:0) after 8m28s
[17:00:12.946] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:9:0)
[17:00:13.083] Build status: SCHEDULED
[17:00:13.083] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:10:0) after 4m32s
[17:04:45.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:10:0)
[17:04:45.219] Build status: SCHEDULED
[17:04:45.219] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:11:0) after 8m5s
[17:12:50.310] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:11:0)
[17:12:50.341] Build status: SCHEDULED
[17:12:50.341] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:12:0) after 6m8s
[17:18:58.752] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:12:0)
[17:18:58.798] Build status: SCHEDULED
[17:18:58.798] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:13:0) after 6m39s
[17:25:38.070] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:13:0)
[17:25:38.117] Build status: SCHEDULED
[17:25:38.117] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:14:0) after 7m46s
[17:33:24.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:14:0)
[17:33:24.298] Build status: SCHEDULED
[17:33:24.298] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:15:0) after 1m44s
[17:35:08.317] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:15:0)
[17:35:08.418] Build status: SCHEDULED
[17:35:08.418] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:16:0) after 6m58s
[17:42:06.599] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:16:0)
[17:42:06.634] Build status: SCHEDULED
[17:42:06.634] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:17:0) after 3m28s
[17:45:34.752] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:17:0)
[17:45:34.788] Build status: SCHEDULED
[17:45:34.788] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:18:0) after 7m48s
[17:53:23.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:18:0)
[17:53:23.126] Build status: SCHEDULED
[17:53:23.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:19:0) after 1m24s
[17:54:47.277] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:19:0)
[17:54:47.309] Build status: SCHEDULED
[17:54:47.309] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:20:0) after 1m15s
[17:56:02.446] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:20:0)
[17:56:02.523] Build status: SCHEDULED
[17:56:02.523] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:21:0) after 6m39s
[18:02:41.543] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:21:0)
[18:02:41.590] Build status: SCHEDULED
[18:02:41.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:22:0) after 3m9s
[18:05:50.775] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:22:0)
[18:05:50.802] Build status: SCHEDULED
[18:05:50.802] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:23:0) after 2m36s
[18:08:26.946] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:23:0)
[18:08:27.015] Build status: SCHEDULED
[18:08:27.015] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:24:0) after 3m40s
[18:12:07.042] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:24:0)
[18:12:07.085] Build status: SCHEDULED
[18:12:07.085] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:25:0) after 1m40s
[18:13:47.124] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:25:0)
[18:13:47.154] Build status: SCHEDULED
[18:13:47.154] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:26:0) after 7m54s
[18:21:41.190] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:26:0)
[18:21:41.228] Build status: SCHEDULED
[18:21:41.228] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:27:0) after 7m15s
[18:28:56.430] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:27:0)
[18:28:56.465] Build status: SCHEDULED
[18:28:56.465] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:28:0) after 2m9s
[18:31:05.570] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:28:0)
[18:31:05.597] Build status: SCHEDULED
[18:31:05.597] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:29:0) after 7m38s
[18:38:43.657] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:29:0)
[18:38:43.689] Build status: SCHEDULED
[18:38:43.689] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:30:0) after 8m47s
[18:47:30.715] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:30:0)
[18:47:30.753] Build status: SCHEDULED
[18:47:30.753] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:31:0) after 5m58s
[18:53:28.729] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:31:0)
[18:53:28.772] Build status: SCHEDULED
[18:53:28.772] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:32:0) after 7m41s
[19:01:09.858] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:32:0)
[19:01:09.898] Build status: SCHEDULED
[19:01:09.898] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:33:0) after 7m8s
[19:08:18.053] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:33:0)
[19:08:18.092] Build status: SCHEDULED
[19:08:18.092] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:34:0) after 2m17s
[19:10:35.116] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:34:0)
[19:10:35.159] Build status: SCHEDULED
[19:10:35.159] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:35:0) after 4m54s
[19:15:29.295] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:35:0)
[19:15:29.355] Build status: SCHEDULED
[19:15:29.355] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:36:0) after 7m23s
[19:22:52.673] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:36:0)
[19:22:52.709] Build status: SCHEDULED
[19:22:52.709] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:37:0) after 1m22s
[19:24:14.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:37:0)
[19:24:14.765] Build status: SCHEDULED
[19:24:14.765] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:38:0) after 9m48s
[19:34:03.142] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:38:0)
[19:34:03.184] Build status: SCHEDULED
[19:34:03.184] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:39:0) after 5m10s
[19:39:13.209] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:39:0)
[19:39:13.243] Build status: SCHEDULED
[19:39:13.243] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:40:0) after 2m55s
[19:42:08.359] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:40:0)
[19:42:08.404] Build status: SCHEDULED
[19:42:08.404] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:41:0) after 2m26s
[19:44:34.455] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:41:0)
[19:44:34.492] Build status: SCHEDULED
[19:44:34.492] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:42:0) after 3m28s
[19:48:02.515] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:42:0)
[19:48:02.545] Build status: SCHEDULED
[19:48:02.545] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:43:0) after 8m57s
[19:57:00.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:43:0)
[19:57:00.270] Build status: SCHEDULED
[19:57:00.270] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:44:0) after 1m24s
[19:58:24.410] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:44:0)
[19:58:24.441] Build status: SCHEDULED
[19:58:24.441] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:45:0) after 7m24s
[20:05:48.565] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:45:0)
[20:05:48.600] Build status: SCHEDULED
[20:05:48.600] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:46:0) after 1m41s
[20:07:29.643] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:46:0)
[20:07:29.680] Build status: SCHEDULED
[20:07:29.680] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:47:0) after 7m37s
[20:15:06.805] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:47:0)
[20:15:06.841] Build status: SCHEDULED
[20:15:06.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:48:0) after 4m52s
[20:19:58.987] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:48:0)
[20:19:59.017] Build status: SCHEDULED
[20:19:59.017] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:49:0) after 9m29s
[20:29:28.104] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:49:0)
[20:29:28.148] Build status: SCHEDULED
[20:29:28.148] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:50:0) after 1m31s
[20:30:59.246] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:50:0)
[20:30:59.282] Build status: SCHEDULED
[20:30:59.282] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:51:0) after 4m51s
[20:35:50.305] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:51:0)
[20:35:50.351] Build status: SCHEDULED
[20:35:50.351] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:52:0) after 6m23s
[20:42:13.407] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:52:0)
[20:42:13.493] Build status: SCHEDULED
[20:42:13.493] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:53:0) after 1m54s
[20:44:07.622] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:53:0)
[20:44:07.685] Build status: SCHEDULED
[20:44:07.685] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:54:0) after 4m24s
[20:48:31.849] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:54:0)
[20:48:31.880] Build status: SCHEDULED
[20:48:31.880] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:55:0) after 2m27s
[20:50:58.916] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:55:0)
[20:50:58.948] Build status: SCHEDULED
[20:50:58.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:56:0) after 4m9s
[20:55:08.120] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:56:0)
[20:55:08.150] Build status: SCHEDULED
[20:55:08.150] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:57:0) after 4m48s
[20:59:56.177] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:57:0)
[20:59:56.214] Build status: SCHEDULED
[20:59:56.214] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:58:0) after 1m37s
[21:01:33.324] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:58:0)
[21:01:33.361] Build status: SCHEDULED
[21:01:33.361] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:59:0) after 9m47s
[21:10:41.607] Received PubSub notification, asking Buildbucket for the build status
[21:10:41.661] Build status: STARTED
[21:11:20.381] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:59:0)
[21:11:20.413] Build status: STARTED
[21:11:20.413] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:61:0) after 3m32s
[21:14:52.438] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:61:0)
[21:14:52.470] Build status: STARTED
[21:14:52.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:62:0) after 7m49s
[21:22:41.593] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:62:0)
[21:22:41.629] Build status: STARTED
[21:22:41.629] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:63:0) after 3m9s
[21:25:50.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:63:0)
[21:25:50.690] Build status: STARTED
[21:25:50.690] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:64:0) after 3m5s
[21:28:55.764] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:64:0)
[21:28:55.797] Build status: STARTED
[21:28:55.797] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:65:0) after 9m27s
[21:38:22.988] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:65:0)
[21:38:23.025] Build status: STARTED
[21:38:23.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:66:0) after 1m15s
[21:39:38.152] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:66:0)
[21:39:38.185] Build status: STARTED
[21:39:38.185] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:67:0) after 3m32s
[21:43:10.228] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:67:0)
[21:43:10.264] Build status: STARTED
[21:43:10.264] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:68:0) after 2m17s
[21:45:27.398] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:68:0)
[21:45:27.572] Build status: STARTED
[21:45:27.572] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:69:0) after 7m13s
[21:52:40.993] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:69:0)
[21:52:41.032] Build status: STARTED
[21:52:41.032] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:70:0) after 6m34s
[21:59:15.061] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:70:0)
[21:59:15.092] Build status: STARTED
[21:59:15.092] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:71:0) after 7m51s
[22:07:06.409] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:71:0)
[22:07:06.442] Build status: STARTED
[22:07:06.442] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:72:0) after 2m37s
[22:09:43.467] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:72:0)
[22:09:43.503] Build status: STARTED
[22:09:43.503] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:73:0) after 4m39s
[22:14:22.547] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:73:0)
[22:14:22.683] Build status: STARTED
[22:14:22.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:74:0) after 1m40s
[22:16:02.884] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:74:0)
[22:16:02.948] Build status: STARTED
[22:16:02.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:75:0) after 8m6s
[22:24:09.398] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:75:0)
[22:24:09.432] Build status: STARTED
[22:24:09.432] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:76:0) after 6m6s
[22:30:15.596] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:76:0)
[22:30:15.628] Build status: STARTED
[22:30:15.628] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:77:0) after 4m36s
[22:34:51.768] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:77:0)
[22:34:51.796] Build status: STARTED
[22:34:51.796] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:78:0) after 2m26s
[22:37:18.212] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:78:0)
[22:37:18.242] Build status: STARTED
[22:37:18.242] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:79:0) after 3m47s
[22:41:05.418] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:79:0)
[22:41:05.460] Build status: STARTED
[22:41:05.460] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:80:0) after 7m52s
[22:48:57.841] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:80:0)
[22:48:57.873] Build status: STARTED
[22:48:57.873] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:81:0) after 1m24s
[22:50:21.947] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:81:0)
[22:50:21.995] Build status: STARTED
[22:50:21.995] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:82:0) after 9m41s
[23:00:03.018] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:82:0)
[23:00:03.055] Build status: STARTED
[23:00:03.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:83:0) after 1m46s
[23:01:49.080] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:83:0)
[23:01:49.126] Build status: STARTED
[23:01:49.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:84:0) after 5m50s
[23:07:39.378] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:84:0)
[23:07:39.417] Build status: STARTED
[23:07:39.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:85:0) after 4m4s
[23:11:43.439] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:85:0)
[23:11:43.469] Build status: STARTED
[23:11:43.469] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:86:0) after 6m59s
[23:18:42.492] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:86:0)
[23:18:42.527] Build status: STARTED
[23:18:42.527] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:87:0) after 2m45s
[23:21:27.679] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:87:0)
[23:21:27.706] Build status: STARTED
[23:21:27.706] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:88:0) after 6m25s
[23:27:52.702] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:88:0)
[23:27:52.735] Build status: STARTED
[23:27:52.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:89:0) after 7m6s
[23:34:58.790] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:89:0)
[23:34:58.854] Build status: STARTED
[23:34:58.854] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:90:0) after 4m45s
[23:39:44.029] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:90:0)
[23:39:44.058] Build status: STARTED
[23:39:44.058] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9002616770994015648:91:0) after 8m33s
[23:41:33.171] Received PubSub notification, asking Buildbucket for the build status
[23:41:33.204] Build:
{
"id": "8837186708875948241",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2015,
"createdBy": "project:r8",
"createTime": "2021-09-02T16:10:28.666393072Z",
"startTime": "2021-09-02T21:10:39.585940Z",
"endTime": "2021-09-02T23:41:32.774378116Z",
"updateTime": "2021-09-02T23:41:32.774754251Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "ead0d8b056fa36d1f8903261f4752908bc81f9c8",
"ref": "refs/heads/main"
}
},
"canary": true
}
[23:41:33.205] Invocation finished in 7h31m5.942419919s with status FAILED