[13:31:26.607] New invocation is queued and will start shortly
[13:31:27.742] Starting the invocation (attempt 1)
[13:31:27.833] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/474c9f01cb4aa3dd5f192b7e9d9478c3cd0f0403"
[13:31:27.833] Popped gitiles commit info from properties and tags
[13:31:27.833] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:31:27.835] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:31:27.835] Buildbucket request:
{
"requestId": "8975991266991998160",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "474c9f01cb4aa3dd5f192b7e9d9478c3cd0f0403",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8975991266991998160"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:31:28.439] Scheduled build:
{
"id": "8810561204875201297",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 3632,
"createdBy": "project:chromium",
"createTime": "2022-06-23T13:31:27.993614521Z",
"updateTime": "2022-06-23T13:31:27.993614521Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "474c9f01cb4aa3dd5f192b7e9d9478c3cd0f0403",
"ref": "refs/heads/main"
}
}
}
[13:31:28.439] Task URL: https://cr-buildbucket.appspot.com/build/8810561204875201297
[13:31:28.439] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:2:0) after 5m12s
[13:33:04.787] Received PubSub notification, asking Buildbucket for the build status
[13:33:04.834] Build status: STARTED
[13:36:40.458] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:2:0)
[13:36:40.488] Build status: STARTED
[13:36:40.488] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:4:0) after 5m56s
[13:42:36.836] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:4:0)
[13:42:36.856] Build status: STARTED
[13:42:36.856] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:5:0) after 7m51s
[13:50:28.254] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:5:0)
[13:50:28.275] Build status: STARTED
[13:50:28.275] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:6:0) after 7m51s
[13:58:19.295] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:6:0)
[13:58:19.318] Build status: STARTED
[13:58:19.318] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:7:0) after 2m3s
[14:00:22.585] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:7:0)
[14:00:22.666] Build status: STARTED
[14:00:22.666] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:8:0) after 2m52s
[14:03:14.785] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:8:0)
[14:03:14.818] Build status: STARTED
[14:03:14.818] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:9:0) after 2m16s
[14:05:31.162] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:9:0)
[14:05:31.183] Build status: STARTED
[14:05:31.183] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:10:0) after 8m44s
[14:14:15.205] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:10:0)
[14:14:15.227] Build status: STARTED
[14:14:15.227] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:11:0) after 6m34s
[14:20:49.469] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:11:0)
[14:20:49.497] Build status: STARTED
[14:20:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:12:0) after 3m32s
[14:24:21.516] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:12:0)
[14:24:21.547] Build status: STARTED
[14:24:21.547] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:13:0) after 6m21s
[14:30:42.572] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:13:0)
[14:30:42.592] Build status: STARTED
[14:30:42.592] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:14:0) after 9m45s
[14:40:27.760] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:14:0)
[14:40:27.781] Build status: STARTED
[14:40:27.781] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:15:0) after 7m35s
[14:48:03.287] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:15:0)
[14:48:03.331] Build status: STARTED
[14:48:03.331] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:16:0) after 9m13s
[14:57:16.431] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:16:0)
[14:57:16.466] Build status: STARTED
[14:57:16.466] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:17:0) after 4m57s
[15:02:13.592] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:17:0)
[15:02:13.613] Build status: STARTED
[15:02:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:18:0) after 3m57s
[15:06:10.691] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:18:0)
[15:06:10.768] Build status: STARTED
[15:06:10.768] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:19:0) after 8m55s
[15:15:06.377] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:19:0)
[15:15:06.399] Build status: STARTED
[15:15:06.399] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:20:0) after 4m31s
[15:19:37.521] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:20:0)
[15:19:37.551] Build status: STARTED
[15:19:37.551] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:21:0) after 3m17s
[15:22:54.559] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:21:0)
[15:22:54.582] Build status: STARTED
[15:22:54.582] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:22:0) after 3m43s
[15:26:37.624] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:22:0)
[15:26:37.660] Build status: STARTED
[15:26:37.660] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:23:0) after 8m8s
[15:34:45.761] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:23:0)
[15:34:45.781] Build status: STARTED
[15:34:45.781] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:24:0) after 7m27s
[15:42:12.806] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:24:0)
[15:42:12.859] Build status: STARTED
[15:42:12.859] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:25:0) after 2m39s
[15:44:51.884] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:25:0)
[15:44:51.908] Build status: STARTED
[15:44:51.908] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:26:0) after 2m58s
[15:47:50.063] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:26:0)
[15:47:50.094] Build status: STARTED
[15:47:50.094] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:27:0) after 8m54s
[15:56:44.213] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:27:0)
[15:56:44.260] Build status: STARTED
[15:56:44.260] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:28:0) after 4m59s
[16:01:43.524] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:28:0)
[16:01:43.578] Build status: STARTED
[16:01:43.578] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:29:0) after 6m4s
[16:07:47.788] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:29:0)
[16:07:47.819] Build status: STARTED
[16:07:47.820] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:30:0) after 5m39s
[16:13:26.857] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:30:0)
[16:13:26.898] Build status: STARTED
[16:13:26.898] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:31:0) after 1m49s
[16:15:16.018] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:31:0)
[16:15:16.054] Build status: STARTED
[16:15:16.055] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:32:0) after 9m14s
[16:24:30.167] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:32:0)
[16:24:30.199] Build status: STARTED
[16:24:30.199] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:33:0) after 1m12s
[16:25:42.220] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:33:0)
[16:25:42.243] Build status: STARTED
[16:25:42.243] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:34:0) after 9m20s
[16:35:02.693] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:34:0)
[16:35:02.724] Build status: STARTED
[16:35:02.724] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:35:0) after 6m52s
[16:41:55.119] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:35:0)
[16:41:55.141] Build status: STARTED
[16:41:55.141] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:36:0) after 2m25s
[16:44:20.278] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:36:0)
[16:44:20.356] Build status: STARTED
[16:44:20.356] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:37:0) after 5m17s
[16:49:37.645] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:37:0)
[16:49:37.738] Build status: STARTED
[16:49:37.738] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:38:0) after 3m36s
[16:53:13.759] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:38:0)
[16:53:13.792] Build status: STARTED
[16:53:13.792] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:39:0) after 1m26s
[16:54:39.826] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:39:0)
[16:54:39.855] Build status: STARTED
[16:54:39.855] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:40:0) after 5m6s
[16:59:45.875] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:40:0)
[16:59:45.913] Build status: STARTED
[16:59:45.913] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:41:0) after 6m10s
[17:05:55.944] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:41:0)
[17:05:55.980] Build status: STARTED
[17:05:55.980] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:42:0) after 1m45s
[17:07:41.011] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:42:0)
[17:07:41.043] Build status: STARTED
[17:07:41.043] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:43:0) after 1m37s
[17:09:18.252] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:43:0)
[17:09:18.278] Build status: STARTED
[17:09:18.278] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:44:0) after 3m12s
[17:12:30.760] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:44:0)
[17:12:30.786] Build status: STARTED
[17:12:30.786] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:45:0) after 9m37s
[17:22:07.846] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:45:0)
[17:22:07.871] Build status: STARTED
[17:22:07.871] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:46:0) after 3m17s
[17:25:24.916] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:46:0)
[17:25:24.967] Build status: STARTED
[17:25:24.967] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:47:0) after 4m13s
[17:29:38.206] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:47:0)
[17:29:38.227] Build status: STARTED
[17:29:38.227] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:48:0) after 7m51s
[17:37:29.298] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:48:0)
[17:37:29.342] Build status: STARTED
[17:37:29.342] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:49:0) after 3m17s
[17:40:46.414] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:49:0)
[17:40:46.458] Build status: STARTED
[17:40:46.458] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:50:0) after 2m59s
[17:43:45.536] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:50:0)
[17:43:45.562] Build status: STARTED
[17:43:45.562] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:51:0) after 1m20s
[17:45:05.560] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:51:0)
[17:45:05.587] Build status: STARTED
[17:45:05.587] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:52:0) after 8m40s
[17:53:45.608] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:52:0)
[17:53:45.663] Build status: STARTED
[17:53:45.663] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:53:0) after 3m56s
[17:57:42.031] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:53:0)
[17:57:42.052] Build status: STARTED
[17:57:42.053] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:54:0) after 3m18s
[18:01:00.078] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:54:0)
[18:01:00.128] Build status: STARTED
[18:01:00.128] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:55:0) after 5m12s
[18:06:12.235] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:55:0)
[18:06:12.270] Build status: STARTED
[18:06:12.270] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:56:0) after 4m40s
[18:10:52.293] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:56:0)
[18:10:52.315] Build status: STARTED
[18:10:52.316] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:57:0) after 9m16s
[18:20:08.703] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:57:0)
[18:20:08.725] Build status: STARTED
[18:20:08.725] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:58:0) after 5m26s
[18:25:34.848] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:58:0)
[18:25:34.880] Build status: STARTED
[18:25:34.880] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:59:0) after 7m44s
[18:33:19.098] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:59:0)
[18:33:19.118] Build status: STARTED
[18:33:19.118] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:60:0) after 1m57s
[18:35:16.239] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:60:0)
[18:35:16.295] Build status: STARTED
[18:35:16.295] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:61:0) after 9m34s
[18:44:50.660] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:61:0)
[18:44:50.693] Build status: STARTED
[18:44:50.694] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:62:0) after 1m21s
[18:46:11.890] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:62:0)
[18:46:11.921] Build status: STARTED
[18:46:11.921] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8975991266991998160:63:0) after 7m39s
[18:48:48.633] Received PubSub notification, asking Buildbucket for the build status
[18:48:48.653] Build:
{
"id": "8810561204875201297",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 3632,
"createdBy": "project:chromium",
"createTime": "2022-06-23T13:31:27.993614521Z",
"startTime": "2022-06-23T13:32:50.387233Z",
"endTime": "2022-06-23T18:48:48.377984528Z",
"updateTime": "2022-06-23T18:48:48.377984528Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "474c9f01cb4aa3dd5f192b7e9d9478c3cd0f0403",
"ref": "refs/heads/main"
}
}
}
[18:48:48.653] Invocation finished in 5h17m22.063035446s with status SUCCEEDED