[09:52:05.551] New invocation is queued and will start shortly
[09:52:06.652] Starting the invocation (attempt 1)
[09:52:06.678] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c14771f2f5e04f41e73084ee7eb66bbebb5fffb9"
[09:52:06.678] Popped gitiles commit info from properties and tags
[09:52:06.678] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:52:06.680] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:52:06.680] Buildbucket request:
{
"requestId": "8941397026190956256",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8941397026190956256",
"job": "emscripten-releases/mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "c14771f2f5e04f41e73084ee7eb66bbebb5fffb9"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@c14771f2f5e04f41e73084ee7eb66bbebb5fffb9",
"title": "c14771f2f5e04f41e73084ee7eb66bbebb5fffb9",
"url": "https://chromium.googlesource.com/emscripten-releases/+/c14771f2f5e04f41e73084ee7eb66bbebb5fffb9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "c14771f2f5e04f41e73084ee7eb66bbebb5fffb9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8941397026190956256"
},
{
"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"
}
}
[09:52:06.960] Scheduled build:
{
"id": "8775966964294300161",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-07-10T09:52:06.730494007Z",
"updateTime": "2023-07-10T09:52:06.730494007Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "c14771f2f5e04f41e73084ee7eb66bbebb5fffb9",
"ref": "refs/heads/main"
}
}
}
[09:52:06.960] Task URL: https://cr-buildbucket.appspot.com/build/8775966964294300161
[09:52:06.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:2:0) after 4m47s
[09:52:09.036] Received PubSub notification, asking Buildbucket for the build status
[09:52:09.071] Build status: STARTED
[09:56:53.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:2:0)
[09:56:53.990] Build status: STARTED
[09:56:53.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:4:0) after 8m34s
[10:05:28.101] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:4:0)
[10:05:28.120] Build status: STARTED
[10:05:28.120] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:5:0) after 1m55s
[10:07:23.142] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:5:0)
[10:07:23.242] Build status: STARTED
[10:07:23.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:6:0) after 5m1s
[10:12:24.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:6:0)
[10:12:24.423] Build status: STARTED
[10:12:24.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:7:0) after 9m30s
[10:21:54.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:7:0)
[10:21:54.639] Build status: STARTED
[10:21:54.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:8:0) after 3m55s
[10:25:49.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:8:0)
[10:25:49.679] Build status: STARTED
[10:25:49.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:9:0) after 3m9s
[10:28:58.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:9:0)
[10:28:58.720] Build status: STARTED
[10:28:58.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:10:0) after 9m51s
[10:38:49.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:10:0)
[10:38:49.758] Build status: STARTED
[10:38:49.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:11:0) after 1m58s
[10:40:47.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:11:0)
[10:40:47.792] Build status: STARTED
[10:40:47.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:12:0) after 8m40s
[10:49:27.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:12:0)
[10:49:27.782] Build status: STARTED
[10:49:27.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:13:0) after 5m37s
[10:55:04.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:13:0)
[10:55:04.814] Build status: STARTED
[10:55:04.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:14:0) after 2m10s
[10:57:14.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:14:0)
[10:57:14.947] Build status: STARTED
[10:57:14.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:15:0) after 6m56s
[11:04:10.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:15:0)
[11:04:10.968] Build status: STARTED
[11:04:10.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:16:0) after 5m19s
[11:09:30.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:16:0)
[11:09:30.055] Build status: STARTED
[11:09:30.055] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:17:0) after 5m33s
[11:15:03.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:17:0)
[11:15:03.131] Build status: STARTED
[11:15:03.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:18:0) after 8m1s
[11:23:04.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:18:0)
[11:23:04.283] Build status: STARTED
[11:23:04.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:19:0) after 1m15s
[11:24:19.492] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:19:0)
[11:24:19.511] Build status: STARTED
[11:24:19.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:20:0) after 3m47s
[11:28:06.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:20:0)
[11:28:06.547] Build status: STARTED
[11:28:06.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:21:0) after 5m12s
[11:33:18.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:21:0)
[11:33:18.581] Build status: STARTED
[11:33:18.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:22:0) after 6m44s
[11:40:02.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:22:0)
[11:40:02.840] Build status: STARTED
[11:40:02.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:23:0) after 2m35s
[11:42:37.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:23:0)
[11:42:37.923] Build status: STARTED
[11:42:37.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/mac:8941397026190956256:24:0) after 6m52s
[11:45:07.347] Received PubSub notification, asking Buildbucket for the build status
[11:45:07.368] Build:
{
"id": "8775966964294300161",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "mac"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-07-10T09:52:06.730494007Z",
"startTime": "2023-07-10T09:52:08.513097Z",
"endTime": "2023-07-10T11:45:07.016375598Z",
"updateTime": "2023-07-10T11:45:07.016375598Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "c14771f2f5e04f41e73084ee7eb66bbebb5fffb9",
"ref": "refs/heads/main"
}
}
}
[11:45:07.368] Invocation finished in 1h53m1.830257802s with status FAILED