[17:52:40.450] New invocation is queued and will start shortly
[17:52:41.653] Starting the invocation (attempt 1)
[17:52:41.692] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/57a16e19610b0e8821723ed44664b7145a8e80c7"
[17:52:41.692] Popped gitiles commit info from properties and tags
[17:52:41.692] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:52:41.692] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:52:41.693] Buildbucket request:
{
"requestId": "8968364686601361760",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8968364686601361760",
"job": "r8/linux-internal",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "67b189ed86f7a33d4b90f9372ad9c527fc0d518d"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@67b189ed86f7a33d4b90f9372ad9c527fc0d518d",
"title": "67b189ed86f7a33d4b90f9372ad9c527fc0d518d",
"url": "https://r8.googlesource.com/r8/+/67b189ed86f7a33d4b90f9372ad9c527fc0d518d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "6d99d3a0513bd501671e5c864c0aa6f88a3ab553"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@6d99d3a0513bd501671e5c864c0aa6f88a3ab553",
"title": "6d99d3a0513bd501671e5c864c0aa6f88a3ab553",
"url": "https://r8.googlesource.com/r8/+/6d99d3a0513bd501671e5c864c0aa6f88a3ab553"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "159476ce90cba081c1f7617cd2cf951cfe7238ad"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@159476ce90cba081c1f7617cd2cf951cfe7238ad",
"title": "159476ce90cba081c1f7617cd2cf951cfe7238ad",
"url": "https://r8.googlesource.com/r8/+/159476ce90cba081c1f7617cd2cf951cfe7238ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "7aa59cd9f612392f73e12c283beadc92e176001e"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@7aa59cd9f612392f73e12c283beadc92e176001e",
"title": "7aa59cd9f612392f73e12c283beadc92e176001e",
"url": "https://r8.googlesource.com/r8/+/7aa59cd9f612392f73e12c283beadc92e176001e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "57a16e19610b0e8821723ed44664b7145a8e80c7"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@57a16e19610b0e8821723ed44664b7145a8e80c7",
"title": "57a16e19610b0e8821723ed44664b7145a8e80c7",
"url": "https://r8.googlesource.com/r8/+/57a16e19610b0e8821723ed44664b7145a8e80c7"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "57a16e19610b0e8821723ed44664b7145a8e80c7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8968364686601361760"
},
{
"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"
}
}
[17:52:42.380] Scheduled build:
{
"id": "8802934624556027457",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2764,
"createdBy": "project:r8",
"createTime": "2022-09-15T17:52:41.765566001Z",
"updateTime": "2022-09-15T17:52:41.765566001Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "57a16e19610b0e8821723ed44664b7145a8e80c7",
"ref": "refs/heads/main"
}
}
}
[17:52:42.380] Task URL: https://cr-buildbucket.appspot.com/build/8802934624556027457
[17:52:42.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:2:0) after 8m47s
[18:01:29.863] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:2:0)
[18:01:29.888] Build status: SCHEDULED
[18:01:29.888] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:3:0) after 7m53s
[18:09:22.921] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:3:0)
[18:09:22.950] Build status: SCHEDULED
[18:09:22.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:4:0) after 8m58s
[18:18:21.089] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:4:0)
[18:18:21.114] Build status: SCHEDULED
[18:18:21.114] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:5:0) after 2m0s
[18:20:21.360] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:5:0)
[18:20:21.390] Build status: SCHEDULED
[18:20:21.390] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:6:0) after 1m2s
[18:21:23.476] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:6:0)
[18:21:23.508] Build status: SCHEDULED
[18:21:23.508] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:7:0) after 1m43s
[18:23:06.636] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:7:0)
[18:23:06.664] Build status: SCHEDULED
[18:23:06.664] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:8:0) after 4m55s
[18:28:01.717] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:8:0)
[18:28:01.744] Build status: SCHEDULED
[18:28:01.744] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:9:0) after 7m48s
[18:35:49.818] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:9:0)
[18:35:49.849] Build status: SCHEDULED
[18:35:49.849] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:10:0) after 4m13s
[18:40:02.932] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:10:0)
[18:40:02.962] Build status: SCHEDULED
[18:40:02.962] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:11:0) after 2m42s
[18:42:45.569] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:11:0)
[18:42:45.597] Build status: SCHEDULED
[18:42:45.597] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:12:0) after 5m19s
[18:48:05.064] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:12:0)
[18:48:05.117] Build status: SCHEDULED
[18:48:05.117] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:13:0) after 5m15s
[18:53:20.137] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:13:0)
[18:53:20.168] Build status: SCHEDULED
[18:53:20.168] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:14:0) after 8m21s
[19:01:41.212] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:14:0)
[19:01:41.241] Build status: SCHEDULED
[19:01:41.241] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:15:0) after 9m38s
[19:11:19.275] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:15:0)
[19:11:19.315] Build status: SCHEDULED
[19:11:19.315] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:16:0) after 2m51s
[19:14:10.433] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:16:0)
[19:14:10.507] Build status: SCHEDULED
[19:14:10.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:17:0) after 1m56s
[19:16:06.667] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:17:0)
[19:16:06.689] Build status: SCHEDULED
[19:16:06.689] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:18:0) after 6m21s
[19:22:27.712] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:18:0)
[19:22:27.737] Build status: SCHEDULED
[19:22:27.737] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:19:0) after 4m31s
[19:26:58.805] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:19:0)
[19:26:58.828] Build status: SCHEDULED
[19:26:58.828] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:20:0) after 2m22s
[19:29:20.931] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:20:0)
[19:29:20.958] Build status: SCHEDULED
[19:29:20.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:21:0) after 4m46s
[19:34:07.165] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:21:0)
[19:34:07.201] Build status: SCHEDULED
[19:34:07.201] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:22:0) after 7m30s
[19:41:37.328] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:22:0)
[19:41:37.356] Build status: SCHEDULED
[19:41:37.356] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:23:0) after 3m53s
[19:45:30.377] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:23:0)
[19:45:30.417] Build status: SCHEDULED
[19:45:30.418] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:24:0) after 3m50s
[19:49:20.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:24:0)
[19:49:20.758] Build status: SCHEDULED
[19:49:20.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:25:0) after 1m19s
[19:50:40.157] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:25:0)
[19:50:40.190] Build status: SCHEDULED
[19:50:40.190] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:26:0) after 1m46s
[19:52:26.215] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:26:0)
[19:52:26.246] Build status: SCHEDULED
[19:52:26.246] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:27:0) after 2m24s
[19:54:50.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:27:0)
[19:54:50.292] Build status: SCHEDULED
[19:54:50.292] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:28:0) after 8m44s
[20:03:34.731] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:28:0)
[20:03:34.759] Build status: SCHEDULED
[20:03:34.759] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:29:0) after 5m19s
[20:08:53.767] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:29:0)
[20:08:53.802] Build status: SCHEDULED
[20:08:53.802] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:30:0) after 5m11s
[20:14:04.942] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:30:0)
[20:14:04.969] Build status: SCHEDULED
[20:14:04.969] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:31:0) after 8m14s
[20:22:19.094] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:31:0)
[20:22:19.147] Build status: SCHEDULED
[20:22:19.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:32:0) after 4m56s
[20:27:15.481] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:32:0)
[20:27:15.503] Build status: SCHEDULED
[20:27:15.503] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:33:0) after 1m21s
[20:28:36.703] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:33:0)
[20:28:36.747] Build status: SCHEDULED
[20:28:36.747] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:34:0) after 8m51s
[20:37:28.030] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:34:0)
[20:37:28.058] Build status: SCHEDULED
[20:37:28.058] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:35:0) after 2m6s
[20:39:34.426] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:35:0)
[20:39:34.456] Build status: SCHEDULED
[20:39:34.456] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:36:0) after 4m50s
[20:44:24.470] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:36:0)
[20:44:24.503] Build status: SCHEDULED
[20:44:24.503] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:37:0) after 4m34s
[20:48:58.525] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:37:0)
[20:48:58.648] Build status: SCHEDULED
[20:48:58.648] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:38:0) after 1m45s
[20:50:43.671] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:38:0)
[20:50:43.703] Build status: SCHEDULED
[20:50:43.703] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:39:0) after 9m50s
[21:00:33.868] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:39:0)
[21:00:33.894] Build status: SCHEDULED
[21:00:33.894] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:40:0) after 1m52s
[21:02:26.017] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:40:0)
[21:02:26.044] Build status: SCHEDULED
[21:02:26.044] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:41:0) after 2m34s
[21:05:00.125] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:41:0)
[21:05:00.153] Build status: SCHEDULED
[21:05:00.153] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:42:0) after 8m53s
[21:13:53.173] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:42:0)
[21:13:53.197] Build status: SCHEDULED
[21:13:53.197] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:43:0) after 2m38s
[21:16:31.188] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:43:0)
[21:16:31.258] Build status: SCHEDULED
[21:16:31.258] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:44:0) after 2m8s
[21:18:39.377] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:44:0)
[21:18:39.401] Build status: SCHEDULED
[21:18:39.401] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:45:0) after 6m29s
[21:25:08.530] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:45:0)
[21:25:08.575] Build status: SCHEDULED
[21:25:08.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:46:0) after 7m58s
[21:33:06.688] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:46:0)
[21:33:06.725] Build status: SCHEDULED
[21:33:06.725] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:47:0) after 8m43s
[21:41:49.762] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:47:0)
[21:41:49.805] Build status: SCHEDULED
[21:41:49.805] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:48:0) after 8m10s
[21:49:59.952] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:48:0)
[21:49:59.975] Build status: SCHEDULED
[21:49:59.975] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:49:0) after 9m1s
[21:59:01.103] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:49:0)
[21:59:01.129] Build status: SCHEDULED
[21:59:01.130] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:50:0) after 1m7s
[22:00:08.162] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:50:0)
[22:00:08.194] Build status: SCHEDULED
[22:00:08.194] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:51:0) after 3m12s
[22:03:20.235] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:51:0)
[22:03:20.273] Build status: SCHEDULED
[22:03:20.273] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:52:0) after 5m15s
[22:08:35.446] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:52:0)
[22:08:35.484] Build status: SCHEDULED
[22:08:35.484] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:53:0) after 2m13s
[22:10:49.413] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:53:0)
[22:10:49.439] Build status: SCHEDULED
[22:10:49.439] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:54:0) after 1m58s
[22:12:47.613] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:54:0)
[22:12:47.641] Build status: SCHEDULED
[22:12:47.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:55:0) after 1m35s
[22:14:22.759] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:55:0)
[22:14:22.851] Build status: SCHEDULED
[22:14:22.851] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:56:0) after 5m17s
[22:19:39.878] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:56:0)
[22:19:39.907] Build status: SCHEDULED
[22:19:39.908] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:57:0) after 6m15s
[22:24:30.841] Received PubSub notification, asking Buildbucket for the build status
[22:24:30.875] Build status: STARTED
[22:25:54.928] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:57:0)
[22:25:54.958] Build status: STARTED
[22:25:54.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:59:0) after 1m31s
[22:27:25.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:59:0)
[22:27:26.011] Build status: STARTED
[22:27:26.011] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:60:0) after 4m47s
[22:32:13.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:60:0)
[22:32:13.226] Build status: STARTED
[22:32:13.226] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:61:0) after 7m20s
[22:39:33.814] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:61:0)
[22:39:33.841] Build status: STARTED
[22:39:33.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:62:0) after 7m55s
[22:47:29.375] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:62:0)
[22:47:29.433] Build status: STARTED
[22:47:29.433] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:63:0) after 3m16s
[22:50:45.509] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:63:0)
[22:50:45.537] Build status: STARTED
[22:50:45.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:64:0) after 2m27s
[22:53:12.564] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:64:0)
[22:53:12.591] Build status: STARTED
[22:53:12.591] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:65:0) after 2m1s
[22:55:13.960] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:65:0)
[22:55:13.990] Build status: STARTED
[22:55:13.990] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:66:0) after 3m13s
[22:58:27.011] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:66:0)
[22:58:27.056] Build status: STARTED
[22:58:27.056] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:67:0) after 5m43s
[23:04:10.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:67:0)
[23:04:10.212] Build status: STARTED
[23:04:10.212] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:68:0) after 7m12s
[23:11:22.426] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:68:0)
[23:11:22.451] Build status: STARTED
[23:11:22.451] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:69:0) after 9m26s
[23:20:48.774] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:69:0)
[23:20:48.801] Build status: STARTED
[23:20:48.801] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:70:0) after 3m19s
[23:24:09.096] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:70:0)
[23:24:09.123] Build status: STARTED
[23:24:09.123] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:71:0) after 6m54s
[23:31:03.300] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:71:0)
[23:31:03.332] Build status: STARTED
[23:31:03.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:72:0) after 1m58s
[23:33:01.482] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:72:0)
[23:33:01.507] Build status: STARTED
[23:33:01.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:73:0) after 7m54s
[23:40:55.805] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:73:0)
[23:40:55.831] Build status: STARTED
[23:40:55.831] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:74:0) after 6m30s
[23:47:25.826] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:74:0)
[23:47:25.854] Build status: STARTED
[23:47:25.854] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:75:0) after 1m49s
[23:49:15.053] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:75:0)
[23:49:15.096] Build status: STARTED
[23:49:15.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:76:0) after 6m9s
[23:55:24.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:76:0)
[23:55:24.242] Build status: STARTED
[23:55:24.242] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:77:0) after 6m7s
[00:01:31.338] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:77:0)
[00:01:31.379] Build status: STARTED
[00:01:31.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:78:0) after 5m58s
[00:07:29.678] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:78:0)
[00:07:29.701] Build status: STARTED
[00:07:29.702] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:79:0) after 2m19s
[00:09:48.849] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:79:0)
[00:09:48.879] Build status: STARTED
[00:09:48.879] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:80:0) after 4m34s
[00:14:23.239] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:80:0)
[00:14:23.266] Build status: STARTED
[00:14:23.266] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:81:0) after 8m56s
[00:23:19.588] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:81:0)
[00:23:19.629] Build status: STARTED
[00:23:19.629] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:82:0) after 5m8s
[00:28:27.687] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:82:0)
[00:28:27.711] Build status: STARTED
[00:28:27.711] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:83:0) after 9m26s
[00:37:53.871] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:83:0)
[00:37:53.899] Build status: STARTED
[00:37:53.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:84:0) after 9m55s
[00:47:49.307] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:84:0)
[00:47:49.334] Build status: STARTED
[00:47:49.334] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:85:0) after 7m22s
[00:55:11.456] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:85:0)
[00:55:11.486] Build status: STARTED
[00:55:11.486] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:86:0) after 2m8s
[00:57:19.561] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:86:0)
[00:57:19.608] Build status: STARTED
[00:57:19.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:87:0) after 4m39s
[01:01:58.916] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:87:0)
[01:01:58.946] Build status: STARTED
[01:01:58.946] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:88:0) after 4m10s
[01:06:09.267] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:88:0)
[01:06:09.332] Build status: STARTED
[01:06:09.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:89:0) after 7m10s
[01:13:19.374] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:89:0)
[01:13:19.429] Build status: STARTED
[01:13:19.429] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:90:0) after 1m17s
[01:14:36.652] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:90:0)
[01:14:36.679] Build status: STARTED
[01:14:36.679] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:91:0) after 7m46s
[01:22:22.764] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:91:0)
[01:22:22.802] Build status: STARTED
[01:22:22.802] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:92:0) after 9m4s
[01:31:26.840] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:92:0)
[01:31:26.870] Build status: STARTED
[01:31:26.870] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:93:0) after 1m29s
[01:32:55.985] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:93:0)
[01:32:56.014] Build status: STARTED
[01:32:56.014] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:94:0) after 3m55s
[01:36:51.042] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:94:0)
[01:36:51.075] Build status: STARTED
[01:36:51.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:95:0) after 7m54s
[01:44:45.204] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:95:0)
[01:44:45.235] Build status: STARTED
[01:44:45.235] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:96:0) after 2m56s
[01:47:41.745] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:96:0)
[01:47:41.797] Build status: STARTED
[01:47:41.797] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:97:0) after 6m24s
[01:54:05.823] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:97:0)
[01:54:05.851] Build status: STARTED
[01:54:05.851] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:98:0) after 9m42s
[02:03:47.913] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:98:0)
[02:03:47.943] Build status: STARTED
[02:03:47.943] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:99:0) after 4m42s
[02:08:30.370] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:99:0)
[02:08:30.420] Build status: STARTED
[02:08:30.420] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:100:0) after 2m21s
[02:10:51.626] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:100:0)
[02:10:51.666] Build status: STARTED
[02:10:51.667] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:101:0) after 4m8s
[02:14:59.790] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:101:0)
[02:14:59.816] Build status: STARTED
[02:14:59.816] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:102:0) after 1m24s
[02:16:24.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:102:0)
[02:16:24.126] Build status: STARTED
[02:16:24.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:103:0) after 4m15s
[02:20:39.348] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:103:0)
[02:20:39.412] Build status: STARTED
[02:20:39.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:104:0) after 2m49s
[02:23:28.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:104:0)
[02:23:28.569] Build status: STARTED
[02:23:28.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:105:0) after 4m23s
[02:27:51.736] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:105:0)
[02:27:51.767] Build status: STARTED
[02:27:51.768] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:106:0) after 9m41s
[02:37:32.805] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:106:0)
[02:37:32.840] Build status: STARTED
[02:37:32.840] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:107:0) after 5m3s
[02:42:35.876] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:107:0)
[02:42:35.906] Build status: STARTED
[02:42:35.906] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:108:0) after 6m3s
[02:48:39.789] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:108:0)
[02:48:39.817] Build status: STARTED
[02:48:39.817] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8968364686601361760:109:0) after 5m17s
[02:50:46.948] Received PubSub notification, asking Buildbucket for the build status
[02:50:46.989] Build:
{
"id": "8802934624556027457",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2764,
"createdBy": "project:r8",
"createTime": "2022-09-15T17:52:41.765566001Z",
"startTime": "2022-09-15T22:24:30.239724Z",
"endTime": "2022-09-16T02:50:46.436376266Z",
"updateTime": "2022-09-16T02:50:46.436376266Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "57a16e19610b0e8821723ed44664b7145a8e80c7",
"ref": "refs/heads/main"
}
}
}
[02:50:46.989] Invocation finished in 8h58m6.557988072s with status SUCCEEDED