[21:18:18.680] New invocation is queued and will start shortly
[21:18:19.768] Starting the invocation (attempt 1)
[21:18:19.799] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1558fca57722f17f536fffe17353f4107373cecf"
[21:18:19.799] Popped gitiles commit info from properties and tags
[21:18:19.799] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:18:19.799] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:18:19.799] Buildbucket request:
{
"requestId": "8909554317820944544",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8909554317820944544",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "1558fca57722f17f536fffe17353f4107373cecf"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@1558fca57722f17f536fffe17353f4107373cecf",
"title": "1558fca57722f17f536fffe17353f4107373cecf",
"url": "https://chromium.googlesource.com/emscripten-releases/+/1558fca57722f17f536fffe17353f4107373cecf"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "1558fca57722f17f536fffe17353f4107373cecf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8909554317820944544"
},
{
"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"
}
}
[21:18:20.704] Scheduled build:
{
"id": "8744124255806025121",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-06-25T21:18:19.980987519Z",
"updateTime": "2024-06-25T21:18:19.980987519Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "1558fca57722f17f536fffe17353f4107373cecf",
"ref": "refs/heads/main"
}
}
}
[21:18:20.704] Task URL: https://cr-buildbucket.appspot.com/build/8744124255806025121
[21:18:20.704] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:2:0) after 7m37s
[21:18:21.725] Received PubSub notification, asking Buildbucket for the build status
[21:18:21.747] Build status: SCHEDULED
[21:25:57.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:2:0)
[21:25:57.799] Build status: SCHEDULED
[21:25:57.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:4:0) after 3m6s
[21:29:03.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:4:0)
[21:29:03.841] Build status: SCHEDULED
[21:29:03.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:5:0) after 2m51s
[21:31:54.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:5:0)
[21:31:54.889] Build status: SCHEDULED
[21:31:54.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:6:0) after 1m44s
[21:33:38.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:6:0)
[21:33:38.968] Build status: SCHEDULED
[21:33:38.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:7:0) after 1m39s
[21:35:18.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:7:0)
[21:35:18.091] Build status: SCHEDULED
[21:35:18.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:8:0) after 6m48s
[21:42:06.073] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:8:0)
[21:42:06.115] Build status: SCHEDULED
[21:42:06.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:9:0) after 6m6s
[21:48:12.130] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:9:0)
[21:48:12.163] Build status: SCHEDULED
[21:48:12.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:10:0) after 3m37s
[21:51:49.270] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:10:0)
[21:51:49.309] Build status: SCHEDULED
[21:51:49.309] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:11:0) after 6m32s
[21:58:21.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:11:0)
[21:58:21.361] Build status: SCHEDULED
[21:58:21.361] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:12:0) after 4m37s
[22:02:58.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:12:0)
[22:02:58.454] Build status: SCHEDULED
[22:02:58.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:13:0) after 4m53s
[22:07:51.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:13:0)
[22:07:51.546] Build status: SCHEDULED
[22:07:51.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:14:0) after 9m33s
[22:17:24.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:14:0)
[22:17:24.617] Build status: SCHEDULED
[22:17:24.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:15:0) after 7m49s
[22:25:13.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:15:0)
[22:25:13.719] Build status: SCHEDULED
[22:25:13.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:16:0) after 7m45s
[22:32:58.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:16:0)
[22:32:58.808] Build status: SCHEDULED
[22:32:58.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:17:0) after 9m21s
[22:42:19.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:17:0)
[22:42:19.842] Build status: SCHEDULED
[22:42:19.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:18:0) after 2m7s
[22:44:26.864] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:18:0)
[22:44:26.901] Build status: SCHEDULED
[22:44:26.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:19:0) after 4m11s
[22:48:37.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:19:0)
[22:48:37.950] Build status: SCHEDULED
[22:48:37.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:20:0) after 4m55s
[22:53:32.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:20:0)
[22:53:32.988] Build status: SCHEDULED
[22:53:32.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:21:0) after 2m45s
[22:56:18.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:21:0)
[22:56:18.021] Build status: SCHEDULED
[22:56:18.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:22:0) after 5m38s
[23:01:56.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:22:0)
[23:01:56.072] Build status: SCHEDULED
[23:01:56.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:23:0) after 5m26s
[23:07:22.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:23:0)
[23:07:22.125] Build status: SCHEDULED
[23:07:22.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:24:0) after 1m47s
[23:09:09.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:24:0)
[23:09:09.254] Build status: SCHEDULED
[23:09:09.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:25:0) after 8m47s
[23:17:56.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:25:0)
[23:17:56.269] Build status: SCHEDULED
[23:17:56.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:26:0) after 6m16s
[23:24:12.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:26:0)
[23:24:12.370] Build status: SCHEDULED
[23:24:12.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:27:0) after 9m38s
[23:33:50.389] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:27:0)
[23:33:50.420] Build status: SCHEDULED
[23:33:50.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:28:0) after 6m43s
[23:40:33.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:28:0)
[23:40:33.432] Build status: SCHEDULED
[23:40:33.432] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:29:0) after 3m52s
[23:44:25.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:29:0)
[23:44:25.848] Build status: SCHEDULED
[23:44:25.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:30:0) after 8m40s
[23:52:58.556] Received PubSub notification, asking Buildbucket for the build status
[23:52:58.619] Build status: STARTED
[23:53:05.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:30:0)
[23:53:05.888] Build status: STARTED
[23:53:05.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:32:0) after 9m14s
[00:02:19.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:32:0)
[00:02:19.979] Build status: STARTED
[00:02:19.979] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:33:0) after 2m8s
[00:04:27.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:33:0)
[00:04:28.013] Build status: STARTED
[00:04:28.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:34:0) after 1m12s
[00:05:40.103] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:34:0)
[00:05:40.202] Build status: STARTED
[00:05:40.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:35:0) after 3m40s
[00:09:20.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:35:0)
[00:09:20.270] Build status: STARTED
[00:09:20.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:36:0) after 7m55s
[00:17:15.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:36:0)
[00:17:15.326] Build status: STARTED
[00:17:15.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:37:0) after 2m12s
[00:19:27.298] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:37:0)
[00:19:27.315] Build status: STARTED
[00:19:27.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:38:0) after 7m57s
[00:27:24.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:38:0)
[00:27:24.352] Build status: STARTED
[00:27:24.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:39:0) after 7m7s
[00:34:31.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:39:0)
[00:34:31.675] Build status: STARTED
[00:34:31.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:40:0) after 9m0s
[00:43:31.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:40:0)
[00:43:31.719] Build status: STARTED
[00:43:31.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:41:0) after 5m54s
[00:49:25.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:41:0)
[00:49:25.716] Build status: STARTED
[00:49:25.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:42:0) after 5m21s
[00:54:46.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:42:0)
[00:54:46.757] Build status: STARTED
[00:54:46.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:43:0) after 2m9s
[00:56:55.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:43:0)
[00:56:55.801] Build status: STARTED
[00:56:55.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:44:0) after 4m58s
[01:01:53.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:44:0)
[01:01:53.843] Build status: STARTED
[01:01:53.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:45:0) after 9m9s
[01:11:02.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:45:0)
[01:11:02.877] Build status: STARTED
[01:11:02.877] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:46:0) after 9m20s
[01:20:22.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:46:0)
[01:20:22.912] Build status: STARTED
[01:20:22.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:47:0) after 4m15s
[01:24:37.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:47:0)
[01:24:37.984] Build status: STARTED
[01:24:37.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:48:0) after 5m35s
[01:30:13.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:48:0)
[01:30:13.197] Build status: STARTED
[01:30:13.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:49:0) after 1m39s
[01:31:52.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:49:0)
[01:31:52.258] Build status: STARTED
[01:31:52.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:50:0) after 6m50s
[01:38:42.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:50:0)
[01:38:42.320] Build status: STARTED
[01:38:42.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:51:0) after 3m17s
[01:41:59.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:51:0)
[01:41:59.404] Build status: STARTED
[01:41:59.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:52:0) after 9m57s
[01:51:56.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:52:0)
[01:51:56.456] Build status: STARTED
[01:51:56.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:53:0) after 2m36s
[01:54:32.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:53:0)
[01:54:32.502] Build status: STARTED
[01:54:32.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:54:0) after 1m54s
[01:56:26.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:54:0)
[01:56:26.553] Build status: STARTED
[01:56:26.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8909554317820944544:55:0) after 6m38s
[02:01:48.336] Received PubSub notification, asking Buildbucket for the build status
[02:01:48.365] Build:
{
"id": "8744124255806025121",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-06-25T21:18:19.980987519Z",
"startTime": "2024-06-25T23:52:58.175197419Z",
"endTime": "2024-06-26T02:01:47.923198648Z",
"updateTime": "2024-06-26T02:01:47.923198648Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "1558fca57722f17f536fffe17353f4107373cecf",
"ref": "refs/heads/main"
}
}
}
[02:01:48.365] Invocation finished in 4h43m29.689612064s with status SUCCEEDED