[01:42:54.470] New invocation is queued and will start shortly
[01:42:55.659] Starting the invocation (attempt 1)
[01:42:55.697] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/73d939687fdf71a5ca8d56d7dc6650312674f8f9"
[01:42:55.697] Popped gitiles commit info from properties and tags
[01:42:55.697] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:55.697] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:55.697] Buildbucket request:
{
"requestId": "8931190345826922992",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931190345826922992",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "73d939687fdf71a5ca8d56d7dc6650312674f8f9"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@73d939687fdf71a5ca8d56d7dc6650312674f8f9",
"title": "73d939687fdf71a5ca8d56d7dc6650312674f8f9",
"url": "https://chromium.googlesource.com/emscripten-releases/+/73d939687fdf71a5ca8d56d7dc6650312674f8f9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "73d939687fdf71a5ca8d56d7dc6650312674f8f9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931190345826922992"
},
{
"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"
}
}
[01:42:56.251] Scheduled build:
{
"id": "8765760283806537601",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-10-31T01:42:55.767800548Z",
"updateTime": "2023-10-31T01:42:55.767800548Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "73d939687fdf71a5ca8d56d7dc6650312674f8f9",
"ref": "refs/heads/main"
}
}
}
[01:42:56.251] Task URL: https://cr-buildbucket.appspot.com/build/8765760283806537601
[01:42:56.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:2:0) after 7m49s
[01:42:57.202] Received PubSub notification, asking Buildbucket for the build status
[01:42:57.323] Build status: SCHEDULED
[01:50:45.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:2:0)
[01:50:45.420] Build status: SCHEDULED
[01:50:45.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:4:0) after 6m59s
[01:57:44.513] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:4:0)
[01:57:44.535] Build status: SCHEDULED
[01:57:44.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:5:0) after 5m33s
[02:03:17.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:5:0)
[02:03:17.842] Build status: SCHEDULED
[02:03:17.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:6:0) after 8m17s
[02:11:35.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:6:0)
[02:11:35.172] Build status: SCHEDULED
[02:11:35.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:7:0) after 3m34s
[02:15:09.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:7:0)
[02:15:09.477] Build status: SCHEDULED
[02:15:09.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:8:0) after 1m58s
[02:17:07.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:8:0)
[02:17:07.594] Build status: SCHEDULED
[02:17:07.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:9:0) after 5m36s
[02:22:43.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:9:0)
[02:22:43.751] Build status: SCHEDULED
[02:22:43.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:10:0) after 6m15s
[02:28:58.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:10:0)
[02:28:58.925] Build status: SCHEDULED
[02:28:58.925] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:11:0) after 6m56s
[02:35:55.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:11:0)
[02:35:55.219] Build status: SCHEDULED
[02:35:55.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:12:0) after 6m37s
[02:42:33.294] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:12:0)
[02:42:33.326] Build status: SCHEDULED
[02:42:33.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:13:0) after 4m12s
[02:46:45.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:13:0)
[02:46:45.575] Build status: SCHEDULED
[02:46:45.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:14:0) after 8m35s
[02:55:20.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:14:0)
[02:55:20.659] Build status: SCHEDULED
[02:55:20.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:15:0) after 2m12s
[02:57:32.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:15:0)
[02:57:32.698] Build status: SCHEDULED
[02:57:32.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:16:0) after 1m11s
[02:58:43.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:16:0)
[02:58:43.854] Build status: SCHEDULED
[02:58:43.854] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:17:0) after 9m13s
[03:07:56.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:17:0)
[03:07:56.917] Build status: SCHEDULED
[03:07:56.917] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:18:0) after 5m40s
[03:13:37.060] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:18:0)
[03:13:37.096] Build status: SCHEDULED
[03:13:37.096] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:19:0) after 9m16s
[03:22:53.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:19:0)
[03:22:53.291] Build status: SCHEDULED
[03:22:53.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:20:0) after 2m9s
[03:25:02.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:20:0)
[03:25:02.580] Build status: SCHEDULED
[03:25:02.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:21:0) after 3m16s
[03:28:18.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:21:0)
[03:28:18.699] Build status: SCHEDULED
[03:28:18.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:22:0) after 2m1s
[03:30:19.723] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:22:0)
[03:30:19.822] Build status: SCHEDULED
[03:30:19.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:23:0) after 7m9s
[03:37:29.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:23:0)
[03:37:29.082] Build status: SCHEDULED
[03:37:29.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:24:0) after 2m56s
[03:40:25.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:24:0)
[03:40:25.178] Build status: SCHEDULED
[03:40:25.178] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:25:0) after 6m18s
[03:46:43.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:25:0)
[03:46:43.417] Build status: SCHEDULED
[03:46:43.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:26:0) after 9m23s
[03:56:06.434] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:26:0)
[03:56:06.462] Build status: SCHEDULED
[03:56:06.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:27:0) after 5m16s
[04:01:22.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:27:0)
[04:01:22.656] Build status: SCHEDULED
[04:01:22.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:28:0) after 4m47s
[04:06:09.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:28:0)
[04:06:09.706] Build status: SCHEDULED
[04:06:09.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:29:0) after 7m1s
[04:13:10.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:29:0)
[04:13:10.746] Build status: SCHEDULED
[04:13:10.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:30:0) after 7m24s
[04:20:34.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:30:0)
[04:20:34.830] Build status: SCHEDULED
[04:20:34.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:31:0) after 5m29s
[04:25:43.041] Received PubSub notification, asking Buildbucket for the build status
[04:25:43.057] Build status: STARTED
[04:26:03.866] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:31:0)
[04:26:03.914] Build status: STARTED
[04:26:03.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:33:0) after 7m6s
[04:33:09.920] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:33:0)
[04:33:10.046] Build status: STARTED
[04:33:10.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:34:0) after 5m45s
[04:38:55.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:34:0)
[04:38:55.355] Build status: STARTED
[04:38:55.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:35:0) after 7m1s
[04:45:56.382] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:35:0)
[04:45:56.404] Build status: STARTED
[04:45:56.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:36:0) after 7m34s
[04:53:30.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:36:0)
[04:53:30.435] Build status: STARTED
[04:53:30.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:37:0) after 4m25s
[04:57:55.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:37:0)
[04:57:55.567] Build status: STARTED
[04:57:55.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:38:0) after 6m53s
[05:04:48.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:38:0)
[05:04:48.603] Build status: STARTED
[05:04:48.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:39:0) after 9m58s
[05:14:46.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:39:0)
[05:14:46.719] Build status: STARTED
[05:14:46.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:40:0) after 2m19s
[05:17:05.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:40:0)
[05:17:05.758] Build status: STARTED
[05:17:05.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:41:0) after 8m49s
[05:25:54.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:41:0)
[05:25:54.807] Build status: STARTED
[05:25:54.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:42:0) after 3m35s
[05:29:29.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:42:0)
[05:29:30.022] Build status: STARTED
[05:29:30.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:43:0) after 9m32s
[05:39:02.101] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:43:0)
[05:39:02.144] Build status: STARTED
[05:39:02.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:44:0) after 7m42s
[05:46:44.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:44:0)
[05:46:44.304] Build status: STARTED
[05:46:44.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:45:0) after 8m41s
[05:55:25.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:45:0)
[05:55:25.346] Build status: STARTED
[05:55:25.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:46:0) after 9m27s
[06:04:52.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:46:0)
[06:04:52.818] Build status: STARTED
[06:04:52.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:47:0) after 8m55s
[06:13:48.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:47:0)
[06:13:48.059] Build status: STARTED
[06:13:48.059] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8931190345826922992:48:0) after 8m42s
[06:20:03.098] Received PubSub notification, asking Buildbucket for the build status
[06:20:03.132] Build:
{
"id": "8765760283806537601",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-10-31T01:42:55.767800548Z",
"startTime": "2023-10-31T04:25:42.482639Z",
"endTime": "2023-10-31T06:20:01.939842Z",
"updateTime": "2023-10-31T06:20:01.939842Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "73d939687fdf71a5ca8d56d7dc6650312674f8f9",
"ref": "refs/heads/main"
}
}
}
[06:20:03.132] Invocation finished in 4h37m8.675459517s with status SUCCEEDED