[23:06:22.663] New invocation is queued and will start shortly
[23:06:23.793] Starting the invocation (attempt 1)
[23:06:23.896] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/eb9ab6e05c18823f00097c650bb2edcf51bded97"
[23:06:23.896] Popped gitiles commit info from properties and tags
[23:06:23.896] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:06:23.897] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:06:23.897] Buildbucket request:
{
"requestId": "8894417825489873040",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-old"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894417825489873040",
"job": "r8/linux-old",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "e2e45e6d2a191e92c5f29156135cf81a0b362351"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@e2e45e6d2a191e92c5f29156135cf81a0b362351",
"title": "e2e45e6d2a191e92c5f29156135cf81a0b362351",
"url": "https://r8.googlesource.com/r8/+/e2e45e6d2a191e92c5f29156135cf81a0b362351"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "eb9ab6e05c18823f00097c650bb2edcf51bded97"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@eb9ab6e05c18823f00097c650bb2edcf51bded97",
"title": "eb9ab6e05c18823f00097c650bb2edcf51bded97",
"url": "https://r8.googlesource.com/r8/+/eb9ab6e05c18823f00097c650bb2edcf51bded97"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "eb9ab6e05c18823f00097c650bb2edcf51bded97",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894417825489873040"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-old"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:06:24.374] Scheduled build:
{
"id": "8728987763426852369",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-old"
},
"number": 320,
"createdBy": "project:r8",
"createTime": "2024-12-09T23:06:24.003194182Z",
"updateTime": "2024-12-09T23:06:24.003194182Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "eb9ab6e05c18823f00097c650bb2edcf51bded97",
"ref": "refs/heads/main"
}
}
}
[23:06:24.375] Task URL: https://cr-buildbucket.appspot.com/build/8728987763426852369
[23:06:24.375] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:2:0) after 6m52s
[23:06:25.357] Received PubSub notification, asking Buildbucket for the build status
[23:06:25.382] Build status: SCHEDULED
[23:06:33.041] Received PubSub notification, asking Buildbucket for the build status
[23:06:33.060] Build status: STARTED
[23:13:16.392] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:2:0)
[23:13:16.432] Build status: STARTED
[23:13:16.432] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:5:0) after 1m29s
[23:14:45.492] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:5:0)
[23:14:45.530] Build status: STARTED
[23:14:45.530] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:6:0) after 6m38s
[23:21:23.522] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:6:0)
[23:21:23.556] Build status: STARTED
[23:21:23.557] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:7:0) after 8m28s
[23:29:51.584] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:7:0)
[23:29:51.642] Build status: STARTED
[23:29:51.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:8:0) after 1m7s
[23:30:58.656] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:8:0)
[23:30:58.698] Build status: STARTED
[23:30:58.698] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:9:0) after 1m2s
[23:32:00.706] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:9:0)
[23:32:00.732] Build status: STARTED
[23:32:00.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:10:0) after 9m21s
[23:41:21.754] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:10:0)
[23:41:21.802] Build status: STARTED
[23:41:21.802] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:11:0) after 8m48s
[23:50:09.815] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:11:0)
[23:50:09.831] Build status: STARTED
[23:50:09.832] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:12:0) after 4m27s
[23:54:36.846] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:12:0)
[23:54:36.871] Build status: STARTED
[23:54:36.871] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:13:0) after 4m51s
[23:59:27.940] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:13:0)
[23:59:27.957] Build status: STARTED
[23:59:27.957] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:14:0) after 7m41s
[00:07:09.020] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:14:0)
[00:07:09.060] Build status: STARTED
[00:07:09.060] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:15:0) after 5m55s
[00:13:04.074] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:15:0)
[00:13:04.098] Build status: STARTED
[00:13:04.098] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:16:0) after 9m45s
[00:22:49.116] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:16:0)
[00:22:49.148] Build status: STARTED
[00:22:49.148] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:17:0) after 9m27s
[00:32:16.163] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:17:0)
[00:32:16.215] Build status: STARTED
[00:32:16.215] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:18:0) after 6m46s
[00:39:02.344] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:18:0)
[00:39:02.384] Build status: STARTED
[00:39:02.384] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:19:0) after 8m3s
[00:47:05.518] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:19:0)
[00:47:05.546] Build status: STARTED
[00:47:05.546] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:20:0) after 4m6s
[00:51:11.720] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:20:0)
[00:51:11.745] Build status: STARTED
[00:51:11.745] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:21:0) after 4m11s
[00:55:22.758] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:21:0)
[00:55:22.775] Build status: STARTED
[00:55:22.775] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:22:0) after 9m56s
[01:05:18.840] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:22:0)
[01:05:18.857] Build status: STARTED
[01:05:18.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:23:0) after 8m16s
[01:13:34.872] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:23:0)
[01:13:34.898] Build status: STARTED
[01:13:34.898] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:24:0) after 1m6s
[01:14:40.939] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:24:0)
[01:14:41.089] Build status: STARTED
[01:14:41.089] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:25:0) after 7m29s
[01:22:10.198] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:25:0)
[01:22:10.236] Build status: STARTED
[01:22:10.236] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:26:0) after 5m15s
[01:27:25.305] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:26:0)
[01:27:25.332] Build status: STARTED
[01:27:25.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:27:0) after 5m31s
[01:32:56.392] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:27:0)
[01:32:56.416] Build status: STARTED
[01:32:56.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:28:0) after 9m56s
[01:42:52.591] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:28:0)
[01:42:52.617] Build status: STARTED
[01:42:52.617] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:29:0) after 1m42s
[01:44:34.632] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:29:0)
[01:44:34.658] Build status: STARTED
[01:44:34.658] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:30:0) after 4m7s
[01:48:41.669] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:30:0)
[01:48:41.696] Build status: STARTED
[01:48:41.697] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:31:0) after 4m50s
[01:53:31.881] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:31:0)
[01:53:31.906] Build status: STARTED
[01:53:31.906] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:32:0) after 1m34s
[01:55:05.942] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:32:0)
[01:55:05.962] Build status: STARTED
[01:55:05.962] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:33:0) after 7m52s
[02:02:57.985] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:33:0)
[02:02:58.068] Build status: STARTED
[02:02:58.068] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:34:0) after 8m28s
[02:11:26.125] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:34:0)
[02:11:26.153] Build status: STARTED
[02:11:26.153] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:35:0) after 3m53s
[02:15:19.199] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:35:0)
[02:15:19.220] Build status: STARTED
[02:15:19.220] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:36:0) after 8m41s
[02:24:00.237] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:36:0)
[02:24:00.266] Build status: STARTED
[02:24:00.266] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:37:0) after 8m10s
[02:32:10.344] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:37:0)
[02:32:10.379] Build status: STARTED
[02:32:10.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:38:0) after 1m36s
[02:33:46.521] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:38:0)
[02:33:46.546] Build status: STARTED
[02:33:46.546] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:39:0) after 9m21s
[02:43:07.564] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:39:0)
[02:43:07.603] Build status: STARTED
[02:43:07.603] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:40:0) after 6m39s
[02:49:46.659] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:40:0)
[02:49:46.705] Build status: STARTED
[02:49:46.705] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:41:0) after 7m40s
[02:57:26.721] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:41:0)
[02:57:26.748] Build status: STARTED
[02:57:26.748] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:42:0) after 5m45s
[03:03:11.812] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:42:0)
[03:03:11.899] Build status: STARTED
[03:03:11.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:43:0) after 3m49s
[03:07:00.963] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:43:0)
[03:07:00.980] Build status: STARTED
[03:07:00.980] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:44:0) after 8m56s
[03:15:57.056] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:44:0)
[03:15:57.079] Build status: STARTED
[03:15:57.080] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:45:0) after 7m13s
[03:23:10.155] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:45:0)
[03:23:10.181] Build status: STARTED
[03:23:10.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:46:0) after 8m5s
[03:31:15.297] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:46:0)
[03:31:15.394] Build status: STARTED
[03:31:15.394] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:47:0) after 7m3s
[03:38:18.415] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:47:0)
[03:38:18.441] Build status: STARTED
[03:38:18.441] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:48:0) after 2m42s
[03:41:00.504] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:48:0)
[03:41:00.611] Build status: STARTED
[03:41:00.611] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:49:0) after 2m26s
[03:43:26.626] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:49:0)
[03:43:26.657] Build status: STARTED
[03:43:26.657] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:50:0) after 6m29s
[03:49:55.828] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:50:0)
[03:49:55.874] Build status: STARTED
[03:49:55.874] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:51:0) after 8m3s
[03:57:58.961] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:51:0)
[03:57:58.988] Build status: STARTED
[03:57:58.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:52:0) after 9m43s
[04:07:42.007] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:52:0)
[04:07:42.107] Build status: STARTED
[04:07:42.107] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:53:0) after 6m8s
[04:13:50.412] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:53:0)
[04:13:50.441] Build status: STARTED
[04:13:50.441] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:54:0) after 9m41s
[04:23:31.502] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:54:0)
[04:23:31.525] Build status: STARTED
[04:23:31.526] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:55:0) after 3m18s
[04:26:49.668] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:55:0)
[04:26:49.761] Build status: STARTED
[04:26:49.761] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:56:0) after 4m26s
[04:31:15.840] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:56:0)
[04:31:15.900] Build status: STARTED
[04:31:15.900] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:57:0) after 1m11s
[04:32:27.360] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:57:0)
[04:32:27.401] Build status: STARTED
[04:32:27.401] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:58:0) after 5m6s
[04:37:34.005] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:58:0)
[04:37:34.026] Build status: STARTED
[04:37:34.026] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:59:0) after 6m1s
[04:43:35.044] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:59:0)
[04:43:35.075] Build status: STARTED
[04:43:35.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:60:0) after 5m50s
[04:49:25.252] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:60:0)
[04:49:25.277] Build status: STARTED
[04:49:25.277] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:61:0) after 8m51s
[04:58:16.305] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:61:0)
[04:58:16.330] Build status: STARTED
[04:58:16.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:62:0) after 2m33s
[05:00:49.401] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:62:0)
[05:00:49.426] Build status: STARTED
[05:00:49.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:63:0) after 1m59s
[05:02:48.441] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:63:0)
[05:02:48.501] Build status: STARTED
[05:02:48.501] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:64:0) after 2m57s
[05:05:45.473] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:64:0)
[05:05:45.493] Build status: STARTED
[05:05:45.493] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:65:0) after 2m45s
[05:08:30.550] Handling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:65:0)
[05:08:30.582] Build status: STARTED
[05:08:30.582] Scheduling timer "check-buildbucket-build-status" (r8/linux-old:8894417825489873040:66:0) after 9m12s
[05:11:38.227] Received PubSub notification, asking Buildbucket for the build status
[05:11:38.289] Build:
{
"id": "8728987763426852369",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-old"
},
"number": 320,
"createdBy": "project:r8",
"createTime": "2024-12-09T23:06:24.003194182Z",
"startTime": "2024-12-09T23:06:32.838527221Z",
"endTime": "2024-12-10T05:11:38.020655253Z",
"updateTime": "2024-12-10T05:11:38.020655253Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "eb9ab6e05c18823f00097c650bb2edcf51bded97",
"ref": "refs/heads/main"
}
}
}
[05:11:38.289] Invocation finished in 6h5m15.636361705s with status SUCCEEDED