[22:55:07.316] New invocation is queued and will start shortly
[22:55:11.680] Starting the invocation (attempt 1)
[22:55:11.709] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e20f5712b89627f259f58be8598d1be2334717c5"
[22:55:11.709] Popped gitiles commit info from properties and tags
[22:55:11.709] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:55:11.710] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:55:11.710] Buildbucket request:
{
"requestId": "8956115067756114080",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e20f5712b89627f259f58be8598d1be2334717c5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8956115067756114080"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-code-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:55:12.171] Scheduled build:
{
"id": "8790685002424161009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-code-coverage"
},
"number": 9150,
"createdBy": "project:chromium",
"createTime": "2023-01-28T22:55:11.778571246Z",
"updateTime": "2023-01-28T22:55:11.778571246Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e20f5712b89627f259f58be8598d1be2334717c5",
"ref": "refs/heads/main"
}
}
}
[22:55:12.171] Task URL: https://cr-buildbucket.appspot.com/build/8790685002424161009
[22:55:12.171] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:2:0) after 9m1s
[23:04:13.217] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:2:0)
[23:04:13.241] Build status: SCHEDULED
[23:04:13.241] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:3:0) after 6m54s
[23:11:07.259] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:3:0)
[23:11:07.277] Build status: SCHEDULED
[23:11:07.277] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:4:0) after 3m3s
[23:14:10.675] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:4:0)
[23:14:10.695] Build status: SCHEDULED
[23:14:10.695] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:5:0) after 3m52s
[23:18:02.718] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:5:0)
[23:18:02.743] Build status: SCHEDULED
[23:18:02.743] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:6:0) after 4m33s
[23:22:35.835] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:6:0)
[23:22:35.863] Build status: SCHEDULED
[23:22:35.863] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:7:0) after 9m28s
[23:32:04.060] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:7:0)
[23:32:04.105] Build status: SCHEDULED
[23:32:04.105] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:8:0) after 9m7s
[23:41:11.130] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:8:0)
[23:41:11.148] Build status: SCHEDULED
[23:41:11.148] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:9:0) after 2m6s
[23:43:17.169] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:9:0)
[23:43:17.192] Build status: SCHEDULED
[23:43:17.192] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:10:0) after 1m50s
[23:44:46.672] Received PubSub notification, asking Buildbucket for the build status
[23:44:46.696] Build status: STARTED
[23:45:07.383] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:10:0)
[23:45:07.422] Build status: STARTED
[23:45:07.422] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:12:0) after 5m25s
[23:50:32.438] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:12:0)
[23:50:32.467] Build status: STARTED
[23:50:32.467] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:13:0) after 4m49s
[23:55:21.528] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:13:0)
[23:55:21.556] Build status: STARTED
[23:55:21.556] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:14:0) after 7m47s
[00:03:09.388] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:14:0)
[00:03:09.411] Build status: STARTED
[00:03:09.411] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:15:0) after 1m10s
[00:04:19.765] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:15:0)
[00:04:19.797] Build status: STARTED
[00:04:19.797] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:16:0) after 2m47s
[00:07:06.815] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:16:0)
[00:07:06.838] Build status: STARTED
[00:07:06.838] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:17:0) after 1m8s
[00:08:15.247] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:17:0)
[00:08:15.276] Build status: STARTED
[00:08:15.276] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:18:0) after 6m31s
[00:14:46.293] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:18:0)
[00:14:46.324] Build status: STARTED
[00:14:46.324] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:19:0) after 2m1s
[00:16:47.349] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:19:0)
[00:16:47.612] Build status: STARTED
[00:16:47.612] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:20:0) after 3m4s
[00:19:51.632] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:20:0)
[00:19:51.658] Build status: STARTED
[00:19:51.658] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:21:0) after 1m51s
[00:21:42.638] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:21:0)
[00:21:42.705] Build status: STARTED
[00:21:42.705] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:22:0) after 1m2s
[00:22:44.728] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:22:0)
[00:22:44.751] Build status: STARTED
[00:22:44.751] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:23:0) after 5m11s
[00:27:55.965] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:23:0)
[00:27:55.991] Build status: STARTED
[00:27:55.991] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:24:0) after 9m49s
[00:37:45.327] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:24:0)
[00:37:45.349] Build status: STARTED
[00:37:45.349] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:25:0) after 7m15s
[00:45:00.388] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:25:0)
[00:45:00.409] Build status: STARTED
[00:45:00.409] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:26:0) after 3m9s
[00:48:09.439] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:26:0)
[00:48:09.461] Build status: STARTED
[00:48:09.461] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:27:0) after 5m28s
[00:53:37.487] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:27:0)
[00:53:37.508] Build status: STARTED
[00:53:37.508] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:28:0) after 5m58s
[00:59:35.564] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:28:0)
[00:59:35.590] Build status: STARTED
[00:59:35.590] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:29:0) after 6m46s
[01:06:21.953] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:29:0)
[01:06:21.974] Build status: STARTED
[01:06:21.975] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:30:0) after 9m54s
[01:16:15.999] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:30:0)
[01:16:16.020] Build status: STARTED
[01:16:16.020] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:31:0) after 9m12s
[01:25:28.164] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:31:0)
[01:25:28.184] Build status: STARTED
[01:25:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:32:0) after 7m40s
[01:33:08.383] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:32:0)
[01:33:08.402] Build status: STARTED
[01:33:08.402] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:33:0) after 3m59s
[01:37:07.419] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:33:0)
[01:37:07.447] Build status: STARTED
[01:37:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:34:0) after 5m40s
[01:42:47.466] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:34:0)
[01:42:47.493] Build status: STARTED
[01:42:47.493] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:35:0) after 2m29s
[01:45:16.511] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:35:0)
[01:45:16.532] Build status: STARTED
[01:45:16.532] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:36:0) after 8m20s
[01:53:36.580] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:36:0)
[01:53:36.611] Build status: STARTED
[01:53:36.611] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:37:0) after 4m41s
[01:58:17.639] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:37:0)
[01:58:17.675] Build status: STARTED
[01:58:17.675] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:38:0) after 4m23s
[02:02:40.704] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:38:0)
[02:02:40.729] Build status: STARTED
[02:02:40.730] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:39:0) after 9m49s
[02:12:29.748] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:39:0)
[02:12:29.769] Build status: STARTED
[02:12:29.769] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:40:0) after 8m40s
[02:21:09.775] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:40:0)
[02:21:09.799] Build status: STARTED
[02:21:09.799] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:41:0) after 2m30s
[02:23:39.823] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:41:0)
[02:23:39.847] Build status: STARTED
[02:23:39.847] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:42:0) after 8m34s
[02:32:13.865] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:42:0)
[02:32:13.930] Build status: STARTED
[02:32:13.930] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:43:0) after 9m6s
[02:41:19.949] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:43:0)
[02:41:19.972] Build status: STARTED
[02:41:19.972] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:44:0) after 9m35s
[02:50:55.255] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:44:0)
[02:50:55.282] Build status: STARTED
[02:50:55.282] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:45:0) after 3m26s
[02:54:21.301] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:45:0)
[02:54:21.325] Build status: STARTED
[02:54:21.325] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:46:0) after 6m57s
[03:01:18.344] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:46:0)
[03:01:18.365] Build status: STARTED
[03:01:18.365] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:47:0) after 6m44s
[03:08:02.384] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:47:0)
[03:08:02.427] Build status: STARTED
[03:08:02.427] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:48:0) after 5m20s
[03:13:22.455] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:48:0)
[03:13:22.478] Build status: STARTED
[03:13:22.478] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:49:0) after 8m38s
[03:22:00.496] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:49:0)
[03:22:00.520] Build status: STARTED
[03:22:00.520] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:50:0) after 3m36s
[03:25:36.590] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:50:0)
[03:25:36.619] Build status: STARTED
[03:25:36.619] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:51:0) after 6m2s
[03:31:38.839] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:51:0)
[03:31:38.859] Build status: STARTED
[03:31:38.859] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:52:0) after 4m10s
[03:35:48.917] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:52:0)
[03:35:48.954] Build status: STARTED
[03:35:48.954] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:53:0) after 5m11s
[03:41:00.128] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:53:0)
[03:41:00.149] Build status: STARTED
[03:41:00.149] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:54:0) after 5m43s
[03:46:43.252] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:54:0)
[03:46:43.271] Build status: STARTED
[03:46:43.271] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:55:0) after 8m32s
[03:55:15.290] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:55:0)
[03:55:15.310] Build status: STARTED
[03:55:15.310] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:56:0) after 2m40s
[03:57:55.380] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:56:0)
[03:57:55.404] Build status: STARTED
[03:57:55.404] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:57:0) after 8m36s
[04:06:31.527] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:57:0)
[04:06:31.548] Build status: STARTED
[04:06:31.548] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8956115067756114080:58:0) after 7m31s
[04:10:48.947] Received PubSub notification, asking Buildbucket for the build status
[04:10:48.970] Build:
{
"id": "8790685002424161009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-code-coverage"
},
"number": 9150,
"createdBy": "project:chromium",
"createTime": "2023-01-28T22:55:11.778571246Z",
"startTime": "2023-01-28T23:44:46.222275Z",
"endTime": "2023-01-29T04:10:48.460021138Z",
"updateTime": "2023-01-29T04:10:48.460021138Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e20f5712b89627f259f58be8598d1be2334717c5",
"ref": "refs/heads/main"
}
}
}
[04:10:48.971] Invocation finished in 5h15m41.661343022s with status SUCCEEDED