[15:35:17.185] New invocation is queued and will start shortly
[15:35:18.322] Starting the invocation (attempt 1)
[15:35:18.348] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/df8ffcfc3eac646b38886071e024091526e88a07"
[15:35:18.348] Popped gitiles commit info from properties and tags
[15:35:18.348] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:35:18.348] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:35:18.348] Buildbucket request:
{
"requestId": "8923709025847862384",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "df8ffcfc3eac646b38886071e024091526e88a07",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8923709025847862384"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:35:18.892] Scheduled build:
{
"id": "8758278963906786241",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 390,
"createdBy": "project:chromium",
"createTime": "2024-01-21T15:35:18.408779910Z",
"updateTime": "2024-01-21T15:35:18.408779910Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "df8ffcfc3eac646b38886071e024091526e88a07",
"ref": "refs/heads/main"
}
}
}
[15:35:18.893] Task URL: https://cr-buildbucket.appspot.com/build/8758278963906786241
[15:35:18.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:2:0) after 1m39s
[15:35:20.461] Received PubSub notification, asking Buildbucket for the build status
[15:35:20.478] Build status: SCHEDULED
[15:35:38.474] Received PubSub notification, asking Buildbucket for the build status
[15:35:38.498] Build status: STARTED
[15:36:57.914] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:2:0)
[15:36:58.008] Build status: STARTED
[15:36:58.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:5:0) after 8m34s
[15:45:32.074] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:5:0)
[15:45:32.129] Build status: STARTED
[15:45:32.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:6:0) after 6m55s
[15:52:27.285] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:6:0)
[15:52:27.313] Build status: STARTED
[15:52:27.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:7:0) after 1m42s
[15:54:09.477] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:7:0)
[15:54:09.493] Build status: STARTED
[15:54:09.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:8:0) after 4m28s
[15:58:37.507] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:8:0)
[15:58:37.550] Build status: STARTED
[15:58:37.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:9:0) after 2m57s
[16:01:34.734] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:9:0)
[16:01:34.763] Build status: STARTED
[16:01:34.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:10:0) after 1m50s
[16:03:24.782] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:10:0)
[16:03:24.797] Build status: STARTED
[16:03:24.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:11:0) after 1m44s
[16:05:08.816] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:11:0)
[16:05:08.839] Build status: STARTED
[16:05:08.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:12:0) after 8m5s
[16:13:13.858] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:12:0)
[16:13:13.879] Build status: STARTED
[16:13:13.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:13:0) after 4m6s
[16:17:19.925] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:13:0)
[16:17:19.949] Build status: STARTED
[16:17:19.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:14:0) after 3m59s
[16:21:19.019] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:14:0)
[16:21:19.034] Build status: STARTED
[16:21:19.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:15:0) after 4m15s
[16:25:34.052] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:15:0)
[16:25:34.072] Build status: STARTED
[16:25:34.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:16:0) after 8m16s
[16:33:50.179] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:16:0)
[16:33:50.199] Build status: STARTED
[16:33:50.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:17:0) after 7m22s
[16:41:12.225] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:17:0)
[16:41:12.244] Build status: STARTED
[16:41:12.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:18:0) after 2m32s
[16:43:44.330] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:18:0)
[16:43:44.354] Build status: STARTED
[16:43:44.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:19:0) after 8m24s
[16:52:09.534] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:19:0)
[16:52:09.874] Build status: STARTED
[16:52:09.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:20:0) after 4m13s
[16:56:24.110] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:20:0)
[16:56:24.222] Build status: STARTED
[16:56:24.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:21:0) after 1m12s
[16:57:36.237] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:21:0)
[16:57:36.253] Build status: STARTED
[16:57:36.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:22:0) after 2m28s
[17:00:04.447] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:22:0)
[17:00:04.468] Build status: STARTED
[17:00:04.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:23:0) after 2m37s
[17:02:41.540] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:23:0)
[17:02:41.589] Build status: STARTED
[17:02:41.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:24:0) after 6m8s
[17:08:49.608] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:24:0)
[17:08:49.641] Build status: STARTED
[17:08:49.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:25:0) after 6m53s
[17:15:42.851] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:25:0)
[17:15:42.888] Build status: STARTED
[17:15:42.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:26:0) after 1m43s
[17:17:25.946] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:26:0)
[17:17:25.974] Build status: STARTED
[17:17:25.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:27:0) after 3m4s
[17:20:29.967] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:27:0)
[17:20:29.985] Build status: STARTED
[17:20:29.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:28:0) after 6m14s
[17:26:44.362] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:28:0)
[17:26:44.380] Build status: STARTED
[17:26:44.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:29:0) after 1m23s
[17:28:07.496] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:29:0)
[17:28:07.524] Build status: STARTED
[17:28:07.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:30:0) after 8m28s
[17:36:35.587] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:30:0)
[17:36:35.697] Build status: STARTED
[17:36:35.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:31:0) after 3m22s
[17:39:57.695] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:31:0)
[17:39:57.903] Build status: STARTED
[17:39:57.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:32:0) after 2m45s
[17:42:42.971] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:32:0)
[17:42:43.055] Build status: STARTED
[17:42:43.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:33:0) after 8m56s
[17:51:39.070] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:33:0)
[17:51:39.166] Build status: STARTED
[17:51:39.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:34:0) after 7m58s
[17:59:37.369] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:34:0)
[17:59:37.417] Build status: STARTED
[17:59:37.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:35:0) after 1m42s
[18:01:19.656] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:35:0)
[18:01:19.673] Build status: STARTED
[18:01:19.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:36:0) after 1m23s
[18:02:42.690] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:36:0)
[18:02:42.709] Build status: STARTED
[18:02:42.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:37:0) after 9m4s
[18:11:46.728] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:37:0)
[18:11:46.765] Build status: STARTED
[18:11:46.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:38:0) after 1m28s
[18:13:14.923] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:38:0)
[18:13:14.952] Build status: STARTED
[18:13:14.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:39:0) after 8m8s
[18:21:22.978] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:39:0)
[18:21:22.997] Build status: STARTED
[18:21:22.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:40:0) after 1m50s
[18:23:13.161] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:40:0)
[18:23:13.179] Build status: STARTED
[18:23:13.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:41:0) after 5m0s
[18:28:13.197] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:41:0)
[18:28:13.245] Build status: STARTED
[18:28:13.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:42:0) after 2m33s
[18:30:46.313] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:42:0)
[18:30:46.361] Build status: STARTED
[18:30:46.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:43:0) after 2m56s
[18:33:42.454] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:43:0)
[18:33:42.504] Build status: STARTED
[18:33:42.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:44:0) after 1m51s
[18:35:33.517] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:44:0)
[18:35:33.536] Build status: STARTED
[18:35:33.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:45:0) after 8m51s
[18:44:24.570] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:45:0)
[18:44:24.593] Build status: STARTED
[18:44:24.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8923709025847862384:46:0) after 9m7s
[18:53:03.097] Received PubSub notification, asking Buildbucket for the build status
[18:53:03.147] Build:
{
"id": "8758278963906786241",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 390,
"createdBy": "project:chromium",
"createTime": "2024-01-21T15:35:18.408779910Z",
"startTime": "2024-01-21T15:35:34.781819Z",
"endTime": "2024-01-21T18:53:01.336524Z",
"updateTime": "2024-01-21T18:53:01.336524Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "df8ffcfc3eac646b38886071e024091526e88a07",
"ref": "refs/heads/main"
}
}
}
[18:53:03.147] Invocation finished in 3h17m45.973286495s with status SUCCEEDED