[17:50:56.275] New invocation is queued and will start shortly
[17:50:56.275] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[17:50:57.559] Starting the invocation (attempt 1)
[17:50:57.599] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/043ecd71882fcfb2a930d434d9653b951e7a7dc8"
[17:50:57.599] Reconstructed gitiles commit from tags
[17:50:57.599] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:50:57.599] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:50:57.600] Buildbucket request:
{
"requestId": "9014297457796073760",
"builder": {
"project": "v8",
"bucket": "ci.br.stable",
"builder": "V8 Linux64 - debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"oldest_gitiles_revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"parent_build_environment": {
"gn_args": "is_component_build = true is_debug = true target_cpu = \"x64\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true"
},
"parent_buildername": "V8 Linux64 - debug builder",
"parent_got_revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"parent_got_revision_cp": "refs/branch-heads/9.0@{#48}",
"parent_got_swarming_client_revision": "a32a1607f6093d338f756c7e7c7b4333b0c50c9c",
"parent_test_spec": {
"swarming_dimensions": {
"cpu": "x86-64-avx2",
"os": "Ubuntu-16.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"benchmarks",
1,
null,
"",
[
],
{
},
{
}
],
[
"benchmarks",
1,
"extra",
"extra",
[
],
{
},
{
}
],
[
"mjsunit_sp_frame_access",
1,
null,
"",
[
],
{
},
{
}
],
[
"mozilla",
1,
null,
"",
[
],
{
},
{
}
],
[
"mozilla",
1,
"extra",
"extra",
[
],
{
},
{
}
],
[
"optimize_for_size",
1,
null,
"",
[
],
{
},
{
}
],
[
"test262",
5,
null,
"",
[
],
{
},
{
}
],
[
"test262",
3,
"extra",
"extra",
[
],
{
},
{
}
],
[
"v8testing",
2,
null,
"",
[
],
{
},
{
}
],
[
"v8testing",
2,
"extra",
"extra",
[
],
{
},
{
}
],
[
"v8testing",
1,
"minor_mc",
"minor_mc",
[
],
{
},
{
}
],
[
"v8testing",
1,
"no_lfa",
"no_lfa",
[
],
{
},
{
}
],
[
"v8testing",
1,
"slow_path",
"slow_path",
[
],
{
},
{
}
],
[
"v8testing",
1,
"stress_instruction_scheduling",
"stress_instruction_scheduling",
[
],
{
},
{
}
],
[
"v8testing",
1,
"stress_concurrent_allocation",
"stress_concurrent_allocation",
[
],
{
},
{
}
],
[
"v8testing",
1,
"stress_concurrent_inlining",
"stress_concurrent_inlining",
[
],
{
},
{
}
],
[
"mozilla",
1,
null,
"noavx",
[
"--extra-flags",
"--noenable-avx"
],
{
},
{
}
],
[
"test262",
1,
"default",
"noavx",
[
"--extra-flags",
"--noenable-avx"
],
{
},
{
}
],
[
"v8testing",
2,
null,
"noavx",
[
"--extra-flags",
"--noenable-avx"
],
{
},
{
}
]
]
},
"revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"swarm_hashes": {
"benchmarks": "aa175b65969aa3a1fb6a97e371261cf5a31de09b656a9b44a78063e53857bfa7/318",
"bot_default": "fca4b3fed7e6712258a8a9220ebb37ba2238b56708241cd65df2c419a2b2af88/319",
"jsfunfuzz": "dd22bd6125f310a2e14cd2c0fe9f203fa210d13f6f30f9ce6d3bd98ba6e7a8be/156",
"mjsunit": "91fce51037b376d0575fbc2b8486fb5f8d45daa22bb18d24397e99b5c612ef48/318",
"mozilla": "587f37f2e54ae95d8dde2c3a205a5a81a7027c297cecdbbf37097c908793c98e/318",
"optimize_for_size": "baf6db779e12643079034133fbae12e0dc2a2f6cedfd78b0ba91cc692391b3e3/319",
"test262": "9ceb36e2bfbe1a326d49398930c5cbb00f437d0895a1cea42b682d41741a1070/318"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"gitiles_ref:refs/branch-heads/9.0",
"parent_buildername:V8 Linux64 - debug builder",
"parent_buildnumber:116",
"user_agent:recipe"
]
},
"id": "8b9e7cb2-846a-49df-8e66-b34931a28517",
"title": "V8 Linux64 - debug builder/116"
}
]
},
"newest_gitiles_revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"oldest_gitiles_revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"parent_build_environment": {
"gn_args": "is_component_build = true is_debug = true target_cpu = \"x64\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true"
},
"parent_buildername": "V8 Linux64 - debug builder",
"parent_got_revision": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"parent_got_revision_cp": "refs/branch-heads/9.0@{#48}",
"parent_got_swarming_client_revision": "a32a1607f6093d338f756c7e7c7b4333b0c50c9c",
"parent_test_spec": {
"swarming_dimensions": {
"cpu": "x86-64-avx2",
"os": "Ubuntu-16.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"benchmarks",
1,
null,
"",
[
],
{
},
{
}
],
[
"benchmarks",
1,
"extra",
"extra",
[
],
{
},
{
}
],
[
"mjsunit_sp_frame_access",
1,
null,
"",
[
],
{
},
{
}
],
[
"mozilla",
1,
null,
"",
[
],
{
},
{
}
],
[
"mozilla",
1,
"extra",
"extra",
[
],
{
},
{
}
],
[
"optimize_for_size",
1,
null,
"",
[
],
{
},
{
}
],
[
"test262",
5,
null,
"",
[
],
{
},
{
}
],
[
"test262",
3,
"extra",
"extra",
[
],
{
},
{
}
],
[
"v8testing",
2,
null,
"",
[
],
{
},
{
}
],
[
"v8testing",
2,
"extra",
"extra",
[
],
{
},
{
}
],
[
"v8testing",
1,
"minor_mc",
"minor_mc",
[
],
{
},
{
}
],
[
"v8testing",
1,
"no_lfa",
"no_lfa",
[
],
{
},
{
}
],
[
"v8testing",
1,
"slow_path",
"slow_path",
[
],
{
},
{
}
],
[
"v8testing",
1,
"stress_instruction_scheduling",
"stress_instruction_scheduling",
[
],
{
},
{
}
],
[
"v8testing",
1,
"stress_concurrent_allocation",
"stress_concurrent_allocation",
[
],
{
},
{
}
],
[
"v8testing",
1,
"stress_concurrent_inlining",
"stress_concurrent_inlining",
[
],
{
},
{
}
],
[
"mozilla",
1,
null,
"noavx",
[
"--extra-flags",
"--noenable-avx"
],
{
},
{
}
],
[
"test262",
1,
"default",
"noavx",
[
"--extra-flags",
"--noenable-avx"
],
{
},
{
}
],
[
"v8testing",
2,
null,
"noavx",
[
"--extra-flags",
"--noenable-avx"
],
{
},
{
}
]
]
},
"swarm_hashes": {
"benchmarks": "aa175b65969aa3a1fb6a97e371261cf5a31de09b656a9b44a78063e53857bfa7/318",
"bot_default": "fca4b3fed7e6712258a8a9220ebb37ba2238b56708241cd65df2c419a2b2af88/319",
"jsfunfuzz": "dd22bd6125f310a2e14cd2c0fe9f203fa210d13f6f30f9ce6d3bd98ba6e7a8be/156",
"mjsunit": "91fce51037b376d0575fbc2b8486fb5f8d45daa22bb18d24397e99b5c612ef48/318",
"mozilla": "587f37f2e54ae95d8dde2c3a205a5a81a7027c297cecdbbf37097c908793c98e/318",
"optimize_for_size": "baf6db779e12643079034133fbae12e0dc2a2f6cedfd78b0ba91cc692391b3e3/319",
"test262": "9ceb36e2bfbe1a326d49398930c5cbb00f437d0895a1cea42b682d41741a1070/318"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"ref": "refs/branch-heads/9.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9014297457796073760"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.stable-V8 Linux64 - debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Linux64 - debug builder"
},
{
"key": "parent_buildnumber",
"value": "116"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:51:00.812] Scheduled build:
{
"id": "8848867395581133632",
"builder": {
"project": "v8",
"bucket": "ci.br.stable",
"builder": "V8 Linux64 - debug"
},
"number": 111,
"createdBy": "project:v8",
"createTime": "2021-04-26T17:50:57.757455Z",
"updateTime": "2021-04-26T17:51:00.718969Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"ref": "refs/branch-heads/9.0"
}
}
}
[17:51:00.812] Task URL: https://cr-buildbucket.appspot.com/build/8848867395581133632
[17:51:00.812] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:2:0) after 4m43s
[17:55:43.779] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:2:0)
[17:55:43.809] Build status: SCHEDULED
[17:55:43.809] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:3:0) after 6m8s
[18:01:51.830] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:3:0)
[18:01:51.870] Build status: SCHEDULED
[18:01:51.870] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:4:0) after 9m24s
[18:11:15.916] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:4:0)
[18:11:15.942] Build status: SCHEDULED
[18:11:15.942] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:5:0) after 6m3s
[18:17:18.961] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:5:0)
[18:17:19.001] Build status: SCHEDULED
[18:17:19.002] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:6:0) after 4m18s
[18:21:37.368] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:6:0)
[18:21:37.402] Build status: SCHEDULED
[18:21:37.402] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:7:0) after 1m27s
[18:23:04.521] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:7:0)
[18:23:04.570] Build status: SCHEDULED
[18:23:04.570] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:8:0) after 4m2s
[18:27:06.624] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:8:0)
[18:27:06.672] Build status: SCHEDULED
[18:27:06.672] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:9:0) after 4m44s
[18:31:50.797] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:9:0)
[18:31:50.828] Build status: SCHEDULED
[18:31:50.828] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:10:0) after 2m38s
[18:34:29.440] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:10:0)
[18:34:29.479] Build status: SCHEDULED
[18:34:29.479] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:11:0) after 4m37s
[18:39:06.552] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:11:0)
[18:39:06.589] Build status: SCHEDULED
[18:39:06.589] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:12:0) after 4m33s
[18:43:39.774] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:12:0)
[18:43:39.800] Build status: SCHEDULED
[18:43:39.800] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:13:0) after 3m52s
[18:47:31.885] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:13:0)
[18:47:31.925] Build status: SCHEDULED
[18:47:31.925] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:14:0) after 5m36s
[18:53:07.966] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:14:0)
[18:53:07.991] Build status: SCHEDULED
[18:53:07.991] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:15:0) after 8m53s
[18:55:28.475] Received PubSub notification, asking Buildbucket for the build status
[18:55:28.500] Build status: STARTED
[19:02:00.988] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:15:0)
[19:02:01.022] Build status: STARTED
[19:02:01.022] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:17:0) after 8m39s
[19:10:40.298] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:17:0)
[19:10:40.326] Build status: STARTED
[19:10:40.326] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:18:0) after 7m52s
[19:18:32.461] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:18:0)
[19:18:32.515] Build status: STARTED
[19:18:32.515] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:19:0) after 4m50s
[19:23:22.686] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:19:0)
[19:23:22.728] Build status: STARTED
[19:23:22.728] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:20:0) after 2m21s
[19:25:43.772] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:20:0)
[19:25:43.796] Build status: STARTED
[19:25:43.796] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:21:0) after 1m41s
[19:27:24.959] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:21:0)
[19:27:24.986] Build status: STARTED
[19:27:24.986] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:22:0) after 4m22s
[19:31:47.106] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:22:0)
[19:31:47.133] Build status: STARTED
[19:31:47.133] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - debug:9014297457796073760:23:0) after 5m12s
[19:34:17.041] Received PubSub notification, asking Buildbucket for the build status
[19:34:17.069] Build:
{
"id": "8848867395581133632",
"builder": {
"project": "v8",
"bucket": "ci.br.stable",
"builder": "V8 Linux64 - debug"
},
"number": 111,
"createdBy": "project:v8",
"createTime": "2021-04-26T17:50:57.757455Z",
"startTime": "2021-04-26T18:55:25.056004Z",
"endTime": "2021-04-26T19:34:14.970108275Z",
"updateTime": "2021-04-26T19:34:14.970499679Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "043ecd71882fcfb2a930d434d9653b951e7a7dc8",
"ref": "refs/branch-heads/9.0"
}
}
}
[19:34:17.069] Invocation finished in 1h43m20.808301143s with status SUCCEEDED