[00:50:03.182] New invocation is queued and will start shortly
[00:50:04.414] Starting the invocation (attempt 1)
[00:50:04.442] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9fe868712011ce6a8499a8a989512d4617a9f239"
[00:50:04.442] Popped gitiles commit info from properties and tags
[00:50:04.442] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:50:04.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:50:04.442] Buildbucket request:
{
"requestId": "8920412632164708416",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8920412632164708416",
"job": "emscripten-releases/mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "9fe868712011ce6a8499a8a989512d4617a9f239"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@9fe868712011ce6a8499a8a989512d4617a9f239",
"title": "9fe868712011ce6a8499a8a989512d4617a9f239",
"url": "https://chromium.googlesource.com/emscripten-releases/+/9fe868712011ce6a8499a8a989512d4617a9f239"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "9fe868712011ce6a8499a8a989512d4617a9f239",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8920412632164708416"
},
{
"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"
}
}
[00:50:04.981] Scheduled build:
{
"id": "8754982570099781809",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-02-27T00:50:04.519534101Z",
"updateTime": "2024-02-27T00:50:04.519534101Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "9fe868712011ce6a8499a8a989512d4617a9f239",
"ref": "refs/heads/main"
}
}
}
[00:50:04.981] Task URL: https://cr-buildbucket.appspot.com/build/8754982570099781809
[00:50:04.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:2:0) after 8m59s
[00:50:05.979] Received PubSub notification, asking Buildbucket for the build status
[00:50:06.003] Build status: SCHEDULED
[00:59:03.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:2:0)
[00:59:04.085] Build status: SCHEDULED
[00:59:04.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:4:0) after 1m7s
[01:00:11.144] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:4:0)
[01:00:11.232] Build status: SCHEDULED
[01:00:11.232] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:5:0) after 8m48s
[01:08:59.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:5:0)
[01:08:59.292] Build status: SCHEDULED
[01:08:59.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:6:0) after 8m14s
[01:17:13.317] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:6:0)
[01:17:13.346] Build status: SCHEDULED
[01:17:13.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:7:0) after 1m33s
[01:18:46.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:7:0)
[01:18:46.443] Build status: SCHEDULED
[01:18:46.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:8:0) after 5m19s
[01:24:05.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:8:0)
[01:24:05.540] Build status: SCHEDULED
[01:24:05.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:9:0) after 1m11s
[01:25:16.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:9:0)
[01:25:16.598] Build status: SCHEDULED
[01:25:16.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:10:0) after 7m22s
[01:32:38.713] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:10:0)
[01:32:38.753] Build status: SCHEDULED
[01:32:38.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:11:0) after 7m50s
[01:40:28.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:11:0)
[01:40:28.829] Build status: SCHEDULED
[01:40:28.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:12:0) after 6m51s
[01:47:19.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:12:0)
[01:47:19.921] Build status: SCHEDULED
[01:47:19.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:13:0) after 2m20s
[01:49:39.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:13:0)
[01:49:40.000] Build status: SCHEDULED
[01:49:40.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:14:0) after 8m11s
[01:57:51.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:14:0)
[01:57:51.049] Build status: SCHEDULED
[01:57:51.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:15:0) after 6m14s
[02:04:05.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:15:0)
[02:04:05.108] Build status: SCHEDULED
[02:04:05.108] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:16:0) after 5m6s
[02:09:11.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:16:0)
[02:09:11.261] Build status: SCHEDULED
[02:09:11.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:17:0) after 8m34s
[02:17:45.350] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:17:0)
[02:17:45.369] Build status: SCHEDULED
[02:17:45.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:18:0) after 2m18s
[02:20:03.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:18:0)
[02:20:03.459] Build status: SCHEDULED
[02:20:03.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:19:0) after 6m19s
[02:26:22.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:19:0)
[02:26:22.575] Build status: SCHEDULED
[02:26:22.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:20:0) after 5m58s
[02:32:20.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:20:0)
[02:32:20.689] Build status: SCHEDULED
[02:32:20.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:21:0) after 5m44s
[02:38:04.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:21:0)
[02:38:04.832] Build status: SCHEDULED
[02:38:04.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:22:0) after 1m53s
[02:39:57.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:22:0)
[02:39:57.928] Build status: SCHEDULED
[02:39:57.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:23:0) after 9m16s
[02:49:13.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:23:0)
[02:49:14.029] Build status: SCHEDULED
[02:49:14.029] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:24:0) after 5m51s
[02:55:05.043] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:24:0)
[02:55:05.066] Build status: SCHEDULED
[02:55:05.066] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:25:0) after 3m47s
[02:58:52.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:25:0)
[02:58:52.102] Build status: SCHEDULED
[02:58:52.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:26:0) after 5m41s
[03:04:33.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:26:0)
[03:04:33.259] Build status: SCHEDULED
[03:04:33.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:27:0) after 8m51s
[03:13:24.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:27:0)
[03:13:24.314] Build status: SCHEDULED
[03:13:24.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:28:0) after 1m34s
[03:14:58.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:28:0)
[03:14:58.365] Build status: SCHEDULED
[03:14:58.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:29:0) after 7m53s
[03:22:51.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:29:0)
[03:22:51.454] Build status: SCHEDULED
[03:22:51.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:30:0) after 1m3s
[03:23:54.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:30:0)
[03:23:54.638] Build status: SCHEDULED
[03:23:54.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:31:0) after 1m24s
[03:25:18.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:31:0)
[03:25:18.628] Build status: SCHEDULED
[03:25:18.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:32:0) after 6m32s
[03:31:50.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:32:0)
[03:31:50.721] Build status: SCHEDULED
[03:31:50.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:33:0) after 3m57s
[03:35:48.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:33:0)
[03:35:48.125] Build status: SCHEDULED
[03:35:48.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:34:0) after 4m40s
[03:40:28.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:34:0)
[03:40:28.193] Build status: SCHEDULED
[03:40:28.193] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:35:0) after 5m31s
[03:45:59.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:35:0)
[03:45:59.441] Build status: SCHEDULED
[03:45:59.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:36:0) after 1m1s
[03:47:00.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:36:0)
[03:47:00.617] Build status: SCHEDULED
[03:47:00.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:37:0) after 6m46s
[03:53:46.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:37:0)
[03:53:46.900] Build status: SCHEDULED
[03:53:46.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:38:0) after 4m39s
[03:58:26.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:38:0)
[03:58:26.087] Build status: SCHEDULED
[03:58:26.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:39:0) after 2m38s
[04:01:04.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:39:0)
[04:01:04.231] Build status: SCHEDULED
[04:01:04.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:40:0) after 3m36s
[04:04:40.298] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:40:0)
[04:04:40.319] Build status: SCHEDULED
[04:04:40.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:41:0) after 8m39s
[04:13:19.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:41:0)
[04:13:19.598] Build status: SCHEDULED
[04:13:19.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:42:0) after 1m37s
[04:14:56.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:42:0)
[04:14:56.780] Build status: SCHEDULED
[04:14:56.780] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:43:0) after 8m16s
[04:20:46.388] Received PubSub notification, asking Buildbucket for the build status
[04:20:46.421] Build status: STARTED
[04:23:13.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:43:0)
[04:23:13.279] Build status: STARTED
[04:23:13.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:45:0) after 1m23s
[04:24:36.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:45:0)
[04:24:36.728] Build status: STARTED
[04:24:36.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:46:0) after 8m51s
[04:33:27.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:46:0)
[04:33:27.777] Build status: STARTED
[04:33:27.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:47:0) after 5m15s
[04:38:42.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:47:0)
[04:38:42.884] Build status: STARTED
[04:38:42.884] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:48:0) after 2m30s
[04:41:12.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:48:0)
[04:41:13.004] Build status: STARTED
[04:41:13.005] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:49:0) after 6m42s
[04:47:55.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:49:0)
[04:47:55.137] Build status: STARTED
[04:47:55.137] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:50:0) after 1m49s
[04:49:44.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:50:0)
[04:49:44.289] Build status: STARTED
[04:49:44.289] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:51:0) after 7m3s
[04:56:47.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:51:0)
[04:56:47.342] Build status: STARTED
[04:56:47.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:52:0) after 2m38s
[04:59:25.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:52:0)
[04:59:25.509] Build status: STARTED
[04:59:25.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:53:0) after 3m0s
[05:02:25.539] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:53:0)
[05:02:25.616] Build status: STARTED
[05:02:25.616] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:54:0) after 5m2s
[05:07:27.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:54:0)
[05:07:27.664] Build status: STARTED
[05:07:27.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:55:0) after 1m19s
[05:08:46.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:55:0)
[05:08:46.734] Build status: STARTED
[05:08:46.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:56:0) after 7m14s
[05:16:01.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:56:0)
[05:16:01.068] Build status: STARTED
[05:16:01.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:57:0) after 8m17s
[05:24:18.317] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:57:0)
[05:24:18.341] Build status: STARTED
[05:24:18.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:58:0) after 9m36s
[05:33:54.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:58:0)
[05:33:54.539] Build status: STARTED
[05:33:54.539] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:59:0) after 8m25s
[05:42:19.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:59:0)
[05:42:19.779] Build status: STARTED
[05:42:19.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:60:0) after 5m50s
[05:48:09.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:60:0)
[05:48:09.832] Build status: STARTED
[05:48:09.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8920412632164708416:61:0) after 9m6s
[05:54:56.833] Received PubSub notification, asking Buildbucket for the build status
[05:54:56.861] Build:
{
"id": "8754982570099781809",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-02-27T00:50:04.519534101Z",
"startTime": "2024-02-27T04:20:44.847571Z",
"endTime": "2024-02-27T05:54:55.957691Z",
"updateTime": "2024-02-27T05:54:55.957691Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "9fe868712011ce6a8499a8a989512d4617a9f239",
"ref": "refs/heads/main"
}
}
}
[05:54:56.861] Invocation finished in 5h4m53.694988161s with status SUCCEEDED