[19:09:51.685] New invocation is queued and will start shortly
[19:09:54.180] Starting the invocation (attempt 1)
[19:09:54.211] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/cfd98e1d06c2f4168496c87836c9ef6efe95f5e0"
[19:09:54.211] Popped gitiles commit info from properties and tags
[19:09:54.211] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:09:54.211] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:09:54.211] Buildbucket request:
{
"requestId": "8955585658133829056",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8955585658133829056",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "cfd98e1d06c2f4168496c87836c9ef6efe95f5e0"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@cfd98e1d06c2f4168496c87836c9ef6efe95f5e0",
"title": "cfd98e1d06c2f4168496c87836c9ef6efe95f5e0",
"url": "https://chromium.googlesource.com/emscripten-releases/+/cfd98e1d06c2f4168496c87836c9ef6efe95f5e0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "cfd98e1d06c2f4168496c87836c9ef6efe95f5e0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8955585658133829056"
},
{
"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"
}
}
[19:09:54.711] Scheduled build:
{
"id": "8790155594649466833",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-02-03T19:09:54.376860475Z",
"updateTime": "2023-02-03T19:09:54.376860475Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "cfd98e1d06c2f4168496c87836c9ef6efe95f5e0",
"ref": "refs/heads/main"
}
}
}
[19:09:54.711] Task URL: https://cr-buildbucket.appspot.com/build/8790155594649466833
[19:09:54.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:2:0) after 7m52s
[19:17:47.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:2:0)
[19:17:47.292] Build status: SCHEDULED
[19:17:47.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:3:0) after 7m12s
[19:24:59.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:3:0)
[19:24:59.332] Build status: SCHEDULED
[19:24:59.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:4:0) after 3m1s
[19:28:00.450] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:4:0)
[19:28:00.477] Build status: SCHEDULED
[19:28:00.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:5:0) after 9m11s
[19:37:11.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:5:0)
[19:37:11.605] Build status: SCHEDULED
[19:37:11.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:6:0) after 1m8s
[19:38:19.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:6:0)
[19:38:19.655] Build status: SCHEDULED
[19:38:19.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:7:0) after 7m3s
[19:45:22.733] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:7:0)
[19:45:22.769] Build status: SCHEDULED
[19:45:22.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:8:0) after 3m4s
[19:48:26.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:8:0)
[19:48:26.810] Build status: SCHEDULED
[19:48:26.810] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:9:0) after 9m5s
[19:57:32.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:9:0)
[19:57:32.150] Build status: SCHEDULED
[19:57:32.150] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:10:0) after 1m4s
[19:58:36.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:10:0)
[19:58:36.286] Build status: SCHEDULED
[19:58:36.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:11:0) after 9m18s
[20:07:54.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:11:0)
[20:07:54.322] Build status: SCHEDULED
[20:07:54.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:12:0) after 7m26s
[20:15:20.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:12:0)
[20:15:20.426] Build status: SCHEDULED
[20:15:20.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:13:0) after 2m36s
[20:17:56.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:13:0)
[20:17:56.589] Build status: SCHEDULED
[20:17:56.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:14:0) after 6m54s
[20:24:50.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:14:0)
[20:24:50.626] Build status: SCHEDULED
[20:24:50.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:15:0) after 9m31s
[20:34:21.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:15:0)
[20:34:21.739] Build status: SCHEDULED
[20:34:21.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:16:0) after 1m53s
[20:36:14.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:16:0)
[20:36:14.815] Build status: SCHEDULED
[20:36:14.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:17:0) after 2m26s
[20:38:40.845] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:17:0)
[20:38:40.868] Build status: SCHEDULED
[20:38:40.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:18:0) after 1m55s
[20:40:35.886] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:18:0)
[20:40:35.924] Build status: SCHEDULED
[20:40:35.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:19:0) after 8m27s
[20:49:02.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:19:0)
[20:49:02.975] Build status: SCHEDULED
[20:49:02.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:20:0) after 4m51s
[20:50:06.825] Received PubSub notification, asking Buildbucket for the build status
[20:50:06.877] Build status: STARTED
[20:53:54.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:20:0)
[20:53:54.086] Build status: STARTED
[20:53:54.086] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:22:0) after 4m15s
[20:58:09.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:22:0)
[20:58:09.138] Build status: STARTED
[20:58:09.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:23:0) after 4m14s
[21:02:23.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:23:0)
[21:02:24.008] Build status: STARTED
[21:02:24.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:24:0) after 6m1s
[21:08:25.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:24:0)
[21:08:25.048] Build status: STARTED
[21:08:25.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:25:0) after 1m30s
[21:09:55.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:25:0)
[21:09:55.099] Build status: STARTED
[21:09:55.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:26:0) after 1m3s
[21:10:58.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:26:0)
[21:10:58.176] Build status: STARTED
[21:10:58.176] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:27:0) after 6m6s
[21:17:04.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:27:0)
[21:17:04.245] Build status: STARTED
[21:17:04.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:28:0) after 3m35s
[21:20:39.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:28:0)
[21:20:39.307] Build status: STARTED
[21:20:39.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:29:0) after 7m40s
[21:28:19.382] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:29:0)
[21:28:19.415] Build status: STARTED
[21:28:19.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:30:0) after 8m12s
[21:36:31.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:30:0)
[21:36:31.509] Build status: STARTED
[21:36:31.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:31:0) after 5m10s
[21:41:41.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:31:0)
[21:41:41.633] Build status: STARTED
[21:41:41.633] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:32:0) after 8m28s
[21:50:09.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:32:0)
[21:50:09.764] Build status: STARTED
[21:50:09.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:33:0) after 8m46s
[21:58:55.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:33:0)
[21:58:55.863] Build status: STARTED
[21:58:55.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:34:0) after 1m49s
[22:00:44.886] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:34:0)
[22:00:44.977] Build status: STARTED
[22:00:44.978] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:35:0) after 8m8s
[22:08:53.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:35:0)
[22:08:53.107] Build status: STARTED
[22:08:53.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:36:0) after 5m17s
[22:14:10.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:36:0)
[22:14:10.237] Build status: STARTED
[22:14:10.237] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:37:0) after 9m51s
[22:24:01.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:37:0)
[22:24:01.295] Build status: STARTED
[22:24:01.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:38:0) after 2m59s
[22:27:00.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:38:0)
[22:27:00.347] Build status: STARTED
[22:27:00.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:39:0) after 7m19s
[22:34:19.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:39:0)
[22:34:19.433] Build status: STARTED
[22:34:19.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:40:0) after 5m45s
[22:40:04.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:40:0)
[22:40:04.543] Build status: STARTED
[22:40:04.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:41:0) after 4m16s
[22:44:20.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:41:0)
[22:44:20.600] Build status: STARTED
[22:44:20.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:42:0) after 7m47s
[22:52:08.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:42:0)
[22:52:08.079] Build status: STARTED
[22:52:08.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:43:0) after 2m19s
[22:54:27.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:43:0)
[22:54:27.184] Build status: STARTED
[22:54:27.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8955585658133829056:44:0) after 2m49s
[22:55:15.324] Received PubSub notification, asking Buildbucket for the build status
[22:55:15.345] Build:
{
"id": "8790155594649466833",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-02-03T19:09:54.376860475Z",
"startTime": "2023-02-03T20:50:05.634009Z",
"endTime": "2023-02-03T22:55:14.910195957Z",
"updateTime": "2023-02-03T22:55:14.910195957Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "cfd98e1d06c2f4168496c87836c9ef6efe95f5e0",
"ref": "refs/heads/main"
}
}
}
[22:55:15.345] Invocation finished in 3h45m23.676641845s with status SUCCEEDED