[23:16:45.159] New invocation is queued and will start shortly
[23:16:46.464] Starting the invocation (attempt 1)
[23:16:46.496] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/8ad0f19ef4a13cdcd202078a1468771a7dc48c83"
[23:16:46.496] Popped gitiles commit info from properties and tags
[23:16:46.496] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:16:46.496] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:16:46.496] Buildbucket request:
{
"requestId": "8916341638618455632",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916341638618455632",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "8ad0f19ef4a13cdcd202078a1468771a7dc48c83"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@8ad0f19ef4a13cdcd202078a1468771a7dc48c83",
"title": "8ad0f19ef4a13cdcd202078a1468771a7dc48c83",
"url": "https://chromium.googlesource.com/emscripten-releases/+/8ad0f19ef4a13cdcd202078a1468771a7dc48c83"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "8ad0f19ef4a13cdcd202078a1468771a7dc48c83",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916341638618455632"
},
{
"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"
}
}
[23:16:46.978] Scheduled build:
{
"id": "8750911576497429361",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-04-11T23:16:46.560460445Z",
"updateTime": "2024-04-11T23:16:46.560460445Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "8ad0f19ef4a13cdcd202078a1468771a7dc48c83",
"ref": "refs/heads/main"
}
}
}
[23:16:46.979] Task URL: https://cr-buildbucket.appspot.com/build/8750911576497429361
[23:16:46.979] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:2:0) after 2m46s
[23:16:47.776] Received PubSub notification, asking Buildbucket for the build status
[23:16:47.794] Build status: SCHEDULED
[23:19:33.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:2:0)
[23:19:33.047] Build status: SCHEDULED
[23:19:33.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:4:0) after 9m58s
[23:29:31.061] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:4:0)
[23:29:31.098] Build status: SCHEDULED
[23:29:31.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:5:0) after 2m43s
[23:32:14.119] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:5:0)
[23:32:14.152] Build status: SCHEDULED
[23:32:14.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:6:0) after 5m20s
[23:37:34.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:6:0)
[23:37:34.264] Build status: SCHEDULED
[23:37:34.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:7:0) after 6m55s
[23:44:29.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:7:0)
[23:44:29.769] Build status: SCHEDULED
[23:44:29.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:8:0) after 9m55s
[23:54:25.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:8:0)
[23:54:25.098] Build status: SCHEDULED
[23:54:25.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:9:0) after 4m23s
[23:58:48.151] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:9:0)
[23:58:48.170] Build status: SCHEDULED
[23:58:48.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:10:0) after 7m52s
[00:06:40.208] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:10:0)
[00:06:40.502] Build status: SCHEDULED
[00:06:40.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:11:0) after 6m44s
[00:13:24.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:11:0)
[00:13:24.623] Build status: SCHEDULED
[00:13:24.623] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:12:0) after 6m17s
[00:19:41.652] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:12:0)
[00:19:41.682] Build status: SCHEDULED
[00:19:41.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:13:0) after 7m1s
[00:26:42.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:13:0)
[00:26:42.769] Build status: SCHEDULED
[00:26:42.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:14:0) after 8m10s
[00:34:53.460] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:14:0)
[00:34:53.477] Build status: SCHEDULED
[00:34:53.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:15:0) after 3m4s
[00:37:58.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:15:0)
[00:37:58.228] Build status: SCHEDULED
[00:37:58.228] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:16:0) after 2m14s
[00:40:12.296] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:16:0)
[00:40:12.321] Build status: SCHEDULED
[00:40:12.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:17:0) after 2m53s
[00:43:05.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:17:0)
[00:43:05.419] Build status: SCHEDULED
[00:43:05.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:18:0) after 1m11s
[00:44:16.473] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:18:0)
[00:44:16.497] Build status: SCHEDULED
[00:44:16.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:19:0) after 6m22s
[00:50:38.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:19:0)
[00:50:38.547] Build status: SCHEDULED
[00:50:38.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:20:0) after 5m23s
[00:56:01.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:20:0)
[00:56:01.742] Build status: SCHEDULED
[00:56:01.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:21:0) after 2m28s
[00:58:29.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:21:0)
[00:58:29.776] Build status: SCHEDULED
[00:58:29.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:22:0) after 9m25s
[01:07:54.852] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:22:0)
[01:07:54.870] Build status: SCHEDULED
[01:07:54.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:23:0) after 5m11s
[01:13:05.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:23:0)
[01:13:06.100] Build status: SCHEDULED
[01:13:06.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:24:0) after 7m33s
[01:20:39.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:24:0)
[01:20:39.359] Build status: SCHEDULED
[01:20:39.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:25:0) after 5m48s
[01:26:27.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:25:0)
[01:26:27.374] Build status: SCHEDULED
[01:26:27.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:26:0) after 8m24s
[01:34:51.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:26:0)
[01:34:51.571] Build status: SCHEDULED
[01:34:51.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:27:0) after 4m18s
[01:39:09.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:27:0)
[01:39:09.618] Build status: SCHEDULED
[01:39:09.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:28:0) after 4m34s
[01:43:43.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:28:0)
[01:43:43.720] Build status: SCHEDULED
[01:43:43.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:29:0) after 3m14s
[01:46:57.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:29:0)
[01:46:57.868] Build status: SCHEDULED
[01:46:57.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:30:0) after 7m34s
[01:54:31.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:30:0)
[01:54:31.982] Build status: SCHEDULED
[01:54:31.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:31:0) after 4m9s
[01:58:41.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:31:0)
[01:58:41.054] Build status: SCHEDULED
[01:58:41.054] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:32:0) after 2m3s
[02:00:44.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:32:0)
[02:00:44.125] Build status: SCHEDULED
[02:00:44.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:33:0) after 6m7s
[02:06:51.150] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:33:0)
[02:06:51.185] Build status: SCHEDULED
[02:06:51.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:34:0) after 3m25s
[02:10:16.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:34:0)
[02:10:16.307] Build status: SCHEDULED
[02:10:16.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:35:0) after 1m40s
[02:11:56.426] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:35:0)
[02:11:56.499] Build status: SCHEDULED
[02:11:56.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:36:0) after 6m17s
[02:18:13.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:36:0)
[02:18:13.564] Build status: SCHEDULED
[02:18:13.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:37:0) after 6m36s
[02:24:49.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:37:0)
[02:24:49.668] Build status: SCHEDULED
[02:24:49.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:38:0) after 3m49s
[02:28:38.685] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:38:0)
[02:28:38.705] Build status: SCHEDULED
[02:28:38.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:39:0) after 5m48s
[02:34:26.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:39:0)
[02:34:26.906] Build status: SCHEDULED
[02:34:26.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:40:0) after 6m14s
[02:40:41.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:40:0)
[02:40:41.382] Build status: SCHEDULED
[02:40:41.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:41:0) after 3m5s
[02:41:55.053] Received PubSub notification, asking Buildbucket for the build status
[02:41:55.086] Build status: STARTED
[02:43:46.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:41:0)
[02:43:46.990] Build status: STARTED
[02:43:46.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:43:0) after 4m29s
[02:48:16.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:43:0)
[02:48:16.133] Build status: STARTED
[02:48:16.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:44:0) after 7m28s
[02:55:44.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:44:0)
[02:55:44.185] Build status: STARTED
[02:55:44.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:45:0) after 9m9s
[03:04:53.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:45:0)
[03:04:53.245] Build status: STARTED
[03:04:53.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:46:0) after 2m52s
[03:07:45.261] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:46:0)
[03:07:45.325] Build status: STARTED
[03:07:45.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:47:0) after 5m57s
[03:13:42.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:47:0)
[03:13:42.424] Build status: STARTED
[03:13:42.424] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:48:0) after 8m32s
[03:22:14.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:48:0)
[03:22:14.556] Build status: STARTED
[03:22:14.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:49:0) after 4m25s
[03:26:39.718] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:49:0)
[03:26:39.761] Build status: STARTED
[03:26:39.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:50:0) after 9m53s
[03:36:32.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:50:0)
[03:36:32.817] Build status: STARTED
[03:36:32.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:51:0) after 5m43s
[03:42:16.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:51:0)
[03:42:16.110] Build status: STARTED
[03:42:16.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:52:0) after 4m52s
[03:47:08.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:52:0)
[03:47:08.664] Build status: STARTED
[03:47:08.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:53:0) after 4m32s
[03:51:40.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:53:0)
[03:51:40.697] Build status: STARTED
[03:51:40.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:54:0) after 3m6s
[03:54:46.927] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:54:0)
[03:54:47.077] Build status: STARTED
[03:54:47.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:55:0) after 1m2s
[03:55:49.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:55:0)
[03:55:49.509] Build status: STARTED
[03:55:49.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:56:0) after 1m52s
[03:57:41.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:56:0)
[03:57:41.550] Build status: STARTED
[03:57:41.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:57:0) after 6m1s
[04:03:42.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:57:0)
[04:03:42.919] Build status: STARTED
[04:03:42.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:58:0) after 4m5s
[04:07:48.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:58:0)
[04:07:48.130] Build status: STARTED
[04:07:48.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:59:0) after 1m50s
[04:09:38.423] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:59:0)
[04:09:38.612] Build status: STARTED
[04:09:38.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:60:0) after 7m49s
[04:17:27.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:60:0)
[04:17:27.658] Build status: STARTED
[04:17:27.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:61:0) after 3m2s
[04:20:29.854] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:61:0)
[04:20:30.036] Build status: STARTED
[04:20:30.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:62:0) after 2m42s
[04:23:12.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:62:0)
[04:23:12.050] Build status: STARTED
[04:23:12.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:63:0) after 4m54s
[04:28:06.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:63:0)
[04:28:06.220] Build status: STARTED
[04:28:06.220] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:64:0) after 3m26s
[04:31:32.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:64:0)
[04:31:32.581] Build status: STARTED
[04:31:32.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:65:0) after 5m51s
[04:37:23.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:65:0)
[04:37:23.962] Build status: STARTED
[04:37:23.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8916341638618455632:66:0) after 5m53s
[04:42:42.756] Received PubSub notification, asking Buildbucket for the build status
[04:42:42.778] Build:
{
"id": "8750911576497429361",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-04-11T23:16:46.560460445Z",
"startTime": "2024-04-12T02:41:54.846540161Z",
"endTime": "2024-04-12T04:42:42.538760941Z",
"updateTime": "2024-04-12T04:42:42.538760941Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "8ad0f19ef4a13cdcd202078a1468771a7dc48c83",
"ref": "refs/heads/main"
}
}
}
[04:42:42.778] Invocation finished in 5h25m57.624875327s with status SUCCEEDED