[00:32:32.810] New invocation is queued and will start shortly
[00:32:34.180] Starting the invocation (attempt 1)
[00:32:34.225] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8813d322bca84e211ca58c054d532cef4ba488c4"
[00:32:34.225] Popped gitiles commit info from properties and tags
[00:32:34.225] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:32:34.225] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:32:34.225] Buildbucket request:
{
"requestId": "8986005937233579168",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8813d322bca84e211ca58c054d532cef4ba488c4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8986005937233579168"
},
{
"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"
}
}
[00:32:34.811] Scheduled build:
{
"id": "8820575875015099505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 3156,
"createdBy": "project:chromium",
"createTime": "2022-03-05T00:32:34.293266978Z",
"updateTime": "2022-03-05T00:32:34.293266978Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8813d322bca84e211ca58c054d532cef4ba488c4",
"ref": "refs/heads/main"
}
}
}
[00:32:34.811] Task URL: https://cr-buildbucket.appspot.com/build/8820575875015099505
[00:32:34.811] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:2:0) after 2m1s
[00:33:03.532] Received PubSub notification, asking Buildbucket for the build status
[00:33:03.554] Build status: STARTED
[00:34:35.832] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:2:0)
[00:34:35.863] Build status: STARTED
[00:34:35.863] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:4:0) after 7m5s
[00:41:41.071] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:4:0)
[00:41:41.093] Build status: STARTED
[00:41:41.093] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:5:0) after 5m20s
[00:47:01.237] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:5:0)
[00:47:01.260] Build status: STARTED
[00:47:01.260] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:6:0) after 9m8s
[00:56:09.357] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:6:0)
[00:56:09.434] Build status: STARTED
[00:56:09.434] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:7:0) after 2m50s
[00:58:59.434] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:7:0)
[00:58:59.465] Build status: STARTED
[00:58:59.465] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:8:0) after 2m56s
[01:01:55.580] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:8:0)
[01:01:55.610] Build status: STARTED
[01:01:55.610] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:9:0) after 3m21s
[01:05:16.725] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:9:0)
[01:05:16.773] Build status: STARTED
[01:05:16.773] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:10:0) after 5m56s
[01:11:13.102] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:10:0)
[01:11:13.133] Build status: STARTED
[01:11:13.133] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:11:0) after 4m11s
[01:15:24.464] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:11:0)
[01:15:24.487] Build status: STARTED
[01:15:24.487] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:12:0) after 6m18s
[01:21:42.721] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:12:0)
[01:21:42.756] Build status: STARTED
[01:21:42.756] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:13:0) after 1m27s
[01:23:09.973] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:13:0)
[01:23:10.012] Build status: STARTED
[01:23:10.012] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:14:0) after 4m34s
[01:27:44.043] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:14:0)
[01:27:44.083] Build status: STARTED
[01:27:44.083] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:15:0) after 8m31s
[01:36:15.079] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:15:0)
[01:36:15.105] Build status: STARTED
[01:36:15.105] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:16:0) after 7m0s
[01:43:15.261] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:16:0)
[01:43:15.289] Build status: STARTED
[01:43:15.290] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:17:0) after 2m2s
[01:45:17.384] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:17:0)
[01:45:17.411] Build status: STARTED
[01:45:17.411] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:18:0) after 4m27s
[01:49:44.555] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:18:0)
[01:49:44.585] Build status: STARTED
[01:49:44.585] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:19:0) after 8m48s
[01:58:32.800] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:19:0)
[01:58:32.830] Build status: STARTED
[01:58:32.830] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:20:0) after 6m58s
[02:05:30.896] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:20:0)
[02:05:30.929] Build status: STARTED
[02:05:30.929] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:21:0) after 6m6s
[02:11:36.953] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:21:0)
[02:11:37.182] Build status: STARTED
[02:11:37.182] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:22:0) after 1m25s
[02:13:02.307] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:22:0)
[02:13:02.342] Build status: STARTED
[02:13:02.342] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:23:0) after 9m15s
[02:22:17.367] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:23:0)
[02:22:17.387] Build status: STARTED
[02:22:17.387] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:24:0) after 6m31s
[02:28:48.501] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:24:0)
[02:28:48.571] Build status: STARTED
[02:28:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:25:0) after 8m9s
[02:36:57.668] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:25:0)
[02:36:57.693] Build status: STARTED
[02:36:57.693] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:26:0) after 1m19s
[02:38:16.720] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:26:0)
[02:38:16.758] Build status: STARTED
[02:38:16.758] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:27:0) after 7m24s
[02:45:41.008] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:27:0)
[02:45:43.493] Build status: STARTED
[02:45:43.493] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:28:0) after 9m36s
[02:55:19.511] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:28:0)
[02:55:19.531] Build status: STARTED
[02:55:19.531] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:29:0) after 9m42s
[03:05:01.679] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:29:0)
[03:05:01.717] Build status: STARTED
[03:05:01.717] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:30:0) after 6m39s
[03:11:40.891] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:30:0)
[03:11:40.918] Build status: STARTED
[03:11:40.918] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:31:0) after 9m30s
[03:21:10.951] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:31:0)
[03:21:10.979] Build status: STARTED
[03:21:10.979] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:32:0) after 3m0s
[03:24:11.014] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:32:0)
[03:24:11.046] Build status: STARTED
[03:24:11.046] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:33:0) after 9m53s
[03:34:04.274] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:33:0)
[03:34:04.308] Build status: STARTED
[03:34:04.308] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:34:0) after 2m54s
[03:36:58.519] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:34:0)
[03:36:58.544] Build status: STARTED
[03:36:58.544] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:35:0) after 9m24s
[03:46:22.657] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:35:0)
[03:46:22.680] Build status: STARTED
[03:46:22.680] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:36:0) after 9m2s
[03:55:24.678] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:36:0)
[03:55:24.698] Build status: STARTED
[03:55:24.698] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:37:0) after 6m50s
[04:02:14.732] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:37:0)
[04:02:14.800] Build status: STARTED
[04:02:14.800] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:38:0) after 3m25s
[04:05:39.894] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:38:0)
[04:05:39.952] Build status: STARTED
[04:05:39.952] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:39:0) after 3m49s
[04:09:28.981] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:39:0)
[04:09:29.004] Build status: STARTED
[04:09:29.004] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:40:0) after 3m14s
[04:12:43.229] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:40:0)
[04:12:43.255] Build status: STARTED
[04:12:43.255] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:41:0) after 9m51s
[04:22:34.277] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:41:0)
[04:22:34.302] Build status: STARTED
[04:22:34.302] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:42:0) after 3m26s
[04:26:00.360] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:42:0)
[04:26:00.403] Build status: STARTED
[04:26:00.403] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:43:0) after 9m37s
[04:35:37.427] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:43:0)
[04:35:37.472] Build status: STARTED
[04:35:37.472] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:44:0) after 7m36s
[04:43:13.591] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:44:0)
[04:43:13.661] Build status: STARTED
[04:43:13.661] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:45:0) after 1m50s
[04:45:03.805] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:45:0)
[04:45:03.828] Build status: STARTED
[04:45:03.828] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:46:0) after 4m12s
[04:49:15.966] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:46:0)
[04:49:15.996] Build status: STARTED
[04:49:15.996] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:47:0) after 4m0s
[04:53:16.026] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:47:0)
[04:53:16.047] Build status: STARTED
[04:53:16.047] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:48:0) after 9m39s
[05:02:55.125] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:48:0)
[05:02:55.161] Build status: STARTED
[05:02:55.161] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:49:0) after 4m56s
[05:07:51.189] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:49:0)
[05:07:51.221] Build status: STARTED
[05:07:51.221] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:50:0) after 4m6s
[05:11:57.251] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:50:0)
[05:11:57.276] Build status: STARTED
[05:11:57.276] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:51:0) after 7m21s
[05:19:18.355] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:51:0)
[05:19:18.449] Build status: STARTED
[05:19:18.449] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:52:0) after 9m42s
[05:29:00.472] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:52:0)
[05:29:00.498] Build status: STARTED
[05:29:00.498] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8986005937233579168:53:0) after 9m7s
[05:32:13.926] Received PubSub notification, asking Buildbucket for the build status
[05:32:13.956] Build:
{
"id": "8820575875015099505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 3156,
"createdBy": "project:chromium",
"createTime": "2022-03-05T00:32:34.293266978Z",
"startTime": "2022-03-05T00:33:02.553040Z",
"endTime": "2022-03-05T05:32:12.490379077Z",
"updateTime": "2022-03-05T05:32:12.490379077Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8813d322bca84e211ca58c054d532cef4ba488c4",
"ref": "refs/heads/main"
}
}
}
[05:32:13.956] Invocation finished in 4h59m41.162372057s with status SUCCEEDED