[05:51:38.080] New invocation is queued and will start shortly
[05:51:39.152] Starting the invocation (attempt 1)
[05:51:39.180] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6d4741df7ef575831fb90b6e3c92110a0e7153f6"
[05:51:39.180] Popped gitiles commit info from properties and tags
[05:51:39.180] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:51:39.181] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:51:39.181] Buildbucket request:
{
"requestId": "8950200060224082480",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8950200060224082480",
"job": "emscripten-releases/linux-test-suites",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "66234ee7a35c02354aa6a88e5d9f4d913247ea9c"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@66234ee7a35c02354aa6a88e5d9f4d913247ea9c",
"title": "66234ee7a35c02354aa6a88e5d9f4d913247ea9c",
"url": "https://chromium.googlesource.com/emscripten-releases/+/66234ee7a35c02354aa6a88e5d9f4d913247ea9c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "6d4741df7ef575831fb90b6e3c92110a0e7153f6"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@6d4741df7ef575831fb90b6e3c92110a0e7153f6",
"title": "6d4741df7ef575831fb90b6e3c92110a0e7153f6",
"url": "https://chromium.googlesource.com/emscripten-releases/+/6d4741df7ef575831fb90b6e3c92110a0e7153f6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "6d4741df7ef575831fb90b6e3c92110a0e7153f6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8950200060224082480"
},
{
"key": "scheduler_job_id",
"value": "emscripten-releases/linux-test-suites"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:51:39.489] Scheduled build:
{
"id": "8784769998326455361",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-04-04T05:51:39.267403240Z",
"updateTime": "2023-04-04T05:51:39.267403240Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "6d4741df7ef575831fb90b6e3c92110a0e7153f6",
"ref": "refs/heads/main"
}
}
}
[05:51:39.489] Task URL: https://cr-buildbucket.appspot.com/build/8784769998326455361
[05:51:39.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:2:0) after 3m58s
[05:55:37.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:2:0)
[05:55:37.650] Build status: SCHEDULED
[05:55:37.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:3:0) after 8m55s
[06:04:32.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:3:0)
[06:04:32.688] Build status: SCHEDULED
[06:04:32.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:4:0) after 6m13s
[06:10:45.717] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:4:0)
[06:10:45.747] Build status: SCHEDULED
[06:10:45.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:5:0) after 2m30s
[06:13:15.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:5:0)
[06:13:15.953] Build status: SCHEDULED
[06:13:15.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:6:0) after 4m2s
[06:17:17.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:6:0)
[06:17:18.018] Build status: SCHEDULED
[06:17:18.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:7:0) after 5m48s
[06:23:06.047] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:7:0)
[06:23:06.069] Build status: SCHEDULED
[06:23:06.069] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:8:0) after 7m17s
[06:30:23.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:8:0)
[06:30:23.322] Build status: SCHEDULED
[06:30:23.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:9:0) after 9m18s
[06:39:41.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:9:0)
[06:39:41.374] Build status: SCHEDULED
[06:39:41.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:10:0) after 9m12s
[06:48:53.721] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:10:0)
[06:48:53.757] Build status: SCHEDULED
[06:48:53.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:11:0) after 9m32s
[06:58:26.034] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:11:0)
[06:58:26.054] Build status: SCHEDULED
[06:58:26.054] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:12:0) after 1m6s
[06:59:32.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:12:0)
[06:59:32.489] Build status: SCHEDULED
[06:59:32.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:13:0) after 5m24s
[07:04:56.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:13:0)
[07:04:56.572] Build status: SCHEDULED
[07:04:56.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:14:0) after 3m37s
[07:08:33.684] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:14:0)
[07:08:33.719] Build status: SCHEDULED
[07:08:33.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:15:0) after 2m29s
[07:11:02.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:15:0)
[07:11:02.836] Build status: SCHEDULED
[07:11:02.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:16:0) after 8m45s
[07:19:47.854] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:16:0)
[07:19:47.883] Build status: SCHEDULED
[07:19:47.883] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:17:0) after 1m44s
[07:21:31.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:17:0)
[07:21:32.057] Build status: SCHEDULED
[07:21:32.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:18:0) after 8m17s
[07:29:49.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:18:0)
[07:29:49.198] Build status: SCHEDULED
[07:29:49.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:19:0) after 7m21s
[07:34:14.845] Received PubSub notification, asking Buildbucket for the build status
[07:34:14.879] Build status: STARTED
[07:37:10.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:19:0)
[07:37:10.253] Build status: STARTED
[07:37:10.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:21:0) after 5m30s
[07:42:40.386] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:21:0)
[07:42:40.408] Build status: STARTED
[07:42:40.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:22:0) after 7m14s
[07:49:54.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:22:0)
[07:49:54.458] Build status: STARTED
[07:49:54.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:23:0) after 1m34s
[07:51:28.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:23:0)
[07:51:28.647] Build status: STARTED
[07:51:28.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:24:0) after 2m27s
[07:53:55.791] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:24:0)
[07:53:55.827] Build status: STARTED
[07:53:55.827] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:25:0) after 7m17s
[08:01:13.040] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:25:0)
[08:01:13.064] Build status: STARTED
[08:01:13.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:26:0) after 5m44s
[08:06:57.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:26:0)
[08:06:57.321] Build status: STARTED
[08:06:57.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:27:0) after 9m6s
[08:16:03.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:27:0)
[08:16:03.517] Build status: STARTED
[08:16:03.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:28:0) after 1m8s
[08:17:11.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:28:0)
[08:17:11.544] Build status: STARTED
[08:17:11.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:29:0) after 7m39s
[08:24:50.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:29:0)
[08:24:50.777] Build status: STARTED
[08:24:50.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:30:0) after 2m32s
[08:27:22.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:30:0)
[08:27:22.883] Build status: STARTED
[08:27:22.883] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:31:0) after 3m1s
[08:30:23.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:31:0)
[08:30:23.973] Build status: STARTED
[08:30:23.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:32:0) after 7m2s
[08:37:25.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:32:0)
[08:37:26.022] Build status: STARTED
[08:37:26.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:33:0) after 6m37s
[08:44:03.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:33:0)
[08:44:03.138] Build status: STARTED
[08:44:03.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:34:0) after 2m56s
[08:46:59.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:34:0)
[08:46:59.280] Build status: STARTED
[08:46:59.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:35:0) after 3m26s
[08:50:25.298] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:35:0)
[08:50:25.319] Build status: STARTED
[08:50:25.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:36:0) after 1m36s
[08:52:01.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:36:0)
[08:52:01.854] Build status: STARTED
[08:52:01.854] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:37:0) after 4m32s
[08:56:33.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:37:0)
[08:56:33.977] Build status: STARTED
[08:56:33.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:38:0) after 2m8s
[08:58:42.472] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:38:0)
[08:58:42.773] Build status: STARTED
[08:58:42.773] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:39:0) after 9m5s
[09:07:48.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:39:0)
[09:07:48.344] Build status: STARTED
[09:07:48.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:40:0) after 6m54s
[09:14:42.375] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:40:0)
[09:14:42.443] Build status: STARTED
[09:14:42.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:41:0) after 4m47s
[09:19:29.555] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:41:0)
[09:19:29.589] Build status: STARTED
[09:19:29.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:42:0) after 8m18s
[09:27:47.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:42:0)
[09:27:47.765] Build status: STARTED
[09:27:47.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:43:0) after 3m18s
[09:31:05.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:43:0)
[09:31:05.921] Build status: STARTED
[09:31:05.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:44:0) after 6m36s
[09:37:42.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:44:0)
[09:37:42.109] Build status: STARTED
[09:37:42.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:45:0) after 1m40s
[09:39:22.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:45:0)
[09:39:22.269] Build status: STARTED
[09:39:22.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:46:0) after 1m52s
[09:41:14.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:46:0)
[09:41:14.401] Build status: STARTED
[09:41:14.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:47:0) after 2m47s
[09:44:01.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:47:0)
[09:44:01.474] Build status: STARTED
[09:44:01.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:48:0) after 4m7s
[09:48:08.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:48:0)
[09:48:08.614] Build status: STARTED
[09:48:08.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:49:0) after 1m56s
[09:50:04.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:49:0)
[09:50:04.788] Build status: STARTED
[09:50:04.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:50:0) after 9m30s
[09:59:34.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:50:0)
[09:59:34.944] Build status: STARTED
[09:59:34.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:51:0) after 2m16s
[10:01:50.993] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:51:0)
[10:01:51.016] Build status: STARTED
[10:01:51.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8950200060224082480:52:0) after 8m48s
[10:02:36.351] Received PubSub notification, asking Buildbucket for the build status
[10:02:36.385] Build:
{
"id": "8784769998326455361",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-04-04T05:51:39.267403240Z",
"startTime": "2023-04-04T07:34:10.809554Z",
"endTime": "2023-04-04T10:02:36.094879699Z",
"updateTime": "2023-04-04T10:02:36.094879699Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "6d4741df7ef575831fb90b6e3c92110a0e7153f6",
"ref": "refs/heads/main"
}
}
}
[10:02:36.385] Invocation finished in 4h10m58.311152039s with status SUCCEEDED