[22:55:26.199] New invocation is queued and will start shortly
[22:55:27.347] Starting the invocation (attempt 1)
[22:55:27.375] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ebd89fecc9cdd074b240c2c881fa877547eb24b"
[22:55:27.375] Popped gitiles commit info from properties and tags
[22:55:27.375] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:55:27.375] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:55:27.375] Buildbucket request:
{
"requestId": "8926489839944405632",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Comparison Windows (reclient)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8ebd89fecc9cdd074b240c2c881fa877547eb24b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926489839944405632"
},
{
"key": "scheduler_job_id",
"value": "chromium/Comparison Windows (reclient)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:55:29.845] Scheduled build:
{
"id": "8761059777879578513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Comparison Windows (reclient)"
},
"number": 9117,
"createdBy": "project:chromium",
"createTime": "2023-12-21T22:55:27.544145442Z",
"updateTime": "2023-12-21T22:55:27.544145442Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8ebd89fecc9cdd074b240c2c881fa877547eb24b",
"ref": "refs/heads/main"
}
}
}
[22:55:29.845] Task URL: https://cr-buildbucket.appspot.com/build/8761059777879578513
[22:55:29.845] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:2:0) after 8m3s
[22:55:30.111] Received PubSub notification, asking Buildbucket for the build status
[22:55:30.142] Build status: SCHEDULED
[22:55:37.080] Received PubSub notification, asking Buildbucket for the build status
[22:55:37.099] Build status: STARTED
[23:03:33.301] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:2:0)
[23:03:33.339] Build status: STARTED
[23:03:33.339] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:5:0) after 4m24s
[23:07:57.357] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:5:0)
[23:07:57.381] Build status: STARTED
[23:07:57.382] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:6:0) after 9m21s
[23:17:18.411] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:6:0)
[23:17:18.429] Build status: STARTED
[23:17:18.429] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:7:0) after 7m23s
[23:24:42.207] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:7:0)
[23:24:42.228] Build status: STARTED
[23:24:42.228] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:8:0) after 3m11s
[23:27:53.355] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:8:0)
[23:27:53.371] Build status: STARTED
[23:27:53.371] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:9:0) after 7m56s
[23:35:49.393] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:9:0)
[23:35:49.434] Build status: STARTED
[23:35:49.434] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:10:0) after 2m49s
[23:38:38.486] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:10:0)
[23:38:38.565] Build status: STARTED
[23:38:38.565] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:11:0) after 1m33s
[23:40:11.588] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:11:0)
[23:40:11.606] Build status: STARTED
[23:40:11.606] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:12:0) after 5m20s
[23:45:31.991] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:12:0)
[23:45:32.009] Build status: STARTED
[23:45:32.009] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:13:0) after 5m12s
[23:50:44.058] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:13:0)
[23:50:44.103] Build status: STARTED
[23:50:44.103] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:14:0) after 1m57s
[23:52:41.130] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:14:0)
[23:52:41.182] Build status: STARTED
[23:52:41.182] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:15:0) after 9m14s
[00:01:55.455] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:15:0)
[00:01:55.477] Build status: STARTED
[00:01:55.477] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:16:0) after 3m12s
[00:05:07.593] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:16:0)
[00:05:07.633] Build status: STARTED
[00:05:07.633] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:17:0) after 1m49s
[00:06:56.630] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:17:0)
[00:06:56.659] Build status: STARTED
[00:06:56.659] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:18:0) after 9m48s
[00:16:44.683] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:18:0)
[00:16:44.947] Build status: STARTED
[00:16:44.947] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:19:0) after 1m44s
[00:18:28.968] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:19:0)
[00:18:28.988] Build status: STARTED
[00:18:28.988] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:20:0) after 9m40s
[00:28:09.006] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:20:0)
[00:28:09.049] Build status: STARTED
[00:28:09.050] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:21:0) after 4m6s
[00:32:15.283] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:21:0)
[00:32:15.302] Build status: STARTED
[00:32:15.302] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:22:0) after 1m12s
[00:33:27.421] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:22:0)
[00:33:27.442] Build status: STARTED
[00:33:27.442] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:23:0) after 3m56s
[00:37:23.461] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:23:0)
[00:37:23.498] Build status: STARTED
[00:37:23.498] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:24:0) after 7m26s
[00:44:49.553] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:24:0)
[00:44:49.576] Build status: STARTED
[00:44:49.576] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:25:0) after 5m14s
[00:50:03.737] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:25:0)
[00:50:03.771] Build status: STARTED
[00:50:03.771] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:26:0) after 4m11s
[00:54:15.060] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:26:0)
[00:54:15.092] Build status: STARTED
[00:54:15.092] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:27:0) after 4m56s
[00:59:11.121] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:27:0)
[00:59:11.149] Build status: STARTED
[00:59:11.149] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:28:0) after 5m34s
[01:04:45.196] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:28:0)
[01:04:45.226] Build status: STARTED
[01:04:45.226] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:29:0) after 6m4s
[01:10:49.354] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:29:0)
[01:10:49.381] Build status: STARTED
[01:10:49.381] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:30:0) after 3m56s
[01:14:45.398] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:30:0)
[01:14:45.424] Build status: STARTED
[01:14:45.424] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:31:0) after 1m29s
[01:16:14.447] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:31:0)
[01:16:14.524] Build status: STARTED
[01:16:14.524] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:32:0) after 8m33s
[01:24:47.870] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:32:0)
[01:24:47.943] Build status: STARTED
[01:24:47.943] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:33:0) after 3m8s
[01:27:55.973] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:33:0)
[01:27:55.995] Build status: STARTED
[01:27:55.995] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:34:0) after 3m20s
[01:31:16.168] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:34:0)
[01:31:16.186] Build status: STARTED
[01:31:16.186] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:35:0) after 8m37s
[01:39:53.168] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:35:0)
[01:39:53.187] Build status: STARTED
[01:39:53.187] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:36:0) after 8m14s
[01:48:07.289] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:36:0)
[01:48:07.307] Build status: STARTED
[01:48:07.307] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:37:0) after 9m10s
[01:57:17.387] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:37:0)
[01:57:17.408] Build status: STARTED
[01:57:17.408] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:38:0) after 3m5s
[02:00:22.499] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:38:0)
[02:00:22.520] Build status: STARTED
[02:00:22.520] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:39:0) after 1m57s
[02:02:19.575] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:39:0)
[02:02:19.601] Build status: STARTED
[02:02:19.601] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:40:0) after 8m26s
[02:10:45.759] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:40:0)
[02:10:45.791] Build status: STARTED
[02:10:45.791] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:41:0) after 1m56s
[02:12:41.838] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:41:0)
[02:12:41.860] Build status: STARTED
[02:12:41.860] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:42:0) after 6m37s
[02:19:18.991] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:42:0)
[02:19:19.019] Build status: STARTED
[02:19:19.020] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:43:0) after 2m42s
[02:22:01.162] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:43:0)
[02:22:01.259] Build status: STARTED
[02:22:01.259] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:44:0) after 7m21s
[02:29:22.343] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:44:0)
[02:29:22.362] Build status: STARTED
[02:29:22.362] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:45:0) after 3m30s
[02:32:52.432] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:45:0)
[02:32:52.459] Build status: STARTED
[02:32:52.459] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:46:0) after 3m52s
[02:36:44.521] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:46:0)
[02:36:44.544] Build status: STARTED
[02:36:44.544] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:47:0) after 7m21s
[02:44:05.613] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:47:0)
[02:44:05.631] Build status: STARTED
[02:44:05.631] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:48:0) after 2m10s
[02:46:15.917] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:48:0)
[02:46:15.934] Build status: STARTED
[02:46:15.934] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:49:0) after 7m57s
[02:54:12.975] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:49:0)
[02:54:13.052] Build status: STARTED
[02:54:13.052] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:50:0) after 2m9s
[02:56:22.273] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:50:0)
[02:56:22.296] Build status: STARTED
[02:56:22.296] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:51:0) after 6m52s
[03:03:14.575] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:51:0)
[03:03:14.593] Build status: STARTED
[03:03:14.593] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:52:0) after 1m58s
[03:05:12.721] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:52:0)
[03:05:12.753] Build status: STARTED
[03:05:12.753] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:53:0) after 1m12s
[03:06:24.795] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:53:0)
[03:06:24.816] Build status: STARTED
[03:06:24.816] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:54:0) after 4m21s
[03:10:46.253] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:54:0)
[03:10:46.336] Build status: STARTED
[03:10:46.336] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:55:0) after 7m52s
[03:18:38.464] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:55:0)
[03:18:38.487] Build status: STARTED
[03:18:38.487] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:56:0) after 4m26s
[03:23:04.506] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:56:0)
[03:23:04.526] Build status: STARTED
[03:23:04.526] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:57:0) after 1m31s
[03:24:35.557] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:57:0)
[03:24:35.576] Build status: STARTED
[03:24:35.576] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:58:0) after 2m5s
[03:26:40.594] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:58:0)
[03:26:40.618] Build status: STARTED
[03:26:40.618] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:59:0) after 3m35s
[03:30:15.646] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:59:0)
[03:30:15.664] Build status: STARTED
[03:30:15.664] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:60:0) after 6m51s
[03:37:06.930] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:60:0)
[03:37:06.952] Build status: STARTED
[03:37:06.952] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:61:0) after 5m51s
[03:42:58.022] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:61:0)
[03:42:58.047] Build status: STARTED
[03:42:58.047] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:62:0) after 8m15s
[03:51:13.075] Handling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:62:0)
[03:51:13.093] Build status: STARTED
[03:51:13.093] Scheduling timer "check-buildbucket-build-status" (chromium/Comparison Windows (reclient):8926489839944405632:63:0) after 9m47s
[03:58:07.549] Received PubSub notification, asking Buildbucket for the build status
[03:58:07.573] Build:
{
"id": "8761059777879578513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Comparison Windows (reclient)"
},
"number": 9117,
"createdBy": "project:chromium",
"createTime": "2023-12-21T22:55:27.544145442Z",
"startTime": "2023-12-21T22:55:36.872380276Z",
"endTime": "2023-12-22T03:58:06.319939Z",
"updateTime": "2023-12-22T03:58:06.319939Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8ebd89fecc9cdd074b240c2c881fa877547eb24b",
"ref": "refs/heads/main"
}
}
}
[03:58:07.573] Invocation finished in 5h2m41.382178372s with status SUCCEEDED