[11:28:22.406] New invocation is queued and will start shortly
[11:28:23.663] Starting the invocation (attempt 1)
[11:28:23.714] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc"
[11:28:23.714] Popped gitiles commit info from properties and tags
[11:28:23.714] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:28:23.715] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:28:23.715] Buildbucket request:
{
"requestId": "8967935879883035440",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8967935879883035440",
"job": "emscripten-releases/mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc",
"title": "b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc",
"url": "https://chromium.googlesource.com/emscripten-releases/+/b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8967935879883035440"
},
{
"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"
}
}
[11:28:24.509] Scheduled build:
{
"id": "8802505817649191553",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-09-20T11:28:23.899739489Z",
"updateTime": "2022-09-20T11:28:23.899739489Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc",
"ref": "refs/heads/main"
}
}
}
[11:28:24.509] Task URL: https://cr-buildbucket.appspot.com/build/8802505817649191553
[11:28:24.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:2:0) after 6m15s
[11:34:39.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:2:0)
[11:34:39.660] Build status: SCHEDULED
[11:34:39.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:3:0) after 4m3s
[11:38:42.682] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:3:0)
[11:38:42.721] Build status: SCHEDULED
[11:38:42.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:4:0) after 9m10s
[11:47:52.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:4:0)
[11:47:52.777] Build status: SCHEDULED
[11:47:52.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:5:0) after 8m5s
[11:55:58.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:5:0)
[11:55:58.133] Build status: SCHEDULED
[11:55:58.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:6:0) after 3m25s
[11:59:23.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:6:0)
[11:59:23.395] Build status: SCHEDULED
[11:59:23.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:7:0) after 7m29s
[12:06:52.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:7:0)
[12:06:52.763] Build status: SCHEDULED
[12:06:52.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:8:0) after 8m9s
[12:15:02.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:8:0)
[12:15:02.218] Build status: SCHEDULED
[12:15:02.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:9:0) after 4m44s
[12:19:46.454] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:9:0)
[12:19:46.489] Build status: SCHEDULED
[12:19:46.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:10:0) after 5m51s
[12:25:37.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:10:0)
[12:25:37.545] Build status: SCHEDULED
[12:25:37.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:11:0) after 1m29s
[12:27:06.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:11:0)
[12:27:06.856] Build status: SCHEDULED
[12:27:06.856] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:12:0) after 3m27s
[12:30:34.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:12:0)
[12:30:34.180] Build status: SCHEDULED
[12:30:34.180] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:13:0) after 7m11s
[12:37:45.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:13:0)
[12:37:45.303] Build status: SCHEDULED
[12:37:45.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:14:0) after 3m6s
[12:40:51.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:14:0)
[12:40:51.464] Build status: SCHEDULED
[12:40:51.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:15:0) after 8m29s
[12:49:20.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:15:0)
[12:49:20.607] Build status: SCHEDULED
[12:49:20.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:16:0) after 2m2s
[12:51:22.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:16:0)
[12:51:22.793] Build status: SCHEDULED
[12:51:22.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:17:0) after 5m44s
[12:57:07.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:17:0)
[12:57:07.159] Build status: SCHEDULED
[12:57:07.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:18:0) after 5m52s
[13:02:59.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:18:0)
[13:02:59.214] Build status: SCHEDULED
[13:02:59.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:19:0) after 2m7s
[13:05:06.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:19:0)
[13:05:06.269] Build status: SCHEDULED
[13:05:06.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:20:0) after 3m14s
[13:08:20.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:20:0)
[13:08:20.499] Build status: SCHEDULED
[13:08:20.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:21:0) after 3m59s
[13:12:19.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:21:0)
[13:12:19.554] Build status: SCHEDULED
[13:12:19.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:22:0) after 7m37s
[13:19:56.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:22:0)
[13:19:56.670] Build status: SCHEDULED
[13:19:56.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:23:0) after 9m12s
[13:29:08.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:23:0)
[13:29:08.793] Build status: SCHEDULED
[13:29:08.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:24:0) after 1m37s
[13:30:45.821] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:24:0)
[13:30:45.850] Build status: SCHEDULED
[13:30:45.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:25:0) after 5m44s
[13:36:29.883] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:25:0)
[13:36:29.958] Build status: SCHEDULED
[13:36:29.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:26:0) after 5m0s
[13:41:30.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:26:0)
[13:41:30.371] Build status: SCHEDULED
[13:41:30.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:27:0) after 8m56s
[13:50:26.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:27:0)
[13:50:26.418] Build status: SCHEDULED
[13:50:26.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:28:0) after 4m59s
[13:55:25.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:28:0)
[13:55:25.501] Build status: SCHEDULED
[13:55:25.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:29:0) after 1m3s
[13:56:28.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:29:0)
[13:56:28.552] Build status: SCHEDULED
[13:56:28.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:30:0) after 5m58s
[14:02:26.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:30:0)
[14:02:26.967] Build status: SCHEDULED
[14:02:26.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:31:0) after 9m48s
[14:12:15.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:31:0)
[14:12:15.075] Build status: SCHEDULED
[14:12:15.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:32:0) after 6m57s
[14:19:12.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:32:0)
[14:19:12.155] Build status: SCHEDULED
[14:19:12.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:33:0) after 3m12s
[14:22:25.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:33:0)
[14:22:25.273] Build status: SCHEDULED
[14:22:25.273] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:34:0) after 3m21s
[14:25:46.331] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:34:0)
[14:25:46.396] Build status: SCHEDULED
[14:25:46.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:35:0) after 1m43s
[14:27:29.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:35:0)
[14:27:29.597] Build status: SCHEDULED
[14:27:29.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:36:0) after 9m16s
[14:36:46.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:36:0)
[14:36:46.187] Build status: SCHEDULED
[14:36:46.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:37:0) after 4m48s
[14:41:34.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:37:0)
[14:41:34.240] Build status: SCHEDULED
[14:41:34.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:38:0) after 2m9s
[14:43:43.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:38:0)
[14:43:43.373] Build status: SCHEDULED
[14:43:43.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:39:0) after 8m13s
[14:51:56.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:39:0)
[14:51:56.395] Build status: SCHEDULED
[14:51:56.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:40:0) after 8m23s
[15:00:19.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:40:0)
[15:00:19.465] Build status: SCHEDULED
[15:00:19.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:41:0) after 1m19s
[15:01:38.536] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:41:0)
[15:01:38.567] Build status: SCHEDULED
[15:01:38.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:42:0) after 5m27s
[15:07:05.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:42:0)
[15:07:05.657] Build status: SCHEDULED
[15:07:05.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:43:0) after 3m18s
[15:10:23.689] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:43:0)
[15:10:23.720] Build status: SCHEDULED
[15:10:23.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:44:0) after 9m36s
[15:15:29.705] Received PubSub notification, asking Buildbucket for the build status
[15:15:29.750] Build status: STARTED
[15:19:59.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:44:0)
[15:19:59.807] Build status: STARTED
[15:19:59.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:46:0) after 3m46s
[15:23:45.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:46:0)
[15:23:45.853] Build status: STARTED
[15:23:45.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:47:0) after 1m39s
[15:25:24.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:47:0)
[15:25:24.916] Build status: STARTED
[15:25:24.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:48:0) after 9m35s
[15:34:59.999] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:48:0)
[15:35:00.031] Build status: STARTED
[15:35:00.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:49:0) after 3m47s
[15:38:47.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:49:0)
[15:38:47.118] Build status: STARTED
[15:38:47.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:50:0) after 6m42s
[15:45:29.150] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:50:0)
[15:45:29.198] Build status: STARTED
[15:45:29.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:51:0) after 7m51s
[15:53:20.290] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:51:0)
[15:53:20.317] Build status: STARTED
[15:53:20.317] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:52:0) after 7m58s
[16:01:18.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:52:0)
[16:01:18.506] Build status: STARTED
[16:01:18.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:53:0) after 8m12s
[16:09:30.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:53:0)
[16:09:30.593] Build status: STARTED
[16:09:30.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:54:0) after 9m20s
[16:18:50.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:54:0)
[16:18:50.696] Build status: STARTED
[16:18:50.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:55:0) after 5m41s
[16:24:31.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:55:0)
[16:24:31.764] Build status: STARTED
[16:24:31.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:56:0) after 6m31s
[16:31:02.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:56:0)
[16:31:02.877] Build status: STARTED
[16:31:02.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:57:0) after 5m7s
[16:36:09.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:57:0)
[16:36:09.930] Build status: STARTED
[16:36:09.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:58:0) after 5m11s
[16:41:20.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:58:0)
[16:41:21.026] Build status: STARTED
[16:41:21.026] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:59:0) after 8m48s
[16:50:09.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:59:0)
[16:50:09.214] Build status: STARTED
[16:50:09.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:60:0) after 3m29s
[16:53:38.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:60:0)
[16:53:38.375] Build status: STARTED
[16:53:38.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:61:0) after 8m8s
[17:01:46.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:61:0)
[17:01:46.485] Build status: STARTED
[17:01:46.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8967935879883035440:62:0) after 8m3s
[17:05:56.617] Received PubSub notification, asking Buildbucket for the build status
[17:05:56.648] Build:
{
"id": "8802505817649191553",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-09-20T11:28:23.899739489Z",
"startTime": "2022-09-20T15:15:28.751697Z",
"endTime": "2022-09-20T17:05:55.991913702Z",
"updateTime": "2022-09-20T17:05:55.991913702Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "b17ee044e1087f6cc32d7cbb7b3f5d745ef50abc",
"ref": "refs/heads/main"
}
}
}
[17:05:56.648] Invocation finished in 5h37m34.263382646s with status SUCCEEDED