[01:49:02.476] New invocation is queued and will start shortly
[01:49:03.563] Starting the invocation (attempt 1)
[01:49:03.607] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/82c152487422d1a627a0e036bb364d9a45327c27"
[01:49:03.607] Popped gitiles commit info from properties and tags
[01:49:03.607] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:49:03.607] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:49:03.607] Buildbucket request:
{
"requestId": "8967066358646288080",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8967066358646288080",
"job": "emscripten-releases/mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "82c152487422d1a627a0e036bb364d9a45327c27"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@82c152487422d1a627a0e036bb364d9a45327c27",
"title": "82c152487422d1a627a0e036bb364d9a45327c27",
"url": "https://chromium.googlesource.com/emscripten-releases/+/82c152487422d1a627a0e036bb364d9a45327c27"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "82c152487422d1a627a0e036bb364d9a45327c27",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8967066358646288080"
},
{
"key": "scheduler_job_id",
"value": "emscripten-releases/mac"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:49:04.049] Scheduled build:
{
"id": "8801636296706406577",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-09-30T01:49:03.690799693Z",
"updateTime": "2022-09-30T01:49:03.690799693Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "82c152487422d1a627a0e036bb364d9a45327c27",
"ref": "refs/heads/main"
}
}
}
[01:49:04.050] Task URL: https://cr-buildbucket.appspot.com/build/8801636296706406577
[01:49:04.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:2:0) after 1m24s
[01:50:28.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:2:0)
[01:50:28.397] Build status: SCHEDULED
[01:50:28.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:3:0) after 6m25s
[01:56:53.472] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:3:0)
[01:56:53.497] Build status: SCHEDULED
[01:56:53.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:4:0) after 2m41s
[01:59:34.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:4:0)
[01:59:34.580] Build status: SCHEDULED
[01:59:34.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:5:0) after 3m26s
[02:03:01.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:5:0)
[02:03:01.060] Build status: SCHEDULED
[02:03:01.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:6:0) after 2m59s
[02:06:00.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:6:0)
[02:06:00.162] Build status: SCHEDULED
[02:06:00.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:7:0) after 2m38s
[02:08:38.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:7:0)
[02:08:38.221] Build status: SCHEDULED
[02:08:38.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:8:0) after 5m1s
[02:13:39.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:8:0)
[02:13:39.371] Build status: SCHEDULED
[02:13:39.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:9:0) after 7m10s
[02:20:49.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:9:0)
[02:20:49.414] Build status: SCHEDULED
[02:20:49.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:10:0) after 4m22s
[02:25:11.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:10:0)
[02:25:11.516] Build status: SCHEDULED
[02:25:11.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:11:0) after 6m2s
[02:31:13.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:11:0)
[02:31:13.757] Build status: SCHEDULED
[02:31:13.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:12:0) after 2m10s
[02:33:23.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:12:0)
[02:33:23.928] Build status: SCHEDULED
[02:33:23.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:13:0) after 2m38s
[02:36:02.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:13:0)
[02:36:02.056] Build status: SCHEDULED
[02:36:02.056] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:14:0) after 4m41s
[02:40:43.081] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:14:0)
[02:40:43.109] Build status: SCHEDULED
[02:40:43.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:15:0) after 7m12s
[02:47:55.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:15:0)
[02:47:55.162] Build status: SCHEDULED
[02:47:55.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:16:0) after 1m27s
[02:49:22.283] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:16:0)
[02:49:22.308] Build status: SCHEDULED
[02:49:22.308] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:17:0) after 9m7s
[02:58:29.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:17:0)
[02:58:29.351] Build status: SCHEDULED
[02:58:29.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:18:0) after 3m51s
[03:02:20.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:18:0)
[03:02:20.656] Build status: SCHEDULED
[03:02:20.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:19:0) after 9m47s
[03:12:07.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:19:0)
[03:12:07.751] Build status: SCHEDULED
[03:12:07.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:20:0) after 6m59s
[03:19:06.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:20:0)
[03:19:06.824] Build status: SCHEDULED
[03:19:06.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:21:0) after 8m30s
[03:27:36.845] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:21:0)
[03:27:36.869] Build status: SCHEDULED
[03:27:36.869] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:22:0) after 3m3s
[03:30:40.038] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:22:0)
[03:30:40.067] Build status: SCHEDULED
[03:30:40.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:23:0) after 1m7s
[03:31:47.173] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:23:0)
[03:31:47.203] Build status: SCHEDULED
[03:31:47.204] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:24:0) after 9m48s
[03:41:35.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:24:0)
[03:41:35.364] Build status: SCHEDULED
[03:41:35.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:25:0) after 8m47s
[03:50:22.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:25:0)
[03:50:22.655] Build status: SCHEDULED
[03:50:22.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:26:0) after 4m24s
[03:54:46.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:26:0)
[03:54:46.956] Build status: SCHEDULED
[03:54:46.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:27:0) after 3m29s
[03:58:16.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:27:0)
[03:58:16.221] Build status: SCHEDULED
[03:58:16.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:28:0) after 8m24s
[04:06:40.790] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:28:0)
[04:06:40.824] Build status: SCHEDULED
[04:06:40.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:29:0) after 4m10s
[04:10:50.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:29:0)
[04:10:51.011] Build status: SCHEDULED
[04:10:51.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:30:0) after 4m41s
[04:15:32.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:30:0)
[04:15:32.209] Build status: SCHEDULED
[04:15:32.209] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:31:0) after 5m50s
[04:21:22.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:31:0)
[04:21:22.325] Build status: SCHEDULED
[04:21:22.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:32:0) after 3m53s
[04:25:15.491] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:32:0)
[04:25:15.511] Build status: SCHEDULED
[04:25:15.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:33:0) after 6m57s
[04:32:13.038] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:33:0)
[04:32:13.065] Build status: SCHEDULED
[04:32:13.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:34:0) after 9m7s
[04:41:20.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:34:0)
[04:41:20.154] Build status: SCHEDULED
[04:41:20.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:35:0) after 2m44s
[04:44:04.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:35:0)
[04:44:04.260] Build status: SCHEDULED
[04:44:04.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:36:0) after 3m8s
[04:47:12.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:36:0)
[04:47:12.331] Build status: SCHEDULED
[04:47:12.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:37:0) after 3m20s
[04:50:32.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:37:0)
[04:50:32.350] Build status: SCHEDULED
[04:50:32.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:38:0) after 8m36s
[04:59:08.745] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:38:0)
[04:59:08.777] Build status: SCHEDULED
[04:59:08.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:39:0) after 8m17s
[05:07:26.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:39:0)
[05:07:26.038] Build status: SCHEDULED
[05:07:26.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:40:0) after 9m24s
[05:16:50.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:40:0)
[05:16:50.204] Build status: SCHEDULED
[05:16:50.204] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:41:0) after 7m20s
[05:24:10.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:41:0)
[05:24:10.255] Build status: SCHEDULED
[05:24:10.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:42:0) after 6m7s
[05:30:17.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:42:0)
[05:30:17.313] Build status: SCHEDULED
[05:30:17.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:43:0) after 7m16s
[05:37:33.543] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:43:0)
[05:37:33.569] Build status: SCHEDULED
[05:37:33.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:44:0) after 2m14s
[05:39:47.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:44:0)
[05:39:47.723] Build status: SCHEDULED
[05:39:47.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:45:0) after 4m29s
[05:39:53.057] Received PubSub notification, asking Buildbucket for the build status
[05:39:53.093] Build status: STARTED
[05:44:16.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:45:0)
[05:44:16.950] Build status: STARTED
[05:44:16.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:47:0) after 5m24s
[05:49:41.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:47:0)
[05:49:41.162] Build status: STARTED
[05:49:41.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:48:0) after 4m29s
[05:54:10.229] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:48:0)
[05:54:10.272] Build status: STARTED
[05:54:10.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:49:0) after 8m45s
[06:02:55.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:49:0)
[06:02:55.394] Build status: STARTED
[06:02:55.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:50:0) after 5m58s
[06:08:53.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:50:0)
[06:08:53.599] Build status: STARTED
[06:08:53.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:51:0) after 3m45s
[06:12:38.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:51:0)
[06:12:38.773] Build status: STARTED
[06:12:38.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:52:0) after 6m5s
[06:18:43.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:52:0)
[06:18:43.825] Build status: STARTED
[06:18:43.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:53:0) after 2m53s
[06:21:36.950] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:53:0)
[06:21:36.977] Build status: STARTED
[06:21:36.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:54:0) after 9m32s
[06:31:09.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:54:0)
[06:31:09.122] Build status: STARTED
[06:31:09.122] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:55:0) after 8m9s
[06:39:18.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:55:0)
[06:39:18.298] Build status: STARTED
[06:39:18.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:56:0) after 1m41s
[06:40:59.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:56:0)
[06:40:59.350] Build status: STARTED
[06:40:59.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:57:0) after 9m39s
[06:50:38.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:57:0)
[06:50:38.551] Build status: STARTED
[06:50:38.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:58:0) after 5m45s
[06:56:23.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:58:0)
[06:56:23.604] Build status: STARTED
[06:56:23.604] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:59:0) after 2m56s
[06:59:20.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:59:0)
[06:59:20.070] Build status: STARTED
[06:59:20.070] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:60:0) after 3m33s
[07:02:53.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:60:0)
[07:02:53.123] Build status: STARTED
[07:02:53.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:61:0) after 7m34s
[07:10:27.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:61:0)
[07:10:27.145] Build status: STARTED
[07:10:27.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:62:0) after 8m58s
[07:19:25.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:62:0)
[07:19:25.356] Build status: STARTED
[07:19:25.356] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:63:0) after 8m32s
[07:27:57.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:63:0)
[07:27:57.417] Build status: STARTED
[07:27:57.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:64:0) after 9m27s
[07:37:24.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:64:0)
[07:37:24.459] Build status: STARTED
[07:37:24.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:65:0) after 1m4s
[07:38:28.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:65:0)
[07:38:28.544] Build status: STARTED
[07:38:28.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967066358646288080:66:0) after 4m21s
[07:41:44.723] Received PubSub notification, asking Buildbucket for the build status
[07:41:44.751] Build:
{
"id": "8801636296706406577",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-09-30T01:49:03.690799693Z",
"startTime": "2022-09-30T05:39:52.597395Z",
"endTime": "2022-09-30T07:41:44.298327926Z",
"updateTime": "2022-09-30T07:41:44.298327926Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "82c152487422d1a627a0e036bb364d9a45327c27",
"ref": "refs/heads/main"
}
}
}
[07:41:44.751] Invocation finished in 5h52m42.295176195s with status SUCCEEDED