[10:31:09.818] New invocation is queued and will start shortly
[10:31:10.976] Starting the invocation (attempt 1)
[10:31:11.033] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/57e9e07754d791fdf08fb322292bd88103484105"
[10:31:11.033] Popped gitiles commit info from properties and tags
[10:31:11.033] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:31:11.033] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:31:11.033] Buildbucket request:
{
"requestId": "8952628591876643776",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8952628591876643776",
"job": "r8/linux-internal",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "bc9edbb61f64846533c9fd3d2a245aafd6757b1b"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@bc9edbb61f64846533c9fd3d2a245aafd6757b1b",
"title": "bc9edbb61f64846533c9fd3d2a245aafd6757b1b",
"url": "https://r8.googlesource.com/r8/+/bc9edbb61f64846533c9fd3d2a245aafd6757b1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "57e9e07754d791fdf08fb322292bd88103484105"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@57e9e07754d791fdf08fb322292bd88103484105",
"title": "57e9e07754d791fdf08fb322292bd88103484105",
"url": "https://r8.googlesource.com/r8/+/57e9e07754d791fdf08fb322292bd88103484105"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "57e9e07754d791fdf08fb322292bd88103484105",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952628591876643776"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:31:12.659] Scheduled build:
{
"id": "8787198529843814129",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 3100,
"createdBy": "project:r8",
"createTime": "2023-03-08T10:31:11.133113172Z",
"updateTime": "2023-03-08T10:31:11.133113172Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "57e9e07754d791fdf08fb322292bd88103484105",
"ref": "refs/heads/main"
}
}
}
[10:31:12.659] Task URL: https://cr-buildbucket.appspot.com/build/8787198529843814129
[10:31:12.659] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:2:0) after 1m45s
[10:32:57.674] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:2:0)
[10:32:57.708] Build status: SCHEDULED
[10:32:57.708] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:3:0) after 3m37s
[10:36:34.714] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:3:0)
[10:36:34.739] Build status: SCHEDULED
[10:36:34.739] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:4:0) after 7m10s
[10:43:44.757] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:4:0)
[10:43:44.823] Build status: SCHEDULED
[10:43:44.823] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:5:0) after 7m38s
[10:51:22.842] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:5:0)
[10:51:22.878] Build status: SCHEDULED
[10:51:22.878] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:6:0) after 3m33s
[10:54:55.899] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:6:0)
[10:54:55.941] Build status: SCHEDULED
[10:54:55.941] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:7:0) after 8m51s
[11:03:47.034] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:7:0)
[11:03:47.055] Build status: SCHEDULED
[11:03:47.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:8:0) after 3m16s
[11:07:03.103] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:8:0)
[11:07:03.145] Build status: SCHEDULED
[11:07:03.145] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:9:0) after 4m5s
[11:11:08.271] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:9:0)
[11:11:08.293] Build status: SCHEDULED
[11:11:08.294] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:10:0) after 4m36s
[11:15:44.312] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:10:0)
[11:15:44.337] Build status: SCHEDULED
[11:15:44.338] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:11:0) after 3m46s
[11:19:30.592] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:11:0)
[11:19:30.612] Build status: SCHEDULED
[11:19:30.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:12:0) after 8m55s
[11:28:25.609] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:12:0)
[11:28:25.642] Build status: SCHEDULED
[11:28:25.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:13:0) after 5m16s
[11:33:41.658] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:13:0)
[11:33:41.693] Build status: SCHEDULED
[11:33:41.693] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:14:0) after 1m5s
[11:34:46.707] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:14:0)
[11:34:46.737] Build status: SCHEDULED
[11:34:46.737] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:15:0) after 8m18s
[11:43:04.772] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:15:0)
[11:43:04.806] Build status: SCHEDULED
[11:43:04.806] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:16:0) after 4m46s
[11:47:50.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:16:0)
[11:47:50.956] Build status: SCHEDULED
[11:47:50.956] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:17:0) after 1m36s
[11:49:27.004] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:17:0)
[11:49:27.023] Build status: SCHEDULED
[11:49:27.023] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:18:0) after 5m36s
[11:55:03.149] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:18:0)
[11:55:03.170] Build status: SCHEDULED
[11:55:03.170] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:19:0) after 5m17s
[12:00:20.366] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:19:0)
[12:00:20.404] Build status: SCHEDULED
[12:00:20.404] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:20:0) after 8m59s
[12:09:19.433] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:20:0)
[12:09:19.456] Build status: SCHEDULED
[12:09:19.456] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:21:0) after 9m44s
[12:19:03.483] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:21:0)
[12:19:03.504] Build status: SCHEDULED
[12:19:03.504] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:22:0) after 3m21s
[12:22:24.535] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:22:0)
[12:22:24.582] Build status: SCHEDULED
[12:22:24.582] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:23:0) after 5m27s
[12:27:51.644] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:23:0)
[12:27:51.680] Build status: SCHEDULED
[12:27:51.680] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:24:0) after 3m20s
[12:31:11.699] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:24:0)
[12:31:11.731] Build status: SCHEDULED
[12:31:11.731] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:25:0) after 3m11s
[12:34:23.055] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:25:0)
[12:34:23.099] Build status: SCHEDULED
[12:34:23.099] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:26:0) after 4m16s
[12:38:39.168] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:26:0)
[12:38:39.215] Build status: SCHEDULED
[12:38:39.215] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:27:0) after 8m37s
[12:47:16.380] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:27:0)
[12:47:16.418] Build status: SCHEDULED
[12:47:16.418] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:28:0) after 7m8s
[12:54:24.434] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:28:0)
[12:54:24.457] Build status: SCHEDULED
[12:54:24.457] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:29:0) after 2m37s
[12:57:01.502] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:29:0)
[12:57:01.546] Build status: SCHEDULED
[12:57:01.546] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:30:0) after 6m35s
[13:03:36.575] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:30:0)
[13:03:36.595] Build status: SCHEDULED
[13:03:36.595] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:31:0) after 5m58s
[13:09:34.893] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:31:0)
[13:09:34.937] Build status: SCHEDULED
[13:09:34.938] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:32:0) after 3m17s
[13:12:51.967] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:32:0)
[13:12:51.998] Build status: SCHEDULED
[13:12:51.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:33:0) after 2m33s
[13:15:25.024] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:33:0)
[13:15:25.051] Build status: SCHEDULED
[13:15:25.051] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:34:0) after 3m41s
[13:19:06.188] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:34:0)
[13:19:06.212] Build status: SCHEDULED
[13:19:06.212] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:35:0) after 1m45s
[13:20:51.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:35:0)
[13:20:51.289] Build status: SCHEDULED
[13:20:51.289] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:36:0) after 1m34s
[13:22:25.417] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:36:0)
[13:22:25.546] Build status: SCHEDULED
[13:22:25.546] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:37:0) after 9m15s
[13:31:41.017] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:37:0)
[13:31:41.055] Build status: SCHEDULED
[13:31:41.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:38:0) after 2m16s
[13:33:57.074] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:38:0)
[13:33:57.113] Build status: SCHEDULED
[13:33:57.113] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:39:0) after 8m13s
[13:42:10.260] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:39:0)
[13:42:10.327] Build status: SCHEDULED
[13:42:10.327] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:40:0) after 3m49s
[13:45:59.348] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:40:0)
[13:45:59.372] Build status: SCHEDULED
[13:45:59.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:41:0) after 7m45s
[13:53:44.507] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:41:0)
[13:53:44.527] Build status: SCHEDULED
[13:53:44.527] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:42:0) after 4m2s
[13:57:46.545] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:42:0)
[13:57:46.576] Build status: SCHEDULED
[13:57:46.576] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:43:0) after 7m54s
[14:05:40.682] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:43:0)
[14:05:40.707] Build status: SCHEDULED
[14:05:40.707] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:44:0) after 7m32s
[14:13:12.731] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:44:0)
[14:13:12.764] Build status: SCHEDULED
[14:13:12.764] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:45:0) after 9m34s
[14:22:46.882] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:45:0)
[14:22:46.922] Build status: SCHEDULED
[14:22:46.922] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:46:0) after 7m28s
[14:30:14.989] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:46:0)
[14:30:15.032] Build status: SCHEDULED
[14:30:15.032] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:47:0) after 7m27s
[14:37:42.145] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:47:0)
[14:37:42.169] Build status: SCHEDULED
[14:37:42.169] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:48:0) after 2m13s
[14:39:55.210] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:48:0)
[14:39:55.248] Build status: SCHEDULED
[14:39:55.248] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:49:0) after 8m5s
[14:48:00.454] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:49:0)
[14:48:00.487] Build status: SCHEDULED
[14:48:00.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:50:0) after 6m38s
[14:54:38.516] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:50:0)
[14:54:38.539] Build status: SCHEDULED
[14:54:38.539] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:51:0) after 6m38s
[15:01:16.601] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:51:0)
[15:01:16.685] Build status: SCHEDULED
[15:01:16.685] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:52:0) after 7m59s
[15:09:16.090] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:52:0)
[15:09:16.141] Build status: SCHEDULED
[15:09:16.141] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:53:0) after 7m47s
[15:17:03.248] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:53:0)
[15:17:03.324] Build status: SCHEDULED
[15:17:03.324] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:54:0) after 3m52s
[15:20:55.402] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:54:0)
[15:20:55.426] Build status: SCHEDULED
[15:20:55.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:55:0) after 3m53s
[15:24:48.547] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:55:0)
[15:24:48.612] Build status: SCHEDULED
[15:24:48.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:56:0) after 6m31s
[15:31:19.659] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:56:0)
[15:31:19.681] Build status: SCHEDULED
[15:31:19.681] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:57:0) after 6m33s
[15:37:52.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:57:0)
[15:37:52.722] Build status: SCHEDULED
[15:37:52.722] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:58:0) after 6m21s
[15:44:14.328] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:58:0)
[15:44:14.357] Build status: SCHEDULED
[15:44:14.357] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:59:0) after 1m27s
[15:45:41.914] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:59:0)
[15:45:41.943] Build status: SCHEDULED
[15:45:41.943] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:60:0) after 3m51s
[15:49:32.988] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:60:0)
[15:49:33.029] Build status: SCHEDULED
[15:49:33.029] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:61:0) after 8m8s
[15:57:41.310] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:61:0)
[15:57:41.379] Build status: SCHEDULED
[15:57:41.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:62:0) after 8m21s
[16:06:02.405] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:62:0)
[16:06:02.458] Build status: SCHEDULED
[16:06:02.458] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:63:0) after 5m46s
[16:11:48.592] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:63:0)
[16:11:48.635] Build status: SCHEDULED
[16:11:48.635] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:64:0) after 6m25s
[16:18:14.018] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:64:0)
[16:18:14.040] Build status: SCHEDULED
[16:18:14.040] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:65:0) after 7m41s
[16:25:55.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:65:0)
[16:25:55.121] Build status: SCHEDULED
[16:25:55.121] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:66:0) after 7m12s
[16:33:07.146] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:66:0)
[16:33:07.179] Build status: SCHEDULED
[16:33:07.179] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:67:0) after 4m13s
[16:37:20.299] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:67:0)
[16:37:20.322] Build status: SCHEDULED
[16:37:20.322] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:68:0) after 5m7s
[16:42:27.518] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:68:0)
[16:42:27.541] Build status: SCHEDULED
[16:42:27.542] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:69:0) after 4m26s
[16:46:54.199] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:69:0)
[16:46:54.219] Build status: SCHEDULED
[16:46:54.219] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:70:0) after 4m17s
[16:51:11.191] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:70:0)
[16:51:11.213] Build status: SCHEDULED
[16:51:11.213] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:71:0) after 4m41s
[16:55:52.289] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:71:0)
[16:55:52.310] Build status: SCHEDULED
[16:55:52.310] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:72:0) after 1m10s
[16:57:02.750] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:72:0)
[16:57:02.770] Build status: SCHEDULED
[16:57:02.770] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:73:0) after 1m38s
[16:58:40.788] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:73:0)
[16:58:40.812] Build status: SCHEDULED
[16:58:40.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:74:0) after 8m2s
[17:06:42.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:74:0)
[17:06:43.107] Build status: SCHEDULED
[17:06:43.107] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:75:0) after 4m16s
[17:10:59.555] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:75:0)
[17:10:59.726] Build status: SCHEDULED
[17:10:59.726] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:76:0) after 6m15s
[17:17:15.766] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:76:0)
[17:17:15.794] Build status: SCHEDULED
[17:17:15.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:77:0) after 5m35s
[17:22:50.913] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:77:0)
[17:22:50.948] Build status: SCHEDULED
[17:22:50.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:78:0) after 4m7s
[17:26:57.923] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:78:0)
[17:26:57.947] Build status: SCHEDULED
[17:26:57.947] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:79:0) after 8m2s
[17:35:00.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:79:0)
[17:35:00.727] Build status: SCHEDULED
[17:35:00.727] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:80:0) after 8m11s
[17:43:11.746] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:80:0)
[17:43:11.787] Build status: SCHEDULED
[17:43:11.787] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:81:0) after 7m34s
[17:50:45.964] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:81:0)
[17:50:45.988] Build status: SCHEDULED
[17:50:45.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:82:0) after 9m7s
[17:59:53.268] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:82:0)
[17:59:53.291] Build status: SCHEDULED
[17:59:53.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:83:0) after 8m22s
[18:08:15.464] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:83:0)
[18:08:15.497] Build status: SCHEDULED
[18:08:15.497] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:84:0) after 4m3s
[18:12:18.556] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:84:0)
[18:12:18.578] Build status: SCHEDULED
[18:12:18.578] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:85:0) after 4m38s
[18:16:56.671] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:85:0)
[18:16:56.696] Build status: SCHEDULED
[18:16:56.696] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:86:0) after 4m21s
[18:21:17.815] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:86:0)
[18:21:17.836] Build status: SCHEDULED
[18:21:17.836] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:87:0) after 3m2s
[18:24:19.951] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:87:0)
[18:24:19.977] Build status: SCHEDULED
[18:24:19.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:88:0) after 1m22s
[18:25:42.012] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:88:0)
[18:25:42.105] Build status: SCHEDULED
[18:25:42.105] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:89:0) after 8m38s
[18:34:20.309] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:89:0)
[18:34:20.338] Build status: SCHEDULED
[18:34:20.338] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:90:0) after 9m19s
[18:43:39.554] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:90:0)
[18:43:39.584] Build status: SCHEDULED
[18:43:39.584] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:91:0) after 9m41s
[18:53:20.754] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:91:0)
[18:53:20.784] Build status: SCHEDULED
[18:53:20.784] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:92:0) after 3m32s
[18:56:52.843] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:92:0)
[18:56:52.869] Build status: SCHEDULED
[18:56:52.869] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:93:0) after 1m43s
[18:58:36.032] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:93:0)
[18:58:36.058] Build status: SCHEDULED
[18:58:36.058] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:94:0) after 7m21s
[19:05:57.087] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:94:0)
[19:05:57.120] Build status: SCHEDULED
[19:05:57.120] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:95:0) after 2m23s
[19:08:20.228] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:95:0)
[19:08:20.262] Build status: SCHEDULED
[19:08:20.262] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:96:0) after 3m54s
[19:12:14.289] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:96:0)
[19:12:14.318] Build status: SCHEDULED
[19:12:14.318] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:97:0) after 1m51s
[19:14:05.344] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:97:0)
[19:14:05.367] Build status: SCHEDULED
[19:14:05.367] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:98:0) after 8m51s
[19:22:56.513] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:98:0)
[19:22:56.563] Build status: SCHEDULED
[19:22:56.563] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:99:0) after 6m54s
[19:29:50.597] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:99:0)
[19:29:50.631] Build status: SCHEDULED
[19:29:50.631] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:100:0) after 9m53s
[19:39:43.835] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:100:0)
[19:39:43.875] Build status: SCHEDULED
[19:39:43.875] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:101:0) after 7m0s
[19:46:44.022] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:101:0)
[19:46:44.052] Build status: SCHEDULED
[19:46:44.052] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:102:0) after 2m19s
[19:49:03.312] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:102:0)
[19:49:03.342] Build status: SCHEDULED
[19:49:03.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:103:0) after 6m18s
[19:55:21.425] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:103:0)
[19:55:21.450] Build status: SCHEDULED
[19:55:21.450] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:104:0) after 4m47s
[20:00:08.488] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:104:0)
[20:00:08.522] Build status: SCHEDULED
[20:00:08.522] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:105:0) after 5m46s
[20:05:54.587] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:105:0)
[20:05:54.612] Build status: SCHEDULED
[20:05:54.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:106:0) after 3m52s
[20:09:46.650] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:106:0)
[20:09:46.678] Build status: SCHEDULED
[20:09:46.678] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:107:0) after 4m39s
[20:14:25.707] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:107:0)
[20:14:25.777] Build status: SCHEDULED
[20:14:25.777] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:108:0) after 7m46s
[20:22:11.796] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:108:0)
[20:22:11.825] Build status: SCHEDULED
[20:22:11.825] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:109:0) after 7m7s
[20:29:18.895] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:109:0)
[20:29:18.951] Build status: SCHEDULED
[20:29:18.951] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:110:0) after 7m37s
[20:36:55.968] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:110:0)
[20:36:55.999] Build status: SCHEDULED
[20:36:55.999] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:111:0) after 5m55s
[20:42:51.018] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:111:0)
[20:42:51.055] Build status: SCHEDULED
[20:42:51.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:112:0) after 6m1s
[20:48:52.086] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:112:0)
[20:48:52.109] Build status: SCHEDULED
[20:48:52.109] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:113:0) after 5m12s
[20:54:04.146] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:113:0)
[20:54:04.167] Build status: SCHEDULED
[20:54:04.168] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:114:0) after 6m46s
[21:00:50.191] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:114:0)
[21:00:50.211] Build status: SCHEDULED
[21:00:50.211] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:115:0) after 4m58s
[21:05:48.376] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:115:0)
[21:05:48.402] Build status: SCHEDULED
[21:05:48.402] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:116:0) after 5m54s
[21:11:42.422] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:116:0)
[21:11:42.445] Build status: SCHEDULED
[21:11:42.445] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:117:0) after 4m55s
[21:16:37.470] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:117:0)
[21:16:37.534] Build status: SCHEDULED
[21:16:37.534] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:118:0) after 1m5s
[21:17:42.573] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:118:0)
[21:17:42.607] Build status: SCHEDULED
[21:17:42.607] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:119:0) after 3m40s
[21:21:22.628] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:119:0)
[21:21:22.663] Build status: SCHEDULED
[21:21:22.663] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:120:0) after 3m18s
[21:24:40.931] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:120:0)
[21:24:40.954] Build status: SCHEDULED
[21:24:40.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:121:0) after 3m10s
[21:27:50.973] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:121:0)
[21:27:51.016] Build status: SCHEDULED
[21:27:51.016] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:122:0) after 6m46s
[21:34:37.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:122:0)
[21:34:37.216] Build status: SCHEDULED
[21:34:37.216] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:123:0) after 5m26s
[21:40:03.382] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:123:0)
[21:40:03.418] Build status: SCHEDULED
[21:40:03.418] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:124:0) after 1m18s
[21:41:21.652] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:124:0)
[21:41:21.685] Build status: SCHEDULED
[21:41:21.685] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:125:0) after 7m12s
[21:48:33.754] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:125:0)
[21:48:33.792] Build status: SCHEDULED
[21:48:33.792] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:126:0) after 6m3s
[21:54:36.814] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:126:0)
[21:54:36.840] Build status: SCHEDULED
[21:54:36.840] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:127:0) after 4m5s
[21:58:41.859] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:127:0)
[21:58:41.943] Build status: SCHEDULED
[21:58:41.943] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:128:0) after 2m9s
[22:00:51.000] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:128:0)
[22:00:51.023] Build status: SCHEDULED
[22:00:51.023] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:129:0) after 8m28s
[22:09:19.025] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:129:0)
[22:09:19.046] Build status: SCHEDULED
[22:09:19.046] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:130:0) after 7m18s
[22:16:37.070] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:130:0)
[22:16:37.096] Build status: SCHEDULED
[22:16:37.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:131:0) after 7m56s
[22:24:33.171] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:131:0)
[22:24:33.193] Build status: SCHEDULED
[22:24:33.193] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:132:0) after 1m35s
[22:26:08.249] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:132:0)
[22:26:08.293] Build status: SCHEDULED
[22:26:08.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:133:0) after 2m31s
[22:28:39.757] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:133:0)
[22:28:39.798] Build status: SCHEDULED
[22:28:39.798] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:134:0) after 5m55s
[22:34:34.853] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:134:0)
[22:34:34.876] Build status: SCHEDULED
[22:34:34.876] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:135:0) after 2m29s
[22:37:04.040] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:135:0)
[22:37:04.073] Build status: SCHEDULED
[22:37:04.073] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:136:0) after 6m7s
[22:37:38.626] Received PubSub notification, asking Buildbucket for the build status
[22:37:38.648] Build status: STARTED
[22:43:11.696] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:136:0)
[22:43:11.734] Build status: STARTED
[22:43:11.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:138:0) after 4m38s
[22:47:49.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:138:0)
[22:47:49.800] Build status: STARTED
[22:47:49.800] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:139:0) after 6m18s
[22:54:07.928] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:139:0)
[22:54:07.949] Build status: STARTED
[22:54:07.949] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:140:0) after 8m39s
[23:02:46.999] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:140:0)
[23:02:47.089] Build status: STARTED
[23:02:47.089] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:141:0) after 9m5s
[23:11:52.110] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:141:0)
[23:11:52.141] Build status: STARTED
[23:11:52.141] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:142:0) after 9m32s
[23:21:24.174] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:142:0)
[23:21:24.326] Build status: STARTED
[23:21:24.326] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:143:0) after 8m17s
[23:29:41.460] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:143:0)
[23:29:41.485] Build status: STARTED
[23:29:41.485] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:144:0) after 8m40s
[23:38:21.565] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:144:0)
[23:38:21.601] Build status: STARTED
[23:38:21.601] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:145:0) after 9m44s
[23:48:05.866] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:145:0)
[23:48:06.274] Build status: STARTED
[23:48:06.275] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:146:0) after 5m18s
[23:53:24.422] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:146:0)
[23:53:24.465] Build status: STARTED
[23:53:24.465] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:147:0) after 8m22s
[00:01:47.070] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:147:0)
[00:01:47.159] Build status: STARTED
[00:01:47.159] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:148:0) after 9m38s
[00:11:25.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:148:0)
[00:11:25.240] Build status: STARTED
[00:11:25.240] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:149:0) after 5m29s
[00:16:54.298] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:149:0)
[00:16:54.335] Build status: STARTED
[00:16:54.335] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:150:0) after 5m0s
[00:21:54.358] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:150:0)
[00:21:54.397] Build status: STARTED
[00:21:54.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:151:0) after 3m45s
[00:25:39.429] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:151:0)
[00:25:39.498] Build status: STARTED
[00:25:39.498] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:152:0) after 7m34s
[00:33:13.625] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:152:0)
[00:33:13.674] Build status: STARTED
[00:33:13.674] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:153:0) after 8m43s
[00:41:56.994] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:153:0)
[00:41:57.028] Build status: STARTED
[00:41:57.028] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:154:0) after 2m19s
[00:44:16.210] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:154:0)
[00:44:16.238] Build status: STARTED
[00:44:16.238] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:155:0) after 3m51s
[00:48:07.434] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:155:0)
[00:48:07.472] Build status: STARTED
[00:48:07.472] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:156:0) after 3m15s
[00:51:22.504] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:156:0)
[00:51:22.535] Build status: STARTED
[00:51:22.535] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:157:0) after 4m19s
[00:55:41.560] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:157:0)
[00:55:41.596] Build status: STARTED
[00:55:41.596] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:158:0) after 5m38s
[01:01:19.616] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:158:0)
[01:01:19.654] Build status: STARTED
[01:01:19.654] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:159:0) after 7m42s
[01:09:01.704] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:159:0)
[01:09:01.730] Build status: STARTED
[01:09:01.730] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:160:0) after 1m17s
[01:10:18.900] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:160:0)
[01:10:18.929] Build status: STARTED
[01:10:18.929] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:161:0) after 2m52s
[01:13:11.261] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:161:0)
[01:13:11.301] Build status: STARTED
[01:13:11.301] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:162:0) after 2m32s
[01:15:43.328] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:162:0)
[01:15:43.365] Build status: STARTED
[01:15:43.365] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:163:0) after 1m25s
[01:17:08.871] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:163:0)
[01:17:08.897] Build status: STARTED
[01:17:08.897] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:164:0) after 8m47s
[01:25:56.050] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:164:0)
[01:25:56.090] Build status: STARTED
[01:25:56.090] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:165:0) after 3m51s
[01:29:47.287] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:165:0)
[01:29:47.350] Build status: STARTED
[01:29:47.350] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:166:0) after 9m32s
[01:39:19.380] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:166:0)
[01:39:19.400] Build status: STARTED
[01:39:19.400] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:167:0) after 7m19s
[01:46:38.869] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:167:0)
[01:46:38.902] Build status: STARTED
[01:46:38.903] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:168:0) after 4m56s
[01:51:34.994] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:168:0)
[01:51:35.035] Build status: STARTED
[01:51:35.035] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:169:0) after 2m59s
[01:54:34.644] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:169:0)
[01:54:34.677] Build status: STARTED
[01:54:34.677] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:170:0) after 8m48s
[02:03:23.063] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:170:0)
[02:03:23.096] Build status: STARTED
[02:03:23.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:171:0) after 7m52s
[02:11:15.326] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:171:0)
[02:11:15.360] Build status: STARTED
[02:11:15.360] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:172:0) after 9m48s
[02:21:03.383] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:172:0)
[02:21:03.429] Build status: STARTED
[02:21:03.429] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:173:0) after 3m41s
[02:24:44.696] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:173:0)
[02:24:44.720] Build status: STARTED
[02:24:44.720] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:174:0) after 2m1s
[02:26:45.738] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:174:0)
[02:26:45.818] Build status: STARTED
[02:26:45.818] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:175:0) after 3m37s
[02:30:22.939] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:175:0)
[02:30:22.988] Build status: STARTED
[02:30:22.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:176:0) after 3m17s
[02:33:40.337] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:176:0)
[02:33:40.359] Build status: STARTED
[02:33:40.359] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:177:0) after 8m18s
[02:41:58.461] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:177:0)
[02:41:58.492] Build status: STARTED
[02:41:58.492] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:178:0) after 9m5s
[02:51:03.608] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:178:0)
[02:51:03.641] Build status: STARTED
[02:51:03.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:179:0) after 8m5s
[02:59:08.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:179:0)
[02:59:08.792] Build status: STARTED
[02:59:08.792] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:180:0) after 3m18s
[03:02:27.018] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:180:0)
[03:02:27.114] Build status: STARTED
[03:02:27.114] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:181:0) after 2m31s
[03:04:58.212] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:181:0)
[03:04:58.317] Build status: STARTED
[03:04:58.317] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:182:0) after 8m40s
[03:13:38.464] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:182:0)
[03:13:38.500] Build status: STARTED
[03:13:38.500] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:183:0) after 1m8s
[03:14:46.859] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:183:0)
[03:14:46.893] Build status: STARTED
[03:14:46.893] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:184:0) after 4m34s
[03:19:20.964] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:184:0)
[03:19:21.009] Build status: STARTED
[03:19:21.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:185:0) after 7m42s
[03:27:03.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:185:0)
[03:27:03.218] Build status: STARTED
[03:27:03.218] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:186:0) after 6m57s
[03:34:00.351] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:186:0)
[03:34:00.388] Build status: STARTED
[03:34:00.388] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:187:0) after 9m31s
[03:43:31.388] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:187:0)
[03:43:31.493] Build status: STARTED
[03:43:31.493] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:188:0) after 4m45s
[03:48:16.512] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:188:0)
[03:48:16.545] Build status: STARTED
[03:48:16.545] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:189:0) after 7m49s
[03:56:05.576] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:189:0)
[03:56:05.645] Build status: STARTED
[03:56:05.645] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:190:0) after 8m59s
[04:05:04.767] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:190:0)
[04:05:04.791] Build status: STARTED
[04:05:04.791] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:191:0) after 7m32s
[04:12:36.812] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:191:0)
[04:12:36.862] Build status: STARTED
[04:12:36.862] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:192:0) after 6m50s
[04:19:27.153] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:192:0)
[04:19:27.175] Build status: STARTED
[04:19:27.175] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:193:0) after 6m49s
[04:26:16.197] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:193:0)
[04:26:16.231] Build status: STARTED
[04:26:16.231] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:194:0) after 5m20s
[04:31:36.370] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:194:0)
[04:31:36.404] Build status: STARTED
[04:31:36.404] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:195:0) after 6m16s
[04:37:52.430] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:195:0)
[04:37:52.456] Build status: STARTED
[04:37:52.456] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:196:0) after 7m24s
[04:45:16.726] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:196:0)
[04:45:16.796] Build status: STARTED
[04:45:16.796] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:197:0) after 5m9s
[04:50:25.915] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:197:0)
[04:50:25.955] Build status: STARTED
[04:50:25.955] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:198:0) after 3m23s
[04:53:49.172] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:198:0)
[04:53:49.209] Build status: STARTED
[04:53:49.209] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:199:0) after 4m45s
[04:58:34.298] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:199:0)
[04:58:34.332] Build status: STARTED
[04:58:34.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:200:0) after 3m44s
[05:02:18.484] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:200:0)
[05:02:18.559] Build status: STARTED
[05:02:18.559] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:201:0) after 3m8s
[05:05:26.674] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:201:0)
[05:05:26.728] Build status: STARTED
[05:05:26.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:202:0) after 5m26s
[05:10:53.013] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:202:0)
[05:10:53.110] Build status: STARTED
[05:10:53.110] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:203:0) after 5m45s
[05:16:38.230] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:203:0)
[05:16:38.284] Build status: STARTED
[05:16:38.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:204:0) after 8m47s
[05:25:25.385] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:204:0)
[05:25:25.416] Build status: STARTED
[05:25:25.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:205:0) after 2m22s
[05:27:47.417] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:205:0)
[05:27:47.552] Build status: STARTED
[05:27:47.552] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:206:0) after 1m27s
[05:29:14.560] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:206:0)
[05:29:14.587] Build status: STARTED
[05:29:14.587] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:207:0) after 7m47s
[05:37:01.730] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:207:0)
[05:37:01.807] Build status: STARTED
[05:37:01.807] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:208:0) after 3m52s
[05:40:53.924] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:208:0)
[05:40:53.982] Build status: STARTED
[05:40:53.983] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:209:0) after 2m38s
[05:43:32.016] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:209:0)
[05:43:32.062] Build status: STARTED
[05:43:32.062] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:210:0) after 7m28s
[05:51:00.267] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:210:0)
[05:51:00.378] Build status: STARTED
[05:51:00.378] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:211:0) after 4m49s
[05:55:49.396] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:211:0)
[05:55:49.442] Build status: STARTED
[05:55:49.442] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:212:0) after 4m40s
[06:00:29.563] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:212:0)
[06:00:29.598] Build status: STARTED
[06:00:29.598] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:213:0) after 8m41s
[06:09:10.635] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:213:0)
[06:09:10.657] Build status: STARTED
[06:09:10.657] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:214:0) after 1m18s
[06:10:29.036] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:214:0)
[06:10:29.085] Build status: STARTED
[06:10:29.085] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:215:0) after 6m35s
[06:17:04.404] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:215:0)
[06:17:04.449] Build status: STARTED
[06:17:04.449] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:216:0) after 6m26s
[06:23:30.456] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:216:0)
[06:23:30.505] Build status: STARTED
[06:23:30.505] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:217:0) after 1m9s
[06:24:39.584] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:217:0)
[06:24:39.623] Build status: STARTED
[06:24:39.623] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:218:0) after 1m19s
[06:25:58.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:218:0)
[06:25:58.724] Build status: STARTED
[06:25:58.724] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:219:0) after 1m6s
[06:27:04.749] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:219:0)
[06:27:04.787] Build status: STARTED
[06:27:04.787] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:220:0) after 1m15s
[06:28:20.025] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:220:0)
[06:28:20.059] Build status: STARTED
[06:28:20.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:221:0) after 3m36s
[06:31:56.153] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:221:0)
[06:31:56.184] Build status: STARTED
[06:31:56.184] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:222:0) after 8m56s
[06:40:52.267] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:222:0)
[06:40:52.292] Build status: STARTED
[06:40:52.292] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:223:0) after 6m30s
[06:47:22.340] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:223:0)
[06:47:22.381] Build status: STARTED
[06:47:22.381] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:224:0) after 6m33s
[06:53:55.413] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:224:0)
[06:53:55.474] Build status: STARTED
[06:53:55.477] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8952628591876643776:225:0) after 2m22s
[06:54:53.732] Received PubSub notification, asking Buildbucket for the build status
[06:54:53.761] Build:
{
"id": "8787198529843814129",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 3100,
"createdBy": "project:r8",
"createTime": "2023-03-08T10:31:11.133113172Z",
"startTime": "2023-03-08T22:37:38.153698Z",
"endTime": "2023-03-09T06:54:53.498376715Z",
"updateTime": "2023-03-09T06:54:53.498376715Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "57e9e07754d791fdf08fb322292bd88103484105",
"ref": "refs/heads/main"
}
}
}
[06:54:53.761] Invocation finished in 20h23m43.950220575s with status FAILED