[07:33:22.915] New invocation is queued and will start shortly
[07:33:24.088] Starting the invocation (attempt 1)
[07:33:24.120] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/772bac2aca7dd87504b289aa58e5c53e1efe4b1d"
[07:33:24.120] Popped gitiles commit info from properties and tags
[07:33:24.121] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:33:24.121] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:33:24.126] Buildbucket request:
{
"requestId": "8916944572180620672",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916944572180620672",
"job": "emscripten-releases/mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "772bac2aca7dd87504b289aa58e5c53e1efe4b1d"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@772bac2aca7dd87504b289aa58e5c53e1efe4b1d",
"title": "772bac2aca7dd87504b289aa58e5c53e1efe4b1d",
"url": "https://chromium.googlesource.com/emscripten-releases/+/772bac2aca7dd87504b289aa58e5c53e1efe4b1d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "772bac2aca7dd87504b289aa58e5c53e1efe4b1d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916944572180620672"
},
{
"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"
}
}
[07:33:24.701] Scheduled build:
{
"id": "8751514510182615073",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-04-05T07:33:24.190895078Z",
"updateTime": "2024-04-05T07:33:24.190895078Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "772bac2aca7dd87504b289aa58e5c53e1efe4b1d",
"ref": "refs/heads/main"
}
}
}
[07:33:24.701] Task URL: https://cr-buildbucket.appspot.com/build/8751514510182615073
[07:33:24.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:2:0) after 8m53s
[07:33:33.014] Received PubSub notification, asking Buildbucket for the build status
[07:33:33.033] Build status: STARTED
[07:33:37.283] Received PubSub notification, asking Buildbucket for the build status
[07:33:37.305] Build status: STARTED
[07:42:17.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:2:0)
[07:42:17.745] Build status: STARTED
[07:42:17.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:5:0) after 5m29s
[07:47:46.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:5:0)
[07:47:47.003] Build status: STARTED
[07:47:47.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:6:0) after 9m5s
[07:56:52.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:6:0)
[07:56:52.102] Build status: STARTED
[07:56:52.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:7:0) after 4m20s
[08:01:12.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:7:0)
[08:01:12.197] Build status: STARTED
[08:01:12.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:8:0) after 5m48s
[08:07:00.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:8:0)
[08:07:00.685] Build status: STARTED
[08:07:00.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:9:0) after 4m10s
[08:11:11.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:9:0)
[08:11:11.192] Build status: STARTED
[08:11:11.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:10:0) after 1m45s
[08:12:56.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:10:0)
[08:12:56.336] Build status: STARTED
[08:12:56.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:11:0) after 4m10s
[08:17:06.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:11:0)
[08:17:06.741] Build status: STARTED
[08:17:06.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:12:0) after 9m43s
[08:26:49.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:12:0)
[08:26:49.847] Build status: STARTED
[08:26:49.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:13:0) after 9m8s
[08:35:57.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:13:0)
[08:35:57.989] Build status: STARTED
[08:35:57.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:14:0) after 5m19s
[08:41:17.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:14:0)
[08:41:17.263] Build status: STARTED
[08:41:17.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:15:0) after 3m30s
[08:44:47.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:15:0)
[08:44:47.328] Build status: STARTED
[08:44:47.328] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:16:0) after 1m57s
[08:46:44.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:16:0)
[08:46:44.525] Build status: STARTED
[08:46:44.525] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:17:0) after 2m1s
[08:48:45.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:17:0)
[08:48:45.749] Build status: STARTED
[08:48:45.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:18:0) after 9m5s
[08:57:51.060] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:18:0)
[08:57:51.080] Build status: STARTED
[08:57:51.080] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:19:0) after 6m10s
[09:04:01.229] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:19:0)
[09:04:01.254] Build status: STARTED
[09:04:01.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:20:0) after 4m46s
[09:08:47.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:20:0)
[09:08:47.409] Build status: STARTED
[09:08:47.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:21:0) after 9m36s
[09:18:23.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:21:0)
[09:18:23.847] Build status: STARTED
[09:18:23.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:22:0) after 6m50s
[09:25:13.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:22:0)
[09:25:13.971] Build status: STARTED
[09:25:13.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:23:0) after 1m17s
[09:26:30.990] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:23:0)
[09:26:31.015] Build status: STARTED
[09:26:31.015] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:24:0) after 7m1s
[09:33:32.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:24:0)
[09:33:32.250] Build status: STARTED
[09:33:32.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:25:0) after 5m8s
[09:38:40.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:25:0)
[09:38:40.530] Build status: STARTED
[09:38:40.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:26:0) after 1m15s
[09:39:55.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:26:0)
[09:39:55.606] Build status: STARTED
[09:39:55.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:27:0) after 8m57s
[09:48:52.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:27:0)
[09:48:52.692] Build status: STARTED
[09:48:52.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:28:0) after 5m5s
[09:53:57.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:28:0)
[09:53:57.835] Build status: STARTED
[09:53:57.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:29:0) after 4m25s
[09:58:22.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:29:0)
[09:58:22.902] Build status: STARTED
[09:58:22.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:30:0) after 8m8s
[10:06:30.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:30:0)
[10:06:30.983] Build status: STARTED
[10:06:30.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:31:0) after 5m2s
[10:11:33.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:31:0)
[10:11:33.049] Build status: STARTED
[10:11:33.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:32:0) after 8m18s
[10:19:51.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:32:0)
[10:19:51.154] Build status: STARTED
[10:19:51.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:33:0) after 5m26s
[10:25:17.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:33:0)
[10:25:17.332] Build status: STARTED
[10:25:17.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:34:0) after 9m27s
[10:34:44.365] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:34:0)
[10:34:44.391] Build status: STARTED
[10:34:44.391] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:35:0) after 1m39s
[10:36:23.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:35:0)
[10:36:23.443] Build status: STARTED
[10:36:23.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:36:0) after 7m14s
[10:43:37.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:36:0)
[10:43:37.481] Build status: STARTED
[10:43:37.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:37:0) after 1m43s
[10:45:20.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:37:0)
[10:45:20.534] Build status: STARTED
[10:45:20.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:38:0) after 6m52s
[10:52:12.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:38:0)
[10:52:12.689] Build status: STARTED
[10:52:12.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:39:0) after 8m14s
[11:00:27.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:39:0)
[11:00:27.032] Build status: STARTED
[11:00:27.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:40:0) after 4m52s
[11:05:19.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:40:0)
[11:05:19.238] Build status: STARTED
[11:05:19.238] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:41:0) after 4m43s
[11:10:02.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:41:0)
[11:10:02.358] Build status: STARTED
[11:10:02.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:42:0) after 4m34s
[11:14:36.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:42:0)
[11:14:36.685] Build status: STARTED
[11:14:36.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:43:0) after 2m45s
[11:17:21.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:43:0)
[11:17:21.817] Build status: STARTED
[11:17:21.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:44:0) after 6m20s
[11:23:41.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:44:0)
[11:23:41.910] Build status: STARTED
[11:23:41.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:45:0) after 5m11s
[11:28:52.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:45:0)
[11:28:52.964] Build status: STARTED
[11:28:52.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:46:0) after 9m1s
[11:37:54.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:46:0)
[11:37:54.415] Build status: STARTED
[11:37:54.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:47:0) after 6m48s
[11:44:42.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:47:0)
[11:44:42.475] Build status: STARTED
[11:44:42.475] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:48:0) after 6m56s
[11:51:38.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:48:0)
[11:51:38.530] Build status: STARTED
[11:51:38.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:49:0) after 9m20s
[12:00:58.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:49:0)
[12:00:58.674] Build status: STARTED
[12:00:58.674] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:50:0) after 5m34s
[12:06:32.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:50:0)
[12:06:32.839] Build status: STARTED
[12:06:32.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:51:0) after 9m54s
[12:16:26.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:51:0)
[12:16:26.914] Build status: STARTED
[12:16:26.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:52:0) after 8m8s
[12:24:34.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:52:0)
[12:24:34.983] Build status: STARTED
[12:24:34.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:53:0) after 7m31s
[12:32:06.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:53:0)
[12:32:06.106] Build status: STARTED
[12:32:06.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:54:0) after 9m39s
[12:41:45.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:54:0)
[12:41:45.599] Build status: STARTED
[12:41:45.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:55:0) after 3m44s
[12:45:29.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:55:0)
[12:45:29.814] Build status: STARTED
[12:45:29.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:56:0) after 3m57s
[12:49:26.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:56:0)
[12:49:26.852] Build status: STARTED
[12:49:26.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:57:0) after 4m40s
[12:54:07.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:57:0)
[12:54:07.151] Build status: STARTED
[12:54:07.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:58:0) after 5m59s
[13:00:06.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:58:0)
[13:00:06.569] Build status: STARTED
[13:00:06.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:59:0) after 8m57s
[13:09:03.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:59:0)
[13:09:03.847] Build status: STARTED
[13:09:03.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:60:0) after 6m53s
[13:15:56.948] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:60:0)
[13:15:56.982] Build status: STARTED
[13:15:56.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:61:0) after 2m18s
[13:18:14.999] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:61:0)
[13:18:15.038] Build status: STARTED
[13:18:15.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8916944572180620672:62:0) after 5m47s
[13:20:48.947] Received PubSub notification, asking Buildbucket for the build status
[13:20:49.021] Build:
{
"id": "8751514510182615073",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-04-05T07:33:24.190895078Z",
"startTime": "2024-04-05T07:33:28.827149745Z",
"endTime": "2024-04-05T13:20:47.505726069Z",
"updateTime": "2024-04-05T13:20:47.505726069Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "772bac2aca7dd87504b289aa58e5c53e1efe4b1d",
"ref": "refs/heads/main"
}
}
}
[13:20:49.021] Invocation finished in 5h47m26.119603208s with status SUCCEEDED