[12:03:36.438] New invocation is queued and will start shortly
[12:03:37.611] Starting the invocation (attempt 1)
[12:03:37.655] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/da075e9f967d469d14a93a2f52bff039f3c75855"
[12:03:37.656] Popped gitiles commit info from properties and tags
[12:03:37.656] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:03:37.658] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:03:37.658] Buildbucket request:
{
"requestId": "8915115631734695680",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8915115631734695680",
"job": "r8/linux-internal",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "da075e9f967d469d14a93a2f52bff039f3c75855"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@da075e9f967d469d14a93a2f52bff039f3c75855",
"title": "da075e9f967d469d14a93a2f52bff039f3c75855",
"url": "https://r8.googlesource.com/r8/+/da075e9f967d469d14a93a2f52bff039f3c75855"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "da075e9f967d469d14a93a2f52bff039f3c75855",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915115631734695680"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:03:38.232] Scheduled build:
{
"id": "8749685569709510433",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 4063,
"createdBy": "project:r8",
"createTime": "2024-04-25T12:03:37.745419060Z",
"updateTime": "2024-04-25T12:03:37.745419060Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "da075e9f967d469d14a93a2f52bff039f3c75855",
"ref": "refs/heads/main"
}
}
}
[12:03:38.232] Task URL: https://cr-buildbucket.appspot.com/build/8749685569709510433
[12:03:38.232] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:2:0) after 9m53s
[12:03:39.168] Received PubSub notification, asking Buildbucket for the build status
[12:03:39.198] Build status: SCHEDULED
[12:13:31.247] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:2:0)
[12:13:31.276] Build status: SCHEDULED
[12:13:31.276] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:4:0) after 4m37s
[12:18:08.379] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:4:0)
[12:18:08.407] Build status: SCHEDULED
[12:18:08.407] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:5:0) after 1m21s
[12:19:29.674] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:5:0)
[12:19:29.727] Build status: SCHEDULED
[12:19:29.727] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:6:0) after 9m36s
[12:29:06.317] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:6:0)
[12:29:06.380] Build status: SCHEDULED
[12:29:06.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:7:0) after 2m2s
[12:31:08.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:7:0)
[12:31:08.531] Build status: SCHEDULED
[12:31:08.531] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:8:0) after 7m14s
[12:38:22.665] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:8:0)
[12:38:22.696] Build status: SCHEDULED
[12:38:22.697] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:9:0) after 6m7s
[12:44:30.357] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:9:0)
[12:44:30.376] Build status: SCHEDULED
[12:44:30.377] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:10:0) after 6m31s
[12:51:01.646] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:10:0)
[12:51:01.663] Build status: SCHEDULED
[12:51:01.663] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:11:0) after 6m43s
[12:57:44.713] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:11:0)
[12:57:44.755] Build status: SCHEDULED
[12:57:44.755] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:12:0) after 8m10s
[13:05:54.965] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:12:0)
[13:05:54.984] Build status: SCHEDULED
[13:05:54.985] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:13:0) after 5m7s
[13:11:02.180] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:13:0)
[13:11:02.208] Build status: SCHEDULED
[13:11:02.208] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:14:0) after 7m5s
[13:18:07.479] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:14:0)
[13:18:07.524] Build status: SCHEDULED
[13:18:07.524] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:15:0) after 7m48s
[13:25:55.625] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:15:0)
[13:25:55.647] Build status: SCHEDULED
[13:25:55.647] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:16:0) after 3m9s
[13:29:04.719] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:16:0)
[13:29:04.750] Build status: SCHEDULED
[13:29:04.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:17:0) after 6m58s
[13:36:03.008] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:17:0)
[13:36:03.051] Build status: SCHEDULED
[13:36:03.051] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:18:0) after 4m37s
[13:40:40.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:18:0)
[13:40:40.133] Build status: SCHEDULED
[13:40:40.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:19:0) after 4m26s
[13:45:06.157] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:19:0)
[13:45:06.213] Build status: SCHEDULED
[13:45:06.213] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:20:0) after 6m41s
[13:51:47.231] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:20:0)
[13:51:47.322] Build status: SCHEDULED
[13:51:47.322] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:21:0) after 1m50s
[13:53:37.364] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:21:0)
[13:53:37.426] Build status: SCHEDULED
[13:53:37.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:22:0) after 2m22s
[13:55:59.511] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:22:0)
[13:55:59.529] Build status: SCHEDULED
[13:55:59.530] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:23:0) after 8m3s
[14:04:02.656] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:23:0)
[14:04:02.712] Build status: SCHEDULED
[14:04:02.712] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:24:0) after 2m56s
[14:06:58.795] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:24:0)
[14:06:58.846] Build status: SCHEDULED
[14:06:58.846] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:25:0) after 1m37s
[14:08:35.866] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:25:0)
[14:08:35.908] Build status: SCHEDULED
[14:08:35.908] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:26:0) after 7m33s
[14:16:08.931] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:26:0)
[14:16:08.984] Build status: SCHEDULED
[14:16:08.984] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:27:0) after 1m13s
[14:17:22.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:27:0)
[14:17:22.202] Build status: SCHEDULED
[14:17:22.202] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:28:0) after 3m30s
[14:20:52.236] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:28:0)
[14:20:52.258] Build status: SCHEDULED
[14:20:52.258] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:29:0) after 4m54s
[14:25:46.299] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:29:0)
[14:25:46.330] Build status: SCHEDULED
[14:25:46.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:30:0) after 6m24s
[14:32:10.570] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:30:0)
[14:32:10.594] Build status: SCHEDULED
[14:32:10.595] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:31:0) after 5m18s
[14:37:28.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:31:0)
[14:37:28.749] Build status: SCHEDULED
[14:37:28.749] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:32:0) after 8m29s
[14:45:57.980] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:32:0)
[14:45:58.214] Build status: SCHEDULED
[14:45:58.214] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:33:0) after 4m47s
[14:50:45.232] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:33:0)
[14:50:45.316] Build status: SCHEDULED
[14:50:45.316] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:34:0) after 1m34s
[14:52:19.402] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:34:0)
[14:52:19.429] Build status: SCHEDULED
[14:52:19.429] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:35:0) after 9m48s
[15:02:07.464] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:35:0)
[15:02:07.498] Build status: SCHEDULED
[15:02:07.498] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:36:0) after 5m21s
[15:07:28.522] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:36:0)
[15:07:28.576] Build status: SCHEDULED
[15:07:28.576] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:37:0) after 4m58s
[15:12:26.605] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:37:0)
[15:12:26.646] Build status: SCHEDULED
[15:12:26.646] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:38:0) after 7m25s
[15:19:51.770] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:38:0)
[15:19:51.796] Build status: SCHEDULED
[15:19:51.796] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:39:0) after 2m50s
[15:22:41.869] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:39:0)
[15:22:41.896] Build status: SCHEDULED
[15:22:41.896] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:40:0) after 3m39s
[15:26:20.922] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:40:0)
[15:26:20.971] Build status: SCHEDULED
[15:26:20.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:41:0) after 3m46s
[15:30:07.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:41:0)
[15:30:07.148] Build status: SCHEDULED
[15:30:07.148] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:42:0) after 4m4s
[15:34:11.176] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:42:0)
[15:34:11.212] Build status: SCHEDULED
[15:34:11.212] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:43:0) after 4m1s
[15:38:12.252] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:43:0)
[15:38:12.284] Build status: SCHEDULED
[15:38:12.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:44:0) after 1m58s
[15:40:10.271] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:44:0)
[15:40:10.294] Build status: SCHEDULED
[15:40:10.294] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:45:0) after 3m4s
[15:43:14.316] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:45:0)
[15:43:14.351] Build status: SCHEDULED
[15:43:14.351] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:46:0) after 9m6s
[15:52:20.377] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:46:0)
[15:52:20.417] Build status: SCHEDULED
[15:52:20.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:47:0) after 9m8s
[16:01:28.553] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:47:0)
[16:01:28.967] Build status: SCHEDULED
[16:01:28.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:48:0) after 7m0s
[16:08:29.040] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:48:0)
[16:08:29.060] Build status: SCHEDULED
[16:08:29.060] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:49:0) after 3m46s
[16:12:15.195] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:49:0)
[16:12:15.238] Build status: SCHEDULED
[16:12:15.238] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:50:0) after 9m19s
[16:21:34.322] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:50:0)
[16:21:34.410] Build status: SCHEDULED
[16:21:34.410] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:51:0) after 8m16s
[16:29:50.440] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:51:0)
[16:29:50.482] Build status: SCHEDULED
[16:29:50.482] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:52:0) after 3m39s
[16:33:29.561] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:52:0)
[16:33:29.598] Build status: SCHEDULED
[16:33:29.598] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:53:0) after 3m18s
[16:36:47.638] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:53:0)
[16:36:47.661] Build status: SCHEDULED
[16:36:47.661] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:54:0) after 3m26s
[16:40:14.257] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:54:0)
[16:40:14.278] Build status: SCHEDULED
[16:40:14.278] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:55:0) after 2m7s
[16:42:21.509] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:55:0)
[16:42:21.667] Build status: SCHEDULED
[16:42:21.667] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:56:0) after 2m13s
[16:44:34.892] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:56:0)
[16:44:34.982] Build status: SCHEDULED
[16:44:34.982] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:57:0) after 6m39s
[16:51:14.029] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:57:0)
[16:51:14.050] Build status: SCHEDULED
[16:51:14.050] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:58:0) after 4m59s
[16:56:13.070] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:58:0)
[16:56:13.095] Build status: SCHEDULED
[16:56:13.095] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:59:0) after 3m25s
[16:59:38.685] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:59:0)
[16:59:38.740] Build status: SCHEDULED
[16:59:38.741] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:60:0) after 8m13s
[17:07:51.775] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:60:0)
[17:07:51.822] Build status: SCHEDULED
[17:07:51.822] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:61:0) after 5m57s
[17:13:48.806] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:61:0)
[17:13:48.831] Build status: SCHEDULED
[17:13:48.831] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:62:0) after 4m20s
[17:18:08.859] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:62:0)
[17:18:08.882] Build status: SCHEDULED
[17:18:08.882] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:63:0) after 6m46s
[17:24:54.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:63:0)
[17:24:54.925] Build status: SCHEDULED
[17:24:54.925] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:64:0) after 8m42s
[17:33:36.950] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:64:0)
[17:33:36.983] Build status: SCHEDULED
[17:33:36.983] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:65:0) after 5m0s
[17:38:37.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:65:0)
[17:38:37.361] Build status: SCHEDULED
[17:38:37.361] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:66:0) after 8m49s
[17:47:26.440] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:66:0)
[17:47:26.473] Build status: SCHEDULED
[17:47:26.473] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:67:0) after 6m37s
[17:54:03.492] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:67:0)
[17:54:03.513] Build status: SCHEDULED
[17:54:03.513] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:68:0) after 2m30s
[17:56:33.597] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:68:0)
[17:56:33.624] Build status: SCHEDULED
[17:56:33.624] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:69:0) after 5m53s
[18:02:26.761] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:69:0)
[18:02:28.453] Build status: SCHEDULED
[18:02:28.453] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:70:0) after 8m36s
[18:11:04.548] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:70:0)
[18:11:04.700] Build status: SCHEDULED
[18:11:04.700] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:71:0) after 3m2s
[18:14:06.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:71:0)
[18:14:06.763] Build status: SCHEDULED
[18:14:06.763] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:72:0) after 7m54s
[18:22:00.886] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:72:0)
[18:22:00.967] Build status: SCHEDULED
[18:22:00.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:73:0) after 2m59s
[18:25:00.032] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:73:0)
[18:25:00.060] Build status: SCHEDULED
[18:25:00.060] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:74:0) after 5m54s
[18:30:54.090] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:74:0)
[18:30:54.124] Build status: SCHEDULED
[18:30:54.124] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:75:0) after 2m43s
[18:33:37.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:75:0)
[18:33:37.249] Build status: SCHEDULED
[18:33:37.249] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:76:0) after 1m33s
[18:35:10.354] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:76:0)
[18:35:10.400] Build status: SCHEDULED
[18:35:10.400] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:77:0) after 2m13s
[18:37:23.429] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:77:0)
[18:37:23.501] Build status: SCHEDULED
[18:37:23.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:78:0) after 7m23s
[18:44:46.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:78:0)
[18:44:46.602] Build status: SCHEDULED
[18:44:46.602] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:79:0) after 2m50s
[18:47:36.636] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:79:0)
[18:47:36.825] Build status: SCHEDULED
[18:47:36.825] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:80:0) after 3m44s
[18:51:20.844] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:80:0)
[18:51:20.927] Build status: SCHEDULED
[18:51:20.927] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:81:0) after 4m44s
[18:56:04.995] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:81:0)
[18:56:05.062] Build status: SCHEDULED
[18:56:05.062] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:82:0) after 9m42s
[19:05:47.071] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:82:0)
[19:05:47.109] Build status: SCHEDULED
[19:05:47.109] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:83:0) after 9m18s
[19:15:05.166] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:83:0)
[19:15:05.241] Build status: SCHEDULED
[19:15:05.241] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:84:0) after 6m59s
[19:22:04.260] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:84:0)
[19:22:04.297] Build status: SCHEDULED
[19:22:04.299] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:85:0) after 2m25s
[19:24:29.313] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:85:0)
[19:24:29.365] Build status: SCHEDULED
[19:24:29.365] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:86:0) after 4m46s
[19:29:15.389] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:86:0)
[19:29:15.419] Build status: SCHEDULED
[19:29:15.420] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:87:0) after 7m31s
[19:36:46.449] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:87:0)
[19:36:46.474] Build status: SCHEDULED
[19:36:46.474] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:88:0) after 9m18s
[19:46:04.511] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:88:0)
[19:46:04.569] Build status: SCHEDULED
[19:46:04.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:89:0) after 5m59s
[19:52:03.648] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:89:0)
[19:52:03.683] Build status: SCHEDULED
[19:52:03.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:90:0) after 6m10s
[19:58:13.775] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:90:0)
[19:58:13.796] Build status: SCHEDULED
[19:58:13.796] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:91:0) after 2m25s
[20:00:38.957] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:91:0)
[20:00:39.028] Build status: SCHEDULED
[20:00:39.028] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:92:0) after 1m35s
[20:02:14.737] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:92:0)
[20:02:14.762] Build status: SCHEDULED
[20:02:14.762] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:93:0) after 9m3s
[20:11:17.803] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:93:0)
[20:11:17.833] Build status: SCHEDULED
[20:11:17.833] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:94:0) after 6m41s
[20:17:58.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:94:0)
[20:17:58.921] Build status: SCHEDULED
[20:17:58.921] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:95:0) after 9m26s
[20:27:24.946] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:95:0)
[20:27:24.995] Build status: SCHEDULED
[20:27:24.995] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:96:0) after 3m32s
[20:30:57.027] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:96:0)
[20:30:57.111] Build status: SCHEDULED
[20:30:57.111] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:97:0) after 1m57s
[20:32:54.234] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:97:0)
[20:32:54.331] Build status: SCHEDULED
[20:32:54.331] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:98:0) after 1m24s
[20:34:18.366] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:98:0)
[20:34:18.401] Build status: SCHEDULED
[20:34:18.401] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:99:0) after 8m34s
[20:42:52.523] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:99:0)
[20:42:52.600] Build status: SCHEDULED
[20:42:52.600] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:100:0) after 3m48s
[20:46:40.770] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:100:0)
[20:46:40.801] Build status: SCHEDULED
[20:46:40.801] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:101:0) after 8m24s
[20:55:04.819] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:101:0)
[20:55:04.844] Build status: SCHEDULED
[20:55:04.844] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:102:0) after 7m17s
[21:02:21.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:102:0)
[21:02:21.980] Build status: SCHEDULED
[21:02:21.980] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:103:0) after 4m12s
[21:06:34.008] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:103:0)
[21:06:34.036] Build status: SCHEDULED
[21:06:34.036] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:104:0) after 7m36s
[21:14:10.078] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:104:0)
[21:14:10.118] Build status: SCHEDULED
[21:14:10.118] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:105:0) after 2m26s
[21:16:36.168] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:105:0)
[21:16:36.213] Build status: SCHEDULED
[21:16:36.213] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:106:0) after 8m46s
[21:25:22.296] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:106:0)
[21:25:22.332] Build status: SCHEDULED
[21:25:22.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:107:0) after 4m15s
[21:29:37.391] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:107:0)
[21:29:37.411] Build status: SCHEDULED
[21:29:37.411] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:108:0) after 3m5s
[21:32:42.696] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:108:0)
[21:32:42.728] Build status: SCHEDULED
[21:32:42.728] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:109:0) after 8m30s
[21:41:12.827] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:109:0)
[21:41:12.941] Build status: SCHEDULED
[21:41:12.941] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:110:0) after 4m34s
[21:45:47.014] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:110:0)
[21:45:47.042] Build status: SCHEDULED
[21:45:47.042] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:111:0) after 1m47s
[21:47:34.130] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:111:0)
[21:47:34.166] Build status: SCHEDULED
[21:47:34.166] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:112:0) after 5m39s
[21:53:13.257] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:112:0)
[21:53:13.331] Build status: SCHEDULED
[21:53:13.331] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:113:0) after 7m48s
[22:01:01.374] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:113:0)
[22:01:01.422] Build status: SCHEDULED
[22:01:01.422] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:114:0) after 5m8s
[22:06:09.401] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:114:0)
[22:06:09.427] Build status: SCHEDULED
[22:06:09.427] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:115:0) after 7m26s
[22:13:35.508] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:115:0)
[22:13:35.559] Build status: SCHEDULED
[22:13:35.559] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:116:0) after 6m52s
[22:20:27.603] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:116:0)
[22:20:27.657] Build status: SCHEDULED
[22:20:27.657] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:117:0) after 9m31s
[22:29:58.691] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:117:0)
[22:29:58.726] Build status: SCHEDULED
[22:29:58.726] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:118:0) after 9m15s
[22:39:13.751] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:118:0)
[22:39:13.803] Build status: SCHEDULED
[22:39:13.804] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:119:0) after 9m59s
[22:49:12.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:119:0)
[22:49:12.899] Build status: SCHEDULED
[22:49:12.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:120:0) after 5m57s
[22:55:09.936] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:120:0)
[22:55:09.958] Build status: SCHEDULED
[22:55:09.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:121:0) after 4m5s
[22:59:14.983] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:121:0)
[22:59:15.014] Build status: SCHEDULED
[22:59:15.014] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:122:0) after 6m1s
[23:05:16.038] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:122:0)
[23:05:16.055] Build status: SCHEDULED
[23:05:16.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:123:0) after 2m44s
[23:08:00.083] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:123:0)
[23:08:00.142] Build status: SCHEDULED
[23:08:00.142] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:124:0) after 2m0s
[23:10:00.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:124:0)
[23:10:00.222] Build status: SCHEDULED
[23:10:00.222] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:125:0) after 2m57s
[23:12:57.323] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:125:0)
[23:12:57.355] Build status: SCHEDULED
[23:12:57.355] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:126:0) after 9m16s
[23:22:13.378] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:126:0)
[23:22:13.422] Build status: SCHEDULED
[23:22:13.422] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:127:0) after 1m6s
[23:23:19.458] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:127:0)
[23:23:19.488] Build status: SCHEDULED
[23:23:19.488] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:128:0) after 7m52s
[23:31:11.613] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:128:0)
[23:31:11.642] Build status: SCHEDULED
[23:31:11.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:129:0) after 2m27s
[23:33:38.823] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:129:0)
[23:33:38.857] Build status: SCHEDULED
[23:33:38.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:130:0) after 7m10s
[23:40:48.900] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:130:0)
[23:40:48.953] Build status: SCHEDULED
[23:40:48.953] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:131:0) after 3m52s
[23:44:40.985] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:131:0)
[23:44:41.020] Build status: SCHEDULED
[23:44:41.020] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:132:0) after 3m47s
[23:48:28.047] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:132:0)
[23:48:28.072] Build status: SCHEDULED
[23:48:28.072] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:133:0) after 6m25s
[23:54:53.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:133:0)
[23:54:53.129] Build status: SCHEDULED
[23:54:53.129] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:134:0) after 8m7s
[00:03:00.149] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:134:0)
[00:03:00.193] Build status: SCHEDULED
[00:03:00.193] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:135:0) after 6m2s
[00:09:02.346] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:135:0)
[00:09:02.427] Build status: SCHEDULED
[00:09:02.427] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:136:0) after 5m24s
[00:10:21.615] Received PubSub notification, asking Buildbucket for the build status
[00:10:21.636] Build status: STARTED
[00:14:26.480] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:136:0)
[00:14:26.515] Build status: STARTED
[00:14:26.516] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:138:0) after 9m53s
[00:24:19.648] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:138:0)
[00:24:19.686] Build status: STARTED
[00:24:19.686] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:139:0) after 3m18s
[00:27:37.795] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:139:0)
[00:27:37.816] Build status: STARTED
[00:27:37.816] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:140:0) after 8m14s
[00:35:51.872] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:140:0)
[00:35:51.957] Build status: STARTED
[00:35:51.957] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:141:0) after 2m9s
[00:38:00.984] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:141:0)
[00:38:01.021] Build status: STARTED
[00:38:01.021] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:142:0) after 1m2s
[00:39:03.049] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:142:0)
[00:39:03.089] Build status: STARTED
[00:39:03.089] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:143:0) after 6m11s
[00:45:14.314] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:143:0)
[00:45:14.356] Build status: STARTED
[00:45:14.356] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:144:0) after 8m19s
[00:53:33.388] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:144:0)
[00:53:33.420] Build status: STARTED
[00:53:33.420] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:145:0) after 4m23s
[00:57:56.518] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:145:0)
[00:57:56.548] Build status: STARTED
[00:57:56.548] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:146:0) after 1m17s
[00:59:13.622] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:146:0)
[00:59:13.721] Build status: STARTED
[00:59:13.721] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:147:0) after 9m24s
[01:08:37.795] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:147:0)
[01:08:37.889] Build status: STARTED
[01:08:37.889] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:148:0) after 1m33s
[01:10:10.937] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:148:0)
[01:10:10.967] Build status: STARTED
[01:10:10.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:149:0) after 6m29s
[01:16:40.459] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:149:0)
[01:16:40.508] Build status: STARTED
[01:16:40.508] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:150:0) after 3m10s
[01:19:50.607] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:150:0)
[01:19:50.634] Build status: STARTED
[01:19:50.634] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:151:0) after 8m57s
[01:28:47.714] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:151:0)
[01:28:47.733] Build status: STARTED
[01:28:47.733] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:152:0) after 9m41s
[01:38:29.083] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:152:0)
[01:38:29.111] Build status: STARTED
[01:38:29.111] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:153:0) after 4m43s
[01:43:12.571] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:153:0)
[01:43:12.731] Build status: STARTED
[01:43:12.731] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:154:0) after 9m26s
[01:52:39.564] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:154:0)
[01:52:39.589] Build status: STARTED
[01:52:39.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:155:0) after 3m3s
[01:55:42.630] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:155:0)
[01:55:42.689] Build status: STARTED
[01:55:42.689] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:156:0) after 2m18s
[01:58:00.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:156:0)
[01:58:00.848] Build status: STARTED
[01:58:00.848] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:157:0) after 6m41s
[02:04:41.880] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:157:0)
[02:04:41.903] Build status: STARTED
[02:04:41.903] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:158:0) after 2m15s
[02:06:56.944] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:158:0)
[02:06:56.972] Build status: STARTED
[02:06:56.972] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:159:0) after 2m9s
[02:09:06.076] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:159:0)
[02:09:06.119] Build status: STARTED
[02:09:06.120] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:160:0) after 5m1s
[02:14:07.159] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:160:0)
[02:14:07.191] Build status: STARTED
[02:14:07.191] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:161:0) after 1m39s
[02:15:46.258] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:161:0)
[02:15:46.291] Build status: STARTED
[02:15:46.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:162:0) after 2m19s
[02:18:05.364] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:162:0)
[02:18:05.397] Build status: STARTED
[02:18:05.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:163:0) after 4m25s
[02:22:30.590] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:163:0)
[02:22:30.675] Build status: STARTED
[02:22:30.675] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:164:0) after 2m38s
[02:25:08.792] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:164:0)
[02:25:08.827] Build status: STARTED
[02:25:08.827] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:165:0) after 5m28s
[02:30:37.485] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:165:0)
[02:30:37.536] Build status: STARTED
[02:30:37.536] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:166:0) after 8m29s
[02:39:06.807] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:166:0)
[02:39:06.840] Build status: STARTED
[02:39:06.840] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:167:0) after 8m27s
[02:47:33.927] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:167:0)
[02:47:33.972] Build status: STARTED
[02:47:33.972] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:168:0) after 4m22s
[02:51:56.076] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:168:0)
[02:51:56.095] Build status: STARTED
[02:51:56.095] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:169:0) after 6m35s
[02:58:31.156] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:169:0)
[02:58:31.284] Build status: STARTED
[02:58:31.284] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:170:0) after 7m59s
[03:06:30.321] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:170:0)
[03:06:30.387] Build status: STARTED
[03:06:30.387] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:171:0) after 9m15s
[03:15:45.603] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:171:0)
[03:15:45.650] Build status: STARTED
[03:15:45.650] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:172:0) after 5m59s
[03:21:44.846] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:172:0)
[03:21:44.895] Build status: STARTED
[03:21:44.895] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:173:0) after 7m38s
[03:29:22.921] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:173:0)
[03:29:22.957] Build status: STARTED
[03:29:22.957] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:174:0) after 7m8s
[03:36:31.042] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:174:0)
[03:36:31.090] Build status: STARTED
[03:36:31.094] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:175:0) after 3m55s
[03:40:26.241] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:175:0)
[03:40:26.298] Build status: STARTED
[03:40:26.298] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:176:0) after 2m30s
[03:42:56.501] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:176:0)
[03:42:56.531] Build status: STARTED
[03:42:56.531] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:177:0) after 8m27s
[03:51:23.633] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:177:0)
[03:51:23.667] Build status: STARTED
[03:51:23.667] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:178:0) after 4m57s
[03:56:20.658] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:178:0)
[03:56:20.692] Build status: STARTED
[03:56:20.692] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:179:0) after 2m56s
[03:59:16.899] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:179:0)
[03:59:16.981] Build status: STARTED
[03:59:16.981] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:180:0) after 9m12s
[04:08:29.010] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:180:0)
[04:08:29.052] Build status: STARTED
[04:08:29.052] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:181:0) after 5m40s
[04:14:09.147] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:181:0)
[04:14:09.183] Build status: STARTED
[04:14:09.183] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:182:0) after 7m4s
[04:21:13.256] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:182:0)
[04:21:13.291] Build status: STARTED
[04:21:13.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:183:0) after 5m8s
[04:26:21.395] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:183:0)
[04:26:21.426] Build status: STARTED
[04:26:21.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:184:0) after 9m46s
[04:36:07.500] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:184:0)
[04:36:07.525] Build status: STARTED
[04:36:07.525] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:185:0) after 5m52s
[04:41:59.552] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:185:0)
[04:41:59.724] Build status: STARTED
[04:41:59.724] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:186:0) after 1m25s
[04:43:25.013] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:186:0)
[04:43:25.049] Build status: STARTED
[04:43:25.049] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:187:0) after 2m34s
[04:45:59.234] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:187:0)
[04:45:59.265] Build status: STARTED
[04:45:59.265] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:188:0) after 7m6s
[04:53:05.346] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:188:0)
[04:53:05.374] Build status: STARTED
[04:53:05.374] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:189:0) after 1m25s
[04:54:30.381] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:189:0)
[04:54:30.442] Build status: STARTED
[04:54:30.442] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:190:0) after 3m16s
[04:57:46.472] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:190:0)
[04:57:46.496] Build status: STARTED
[04:57:46.497] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:191:0) after 2m35s
[05:00:21.535] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:191:0)
[05:00:21.569] Build status: STARTED
[05:00:21.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:192:0) after 7m26s
[05:07:47.616] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:192:0)
[05:07:47.722] Build status: STARTED
[05:07:47.722] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:193:0) after 1m18s
[05:09:05.941] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:193:0)
[05:09:05.989] Build status: STARTED
[05:09:05.989] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:194:0) after 7m17s
[05:16:23.123] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:194:0)
[05:16:23.218] Build status: STARTED
[05:16:23.218] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:195:0) after 2m53s
[05:19:16.243] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:195:0)
[05:19:16.261] Build status: STARTED
[05:19:16.261] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:196:0) after 5m42s
[05:24:58.535] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:196:0)
[05:24:58.618] Build status: STARTED
[05:24:58.618] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:197:0) after 6m34s
[05:31:32.803] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:197:0)
[05:31:32.832] Build status: STARTED
[05:31:32.832] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:198:0) after 7m35s
[05:39:08.039] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:198:0)
[05:39:08.135] Build status: STARTED
[05:39:08.135] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:199:0) after 7m15s
[05:46:23.124] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:199:0)
[05:46:23.263] Build status: STARTED
[05:46:23.263] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:200:0) after 7m53s
[05:54:16.456] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:200:0)
[05:54:16.477] Build status: STARTED
[05:54:16.477] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:201:0) after 7m29s
[06:01:45.556] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:201:0)
[06:01:45.710] Build status: STARTED
[06:01:45.710] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:202:0) after 5m2s
[06:06:47.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:202:0)
[06:06:48.026] Build status: STARTED
[06:06:48.026] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:203:0) after 4m31s
[06:11:19.147] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:203:0)
[06:11:19.184] Build status: STARTED
[06:11:19.184] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:204:0) after 9m9s
[06:20:28.361] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:204:0)
[06:20:28.414] Build status: STARTED
[06:20:28.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:205:0) after 7m31s
[06:27:59.725] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:205:0)
[06:27:59.751] Build status: STARTED
[06:27:59.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:206:0) after 1m43s
[06:29:42.823] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:206:0)
[06:29:42.857] Build status: STARTED
[06:29:42.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:207:0) after 1m1s
[06:30:44.100] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:207:0)
[06:30:44.129] Build status: STARTED
[06:30:44.129] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:208:0) after 3m34s
[06:34:18.228] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:208:0)
[06:34:18.278] Build status: STARTED
[06:34:18.278] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:209:0) after 5m46s
[06:40:04.324] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:209:0)
[06:40:04.377] Build status: STARTED
[06:40:04.377] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:210:0) after 2m3s
[06:42:07.435] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:210:0)
[06:42:07.466] Build status: STARTED
[06:42:07.466] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:211:0) after 7m33s
[06:49:40.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:211:0)
[06:49:40.588] Build status: STARTED
[06:49:40.588] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8915115631734695680:212:0) after 7m9s
[06:51:53.927] Received PubSub notification, asking Buildbucket for the build status
[06:51:53.978] Build:
{
"id": "8749685569709510433",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 4063,
"createdBy": "project:r8",
"createTime": "2024-04-25T12:03:37.745419060Z",
"startTime": "2024-04-26T00:10:20.769876812Z",
"endTime": "2024-04-26T06:51:53.663766012Z",
"updateTime": "2024-04-26T06:51:53.663766012Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "da075e9f967d469d14a93a2f52bff039f3c75855",
"ref": "refs/heads/main"
}
}
}
[06:51:53.978] Invocation finished in 18h48m17.547628681s with status SUCCEEDED