[23:02:32.257] New invocation is queued and will start shortly
[23:02:33.344] Starting the invocation (attempt 1)
[23:02:33.376] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ffb1d7d413e8be8b514e33df9edc3f7c6600b57"
[23:02:33.376] Popped gitiles commit info from properties and tags
[23:02:33.376] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:02:33.376] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:02:33.376] Buildbucket request:
{
"requestId": "8953124901312387312",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6ffb1d7d413e8be8b514e33df9edc3f7c6600b57",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8953124901312387312"
},
{
"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"
}
}
[23:02:33.874] Scheduled build:
{
"id": "8787694839351918641",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-code-coverage"
},
"number": 9214,
"createdBy": "project:chromium",
"createTime": "2023-03-02T23:02:33.502684776Z",
"updateTime": "2023-03-02T23:02:33.502684776Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6ffb1d7d413e8be8b514e33df9edc3f7c6600b57",
"ref": "refs/heads/main"
}
}
}
[23:02:33.874] Task URL: https://cr-buildbucket.appspot.com/build/8787694839351918641
[23:02:33.874] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:2:0) after 7m51s
[23:10:24.964] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:2:0)
[23:10:25.006] Build status: SCHEDULED
[23:10:25.006] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:3:0) after 5m24s
[23:15:49.105] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:3:0)
[23:15:49.161] Build status: SCHEDULED
[23:15:49.161] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:4:0) after 7m24s
[23:23:13.256] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:4:0)
[23:23:13.275] Build status: SCHEDULED
[23:23:13.275] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:5:0) after 1m41s
[23:24:55.540] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:5:0)
[23:24:55.573] Build status: SCHEDULED
[23:24:55.573] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:6:0) after 2m27s
[23:27:22.593] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:6:0)
[23:27:22.613] Build status: SCHEDULED
[23:27:22.613] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:7:0) after 1m55s
[23:29:17.659] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:7:0)
[23:29:17.699] Build status: SCHEDULED
[23:29:17.699] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:8:0) after 2m46s
[23:32:03.852] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:8:0)
[23:32:03.873] Build status: SCHEDULED
[23:32:03.873] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:9:0) after 5m5s
[23:34:07.281] Received PubSub notification, asking Buildbucket for the build status
[23:34:07.302] Build status: STARTED
[23:37:08.900] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:9:0)
[23:37:08.923] Build status: STARTED
[23:37:08.923] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:11:0) after 7m28s
[23:44:36.942] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:11:0)
[23:44:36.975] Build status: STARTED
[23:44:36.975] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:12:0) after 3m3s
[23:47:40.004] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:12:0)
[23:47:40.032] Build status: STARTED
[23:47:40.032] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:13:0) after 1m37s
[23:49:17.083] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:13:0)
[23:49:17.102] Build status: STARTED
[23:49:17.102] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:14:0) after 3m7s
[23:52:24.472] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:14:0)
[23:52:24.497] Build status: STARTED
[23:52:24.497] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:15:0) after 7m46s
[00:00:10.569] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:15:0)
[00:00:10.601] Build status: STARTED
[00:00:10.601] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:16:0) after 7m18s
[00:07:28.687] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:16:0)
[00:07:28.710] Build status: STARTED
[00:07:28.710] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:17:0) after 4m37s
[00:12:05.855] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:17:0)
[00:12:05.893] Build status: STARTED
[00:12:05.893] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:18:0) after 4m10s
[00:16:15.913] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:18:0)
[00:16:15.934] Build status: STARTED
[00:16:15.934] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:19:0) after 6m24s
[00:22:40.543] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:19:0)
[00:22:40.571] Build status: STARTED
[00:22:40.571] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:20:0) after 6m33s
[00:29:13.639] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:20:0)
[00:29:13.665] Build status: STARTED
[00:29:13.665] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:21:0) after 8m43s
[00:37:56.859] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:21:0)
[00:37:56.886] Build status: STARTED
[00:37:56.886] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:22:0) after 1m19s
[00:39:16.450] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:22:0)
[00:39:16.472] Build status: STARTED
[00:39:16.472] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:23:0) after 7m52s
[00:47:08.714] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:23:0)
[00:47:08.737] Build status: STARTED
[00:47:08.737] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:24:0) after 3m48s
[00:50:56.824] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:24:0)
[00:50:56.848] Build status: STARTED
[00:50:56.848] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:25:0) after 9m4s
[01:00:00.872] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:25:0)
[01:00:00.893] Build status: STARTED
[01:00:00.893] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:26:0) after 4m15s
[01:04:16.222] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:26:0)
[01:04:16.268] Build status: STARTED
[01:04:16.268] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:27:0) after 9m58s
[01:14:14.328] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:27:0)
[01:14:14.351] Build status: STARTED
[01:14:14.351] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:28:0) after 4m57s
[01:19:11.425] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:28:0)
[01:19:11.448] Build status: STARTED
[01:19:11.448] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:29:0) after 8m50s
[01:28:01.468] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:29:0)
[01:28:01.490] Build status: STARTED
[01:28:01.490] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:30:0) after 3m54s
[01:31:55.508] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:30:0)
[01:31:55.532] Build status: STARTED
[01:31:55.532] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:31:0) after 1m5s
[01:33:00.554] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:31:0)
[01:33:00.610] Build status: STARTED
[01:33:00.610] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:32:0) after 7m16s
[01:40:16.629] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:32:0)
[01:40:16.696] Build status: STARTED
[01:40:16.696] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:33:0) after 8m21s
[01:48:37.716] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:33:0)
[01:48:37.738] Build status: STARTED
[01:48:37.738] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:34:0) after 6m7s
[01:54:44.759] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:34:0)
[01:54:44.785] Build status: STARTED
[01:54:44.785] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:35:0) after 4m18s
[01:59:02.850] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:35:0)
[01:59:02.874] Build status: STARTED
[01:59:02.874] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:36:0) after 2m3s
[02:01:06.245] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:36:0)
[02:01:06.272] Build status: STARTED
[02:01:06.272] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:37:0) after 8m59s
[02:10:05.297] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:37:0)
[02:10:05.334] Build status: STARTED
[02:10:05.334] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:38:0) after 6m21s
[02:16:26.377] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:38:0)
[02:16:26.403] Build status: STARTED
[02:16:26.403] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:39:0) after 1m37s
[02:18:03.423] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:39:0)
[02:18:03.455] Build status: STARTED
[02:18:03.455] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:40:0) after 9m59s
[02:28:02.592] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:40:0)
[02:28:02.617] Build status: STARTED
[02:28:02.617] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:41:0) after 5m29s
[02:33:31.826] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:41:0)
[02:33:31.872] Build status: STARTED
[02:33:31.872] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:42:0) after 6m21s
[02:39:52.949] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:42:0)
[02:39:52.970] Build status: STARTED
[02:39:52.970] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:43:0) after 5m7s
[02:45:00.167] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:43:0)
[02:45:00.215] Build status: STARTED
[02:45:00.215] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:44:0) after 4m35s
[02:49:35.451] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:44:0)
[02:49:35.472] Build status: STARTED
[02:49:35.472] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:45:0) after 1m32s
[02:51:07.490] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:45:0)
[02:51:07.512] Build status: STARTED
[02:51:07.512] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:46:0) after 9m11s
[03:00:18.694] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:46:0)
[03:00:18.747] Build status: STARTED
[03:00:18.747] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:47:0) after 5m31s
[03:05:50.557] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:47:0)
[03:05:50.585] Build status: STARTED
[03:05:50.585] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:48:0) after 3m19s
[03:09:10.054] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:48:0)
[03:09:10.085] Build status: STARTED
[03:09:10.085] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:49:0) after 2m32s
[03:11:42.588] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:49:0)
[03:11:42.614] Build status: STARTED
[03:11:42.614] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:50:0) after 1m55s
[03:13:37.987] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:50:0)
[03:13:38.014] Build status: STARTED
[03:13:38.014] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:51:0) after 1m13s
[03:14:51.235] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:51:0)
[03:14:51.261] Build status: STARTED
[03:14:51.261] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:52:0) after 7m43s
[03:22:34.275] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:52:0)
[03:22:34.296] Build status: STARTED
[03:22:34.296] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:53:0) after 7m36s
[03:30:10.424] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:53:0)
[03:30:10.449] Build status: STARTED
[03:30:10.449] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:54:0) after 1m22s
[03:31:32.472] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:54:0)
[03:31:32.492] Build status: STARTED
[03:31:32.492] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:55:0) after 6m36s
[03:38:08.511] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:55:0)
[03:38:08.537] Build status: STARTED
[03:38:08.537] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:56:0) after 4m28s
[03:42:36.704] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:56:0)
[03:42:36.729] Build status: STARTED
[03:42:36.729] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:57:0) after 1m2s
[03:43:38.754] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:57:0)
[03:43:38.777] Build status: STARTED
[03:43:38.777] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:58:0) after 2m3s
[03:45:41.796] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:58:0)
[03:45:41.900] Build status: STARTED
[03:45:41.900] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:59:0) after 5m56s
[03:51:37.923] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:59:0)
[03:51:37.948] Build status: STARTED
[03:51:37.948] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:60:0) after 7m29s
[03:59:07.177] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:60:0)
[03:59:07.226] Build status: STARTED
[03:59:07.226] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:61:0) after 6m22s
[04:05:29.255] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:61:0)
[04:05:29.292] Build status: STARTED
[04:05:29.292] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:62:0) after 5m28s
[04:10:57.459] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:62:0)
[04:10:57.479] Build status: STARTED
[04:10:57.479] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:63:0) after 4m25s
[04:15:22.497] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:63:0)
[04:15:22.517] Build status: STARTED
[04:15:22.517] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:64:0) after 4m41s
[04:20:03.638] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:64:0)
[04:20:03.657] Build status: STARTED
[04:20:03.657] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:65:0) after 6m22s
[04:26:25.677] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:65:0)
[04:26:25.701] Build status: STARTED
[04:26:25.701] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:66:0) after 2m52s
[04:29:18.037] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:66:0)
[04:29:18.065] Build status: STARTED
[04:29:18.065] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:67:0) after 9m4s
[04:38:22.102] Handling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:67:0)
[04:38:22.125] Build status: STARTED
[04:38:22.126] Scheduling timer "check-buildbucket-build-status" (chromium/mac-code-coverage:8953124901312387312:68:0) after 9m45s
[04:42:46.457] Received PubSub notification, asking Buildbucket for the build status
[04:42:46.482] Build:
{
"id": "8787694839351918641",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-code-coverage"
},
"number": 9214,
"createdBy": "project:chromium",
"createTime": "2023-03-02T23:02:33.502684776Z",
"startTime": "2023-03-02T23:34:06.659123Z",
"endTime": "2023-03-03T04:42:46.005448523Z",
"updateTime": "2023-03-03T04:42:46.005448523Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6ffb1d7d413e8be8b514e33df9edc3f7c6600b57",
"ref": "refs/heads/main"
}
}
}
[04:42:46.482] Invocation finished in 5h40m14.232724872s with status SUCCEEDED