[18:56:56.946] New invocation is queued and will start shortly
[18:56:58.326] Starting the invocation (attempt 1)
[18:56:58.359] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f40cc3495a759ef0a1449552698561dd22f07af5"
[18:56:58.359] Popped gitiles commit info from properties and tags
[18:56:58.359] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:56:58.359] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:56:58.359] Buildbucket request:
{
"requestId": "8896970233236764896",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f40cc3495a759ef0a1449552698561dd22f07af5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896970233236764896"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:56:59.627] Scheduled build:
{
"id": "8731540171027314017",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 11932,
"createdBy": "project:chromium",
"createTime": "2024-11-11T18:56:58.429504505Z",
"updateTime": "2024-11-11T18:56:58.429504505Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f40cc3495a759ef0a1449552698561dd22f07af5",
"ref": "refs/heads/main"
}
}
}
[18:56:59.627] Task URL: https://cr-buildbucket.appspot.com/build/8731540171027314017
[18:56:59.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:2:0) after 5m37s
[18:57:00.617] Received PubSub notification, asking Buildbucket for the build status
[18:57:00.647] Build status: SCHEDULED
[18:58:43.202] Received PubSub notification, asking Buildbucket for the build status
[18:58:43.218] Build status: STARTED
[19:02:36.704] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:2:0)
[19:02:36.736] Build status: STARTED
[19:02:36.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:5:0) after 4m24s
[19:07:00.776] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:5:0)
[19:07:00.793] Build status: STARTED
[19:07:00.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:6:0) after 9m56s
[19:16:56.850] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:6:0)
[19:16:56.870] Build status: STARTED
[19:16:56.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:7:0) after 9m21s
[19:26:17.892] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:7:0)
[19:26:17.922] Build status: STARTED
[19:26:17.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:8:0) after 5m10s
[19:31:27.999] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:8:0)
[19:31:28.032] Build status: STARTED
[19:31:28.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:9:0) after 3m32s
[19:35:00.049] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:9:0)
[19:35:00.073] Build status: STARTED
[19:35:00.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:10:0) after 5m20s
[19:40:20.096] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:10:0)
[19:40:20.117] Build status: STARTED
[19:40:20.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:11:0) after 2m21s
[19:42:41.148] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:11:0)
[19:42:41.171] Build status: STARTED
[19:42:41.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:12:0) after 8m57s
[19:51:38.193] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:12:0)
[19:51:38.224] Build status: STARTED
[19:51:38.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:13:0) after 8m56s
[20:00:34.276] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:13:0)
[20:00:34.352] Build status: STARTED
[20:00:34.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:14:0) after 9m3s
[20:09:37.373] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:14:0)
[20:09:37.390] Build status: STARTED
[20:09:37.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:15:0) after 9m53s
[20:19:30.527] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:15:0)
[20:19:30.548] Build status: STARTED
[20:19:30.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:16:0) after 5m46s
[20:25:16.617] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:16:0)
[20:25:16.648] Build status: STARTED
[20:25:16.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:17:0) after 6m50s
[20:32:06.742] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:17:0)
[20:32:06.760] Build status: STARTED
[20:32:06.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:18:0) after 8m14s
[20:40:20.846] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:18:0)
[20:40:20.864] Build status: STARTED
[20:40:20.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:19:0) after 6m6s
[20:46:26.882] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:19:0)
[20:46:26.907] Build status: STARTED
[20:46:26.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:20:0) after 3m18s
[20:49:44.936] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:20:0)
[20:49:44.958] Build status: STARTED
[20:49:44.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:21:0) after 3m41s
[20:53:25.978] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:21:0)
[20:53:26.003] Build status: STARTED
[20:53:26.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:22:0) after 3m21s
[20:56:47.059] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:22:0)
[20:56:47.108] Build status: STARTED
[20:56:47.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:23:0) after 5m47s
[21:02:34.136] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:23:0)
[21:02:34.153] Build status: STARTED
[21:02:34.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:24:0) after 2m8s
[21:04:42.163] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:24:0)
[21:04:42.180] Build status: STARTED
[21:04:42.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:25:0) after 3m10s
[21:07:52.199] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:25:0)
[21:07:52.219] Build status: STARTED
[21:07:52.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:26:0) after 8m39s
[21:16:31.341] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:26:0)
[21:16:31.363] Build status: STARTED
[21:16:31.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:27:0) after 9m2s
[21:25:33.383] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:27:0)
[21:25:33.405] Build status: STARTED
[21:25:33.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:28:0) after 1m50s
[21:27:23.427] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:28:0)
[21:27:23.445] Build status: STARTED
[21:27:23.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:29:0) after 2m34s
[21:29:57.913] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:29:0)
[21:29:57.934] Build status: STARTED
[21:29:57.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:30:0) after 7m53s
[21:37:50.956] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:30:0)
[21:37:50.980] Build status: STARTED
[21:37:50.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:31:0) after 1m56s
[21:39:47.053] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:31:0)
[21:39:47.072] Build status: STARTED
[21:39:47.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:32:0) after 8m21s
[21:48:08.093] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:32:0)
[21:48:08.115] Build status: STARTED
[21:48:08.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:33:0) after 6m19s
[21:54:27.137] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:33:0)
[21:54:27.156] Build status: STARTED
[21:54:27.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:34:0) after 4m33s
[21:59:00.289] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:34:0)
[21:59:00.308] Build status: STARTED
[21:59:00.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:35:0) after 8m12s
[22:07:12.428] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:35:0)
[22:07:12.444] Build status: STARTED
[22:07:12.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:36:0) after 8m7s
[22:15:19.544] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:36:0)
[22:15:19.573] Build status: STARTED
[22:15:19.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:37:0) after 5m18s
[22:20:37.694] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:37:0)
[22:20:37.718] Build status: STARTED
[22:20:37.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:38:0) after 4m27s
[22:25:04.772] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:38:0)
[22:25:04.789] Build status: STARTED
[22:25:04.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:39:0) after 8m18s
[22:33:22.813] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:39:0)
[22:33:22.837] Build status: STARTED
[22:33:22.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:40:0) after 6m10s
[22:39:32.859] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:40:0)
[22:39:32.882] Build status: STARTED
[22:39:32.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:41:0) after 1m15s
[22:40:48.022] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:41:0)
[22:40:48.045] Build status: STARTED
[22:40:48.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:42:0) after 5m48s
[22:46:36.138] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:42:0)
[22:46:36.198] Build status: STARTED
[22:46:36.198] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:43:0) after 6m23s
[22:52:59.233] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:43:0)
[22:52:59.256] Build status: STARTED
[22:52:59.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:44:0) after 9m6s
[23:02:05.279] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:44:0)
[23:02:05.297] Build status: STARTED
[23:02:05.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:45:0) after 5m47s
[23:07:52.321] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:45:0)
[23:07:52.376] Build status: STARTED
[23:07:52.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:46:0) after 7m16s
[23:15:08.403] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:46:0)
[23:15:08.424] Build status: STARTED
[23:15:08.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:47:0) after 7m13s
[23:22:21.443] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:47:0)
[23:22:21.466] Build status: STARTED
[23:22:21.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:48:0) after 8m4s
[23:30:25.538] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:48:0)
[23:30:25.559] Build status: STARTED
[23:30:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:49:0) after 7m32s
[23:37:57.651] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:49:0)
[23:37:57.689] Build status: STARTED
[23:37:57.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:50:0) after 4m21s
[23:42:18.810] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:50:0)
[23:42:18.829] Build status: STARTED
[23:42:18.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8896970233236764896:51:0) after 9m29s
[23:45:07.529] Received PubSub notification, asking Buildbucket for the build status
[23:45:07.551] Build:
{
"id": "8731540171027314017",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 11932,
"createdBy": "project:chromium",
"createTime": "2024-11-11T18:56:58.429504505Z",
"startTime": "2024-11-11T18:58:41.540719269Z",
"endTime": "2024-11-11T23:44:36.084525876Z",
"updateTime": "2024-11-11T23:44:36.084525876Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f40cc3495a759ef0a1449552698561dd22f07af5",
"ref": "refs/heads/main"
}
}
}
[23:45:07.551] Invocation finished in 4h48m10.611341847s with status SUCCEEDED