[10:40:22.991] New invocation is queued and will start shortly
[10:40:24.121] Starting the invocation (attempt 1)
[10:40:24.148] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/65e15f6dc80178999c184843b81d5dfa65f97160"
[10:40:24.148] Popped gitiles commit info from properties and tags
[10:40:24.148] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:40:24.148] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:40:24.148] Buildbucket request:
{
"requestId": "8915845643480747712",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8915845643480747712",
"job": "emscripten-releases/mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "65e15f6dc80178999c184843b81d5dfa65f97160"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@65e15f6dc80178999c184843b81d5dfa65f97160",
"title": "65e15f6dc80178999c184843b81d5dfa65f97160",
"url": "https://chromium.googlesource.com/emscripten-releases/+/65e15f6dc80178999c184843b81d5dfa65f97160"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "65e15f6dc80178999c184843b81d5dfa65f97160",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915845643480747712"
},
{
"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"
}
}
[10:40:24.628] Scheduled build:
{
"id": "8750415581536925585",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-04-17T10:40:24.215887684Z",
"updateTime": "2024-04-17T10:40:24.215887684Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "65e15f6dc80178999c184843b81d5dfa65f97160",
"ref": "refs/heads/main"
}
}
}
[10:40:24.628] Task URL: https://cr-buildbucket.appspot.com/build/8750415581536925585
[10:40:24.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:2:0) after 8m46s
[10:43:27.221] Received PubSub notification, asking Buildbucket for the build status
[10:43:27.301] Build status: SCHEDULED
[10:49:10.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:2:0)
[10:49:10.879] Build status: SCHEDULED
[10:49:10.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:4:0) after 3m39s
[10:52:50.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:4:0)
[10:52:50.079] Build status: SCHEDULED
[10:52:50.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:5:0) after 5m3s
[10:57:53.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:5:0)
[10:57:53.125] Build status: SCHEDULED
[10:57:53.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:6:0) after 6m22s
[11:04:15.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:6:0)
[11:04:15.199] Build status: SCHEDULED
[11:04:15.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:7:0) after 6m59s
[11:11:14.428] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:7:0)
[11:11:14.460] Build status: SCHEDULED
[11:11:14.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:8:0) after 5m9s
[11:16:23.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:8:0)
[11:16:23.530] Build status: SCHEDULED
[11:16:23.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:9:0) after 6m51s
[11:23:14.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:9:0)
[11:23:14.797] Build status: SCHEDULED
[11:23:14.798] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:10:0) after 4m18s
[11:27:32.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:10:0)
[11:27:32.972] Build status: SCHEDULED
[11:27:32.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:11:0) after 6m12s
[11:33:45.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:11:0)
[11:33:45.206] Build status: SCHEDULED
[11:33:45.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:12:0) after 1m32s
[11:35:17.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:12:0)
[11:35:17.495] Build status: SCHEDULED
[11:35:17.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:13:0) after 8m40s
[11:43:57.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:13:0)
[11:43:57.696] Build status: SCHEDULED
[11:43:57.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:14:0) after 9m56s
[11:47:29.158] Received PubSub notification, asking Buildbucket for the build status
[11:47:29.179] Build status: STARTED
[11:53:53.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:14:0)
[11:53:54.087] Build status: STARTED
[11:53:54.088] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:16:0) after 5m20s
[11:59:14.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:16:0)
[11:59:14.140] Build status: STARTED
[11:59:14.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:17:0) after 8m5s
[12:07:19.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:17:0)
[12:07:19.412] Build status: STARTED
[12:07:19.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:18:0) after 8m1s
[12:15:20.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:18:0)
[12:15:20.699] Build status: STARTED
[12:15:20.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:19:0) after 6m48s
[12:22:08.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:19:0)
[12:22:08.751] Build status: STARTED
[12:22:08.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:20:0) after 3m8s
[12:25:16.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:20:0)
[12:25:16.876] Build status: STARTED
[12:25:16.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:21:0) after 9m51s
[12:35:08.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:21:0)
[12:35:08.253] Build status: STARTED
[12:35:08.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:22:0) after 7m5s
[12:42:13.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:22:0)
[12:42:13.293] Build status: STARTED
[12:42:13.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:23:0) after 8m56s
[12:51:09.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:23:0)
[12:51:09.394] Build status: STARTED
[12:51:09.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:24:0) after 4m48s
[12:55:57.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:24:0)
[12:55:57.794] Build status: STARTED
[12:55:57.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:25:0) after 7m41s
[13:03:38.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:25:0)
[13:03:38.848] Build status: STARTED
[13:03:38.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:26:0) after 2m22s
[13:06:01.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:26:0)
[13:06:01.544] Build status: STARTED
[13:06:01.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:27:0) after 8m33s
[13:14:34.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:27:0)
[13:14:34.690] Build status: STARTED
[13:14:34.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:28:0) after 6m22s
[13:20:56.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:28:0)
[13:20:56.765] Build status: STARTED
[13:20:56.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:29:0) after 7m52s
[13:28:48.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:29:0)
[13:28:48.840] Build status: STARTED
[13:28:48.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:30:0) after 1m12s
[13:30:00.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:30:0)
[13:30:00.908] Build status: STARTED
[13:30:00.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:31:0) after 1m11s
[13:31:12.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:31:0)
[13:31:12.226] Build status: STARTED
[13:31:12.226] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:32:0) after 9m58s
[13:41:10.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:32:0)
[13:41:10.346] Build status: STARTED
[13:41:10.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:33:0) after 5m55s
[13:47:05.446] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:33:0)
[13:47:05.497] Build status: STARTED
[13:47:05.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:34:0) after 2m0s
[13:49:05.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:34:0)
[13:49:05.527] Build status: STARTED
[13:49:05.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:35:0) after 1m58s
[13:51:03.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:35:0)
[13:51:03.865] Build status: STARTED
[13:51:03.865] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:36:0) after 9m17s
[14:00:20.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:36:0)
[14:00:20.967] Build status: STARTED
[14:00:20.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:37:0) after 4m39s
[14:05:00.070] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:37:0)
[14:05:00.116] Build status: STARTED
[14:05:00.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:38:0) after 1m9s
[14:06:09.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:38:0)
[14:06:09.284] Build status: STARTED
[14:06:09.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:39:0) after 9m54s
[14:16:03.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:39:0)
[14:16:03.340] Build status: STARTED
[14:16:03.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:40:0) after 8m36s
[14:24:39.350] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:40:0)
[14:24:39.372] Build status: STARTED
[14:24:39.372] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:41:0) after 4m37s
[14:29:16.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:41:0)
[14:29:16.442] Build status: STARTED
[14:29:16.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:42:0) after 1m46s
[14:31:02.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:42:0)
[14:31:02.570] Build status: STARTED
[14:31:02.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:43:0) after 4m49s
[14:35:51.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:43:0)
[14:35:51.631] Build status: STARTED
[14:35:51.631] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:44:0) after 9m7s
[14:44:58.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:44:0)
[14:44:58.713] Build status: STARTED
[14:44:58.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:45:0) after 8m34s
[14:53:32.927] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:45:0)
[14:53:32.963] Build status: STARTED
[14:53:32.963] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:46:0) after 7m28s
[15:01:01.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:46:0)
[15:01:01.181] Build status: STARTED
[15:01:01.181] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:47:0) after 7m46s
[15:08:47.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:47:0)
[15:08:47.425] Build status: STARTED
[15:08:47.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:48:0) after 7m29s
[15:16:16.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:48:0)
[15:16:16.531] Build status: STARTED
[15:16:16.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:49:0) after 6m21s
[15:22:37.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:49:0)
[15:22:37.595] Build status: STARTED
[15:22:37.595] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:50:0) after 6m27s
[15:29:04.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:50:0)
[15:29:04.672] Build status: STARTED
[15:29:04.672] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:51:0) after 6m21s
[15:35:25.920] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:51:0)
[15:35:25.945] Build status: STARTED
[15:35:25.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:52:0) after 8m37s
[15:44:02.977] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:52:0)
[15:44:03.066] Build status: STARTED
[15:44:03.066] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:53:0) after 7m54s
[15:51:57.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:53:0)
[15:51:57.116] Build status: STARTED
[15:51:57.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:54:0) after 8m50s
[16:00:48.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:54:0)
[16:00:48.733] Build status: STARTED
[16:00:48.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:55:0) after 8m7s
[16:08:55.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:55:0)
[16:08:55.774] Build status: STARTED
[16:08:55.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:56:0) after 3m55s
[16:12:50.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:56:0)
[16:12:50.857] Build status: STARTED
[16:12:50.857] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8915845643480747712:57:0) after 4m6s
[16:16:37.652] Received PubSub notification, asking Buildbucket for the build status
[16:16:37.873] Build:
{
"id": "8750415581536925585",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-04-17T10:40:24.215887684Z",
"startTime": "2024-04-17T11:47:28.573595230Z",
"endTime": "2024-04-17T16:16:36.639001919Z",
"updateTime": "2024-04-17T16:16:36.639001919Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "65e15f6dc80178999c184843b81d5dfa65f97160",
"ref": "refs/heads/main"
}
}
}
[16:16:37.873] Invocation finished in 5h36m14.895554759s with status SUCCEEDED