[21:28:29.049] New invocation is queued and will start shortly
[21:28:32.152] Starting the invocation (attempt 1)
[21:28:32.189] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a8a73dd36ec2e0abd7b3d1fd0a048da48cd4727"
[21:28:32.189] Popped gitiles commit info from properties and tags
[21:28:32.189] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:28:32.189] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:28:32.189] Buildbucket request:
{
"requestId": "9005224074331072784",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9a8a73dd36ec2e0abd7b3d1fd0a048da48cd4727",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9005224074331072784"
},
{
"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:28:32.782] Scheduled build:
{
"id": "8839794010279032992",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 2108,
"createdBy": "project:chromium",
"createTime": "2021-08-04T21:28:32.283048Z",
"updateTime": "2021-08-04T21:28:32.677533Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9a8a73dd36ec2e0abd7b3d1fd0a048da48cd4727",
"ref": "refs/heads/main"
}
}
}
[21:28:32.782] Task URL: https://cr-buildbucket.appspot.com/build/8839794010279032992
[21:28:32.789] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:2:0) after 2m27s
[21:28:41.503] Received PubSub notification, asking Buildbucket for the build status
[21:28:41.542] Build status: STARTED
[21:30:59.827] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:2:0)
[21:30:59.856] Build status: STARTED
[21:30:59.856] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:4:0) after 6m45s
[21:37:44.885] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:4:0)
[21:37:44.916] Build status: STARTED
[21:37:44.916] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:5:0) after 4m16s
[21:42:00.992] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:5:0)
[21:42:01.025] Build status: STARTED
[21:42:01.025] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:6:0) after 3m39s
[21:45:40.430] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:6:0)
[21:45:40.456] Build status: STARTED
[21:45:40.456] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:7:0) after 3m57s
[21:49:37.755] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:7:0)
[21:49:37.787] Build status: STARTED
[21:49:37.787] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:8:0) after 5m19s
[21:54:56.880] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:8:0)
[21:54:56.910] Build status: STARTED
[21:54:56.910] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:9:0) after 4m40s
[21:59:37.216] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:9:0)
[21:59:37.317] Build status: STARTED
[21:59:37.317] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:10:0) after 5m53s
[22:05:30.325] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:10:0)
[22:05:30.355] Build status: STARTED
[22:05:30.355] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:11:0) after 8m42s
[22:14:12.559] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:11:0)
[22:14:12.588] Build status: STARTED
[22:14:12.588] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:12:0) after 8m7s
[22:22:19.623] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:12:0)
[22:22:19.657] Build status: STARTED
[22:22:19.657] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:13:0) after 8m18s
[22:30:38.035] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:13:0)
[22:30:38.094] Build status: STARTED
[22:30:38.094] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:14:0) after 6m2s
[22:36:40.169] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:14:0)
[22:36:40.199] Build status: STARTED
[22:36:40.199] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:15:0) after 9m36s
[22:46:16.298] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:15:0)
[22:46:16.347] Build status: STARTED
[22:46:16.347] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:16:0) after 3m18s
[22:49:34.747] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:16:0)
[22:49:34.794] Build status: STARTED
[22:49:34.794] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:17:0) after 8m27s
[22:58:01.824] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:17:0)
[22:58:01.851] Build status: STARTED
[22:58:01.851] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:18:0) after 8m2s
[23:06:05.294] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:18:0)
[23:06:05.319] Build status: STARTED
[23:06:05.319] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:19:0) after 6m48s
[23:12:53.346] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:19:0)
[23:12:53.377] Build status: STARTED
[23:12:53.377] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:20:0) after 4m28s
[23:17:21.431] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:20:0)
[23:17:21.460] Build status: STARTED
[23:17:21.460] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:21:0) after 3m36s
[23:20:57.511] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:21:0)
[23:20:57.549] Build status: STARTED
[23:20:57.549] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:22:0) after 4m42s
[23:25:39.614] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:22:0)
[23:25:39.660] Build status: STARTED
[23:25:39.660] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:23:0) after 4m50s
[23:30:29.689] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:23:0)
[23:30:29.739] Build status: STARTED
[23:30:29.739] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:24:0) after 5m36s
[23:36:05.870] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:24:0)
[23:36:05.977] Build status: STARTED
[23:36:05.977] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:25:0) after 8m27s
[23:44:33.020] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:25:0)
[23:44:33.051] Build status: STARTED
[23:44:33.051] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:26:0) after 1m6s
[23:45:39.194] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:26:0)
[23:45:39.221] Build status: STARTED
[23:45:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:27:0) after 5m49s
[23:51:28.346] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:27:0)
[23:51:28.379] Build status: STARTED
[23:51:28.380] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:28:0) after 8m33s
[00:00:01.587] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:28:0)
[00:00:01.619] Build status: STARTED
[00:00:01.619] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:29:0) after 2m37s
[00:02:38.639] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:29:0)
[00:02:38.671] Build status: STARTED
[00:02:38.671] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:30:0) after 4m1s
[00:06:39.792] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:30:0)
[00:06:39.896] Build status: STARTED
[00:06:39.896] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:31:0) after 1m56s
[00:08:36.149] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:31:0)
[00:08:36.181] Build status: STARTED
[00:08:36.181] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:32:0) after 2m3s
[00:10:39.205] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:32:0)
[00:10:39.240] Build status: STARTED
[00:10:39.240] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:33:0) after 8m46s
[00:19:25.331] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:33:0)
[00:19:25.376] Build status: STARTED
[00:19:25.376] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:34:0) after 7m24s
[00:26:49.450] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:34:0)
[00:26:49.505] Build status: STARTED
[00:26:49.505] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:35:0) after 2m25s
[00:29:14.625] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:35:0)
[00:29:14.664] Build status: STARTED
[00:29:14.664] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:36:0) after 7m36s
[00:36:50.686] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:36:0)
[00:36:50.717] Build status: STARTED
[00:36:50.717] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:37:0) after 3m5s
[00:39:55.740] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:37:0)
[00:39:55.786] Build status: STARTED
[00:39:55.786] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:38:0) after 7m1s
[00:46:56.857] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:38:0)
[00:46:56.897] Build status: STARTED
[00:46:56.897] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:39:0) after 1m41s
[00:48:38.120] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:39:0)
[00:48:38.215] Build status: STARTED
[00:48:38.215] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:40:0) after 2m0s
[00:50:38.237] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:40:0)
[00:50:38.274] Build status: STARTED
[00:50:38.275] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:41:0) after 2m41s
[00:53:19.319] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:41:0)
[00:53:19.347] Build status: STARTED
[00:53:19.347] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:42:0) after 8m46s
[01:02:05.381] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:42:0)
[01:02:05.413] Build status: STARTED
[01:02:05.413] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:43:0) after 6m24s
[01:08:29.497] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:43:0)
[01:08:29.532] Build status: STARTED
[01:08:29.532] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:44:0) after 1m31s
[01:10:00.519] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:44:0)
[01:10:00.568] Build status: STARTED
[01:10:00.568] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005224074331072784:45:0) after 8m46s
[01:14:46.214] Received PubSub notification, asking Buildbucket for the build status
[01:14:46.254] Build:
{
"id": "8839794010279032992",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 2108,
"createdBy": "project:chromium",
"createTime": "2021-08-04T21:28:32.283048Z",
"startTime": "2021-08-04T21:28:40.699084Z",
"endTime": "2021-08-05T01:14:45.785166104Z",
"updateTime": "2021-08-05T01:14:45.785482719Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9a8a73dd36ec2e0abd7b3d1fd0a048da48cd4727",
"ref": "refs/heads/main"
}
}
}
[01:14:46.254] Invocation finished in 3h46m17.219574766s with status SUCCEEDED