[06:45:04.656] New invocation is queued and will start shortly
[06:45:05.794] Starting the invocation (attempt 1)
[06:45:05.824] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/8ca05c8aebfae68a501e3b9582758506b3dfc25d"
[06:45:05.824] Popped gitiles commit info from properties and tags
[06:45:05.824] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:45:05.824] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:45:05.824] Buildbucket request:
{
"requestId": "8949290728224377264",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8949290728224377264",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "8ca05c8aebfae68a501e3b9582758506b3dfc25d"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@8ca05c8aebfae68a501e3b9582758506b3dfc25d",
"title": "8ca05c8aebfae68a501e3b9582758506b3dfc25d",
"url": "https://chromium.googlesource.com/emscripten-releases/+/8ca05c8aebfae68a501e3b9582758506b3dfc25d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "8ca05c8aebfae68a501e3b9582758506b3dfc25d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8949290728224377264"
},
{
"key": "scheduler_job_id",
"value": "emscripten-releases/linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:45:06.202] Scheduled build:
{
"id": "8783860666284322689",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-04-14T06:45:05.881630905Z",
"updateTime": "2023-04-14T06:45:05.881630905Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "8ca05c8aebfae68a501e3b9582758506b3dfc25d",
"ref": "refs/heads/main"
}
}
}
[06:45:06.202] Task URL: https://cr-buildbucket.appspot.com/build/8783860666284322689
[06:45:06.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:2:0) after 1m28s
[06:46:34.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:2:0)
[06:46:34.271] Build status: SCHEDULED
[06:46:34.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:3:0) after 4m13s
[06:50:47.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:3:0)
[06:50:47.652] Build status: SCHEDULED
[06:50:47.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:4:0) after 5m36s
[06:56:23.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:4:0)
[06:56:23.679] Build status: SCHEDULED
[06:56:23.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:5:0) after 9m25s
[07:05:48.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:5:0)
[07:05:48.775] Build status: SCHEDULED
[07:05:48.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:6:0) after 4m30s
[07:10:18.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:6:0)
[07:10:18.929] Build status: SCHEDULED
[07:10:18.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:7:0) after 3m46s
[07:14:04.950] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:7:0)
[07:14:04.976] Build status: SCHEDULED
[07:14:04.976] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:8:0) after 6m9s
[07:20:13.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:8:0)
[07:20:14.018] Build status: SCHEDULED
[07:20:14.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:9:0) after 7m59s
[07:28:13.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:9:0)
[07:28:13.064] Build status: SCHEDULED
[07:28:13.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:10:0) after 5m45s
[07:33:58.170] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:10:0)
[07:33:58.196] Build status: SCHEDULED
[07:33:58.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:11:0) after 1m9s
[07:35:07.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:11:0)
[07:35:07.310] Build status: SCHEDULED
[07:35:07.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:12:0) after 6m29s
[07:41:36.327] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:12:0)
[07:41:36.360] Build status: SCHEDULED
[07:41:36.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:13:0) after 2m56s
[07:44:32.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:13:0)
[07:44:32.410] Build status: SCHEDULED
[07:44:32.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:14:0) after 9m4s
[07:53:36.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:14:0)
[07:53:36.689] Build status: SCHEDULED
[07:53:36.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:15:0) after 6m55s
[08:00:32.473] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:15:0)
[08:00:32.529] Build status: SCHEDULED
[08:00:32.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:16:0) after 6m12s
[08:06:44.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:16:0)
[08:06:44.807] Build status: SCHEDULED
[08:06:44.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:17:0) after 7m40s
[08:14:25.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:17:0)
[08:14:25.116] Build status: SCHEDULED
[08:14:25.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:18:0) after 9m13s
[08:23:38.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:18:0)
[08:23:38.419] Build status: SCHEDULED
[08:23:38.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:19:0) after 1m57s
[08:25:35.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:19:0)
[08:25:35.618] Build status: SCHEDULED
[08:25:35.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:20:0) after 2m44s
[08:28:19.840] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:20:0)
[08:28:19.915] Build status: SCHEDULED
[08:28:19.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:21:0) after 4m48s
[08:31:29.250] Received PubSub notification, asking Buildbucket for the build status
[08:31:29.273] Build status: STARTED
[08:33:07.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:21:0)
[08:33:07.954] Build status: STARTED
[08:33:07.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:23:0) after 3m31s
[08:36:39.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:23:0)
[08:36:39.114] Build status: STARTED
[08:36:39.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:24:0) after 8m5s
[08:44:44.160] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:24:0)
[08:44:44.182] Build status: STARTED
[08:44:44.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:25:0) after 5m23s
[08:50:07.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:25:0)
[08:50:07.258] Build status: STARTED
[08:50:07.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:26:0) after 1m0s
[08:51:07.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:26:0)
[08:51:07.327] Build status: STARTED
[08:51:07.328] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:27:0) after 3m10s
[08:54:17.481] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:27:0)
[08:54:17.501] Build status: STARTED
[08:54:17.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:28:0) after 3m31s
[08:57:48.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:28:0)
[08:57:48.859] Build status: STARTED
[08:57:48.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:29:0) after 8m49s
[09:06:37.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:29:0)
[09:06:37.928] Build status: STARTED
[09:06:37.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:30:0) after 4m9s
[09:10:46.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:30:0)
[09:10:47.021] Build status: STARTED
[09:10:47.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:31:0) after 8m14s
[09:19:01.040] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:31:0)
[09:19:01.060] Build status: STARTED
[09:19:01.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:32:0) after 1m42s
[09:20:43.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:32:0)
[09:20:43.130] Build status: STARTED
[09:20:43.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:33:0) after 7m51s
[09:28:34.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:33:0)
[09:28:34.294] Build status: STARTED
[09:28:34.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:34:0) after 9m39s
[09:38:13.311] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:34:0)
[09:38:13.332] Build status: STARTED
[09:38:13.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:35:0) after 3m36s
[09:41:49.522] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:35:0)
[09:41:49.544] Build status: STARTED
[09:41:49.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:36:0) after 2m17s
[09:44:06.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:36:0)
[09:44:06.641] Build status: STARTED
[09:44:06.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:37:0) after 9m20s
[09:53:26.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:37:0)
[09:53:26.689] Build status: STARTED
[09:53:26.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:38:0) after 4m53s
[09:58:19.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:38:0)
[09:58:19.777] Build status: STARTED
[09:58:19.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:39:0) after 6m18s
[10:04:37.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:39:0)
[10:04:37.902] Build status: STARTED
[10:04:37.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:40:0) after 7m22s
[10:11:59.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:40:0)
[10:11:59.972] Build status: STARTED
[10:11:59.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:41:0) after 5m14s
[10:17:14.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:41:0)
[10:17:14.115] Build status: STARTED
[10:17:14.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:42:0) after 9m44s
[10:26:58.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:42:0)
[10:26:58.125] Build status: STARTED
[10:26:58.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:43:0) after 1m21s
[10:28:19.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:43:0)
[10:28:19.191] Build status: STARTED
[10:28:19.191] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:44:0) after 7m14s
[10:35:33.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:44:0)
[10:35:33.429] Build status: STARTED
[10:35:33.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:45:0) after 5m30s
[10:41:03.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:45:0)
[10:41:03.474] Build status: STARTED
[10:41:03.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:46:0) after 2m53s
[10:43:56.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:46:0)
[10:43:56.522] Build status: STARTED
[10:43:56.522] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:47:0) after 4m15s
[10:48:11.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:47:0)
[10:48:11.756] Build status: STARTED
[10:48:11.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8949290728224377264:48:0) after 5m21s
[10:48:17.997] Received PubSub notification, asking Buildbucket for the build status
[10:48:18.023] Build:
{
"id": "8783860666284322689",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-04-14T06:45:05.881630905Z",
"startTime": "2023-04-14T08:31:28.654271Z",
"endTime": "2023-04-14T10:48:17.757262483Z",
"updateTime": "2023-04-14T10:48:17.757262483Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "8ca05c8aebfae68a501e3b9582758506b3dfc25d",
"ref": "refs/heads/main"
}
}
}
[10:48:18.023] Invocation finished in 4h3m13.375746499s with status SUCCEEDED