[00:31:41.244] New invocation is queued and will start shortly
[00:31:42.322] Starting the invocation (attempt 1)
[00:31:42.365] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b8b8417db116a609724998ffdd43fd2915bdb3b6"
[00:31:42.365] Popped gitiles commit info from properties and tags
[00:31:42.365] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:31:42.367] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:31:42.367] Buildbucket request:
{
"requestId": "8975406146236695088",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8975406146236695088",
"job": "emscripten-releases/linux-test-suites",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "b8b8417db116a609724998ffdd43fd2915bdb3b6"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@b8b8417db116a609724998ffdd43fd2915bdb3b6",
"title": "b8b8417db116a609724998ffdd43fd2915bdb3b6",
"url": "https://chromium.googlesource.com/emscripten-releases/+/b8b8417db116a609724998ffdd43fd2915bdb3b6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "b8b8417db116a609724998ffdd43fd2915bdb3b6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8975406146236695088"
},
{
"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"
}
}
[00:31:42.843] Scheduled build:
{
"id": "8809976084300481809",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-06-30T00:31:42.457246368Z",
"updateTime": "2022-06-30T00:31:42.457246368Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "b8b8417db116a609724998ffdd43fd2915bdb3b6",
"ref": "refs/heads/main"
}
}
}
[00:31:42.843] Task URL: https://cr-buildbucket.appspot.com/build/8809976084300481809
[00:31:42.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:2:0) after 3m38s
[00:35:20.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:2:0)
[00:35:20.997] Build status: SCHEDULED
[00:35:20.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:3:0) after 5m27s
[00:40:48.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:3:0)
[00:40:48.077] Build status: SCHEDULED
[00:40:48.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:4:0) after 9m22s
[00:46:31.005] Received PubSub notification, asking Buildbucket for the build status
[00:46:31.041] Build status: STARTED
[00:50:10.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:4:0)
[00:50:10.652] Build status: STARTED
[00:50:10.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:6:0) after 6m46s
[00:56:56.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:6:0)
[00:56:56.855] Build status: STARTED
[00:56:56.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:7:0) after 6m16s
[01:03:13.029] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:7:0)
[01:03:13.064] Build status: STARTED
[01:03:13.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:8:0) after 1m51s
[01:05:04.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:8:0)
[01:05:04.439] Build status: STARTED
[01:05:04.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:9:0) after 3m59s
[01:09:04.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:9:0)
[01:09:04.520] Build status: STARTED
[01:09:04.520] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:10:0) after 2m16s
[01:11:20.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:10:0)
[01:11:20.627] Build status: STARTED
[01:11:20.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:11:0) after 5m2s
[01:16:22.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:11:0)
[01:16:22.741] Build status: STARTED
[01:16:22.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:12:0) after 3m5s
[01:19:27.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:12:0)
[01:19:27.793] Build status: STARTED
[01:19:27.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:13:0) after 4m16s
[01:23:43.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:13:0)
[01:23:43.890] Build status: STARTED
[01:23:43.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:14:0) after 5m56s
[01:29:40.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:14:0)
[01:29:40.570] Build status: STARTED
[01:29:40.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:15:0) after 5m47s
[01:35:27.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:15:0)
[01:35:27.645] Build status: STARTED
[01:35:27.645] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:16:0) after 4m50s
[01:40:17.665] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:16:0)
[01:40:17.696] Build status: STARTED
[01:40:17.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:17:0) after 5m56s
[01:46:13.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:17:0)
[01:46:13.750] Build status: STARTED
[01:46:13.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:18:0) after 9m40s
[01:55:53.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:18:0)
[01:55:53.814] Build status: STARTED
[01:55:53.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:19:0) after 8m46s
[02:04:39.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:19:0)
[02:04:39.872] Build status: STARTED
[02:04:39.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:20:0) after 8m17s
[02:12:56.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:20:0)
[02:12:57.004] Build status: STARTED
[02:12:57.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:21:0) after 9m59s
[02:22:56.061] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:21:0)
[02:22:56.095] Build status: STARTED
[02:22:56.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:22:0) after 1m21s
[02:24:17.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:22:0)
[02:24:17.216] Build status: STARTED
[02:24:17.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:23:0) after 4m55s
[02:29:12.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:23:0)
[02:29:12.390] Build status: STARTED
[02:29:12.390] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:24:0) after 4m7s
[02:33:19.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:24:0)
[02:33:19.543] Build status: STARTED
[02:33:19.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:25:0) after 6m52s
[02:40:11.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:25:0)
[02:40:11.944] Build status: STARTED
[02:40:11.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:26:0) after 4m23s
[02:44:34.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:26:0)
[02:44:35.016] Build status: STARTED
[02:44:35.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:27:0) after 7m27s
[02:52:02.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:27:0)
[02:52:02.180] Build status: STARTED
[02:52:02.180] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:28:0) after 8m0s
[03:00:02.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:28:0)
[03:00:02.598] Build status: STARTED
[03:00:02.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:29:0) after 2m58s
[03:03:01.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:29:0)
[03:03:01.150] Build status: STARTED
[03:03:01.150] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:30:0) after 2m25s
[03:05:26.252] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:30:0)
[03:05:26.303] Build status: STARTED
[03:05:26.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:31:0) after 8m2s
[03:13:28.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:31:0)
[03:13:28.533] Build status: STARTED
[03:13:28.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:32:0) after 1m30s
[03:14:58.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:32:0)
[03:14:58.650] Build status: STARTED
[03:14:58.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8975406146236695088:33:0) after 8m55s
[03:19:55.500] Received PubSub notification, asking Buildbucket for the build status
[03:19:55.565] Build:
{
"id": "8809976084300481809",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-06-30T00:31:42.457246368Z",
"startTime": "2022-06-30T00:46:29.502204Z",
"endTime": "2022-06-30T03:19:54.786725695Z",
"updateTime": "2022-06-30T03:19:54.786725695Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "b8b8417db116a609724998ffdd43fd2915bdb3b6",
"ref": "refs/heads/main"
}
}
}
[03:19:55.565] Invocation finished in 2h48m14.337920093s with status FAILED