[01:39:42.733] New invocation is queued and will start shortly
[01:39:43.929] Starting the invocation (attempt 1)
[01:39:43.954] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d30c8dea3094a44c9c6a1879eeaa08f641c3a22"
[01:39:43.954] Popped gitiles commit info from properties and tags
[01:39:43.954] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:39:43.954] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:39:43.954] Buildbucket request:
{
"requestId": "8930737562039036080",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2d30c8dea3094a44c9c6a1879eeaa08f641c3a22",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8930737562039036080"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:39:44.489] Scheduled build:
{
"id": "8765307500037245297",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-build-perf-developer"
},
"number": 260,
"createdBy": "project:chromium",
"createTime": "2023-11-05T01:39:44.019108348Z",
"updateTime": "2023-11-05T01:39:44.019108348Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2d30c8dea3094a44c9c6a1879eeaa08f641c3a22",
"ref": "refs/heads/main"
}
}
}
[01:39:44.489] Task URL: https://cr-buildbucket.appspot.com/build/8765307500037245297
[01:39:44.489] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:2:0) after 4m3s
[01:39:45.484] Received PubSub notification, asking Buildbucket for the build status
[01:39:45.518] Build status: SCHEDULED
[01:39:46.946] Received PubSub notification, asking Buildbucket for the build status
[01:39:46.978] Build status: STARTED
[01:43:47.616] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:2:0)
[01:43:47.664] Build status: STARTED
[01:43:47.665] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:5:0) after 6m20s
[01:50:07.725] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:5:0)
[01:50:07.767] Build status: STARTED
[01:50:07.767] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:6:0) after 4m30s
[01:54:37.781] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:6:0)
[01:54:37.804] Build status: STARTED
[01:54:37.804] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:7:0) after 9m42s
[02:04:19.822] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:7:0)
[02:04:19.847] Build status: STARTED
[02:04:19.847] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:8:0) after 3m26s
[02:07:45.871] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:8:0)
[02:07:45.896] Build status: STARTED
[02:07:45.896] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:9:0) after 2m31s
[02:10:17.040] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:9:0)
[02:10:17.072] Build status: STARTED
[02:10:17.072] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:10:0) after 4m58s
[02:15:15.199] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:10:0)
[02:15:15.228] Build status: STARTED
[02:15:15.228] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:11:0) after 3m3s
[02:18:18.342] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:11:0)
[02:18:18.360] Build status: STARTED
[02:18:18.360] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:12:0) after 1m21s
[02:19:39.375] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:12:0)
[02:19:39.401] Build status: STARTED
[02:19:39.402] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:13:0) after 3m24s
[02:23:03.456] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:13:0)
[02:23:03.486] Build status: STARTED
[02:23:03.486] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:14:0) after 6m47s
[02:29:50.502] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:14:0)
[02:29:50.518] Build status: STARTED
[02:29:50.519] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:15:0) after 1m8s
[02:30:58.619] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:15:0)
[02:30:58.657] Build status: STARTED
[02:30:58.658] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:16:0) after 3m1s
[02:33:59.844] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:16:0)
[02:33:59.881] Build status: STARTED
[02:33:59.881] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:17:0) after 9m31s
[02:43:30.988] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:17:0)
[02:43:31.019] Build status: STARTED
[02:43:31.019] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:18:0) after 8m2s
[02:51:33.203] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:18:0)
[02:51:33.364] Build status: STARTED
[02:51:33.364] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:19:0) after 2m9s
[02:53:42.387] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:19:0)
[02:53:42.408] Build status: STARTED
[02:53:42.408] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:20:0) after 7m33s
[03:01:15.516] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:20:0)
[03:01:15.604] Build status: STARTED
[03:01:15.604] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:21:0) after 8m1s
[03:09:16.631] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:21:0)
[03:09:16.654] Build status: STARTED
[03:09:16.654] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:22:0) after 6m0s
[03:15:16.771] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:22:0)
[03:15:16.796] Build status: STARTED
[03:15:16.796] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:23:0) after 3m5s
[03:18:21.863] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:23:0)
[03:18:21.887] Build status: STARTED
[03:18:21.887] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:24:0) after 3m8s
[03:21:29.910] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:24:0)
[03:21:29.933] Build status: STARTED
[03:21:29.933] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:25:0) after 4m11s
[03:25:40.976] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:25:0)
[03:25:41.010] Build status: STARTED
[03:25:41.011] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:26:0) after 2m34s
[03:28:15.107] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:26:0)
[03:28:15.150] Build status: STARTED
[03:28:15.151] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:27:0) after 4m18s
[03:32:33.119] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:27:0)
[03:32:33.150] Build status: STARTED
[03:32:33.150] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:28:0) after 8m6s
[03:40:39.187] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:28:0)
[03:40:39.207] Build status: STARTED
[03:40:39.207] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:29:0) after 6m13s
[03:46:52.314] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:29:0)
[03:46:52.337] Build status: STARTED
[03:46:52.337] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:30:0) after 7m33s
[03:54:25.502] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:30:0)
[03:54:25.521] Build status: STARTED
[03:54:25.521] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:31:0) after 1m52s
[03:56:17.617] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:31:0)
[03:56:17.641] Build status: STARTED
[03:56:17.641] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:32:0) after 3m48s
[04:00:05.658] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:32:0)
[04:00:05.677] Build status: STARTED
[04:00:05.677] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:33:0) after 1m39s
[04:01:44.687] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:33:0)
[04:01:44.834] Build status: STARTED
[04:01:44.834] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:34:0) after 5m39s
[04:07:23.967] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:34:0)
[04:07:23.997] Build status: STARTED
[04:07:23.997] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:35:0) after 1m56s
[04:09:20.014] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:35:0)
[04:09:20.034] Build status: STARTED
[04:09:20.034] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:36:0) after 3m59s
[04:13:19.051] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:36:0)
[04:13:19.072] Build status: STARTED
[04:13:19.072] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:37:0) after 6m17s
[04:19:36.108] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:37:0)
[04:19:36.145] Build status: STARTED
[04:19:36.145] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:38:0) after 8m43s
[04:28:19.307] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:38:0)
[04:28:19.330] Build status: STARTED
[04:28:19.330] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:39:0) after 8m57s
[04:37:16.453] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:39:0)
[04:37:16.481] Build status: STARTED
[04:37:16.481] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:40:0) after 6m51s
[04:44:07.474] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:40:0)
[04:44:07.496] Build status: STARTED
[04:44:07.496] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:41:0) after 4m35s
[04:48:42.767] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:41:0)
[04:48:42.815] Build status: STARTED
[04:48:42.815] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:42:0) after 4m33s
[04:53:15.833] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:42:0)
[04:53:15.916] Build status: STARTED
[04:53:15.917] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:43:0) after 4m10s
[04:57:25.962] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:43:0)
[04:57:25.987] Build status: STARTED
[04:57:25.987] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:44:0) after 1m21s
[04:58:47.024] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:44:0)
[04:58:47.057] Build status: STARTED
[04:58:47.057] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:45:0) after 8m55s
[05:07:42.079] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:45:0)
[05:07:42.099] Build status: STARTED
[05:07:42.099] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:46:0) after 2m46s
[05:10:28.181] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:46:0)
[05:10:28.205] Build status: STARTED
[05:10:28.205] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:47:0) after 1m53s
[05:12:21.222] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:47:0)
[05:12:21.245] Build status: STARTED
[05:12:21.245] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:48:0) after 1m37s
[05:13:58.376] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:48:0)
[05:13:58.397] Build status: STARTED
[05:13:58.397] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:49:0) after 4m48s
[05:18:46.423] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:49:0)
[05:18:46.450] Build status: STARTED
[05:18:46.450] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:50:0) after 7m39s
[05:26:25.470] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:50:0)
[05:26:25.518] Build status: STARTED
[05:26:25.518] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:51:0) after 3m23s
[05:29:48.656] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:51:0)
[05:29:48.707] Build status: STARTED
[05:29:48.707] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:52:0) after 5m58s
[05:35:46.725] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:52:0)
[05:35:46.754] Build status: STARTED
[05:35:46.754] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:53:0) after 1m28s
[05:37:14.816] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:53:0)
[05:37:14.841] Build status: STARTED
[05:37:14.841] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:54:0) after 7m22s
[05:44:36.955] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:54:0)
[05:44:36.992] Build status: STARTED
[05:44:36.992] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:55:0) after 6m2s
[05:50:39.015] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:55:0)
[05:50:39.040] Build status: STARTED
[05:50:39.040] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:56:0) after 7m42s
[05:58:21.072] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:56:0)
[05:58:21.096] Build status: STARTED
[05:58:21.096] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:57:0) after 8m1s
[06:06:22.126] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:57:0)
[06:06:22.160] Build status: STARTED
[06:06:22.160] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:58:0) after 1m30s
[06:07:52.181] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:58:0)
[06:07:52.226] Build status: STARTED
[06:07:52.226] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:59:0) after 6m17s
[06:14:09.466] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:59:0)
[06:14:09.498] Build status: STARTED
[06:14:09.498] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:60:0) after 8m11s
[06:22:20.906] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:60:0)
[06:22:20.937] Build status: STARTED
[06:22:20.937] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:61:0) after 4m30s
[06:26:50.959] Handling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:61:0)
[06:26:50.979] Build status: STARTED
[06:26:50.979] Scheduling timer "check-buildbucket-build-status" (chromium/mac-build-perf-developer:8930737562039036080:62:0) after 6m42s
[06:28:28.919] Received PubSub notification, asking Buildbucket for the build status
[06:28:28.938] Build:
{
"id": "8765307500037245297",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-build-perf-developer"
},
"number": 260,
"createdBy": "project:chromium",
"createTime": "2023-11-05T01:39:44.019108348Z",
"startTime": "2023-11-05T01:39:46.062612Z",
"endTime": "2023-11-05T06:28:27.590839Z",
"updateTime": "2023-11-05T06:28:27.590839Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2d30c8dea3094a44c9c6a1879eeaa08f641c3a22",
"ref": "refs/heads/main"
}
}
}
[06:28:28.938] Invocation finished in 4h48m46.211564023s with status SUCCEEDED