[21:37:36.247] New invocation is queued and will start shortly
[21:37:37.336] Starting the invocation (attempt 1)
[21:37:37.377] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcd4e4030b376b123f827a9372acb0fa9f6e5d01"
[21:37:37.377] Popped gitiles commit info from properties and tags
[21:37:37.377] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:37:37.377] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:37:37.377] Buildbucket request:
{
"requestId": "8995529625150021632",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dcd4e4030b376b123f827a9372acb0fa9f6e5d01",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8995529625150021632"
},
{
"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"
}
}
[21:37:39.355] Scheduled build:
{
"id": "8830099563225790945",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 2758,
"createdBy": "project:chromium",
"createTime": "2021-11-19T21:37:37.449419693Z",
"updateTime": "2021-11-19T21:37:37.449419693Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dcd4e4030b376b123f827a9372acb0fa9f6e5d01",
"ref": "refs/heads/main"
}
}
}
[21:37:39.355] Task URL: https://cr-buildbucket.appspot.com/build/8830099563225790945
[21:37:39.356] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:2:0) after 1m48s
[21:38:30.392] Received PubSub notification, asking Buildbucket for the build status
[21:38:30.526] Build status: STARTED
[21:39:27.356] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:2:0)
[21:39:27.407] Build status: STARTED
[21:39:27.407] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:4:0) after 5m20s
[21:44:47.908] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:4:0)
[21:44:47.944] Build status: STARTED
[21:44:47.944] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:5:0) after 2m1s
[21:46:48.945] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:5:0)
[21:46:48.974] Build status: STARTED
[21:46:48.974] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:6:0) after 9m57s
[21:56:46.148] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:6:0)
[21:56:46.187] Build status: STARTED
[21:56:46.188] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:7:0) after 4m10s
[22:00:56.300] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:7:0)
[22:00:56.383] Build status: STARTED
[22:00:56.383] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:8:0) after 7m21s
[22:08:17.649] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:8:0)
[22:08:17.682] Build status: STARTED
[22:08:17.682] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:9:0) after 8m24s
[22:16:41.825] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:9:0)
[22:16:41.867] Build status: STARTED
[22:16:41.867] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:10:0) after 1m32s
[22:18:13.984] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:10:0)
[22:18:14.029] Build status: STARTED
[22:18:14.029] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:11:0) after 6m42s
[22:24:56.174] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:11:0)
[22:24:56.206] Build status: STARTED
[22:24:56.206] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:12:0) after 5m54s
[22:30:50.278] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:12:0)
[22:30:50.315] Build status: STARTED
[22:30:50.315] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:13:0) after 3m10s
[22:34:01.118] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:13:0)
[22:34:01.192] Build status: STARTED
[22:34:01.192] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:14:0) after 4m50s
[22:38:51.283] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:14:0)
[22:38:51.317] Build status: STARTED
[22:38:51.317] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:15:0) after 9m46s
[22:48:37.469] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:15:0)
[22:48:37.513] Build status: STARTED
[22:48:37.513] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:16:0) after 5m35s
[22:54:12.545] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:16:0)
[22:54:12.579] Build status: STARTED
[22:54:12.579] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:17:0) after 1m45s
[22:55:57.615] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:17:0)
[22:55:57.653] Build status: STARTED
[22:55:57.653] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:18:0) after 7m49s
[23:03:46.676] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:18:0)
[23:03:46.729] Build status: STARTED
[23:03:46.729] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:19:0) after 9m58s
[23:13:44.783] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:19:0)
[23:13:44.830] Build status: STARTED
[23:13:44.830] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:20:0) after 8m10s
[23:21:55.042] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:20:0)
[23:21:55.078] Build status: STARTED
[23:21:55.078] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:21:0) after 4m3s
[23:25:58.115] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:21:0)
[23:25:58.177] Build status: STARTED
[23:25:58.177] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:22:0) after 8m3s
[23:34:01.215] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:22:0)
[23:34:01.271] Build status: STARTED
[23:34:01.271] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:23:0) after 6m9s
[23:40:10.296] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:23:0)
[23:40:10.326] Build status: STARTED
[23:40:10.326] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:24:0) after 5m7s
[23:45:17.398] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:24:0)
[23:45:17.441] Build status: STARTED
[23:45:17.441] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:25:0) after 5m19s
[23:50:36.553] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:25:0)
[23:50:36.588] Build status: STARTED
[23:50:36.588] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:26:0) after 7m17s
[23:57:53.582] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:26:0)
[23:57:53.620] Build status: STARTED
[23:57:53.620] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:27:0) after 3m9s
[00:01:02.786] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:27:0)
[00:01:02.855] Build status: STARTED
[00:01:02.855] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:28:0) after 8m43s
[00:09:46.121] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:28:0)
[00:09:46.187] Build status: STARTED
[00:09:46.187] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:29:0) after 2m54s
[00:12:40.392] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:29:0)
[00:12:40.438] Build status: STARTED
[00:12:40.438] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:30:0) after 5m54s
[00:18:34.763] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:30:0)
[00:18:34.797] Build status: STARTED
[00:18:34.798] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:31:0) after 7m0s
[00:25:34.947] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:31:0)
[00:25:35.042] Build status: STARTED
[00:25:35.042] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:32:0) after 9m34s
[00:35:09.210] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:32:0)
[00:35:09.242] Build status: STARTED
[00:35:09.242] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:33:0) after 7m36s
[00:42:45.292] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:33:0)
[00:42:45.324] Build status: STARTED
[00:42:45.324] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:34:0) after 5m18s
[00:48:03.445] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:34:0)
[00:48:03.477] Build status: STARTED
[00:48:03.477] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:35:0) after 9m9s
[00:57:12.540] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:35:0)
[00:57:12.571] Build status: STARTED
[00:57:12.571] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:36:0) after 3m44s
[01:00:56.594] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:36:0)
[01:00:56.633] Build status: STARTED
[01:00:56.633] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:37:0) after 1m11s
[01:02:07.788] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:37:0)
[01:02:07.821] Build status: STARTED
[01:02:07.821] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:38:0) after 1m32s
[01:03:39.852] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:38:0)
[01:03:39.886] Build status: STARTED
[01:03:39.886] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:39:0) after 1m42s
[01:05:21.988] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:39:0)
[01:05:22.019] Build status: STARTED
[01:05:22.019] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:40:0) after 8m46s
[01:14:08.285] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:40:0)
[01:14:08.324] Build status: STARTED
[01:14:08.324] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:41:0) after 9m25s
[01:23:33.346] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:41:0)
[01:23:33.422] Build status: STARTED
[01:23:33.422] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:42:0) after 7m38s
[01:31:11.451] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:42:0)
[01:31:11.492] Build status: STARTED
[01:31:11.492] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:43:0) after 5m19s
[01:36:30.516] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:43:0)
[01:36:30.545] Build status: STARTED
[01:36:30.545] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8995529625150021632:44:0) after 4m4s
[01:40:17.778] Received PubSub notification, asking Buildbucket for the build status
[01:40:17.808] Build:
{
"id": "8830099563225790945",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 2758,
"createdBy": "project:chromium",
"createTime": "2021-11-19T21:37:37.449419693Z",
"startTime": "2021-11-19T21:38:29.820235Z",
"endTime": "2021-11-20T01:40:17.486321156Z",
"updateTime": "2021-11-20T01:40:17.486321156Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dcd4e4030b376b123f827a9372acb0fa9f6e5d01",
"ref": "refs/heads/main"
}
}
}
[01:40:17.808] Invocation finished in 4h2m41.577783408s with status SUCCEEDED