[10:46:29.063] New invocation is queued and will start shortly
[10:46:30.954] Starting the invocation (attempt 1)
[10:46:30.985] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/6752ab19588d6e506dfe58cd36759b09905170a0"
[10:46:30.985] Popped gitiles commit info from properties and tags
[10:46:30.985] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:46:30.985] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:46:30.985] Buildbucket request:
{
"requestId": "8990225369042073888",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/3.2",
"repo": "https://r8.googlesource.com/r8",
"revision": "6752ab19588d6e506dfe58cd36759b09905170a0"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/3.2@6752ab19588d6e506dfe58cd36759b09905170a0",
"title": "6752ab19588d6e506dfe58cd36759b09905170a0",
"url": "https://r8.googlesource.com/r8/+/6752ab19588d6e506dfe58cd36759b09905170a0"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "6752ab19588d6e506dfe58cd36759b09905170a0",
"ref": "refs/heads/3.2"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8990225369042073888"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal_release"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:46:31.391] Scheduled build:
{
"id": "8824795306306121393",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 572,
"createdBy": "project:r8",
"createTime": "2022-01-17T10:46:31.041659840Z",
"updateTime": "2022-01-17T10:46:31.041659840Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "6752ab19588d6e506dfe58cd36759b09905170a0",
"ref": "refs/heads/3.2"
}
}
}
[10:46:31.391] Task URL: https://cr-buildbucket.appspot.com/build/8824795306306121393
[10:46:31.391] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:2:0) after 3m38s
[10:50:09.372] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:2:0)
[10:50:09.431] Build status: SCHEDULED
[10:50:09.431] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:3:0) after 5m18s
[10:55:27.548] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:3:0)
[10:55:27.585] Build status: SCHEDULED
[10:55:27.585] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:4:0) after 1m53s
[10:57:20.611] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:4:0)
[10:57:20.636] Build status: SCHEDULED
[10:57:20.636] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:5:0) after 9m35s
[11:06:55.654] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:5:0)
[11:06:55.701] Build status: SCHEDULED
[11:06:55.701] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:6:0) after 6m12s
[11:13:07.776] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:6:0)
[11:13:07.833] Build status: SCHEDULED
[11:13:07.833] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:7:0) after 2m5s
[11:15:12.996] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:7:0)
[11:15:13.046] Build status: SCHEDULED
[11:15:13.046] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:8:0) after 3m49s
[11:19:02.064] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:8:0)
[11:19:02.092] Build status: SCHEDULED
[11:19:02.092] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:9:0) after 4m19s
[11:23:21.119] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:9:0)
[11:23:21.151] Build status: SCHEDULED
[11:23:21.151] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:10:0) after 2m3s
[11:25:24.172] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:10:0)
[11:25:24.264] Build status: SCHEDULED
[11:25:24.264] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:11:0) after 9m20s
[11:34:44.477] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:11:0)
[11:34:44.507] Build status: SCHEDULED
[11:34:44.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:12:0) after 7m20s
[11:42:04.479] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:12:0)
[11:42:04.510] Build status: SCHEDULED
[11:42:04.510] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:13:0) after 1m33s
[11:43:37.530] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:13:0)
[11:43:37.561] Build status: SCHEDULED
[11:43:37.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:14:0) after 5m11s
[11:48:48.691] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:14:0)
[11:48:48.731] Build status: SCHEDULED
[11:48:48.731] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:15:0) after 9m37s
[11:58:25.750] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:15:0)
[11:58:25.778] Build status: SCHEDULED
[11:58:25.778] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:16:0) after 2m12s
[12:00:37.882] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:16:0)
[12:00:37.913] Build status: SCHEDULED
[12:00:37.913] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:17:0) after 2m3s
[12:02:40.930] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:17:0)
[12:02:40.965] Build status: SCHEDULED
[12:02:40.965] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:18:0) after 7m55s
[12:10:35.984] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:18:0)
[12:10:36.240] Build status: SCHEDULED
[12:10:36.240] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:19:0) after 1m25s
[12:12:01.258] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:19:0)
[12:12:01.301] Build status: SCHEDULED
[12:12:01.301] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:20:0) after 1m52s
[12:13:53.331] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:20:0)
[12:13:53.380] Build status: SCHEDULED
[12:13:53.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:21:0) after 9m2s
[12:22:55.553] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:21:0)
[12:22:55.582] Build status: SCHEDULED
[12:22:55.582] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:22:0) after 5m47s
[12:28:42.559] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:22:0)
[12:28:42.588] Build status: SCHEDULED
[12:28:42.588] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:23:0) after 2m15s
[12:30:57.693] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:23:0)
[12:30:57.726] Build status: SCHEDULED
[12:30:57.726] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:24:0) after 8m27s
[12:39:24.744] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:24:0)
[12:39:24.771] Build status: SCHEDULED
[12:39:24.771] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:25:0) after 2m50s
[12:42:14.792] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:25:0)
[12:42:14.889] Build status: SCHEDULED
[12:42:14.889] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:26:0) after 7m18s
[12:49:32.923] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:26:0)
[12:49:32.954] Build status: SCHEDULED
[12:49:32.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:27:0) after 6m29s
[12:56:02.000] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:27:0)
[12:56:02.048] Build status: SCHEDULED
[12:56:02.048] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:28:0) after 6m48s
[13:02:50.072] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:28:0)
[13:02:50.104] Build status: SCHEDULED
[13:02:50.104] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:29:0) after 1m2s
[13:03:52.255] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:29:0)
[13:03:52.290] Build status: SCHEDULED
[13:03:52.290] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:30:0) after 7m59s
[13:11:51.594] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:30:0)
[13:11:51.618] Build status: SCHEDULED
[13:11:51.618] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:31:0) after 1m43s
[13:13:34.688] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:31:0)
[13:13:34.717] Build status: SCHEDULED
[13:13:34.717] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:32:0) after 5m42s
[13:19:16.836] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:32:0)
[13:19:16.865] Build status: SCHEDULED
[13:19:16.865] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:33:0) after 9m55s
[13:29:11.884] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:33:0)
[13:29:11.943] Build status: SCHEDULED
[13:29:11.943] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:34:0) after 9m58s
[13:39:09.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:34:0)
[13:39:10.025] Build status: SCHEDULED
[13:39:10.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:35:0) after 1m13s
[13:40:23.078] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:35:0)
[13:40:23.109] Build status: SCHEDULED
[13:40:23.109] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:36:0) after 2m22s
[13:42:45.130] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:36:0)
[13:42:45.151] Build status: SCHEDULED
[13:42:45.151] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:37:0) after 9m7s
[13:51:52.131] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:37:0)
[13:51:52.177] Build status: SCHEDULED
[13:51:52.177] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:38:0) after 2m37s
[13:54:29.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:38:0)
[13:54:29.225] Build status: SCHEDULED
[13:54:29.225] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:39:0) after 7m27s
[14:01:56.277] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:39:0)
[14:01:56.310] Build status: SCHEDULED
[14:01:56.310] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:40:0) after 9m25s
[14:11:21.371] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:40:0)
[14:11:21.412] Build status: SCHEDULED
[14:11:21.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:41:0) after 9m40s
[14:21:01.526] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:41:0)
[14:21:01.563] Build status: SCHEDULED
[14:21:01.563] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:42:0) after 2m47s
[14:23:48.675] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:42:0)
[14:23:48.709] Build status: SCHEDULED
[14:23:48.709] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:43:0) after 8m16s
[14:32:04.828] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:43:0)
[14:32:04.854] Build status: SCHEDULED
[14:32:04.854] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:44:0) after 6m46s
[14:38:50.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:44:0)
[14:38:50.950] Build status: SCHEDULED
[14:38:50.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:45:0) after 6m37s
[14:45:27.994] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:45:0)
[14:45:28.078] Build status: SCHEDULED
[14:45:28.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:46:0) after 7m2s
[14:52:30.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:46:0)
[14:52:30.212] Build status: SCHEDULED
[14:52:30.212] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:47:0) after 3m34s
[14:56:04.232] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:47:0)
[14:56:04.273] Build status: SCHEDULED
[14:56:04.273] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:48:0) after 3m53s
[14:59:57.299] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:48:0)
[14:59:57.362] Build status: SCHEDULED
[14:59:57.362] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:49:0) after 3m44s
[15:03:41.484] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:49:0)
[15:03:41.503] Build status: SCHEDULED
[15:03:41.503] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:50:0) after 2m7s
[15:05:48.497] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:50:0)
[15:05:48.590] Build status: SCHEDULED
[15:05:48.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:51:0) after 8m48s
[15:07:45.105] Received PubSub notification, asking Buildbucket for the build status
[15:07:45.160] Build status: STARTED
[15:14:36.609] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:51:0)
[15:14:36.641] Build status: STARTED
[15:14:36.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:53:0) after 4m56s
[15:19:32.864] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:53:0)
[15:19:32.931] Build status: STARTED
[15:19:32.931] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:54:0) after 7m46s
[15:27:18.946] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:54:0)
[15:27:18.976] Build status: STARTED
[15:27:18.976] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:55:0) after 1m11s
[15:28:30.012] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:55:0)
[15:28:30.041] Build status: STARTED
[15:28:30.041] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:56:0) after 1m26s
[15:29:56.195] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:56:0)
[15:29:56.224] Build status: STARTED
[15:29:56.224] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:57:0) after 8m54s
[15:38:50.242] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:57:0)
[15:38:50.271] Build status: STARTED
[15:38:50.271] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:58:0) after 4m44s
[15:43:34.291] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:58:0)
[15:43:34.341] Build status: STARTED
[15:43:34.341] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:59:0) after 5m49s
[15:49:23.452] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:59:0)
[15:49:23.479] Build status: STARTED
[15:49:23.479] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:60:0) after 3m26s
[15:52:49.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:60:0)
[15:52:49.532] Build status: STARTED
[15:52:49.532] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:61:0) after 1m41s
[15:54:30.555] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:61:0)
[15:54:30.671] Build status: STARTED
[15:54:30.671] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:62:0) after 7m36s
[16:02:06.695] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:62:0)
[16:02:06.777] Build status: STARTED
[16:02:06.777] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:63:0) after 1m20s
[16:03:26.862] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:63:0)
[16:03:26.899] Build status: STARTED
[16:03:26.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:64:0) after 7m12s
[16:10:39.016] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:64:0)
[16:10:39.049] Build status: STARTED
[16:10:39.049] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:65:0) after 5m19s
[16:15:58.071] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:65:0)
[16:15:58.164] Build status: STARTED
[16:15:58.164] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:66:0) after 3m15s
[16:19:13.151] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:66:0)
[16:19:13.183] Build status: STARTED
[16:19:13.183] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:67:0) after 7m15s
[16:26:28.205] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:67:0)
[16:26:29.485] Build status: STARTED
[16:26:29.485] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:68:0) after 1m38s
[16:28:07.515] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:68:0)
[16:28:07.549] Build status: STARTED
[16:28:07.549] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:69:0) after 1m22s
[16:29:29.668] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:69:0)
[16:29:29.702] Build status: STARTED
[16:29:29.702] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:70:0) after 7m36s
[16:37:05.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:70:0)
[16:37:05.859] Build status: STARTED
[16:37:05.859] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:71:0) after 6m3s
[16:43:08.878] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:71:0)
[16:43:08.912] Build status: STARTED
[16:43:08.912] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:72:0) after 8m11s
[16:51:20.054] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:72:0)
[16:51:20.082] Build status: STARTED
[16:51:20.082] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:73:0) after 9m19s
[17:00:39.216] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:73:0)
[17:00:39.284] Build status: STARTED
[17:00:39.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:74:0) after 6m10s
[17:06:49.334] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:74:0)
[17:06:49.371] Build status: STARTED
[17:06:49.371] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:75:0) after 8m40s
[17:15:29.451] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:75:0)
[17:15:29.481] Build status: STARTED
[17:15:29.481] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:76:0) after 1m57s
[17:17:26.525] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:76:0)
[17:17:26.552] Build status: STARTED
[17:17:26.552] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:77:0) after 2m45s
[17:20:11.537] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:77:0)
[17:20:11.565] Build status: STARTED
[17:20:11.565] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:78:0) after 5m59s
[17:26:10.752] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:78:0)
[17:26:10.791] Build status: STARTED
[17:26:10.791] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:79:0) after 5m25s
[17:31:35.810] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:79:0)
[17:31:35.844] Build status: STARTED
[17:31:35.844] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:80:0) after 1m38s
[17:33:13.902] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:80:0)
[17:33:13.934] Build status: STARTED
[17:33:13.934] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:81:0) after 2m9s
[17:35:23.065] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:81:0)
[17:35:23.098] Build status: STARTED
[17:35:23.098] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:82:0) after 4m18s
[17:39:41.121] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:82:0)
[17:39:41.149] Build status: STARTED
[17:39:41.149] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:83:0) after 7m35s
[17:47:16.183] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:83:0)
[17:47:16.215] Build status: STARTED
[17:47:16.215] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:84:0) after 2m30s
[17:49:46.205] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:84:0)
[17:49:46.232] Build status: STARTED
[17:49:46.232] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:85:0) after 9m52s
[17:59:38.489] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:85:0)
[17:59:38.524] Build status: STARTED
[17:59:38.524] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:86:0) after 4m43s
[18:04:21.544] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:86:0)
[18:04:21.580] Build status: STARTED
[18:04:21.580] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:87:0) after 3m25s
[18:07:46.658] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:87:0)
[18:07:46.693] Build status: STARTED
[18:07:46.693] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:88:0) after 6m42s
[18:14:28.705] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:88:0)
[18:14:28.730] Build status: STARTED
[18:14:28.730] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:89:0) after 4m39s
[18:19:07.755] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:89:0)
[18:19:07.812] Build status: STARTED
[18:19:07.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:90:0) after 2m25s
[18:21:33.025] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:90:0)
[18:21:33.077] Build status: STARTED
[18:21:33.077] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:91:0) after 7m14s
[18:28:47.259] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:91:0)
[18:28:47.293] Build status: STARTED
[18:28:47.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:92:0) after 2m30s
[18:31:17.320] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:92:0)
[18:31:17.358] Build status: STARTED
[18:31:17.358] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:93:0) after 2m8s
[18:33:25.616] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:93:0)
[18:33:25.657] Build status: STARTED
[18:33:25.657] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:94:0) after 5m26s
[18:38:51.774] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:94:0)
[18:38:51.803] Build status: STARTED
[18:38:51.803] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:95:0) after 3m45s
[18:42:36.826] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:95:0)
[18:42:36.869] Build status: STARTED
[18:42:36.869] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:96:0) after 5m1s
[18:47:37.863] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:96:0)
[18:47:37.894] Build status: STARTED
[18:47:37.894] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:97:0) after 9m16s
[18:56:53.929] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:97:0)
[18:56:53.957] Build status: STARTED
[18:56:53.957] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:98:0) after 3m35s
[19:00:29.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:98:0)
[19:00:29.146] Build status: STARTED
[19:00:29.146] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:99:0) after 6m37s
[19:07:06.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:99:0)
[19:07:06.286] Build status: STARTED
[19:07:06.286] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:100:0) after 6m36s
[19:13:42.317] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:100:0)
[19:13:42.347] Build status: STARTED
[19:13:42.347] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:101:0) after 6m6s
[19:19:48.367] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:101:0)
[19:19:48.393] Build status: STARTED
[19:19:48.393] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:102:0) after 9m0s
[19:28:48.498] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:102:0)
[19:28:48.535] Build status: STARTED
[19:28:48.535] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:103:0) after 3m25s
[19:32:13.561] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:103:0)
[19:32:13.654] Build status: STARTED
[19:32:13.654] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:104:0) after 4m13s
[19:36:26.678] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:104:0)
[19:36:26.695] Build status: STARTED
[19:36:26.695] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:105:0) after 7m39s
[19:44:05.720] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:105:0)
[19:44:05.764] Build status: STARTED
[19:44:05.764] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:106:0) after 6m30s
[19:50:35.789] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:106:0)
[19:50:35.817] Build status: STARTED
[19:50:35.817] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:107:0) after 1m42s
[19:52:17.903] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:107:0)
[19:52:17.947] Build status: STARTED
[19:52:17.947] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:108:0) after 9m44s
[20:02:02.109] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:108:0)
[20:02:02.136] Build status: STARTED
[20:02:02.136] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:109:0) after 2m33s
[20:04:35.296] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:109:0)
[20:04:35.324] Build status: STARTED
[20:04:35.324] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:110:0) after 1m17s
[20:05:52.396] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:110:0)
[20:05:52.432] Build status: STARTED
[20:05:52.432] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:111:0) after 9m18s
[20:15:10.455] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:111:0)
[20:15:10.486] Build status: STARTED
[20:15:10.486] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:112:0) after 4m39s
[20:19:49.903] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:112:0)
[20:19:49.950] Build status: STARTED
[20:19:49.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:113:0) after 4m39s
[20:24:28.975] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:113:0)
[20:24:28.993] Build status: STARTED
[20:24:28.993] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:114:0) after 5m50s
[20:30:19.015] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:114:0)
[20:30:19.208] Build status: STARTED
[20:30:19.208] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:115:0) after 7m11s
[20:37:30.241] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:115:0)
[20:37:30.275] Build status: STARTED
[20:37:30.275] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:116:0) after 5m11s
[20:42:41.298] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:116:0)
[20:42:41.324] Build status: STARTED
[20:42:41.324] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:117:0) after 5m16s
[20:47:57.349] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:117:0)
[20:47:57.379] Build status: STARTED
[20:47:57.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:118:0) after 1m22s
[20:49:19.450] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:118:0)
[20:49:19.479] Build status: STARTED
[20:49:19.479] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:119:0) after 1m54s
[20:51:13.450] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:119:0)
[20:51:13.475] Build status: STARTED
[20:51:13.475] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:120:0) after 7m46s
[20:58:59.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:120:0)
[20:58:59.530] Build status: STARTED
[20:58:59.530] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:121:0) after 8m53s
[21:07:52.563] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:121:0)
[21:07:52.624] Build status: STARTED
[21:07:52.624] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8990225369042073888:122:0) after 5m9s
[21:11:24.108] Received PubSub notification, asking Buildbucket for the build status
[21:11:24.159] Build:
{
"id": "8824795306306121393",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 572,
"createdBy": "project:r8",
"createTime": "2022-01-17T10:46:31.041659840Z",
"startTime": "2022-01-17T15:07:43.788707Z",
"endTime": "2022-01-17T21:11:23.824303269Z",
"updateTime": "2022-01-17T21:11:23.824303269Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "6752ab19588d6e506dfe58cd36759b09905170a0",
"ref": "refs/heads/3.2"
}
}
}
[21:11:24.159] Invocation finished in 10h24m55.111017058s with status SUCCEEDED