[14:45:54.389] New invocation is queued and will start shortly
[14:45:54.389] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[14:45:55.776] Starting the invocation (attempt 1)
[14:45:55.808] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/22f5d13b63b2deca04c3a332d07f523ead1e673d"
[14:45:55.808] Reconstructed gitiles commit from tags
[14:45:55.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:45:55.808] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:45:55.808] Buildbucket request:
{
"requestId": "8993290602766587664",
"builder": {
"project": "v8",
"bucket": "ci.br.extended",
"builder": "V8 Linux64 - verify csa"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"oldest_gitiles_revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"parent_build": "https://cr-buildbucket.appspot.com/build/8827862846948353905",
"parent_buildername": "V8 Linux64 - verify csa - builder",
"parent_gn_args": [
"dcheck_always_on = true",
"is_component_build = false",
"is_debug = false",
"target_cpu = \"x64\"",
"use_goma = true",
"v8_enable_google_benchmark = true",
"v8_enable_slow_dchecks = true",
"v8_enable_verify_csa = true"
],
"parent_got_revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"parent_got_revision_cp": "refs/branch-heads/9.6@{#43}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
]
]
},
"revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"swarm_hashes": {
"bot_default": "6d6517ae1ea1301a0bcf0945833942caba66a0940917a220251f6be95e8f1b0f/319"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/22f5d13b63b2deca04c3a332d07f523ead1e673d",
"gitiles_ref:refs/branch-heads/9.6",
"parent_buildername:V8 Linux64 - verify csa - builder",
"parent_buildnumber:20",
"user_agent:recipe"
]
},
"id": "bf7334b0-0f11-44dd-8ec7-57cadc3f0bf9",
"title": "V8 Linux64 - verify csa - builder/20"
}
]
},
"newest_gitiles_revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"oldest_gitiles_revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"parent_build": "https://cr-buildbucket.appspot.com/build/8827862846948353905",
"parent_buildername": "V8 Linux64 - verify csa - builder",
"parent_gn_args": [
"dcheck_always_on = true",
"is_component_build = false",
"is_debug = false",
"target_cpu = \"x64\"",
"use_goma = true",
"v8_enable_google_benchmark = true",
"v8_enable_slow_dchecks = true",
"v8_enable_verify_csa = true"
],
"parent_got_revision": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"parent_got_revision_cp": "refs/branch-heads/9.6@{#43}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
]
]
},
"swarm_hashes": {
"bot_default": "6d6517ae1ea1301a0bcf0945833942caba66a0940917a220251f6be95e8f1b0f/319"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"ref": "refs/branch-heads/9.6"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993290602766587664"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.extended-V8 Linux64 - verify csa"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Linux64 - verify csa - builder"
},
{
"key": "parent_buildnumber",
"value": "20"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:45:56.519] Scheduled build:
{
"id": "8827860540521708065",
"builder": {
"project": "v8",
"bucket": "ci.br.extended",
"builder": "V8 Linux64 - verify csa"
},
"number": 48,
"createdBy": "project:v8",
"createTime": "2021-12-14T14:45:55.896291594Z",
"updateTime": "2021-12-14T14:45:55.896291594Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"ref": "refs/branch-heads/9.6"
}
}
}
[14:45:56.519] Task URL: https://cr-buildbucket.appspot.com/build/8827860540521708065
[14:45:56.519] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:2:0) after 3m30s
[14:49:26.674] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:2:0)
[14:49:26.709] Build status: SCHEDULED
[14:49:26.709] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:3:0) after 2m16s
[14:51:42.993] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:3:0)
[14:51:43.035] Build status: SCHEDULED
[14:51:43.036] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:4:0) after 7m42s
[14:59:25.054] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:4:0)
[14:59:25.116] Build status: SCHEDULED
[14:59:25.116] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:5:0) after 3m47s
[15:03:13.154] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:5:0)
[15:03:13.184] Build status: SCHEDULED
[15:03:13.184] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:6:0) after 3m3s
[15:06:16.229] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:6:0)
[15:06:16.275] Build status: SCHEDULED
[15:06:16.275] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:7:0) after 7m21s
[15:13:37.311] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:7:0)
[15:13:37.446] Build status: SCHEDULED
[15:13:37.446] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:8:0) after 4m37s
[15:18:14.544] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:8:0)
[15:18:14.584] Build status: SCHEDULED
[15:18:14.584] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:9:0) after 8m41s
[15:26:55.713] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:9:0)
[15:26:55.749] Build status: SCHEDULED
[15:26:55.749] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:10:0) after 2m4s
[15:28:59.770] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:10:0)
[15:28:59.799] Build status: SCHEDULED
[15:28:59.799] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:11:0) after 2m7s
[15:31:07.271] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:11:0)
[15:31:07.313] Build status: SCHEDULED
[15:31:07.313] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:12:0) after 3m42s
[15:34:49.330] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:12:0)
[15:34:49.364] Build status: SCHEDULED
[15:34:49.364] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:13:0) after 3m53s
[15:38:42.386] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:13:0)
[15:38:42.424] Build status: SCHEDULED
[15:38:42.424] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:14:0) after 2m41s
[15:41:23.588] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:14:0)
[15:41:23.619] Build status: SCHEDULED
[15:41:23.619] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:15:0) after 8m22s
[15:49:45.968] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:15:0)
[15:49:46.012] Build status: SCHEDULED
[15:49:46.012] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:16:0) after 2m45s
[15:52:31.037] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:16:0)
[15:52:31.095] Build status: SCHEDULED
[15:52:31.095] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:17:0) after 2m53s
[15:55:24.197] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:17:0)
[15:55:24.225] Build status: SCHEDULED
[15:55:24.225] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:18:0) after 8m25s
[16:03:49.245] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:18:0)
[16:03:49.280] Build status: SCHEDULED
[16:03:49.280] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:19:0) after 8m45s
[16:12:34.299] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:19:0)
[16:12:34.331] Build status: SCHEDULED
[16:12:34.331] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:20:0) after 9m58s
[16:22:32.358] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:20:0)
[16:22:32.405] Build status: SCHEDULED
[16:22:32.405] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:21:0) after 8m6s
[16:30:38.432] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:21:0)
[16:30:38.463] Build status: SCHEDULED
[16:30:38.463] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:22:0) after 1m30s
[16:32:08.496] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:22:0)
[16:32:08.527] Build status: SCHEDULED
[16:32:08.527] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:23:0) after 2m11s
[16:34:19.508] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:23:0)
[16:34:19.543] Build status: SCHEDULED
[16:34:19.543] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:24:0) after 7m46s
[16:42:05.607] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:24:0)
[16:42:05.689] Build status: SCHEDULED
[16:42:05.689] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:25:0) after 5m23s
[16:47:28.714] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:25:0)
[16:47:28.743] Build status: SCHEDULED
[16:47:28.743] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:26:0) after 3m4s
[16:50:32.850] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:26:0)
[16:50:32.884] Build status: SCHEDULED
[16:50:32.884] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:27:0) after 9m13s
[17:00:19.594] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:27:0)
[17:00:19.632] Build status: SCHEDULED
[17:00:19.632] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:28:0) after 8m1s
[17:08:20.754] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:28:0)
[17:08:20.785] Build status: SCHEDULED
[17:08:20.785] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:29:0) after 5m42s
[17:14:02.806] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:29:0)
[17:14:02.874] Build status: SCHEDULED
[17:14:02.874] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:30:0) after 7m6s
[17:21:08.988] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:30:0)
[17:21:09.206] Build status: SCHEDULED
[17:21:09.206] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:31:0) after 5m25s
[17:26:34.230] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:31:0)
[17:26:34.275] Build status: SCHEDULED
[17:26:34.275] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:32:0) after 5m56s
[17:32:30.433] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:32:0)
[17:32:30.468] Build status: SCHEDULED
[17:32:30.468] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:33:0) after 3m18s
[17:34:11.266] Received PubSub notification, asking Buildbucket for the build status
[17:34:11.307] Build status: STARTED
[17:35:48.616] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:33:0)
[17:35:48.645] Build status: STARTED
[17:35:48.645] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:35:0) after 6m48s
[17:42:36.668] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:35:0)
[17:42:36.702] Build status: STARTED
[17:42:36.702] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:36:0) after 5m3s
[17:47:39.901] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:36:0)
[17:47:39.930] Build status: STARTED
[17:47:39.930] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - verify csa:8993290602766587664:37:0) after 9m17s
[17:53:56.056] Received PubSub notification, asking Buildbucket for the build status
[17:53:56.093] Build:
{
"id": "8827860540521708065",
"builder": {
"project": "v8",
"bucket": "ci.br.extended",
"builder": "V8 Linux64 - verify csa"
},
"number": 48,
"createdBy": "project:v8",
"createTime": "2021-12-14T14:45:55.896291594Z",
"startTime": "2021-12-14T17:34:07.818500Z",
"endTime": "2021-12-14T17:53:55.387517308Z",
"updateTime": "2021-12-14T17:53:55.387517308Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "22f5d13b63b2deca04c3a332d07f523ead1e673d",
"ref": "refs/branch-heads/9.6"
}
}
}
[17:53:56.093] Invocation finished in 3h8m1.721997761s with status SUCCEEDED